builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0100 starttime: 1484084383.27 results: success (0) buildid: 20170110111005 builduid: ad66a5b709f048b480a5ae877830f55f revision: 838e1c92ded6fff1e1366c81faab1ed3c41add60 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-10 13:39:43.272397) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-10 13:39:43.272940) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-10 13:39:43.273253) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-10 13:39:43.299230) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-10 13:39:43.299633) ========= 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.vyP2OiIY90/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.sv9PZKZdmR/Listeners TMPDIR=/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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-10 13:39:43-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 7.88M=0.001s 2017-01-10 13:39:43 (7.88 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.092036 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-10 13:39:43.415450) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-10 13:39:43.415764) ========= 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.vyP2OiIY90/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.sv9PZKZdmR/Listeners TMPDIR=/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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.020453 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-10 13:39:43.465046) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-10 13:39:43.465456) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 838e1c92ded6fff1e1366c81faab1ed3c41add60 --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 838e1c92ded6fff1e1366c81faab1ed3c41add60 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.vyP2OiIY90/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.sv9PZKZdmR/Listeners TMPDIR=/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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-10 13:39:43,546 truncating revision to first 12 chars 2017-01-10 13:39:43,547 Setting DEBUG logging. 2017-01-10 13:39:43,547 attempt 1/10 2017-01-10 13:39:43,547 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/838e1c92ded6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-10 13:39:44,932 unpacking tar archive at: mozilla-aurora-838e1c92ded6/testing/mozharness/ program finished with exit code 0 elapsedTime=1.638031 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-10 13:39:45.131889) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-10 13:39:45.132448) ========= script_repo_revision: 838e1c92ded6fff1e1366c81faab1ed3c41add60 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-10 13:39:45.136130) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-10 13:39:45.136507) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-10 13:39:45.157325) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2017-01-10 13:39:45.157941) ========= /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.vyP2OiIY90/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.sv9PZKZdmR/Listeners TMPDIR=/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 13:39:45 INFO - MultiFileLogger online at 20170110 13:39:45 in /builds/slave/test 13:39:45 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 13:39:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:39:45 INFO - {'append_to_log': False, 13:39:45 INFO - 'base_work_dir': '/builds/slave/test', 13:39:45 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 13:39:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:39:45 INFO - 'branch': 'Mozilla-Aurora', 13:39:45 INFO - 'buildbot_json_path': 'buildprops.json', 13:39:45 INFO - 'config_files': ('talos/mac_config.py',), 13:39:45 INFO - 'default_actions': ('clobber', 13:39:45 INFO - 'read-buildbot-config', 13:39:45 INFO - 'download-and-extract', 13:39:45 INFO - 'populate-webroot', 13:39:45 INFO - 'create-virtualenv', 13:39:45 INFO - 'install', 13:39:45 INFO - 'run-tests'), 13:39:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:39:45 INFO - 'download_minidump_stackwalk': True, 13:39:45 INFO - 'download_symbols': 'ondemand', 13:39:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:39:45 INFO - 'tooltool.py': '/tools/tooltool.py', 13:39:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:39:45 INFO - '/tools/misc-python/virtualenv.py')}, 13:39:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:39:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:39:45 INFO - 'installer_path': 'installer.exe', 13:39:45 INFO - 'log_level': 'info', 13:39:45 INFO - 'log_name': 'talos', 13:39:45 INFO - 'log_to_console': True, 13:39:45 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:39:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:39:45 INFO - 'opt_config_files': (), 13:39:45 INFO - 'pip_index': False, 13:39:45 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:39:45 INFO - 'cmd': ('bash', 13:39:45 INFO - '-c', 13:39:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:39:45 INFO - 'enabled': True, 13:39:45 INFO - 'halt_on_failure': False, 13:39:45 INFO - 'name': 'check_screen_resolution'},), 13:39:45 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:39:45 INFO - 'cmd': ('bash', 13:39:45 INFO - '-c', 13:39:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:39:45 INFO - 'enabled': True, 13:39:45 INFO - 'halt_on_failure': False, 13:39:45 INFO - 'name': 'check_screen_resolution'},), 13:39:45 INFO - 'run_cmd_checks_enabled': True, 13:39:45 INFO - 'sps_profile': False, 13:39:45 INFO - 'sps_profile_interval': 0, 13:39:45 INFO - 'suite': 'dromaeojs', 13:39:45 INFO - 'system_bits': '32', 13:39:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:39:45 INFO - 'title': 't-yosemite-r7-0100', 13:39:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:39:45 INFO - 'use_talos_json': True, 13:39:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:39:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:39:45 INFO - 'work_dir': 'build'} 13:39:45 INFO - [mozharness: 2017-01-10 21:39:45.368916Z] Running clobber step. 13:39:45 INFO - Running pre-action listener: _resource_record_pre_action 13:39:45 INFO - Running main action method: clobber 13:39:45 INFO - rmtree: /builds/slave/test/build 13:39:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:39:46 INFO - Running post-action listener: _resource_record_post_action 13:39:46 INFO - [mozharness: 2017-01-10 21:39:46.974375Z] Finished clobber step (success) 13:39:46 INFO - [mozharness: 2017-01-10 21:39:46.974531Z] Running read-buildbot-config step. 13:39:46 INFO - Running pre-action listener: _resource_record_pre_action 13:39:46 INFO - Running main action method: read_buildbot_config 13:39:46 INFO - Using buildbot properties: 13:39:46 INFO - { 13:39:46 INFO - "project": "", 13:39:46 INFO - "product": "firefox", 13:39:46 INFO - "script_repo_revision": "production", 13:39:46 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 13:39:46 INFO - "repository": "", 13:39:46 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 13:39:46 INFO - "buildid": "20170110111005", 13:39:46 INFO - "pgo_build": "False", 13:39:46 INFO - "basedir": "/builds/slave/test", 13:39:46 INFO - "buildnumber": 59, 13:39:46 INFO - "slavename": "t-yosemite-r7-0100", 13:39:46 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 13:39:46 INFO - "platform": "yosemite_r7", 13:39:46 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 13:39:46 INFO - "branch": "mozilla-aurora", 13:39:46 INFO - "repo_path": "releases/mozilla-aurora", 13:39:46 INFO - "revision": "838e1c92ded6fff1e1366c81faab1ed3c41add60", 13:39:46 INFO - "stage_platform": "macosx64", 13:39:46 INFO - "builduid": "ad66a5b709f048b480a5ae877830f55f", 13:39:46 INFO - "slavebuilddir": "test" 13:39:46 INFO - } 13:39:46 INFO - Found installer url https://queue.taskcluster.net/v1/task/ADUfEjJzRXOFSd-5BYi_hQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 13:39:46 INFO - Running post-action listener: _resource_record_post_action 13:39:46 INFO - [mozharness: 2017-01-10 21:39:46.977886Z] Finished read-buildbot-config step (success) 13:39:46 INFO - [mozharness: 2017-01-10 21:39:46.978019Z] Running download-and-extract step. 13:39:46 INFO - Running pre-action listener: _resource_record_pre_action 13:39:46 INFO - Running main action method: download_and_extract 13:39:46 INFO - mkdir: /builds/slave/test/build/tests 13:39:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:39:46 INFO - trying https://queue.taskcluster.net/v1/task/ADUfEjJzRXOFSd-5BYi_hQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 13:39:46 INFO - Downloading https://queue.taskcluster.net/v1/task/ADUfEjJzRXOFSd-5BYi_hQ/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 13:39:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ADUfEjJzRXOFSd-5BYi_hQ/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 13:39:48 INFO - Downloaded 1199 bytes. 13:39:48 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 13:39:48 INFO - Using the following test package requirements: 13:39:48 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 13:39:48 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 13:39:48 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 13:39:48 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 13:39:48 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 13:39:48 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 13:39:48 INFO - u'jsshell-mac.zip'], 13:39:48 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 13:39:48 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 13:39:48 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 13:39:48 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 13:39:48 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 13:39:48 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 13:39:48 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 13:39:48 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 13:39:48 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 13:39:48 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 13:39:48 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 13:39:48 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 13:39:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ADUfEjJzRXOFSd-5BYi_hQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 13:39:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ADUfEjJzRXOFSd-5BYi_hQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 13:39:48 INFO - Fetch https://queue.taskcluster.net/v1/task/ADUfEjJzRXOFSd-5BYi_hQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 13:39:49 INFO - Http code: 200 13:39:49 INFO - via: 1.1 ae162f6796e551002447afd7c07ec67a.cloudfront.net (CloudFront) 13:39:49 INFO - x-amz-cf-id: Qh1XDZrf9mS2X0aRQzg75ArBDZRIbNv-WrGy5diYFvvXWvYReWL8Pw== 13:39:49 INFO - x-amz-version-id: D66dRz7mGXc4AaAfF.pHDuvBueg1e492 13:39:49 INFO - Expected file size: 16271797 13:39:49 INFO - Obtained file size: 16271797 13:39:56 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 13:39:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ADUfEjJzRXOFSd-5BYi_hQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 13:39:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ADUfEjJzRXOFSd-5BYi_hQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 13:39:56 INFO - Fetch https://queue.taskcluster.net/v1/task/ADUfEjJzRXOFSd-5BYi_hQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 13:39:56 INFO - Http code: 200 13:39:56 INFO - via: 1.1 bfc29822e21ecc352f71dce83e06ab74.cloudfront.net (CloudFront) 13:39:56 INFO - x-amz-cf-id: G4KVmEd-sluj3SQZuJZS5TtTQ1COcvPILnXuwsBWzcjVnxO7Dju2Qg== 13:39:56 INFO - x-amz-version-id: D66dRz7mGXc4AaAfF.pHDuvBueg1e492 13:39:57 INFO - Expected file size: 16271797 13:39:57 INFO - Obtained file size: 16271797 13:40:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ADUfEjJzRXOFSd-5BYi_hQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 13:40:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ADUfEjJzRXOFSd-5BYi_hQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 13:40:03 INFO - Fetch https://queue.taskcluster.net/v1/task/ADUfEjJzRXOFSd-5BYi_hQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 13:40:04 INFO - Http code: 200 13:40:04 INFO - via: 1.1 423912f2b1c5569e2fa5017167c61fa0.cloudfront.net (CloudFront) 13:40:04 INFO - x-amz-cf-id: xq-QizEmWA1VEDwrrEn3ujRDPhNEDHr-hNZNYE4ihXA2UbcHBFfmjw== 13:40:04 INFO - x-amz-version-id: ESQ9wKjlD4yhLi6Ah6oKv6vtPRsrPJXH 13:40:05 INFO - Expected file size: 14409038 13:40:05 INFO - Obtained file size: 14409038 13:40:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:40:05 INFO - trying https://queue.taskcluster.net/v1/task/ADUfEjJzRXOFSd-5BYi_hQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 13:40:05 INFO - Downloading https://queue.taskcluster.net/v1/task/ADUfEjJzRXOFSd-5BYi_hQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 13:40:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ADUfEjJzRXOFSd-5BYi_hQ/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 13:40:08 INFO - Downloaded 109867974 bytes. 13:40:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ADUfEjJzRXOFSd-5BYi_hQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 13:40:08 INFO - mkdir: /builds/slave/test/properties 13:40:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:40:08 INFO - Writing to file /builds/slave/test/properties/build_url 13:40:08 INFO - Contents: 13:40:08 INFO - build_url:https://queue.taskcluster.net/v1/task/ADUfEjJzRXOFSd-5BYi_hQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 13:40:09 INFO - Running post-action listener: _resource_record_post_action 13:40:09 INFO - Running post-action listener: set_extra_try_arguments 13:40:09 INFO - [mozharness: 2017-01-10 21:40:09.158197Z] Finished download-and-extract step (success) 13:40:09 INFO - [mozharness: 2017-01-10 21:40:09.158316Z] Running populate-webroot step. 13:40:09 INFO - Running pre-action listener: _resource_record_pre_action 13:40:09 INFO - Running main action method: populate_webroot 13:40:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:40:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:40:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:40:09 INFO - '--tppagecycles', 13:40:09 INFO - '7'], 13:40:09 INFO - 'tests': ['tsvgm']}}, 13:40:09 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:40:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:40:09 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:40:09 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:40:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:40:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:40:09 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:40:09 INFO - 'talos_options': ['--disable-e10s'], 13:40:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:40:09 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:40:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:40:09 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:40:09 INFO - 'talos_options': ['--disable-e10s'], 13:40:09 INFO - 'tests': ['damp', 'tps']}, 13:40:09 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:40:09 INFO - 'tests': ['damp', 'tps']}, 13:40:09 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:40:09 INFO - 'tests': ['dromaeo_dom']}, 13:40:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:40:09 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:40:09 INFO - 'tests': ['basic_compositor_video']}, 13:40:09 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 13:40:09 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:40:09 INFO - 'tests': ['a11yr', 13:40:09 INFO - 'ts_paint', 13:40:09 INFO - 'tpaint', 13:40:09 INFO - 'sessionrestore', 13:40:09 INFO - 'sessionrestore_no_auto_restore', 13:40:09 INFO - 'tabpaint']}, 13:40:09 INFO - 'other-e10s': {'tests': ['a11yr', 13:40:09 INFO - 'ts_paint', 13:40:09 INFO - 'tpaint', 13:40:09 INFO - 'sessionrestore', 13:40:09 INFO - 'sessionrestore_no_auto_restore', 13:40:09 INFO - 'tabpaint']}, 13:40:09 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:40:09 INFO - 'ts_paint', 13:40:09 INFO - 'tpaint', 13:40:09 INFO - 'sessionrestore', 13:40:09 INFO - 'sessionrestore_no_auto_restore', 13:40:09 INFO - 'tabpaint']}, 13:40:09 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:40:09 INFO - 'ts_paint', 13:40:09 INFO - 'tpaint', 13:40:09 INFO - 'sessionrestore', 13:40:09 INFO - 'sessionrestore_no_auto_restore', 13:40:09 INFO - 'tabpaint']}, 13:40:09 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:40:09 INFO - 'tests': ['a11yr', 13:40:09 INFO - 'ts_paint', 13:40:09 INFO - 'tpaint', 13:40:09 INFO - 'sessionrestore', 13:40:09 INFO - 'sessionrestore_no_auto_restore', 13:40:09 INFO - 'tabpaint']}, 13:40:09 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:40:09 INFO - 'tests': ['a11yr', 13:40:09 INFO - 'ts_paint', 13:40:09 INFO - 'tpaint', 13:40:09 INFO - 'sessionrestore', 13:40:09 INFO - 'sessionrestore_no_auto_restore', 13:40:09 INFO - 'tabpaint']}, 13:40:09 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:40:09 INFO - 'tests': ['tsvgx', 13:40:09 INFO - 'tsvgr_opacity', 13:40:09 INFO - 'tart', 13:40:09 INFO - 'tscrollx', 13:40:09 INFO - 'cart']}, 13:40:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:40:09 INFO - 'tsvgr_opacity', 13:40:09 INFO - 'tart', 13:40:09 INFO - 'tscrollx', 13:40:09 INFO - 'cart']}, 13:40:09 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:40:09 INFO - 'talos_options': ['--disable-e10s'], 13:40:09 INFO - 'tests': ['tp5o']}, 13:40:09 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:40:09 INFO - 'tests': ['tp5o']}, 13:40:09 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:40:09 INFO - 'talos_options': ['--disable-e10s', 13:40:09 INFO - '--xperf_path', 13:40:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:40:09 INFO - 'tests': ['tp5n']}, 13:40:09 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:40:09 INFO - 'talos_options': ['--xperf_path', 13:40:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:40:09 INFO - 'tests': ['tp5n']}}, 13:40:09 INFO - 'talos.zip': {'path': '', 13:40:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:40:09 INFO - Running post-action listener: _resource_record_post_action 13:40:09 INFO - [mozharness: 2017-01-10 21:40:09.177249Z] Finished populate-webroot step (success) 13:40:09 INFO - [mozharness: 2017-01-10 21:40:09.177367Z] Running create-virtualenv step. 13:40:09 INFO - Running pre-action listener: _resource_record_pre_action 13:40:09 INFO - Running main action method: create_virtualenv 13:40:09 INFO - Creating virtualenv /builds/slave/test/build/venv 13:40:09 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 13:40:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:40:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:40:09 INFO - Using real prefix '/tools/python27' 13:40:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:40:10 INFO - Installing distribute.............................................................................................................................................................................................done. 13:40:12 INFO - Installing pip.................done. 13:40:12 INFO - Return code: 0 13:40:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 13:40:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 13:40:13 INFO - Reading from file tmpfile_stdout 13:40:13 INFO - Output received: 13:40:13 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 13:40:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:40:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:40:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:40:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:40:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:40:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:40:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:40:13 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 0x106271490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061e7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80d2580e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106085d30>, '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 0x105eebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10607db20>, '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.vyP2OiIY90/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.sv9PZKZdmR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:40:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 13:40:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 13:40:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vyP2OiIY90/Render', 13:40:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:40:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:40:13 INFO - 'HOME': '/Users/cltbld', 13:40:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:40:13 INFO - 'LOGNAME': 'cltbld', 13:40:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:40:13 INFO - 'MOZ_NO_REMOTE': '1', 13:40:13 INFO - 'NO_EM_RESTART': '1', 13:40:13 INFO - 'PAGER': '/bin/cat', 13:40:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:40:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:40:13 INFO - 'PWD': '/builds/slave/test', 13:40:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:40:13 INFO - 'SHELL': '/bin/bash', 13:40:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sv9PZKZdmR/Listeners', 13:40:13 INFO - 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', 13:40:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:40:13 INFO - 'USER': 'cltbld', 13:40:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:40:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:40:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:40:13 INFO - 'XPC_FLAGS': '0x0', 13:40:13 INFO - 'XPC_SERVICE_NAME': '0', 13:40:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:40:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:40:13 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 13:40:13 INFO - Cleaning up... 13:40:13 INFO - Return code: 0 13:40:13 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:40:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:40:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:40:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:40:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:40:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:40:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:40:13 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 0x106271490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061e7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80d2580e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106085d30>, '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 0x105eebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10607db20>, '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.vyP2OiIY90/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.sv9PZKZdmR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:40:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 13:40:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 13:40:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vyP2OiIY90/Render', 13:40:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:40:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:40:13 INFO - 'HOME': '/Users/cltbld', 13:40:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:40:13 INFO - 'LOGNAME': 'cltbld', 13:40:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:40:13 INFO - 'MOZ_NO_REMOTE': '1', 13:40:13 INFO - 'NO_EM_RESTART': '1', 13:40:13 INFO - 'PAGER': '/bin/cat', 13:40:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:40:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:40:13 INFO - 'PWD': '/builds/slave/test', 13:40:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:40:13 INFO - 'SHELL': '/bin/bash', 13:40:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sv9PZKZdmR/Listeners', 13:40:13 INFO - 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', 13:40:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:40:13 INFO - 'USER': 'cltbld', 13:40:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:40:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:40:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:40:13 INFO - 'XPC_FLAGS': '0x0', 13:40:13 INFO - 'XPC_SERVICE_NAME': '0', 13:40:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:40:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:40:13 INFO - Downloading/unpacking psutil>=3.1.1 13:40: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 13:40: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 13:40: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 13:40: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 13:40: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 13:40: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 13:40:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:40:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:40:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:40:17 INFO - Installing collected packages: psutil 13:40:17 INFO - Running setup.py install for psutil 13:40:17 INFO - building 'psutil._psutil_osx' extension 13:40:17 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 13:40:17 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 13:40:17 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 13:40:17 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 13:40:17 INFO - building 'psutil._psutil_posix' extension 13:40:17 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 13:40:17 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:40:17 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:40:17 INFO - ^ 13:40:17 INFO - 1 warning generated. 13:40:17 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 13:40:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:40:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:40:18 INFO - Successfully installed psutil 13:40:18 INFO - Cleaning up... 13:40:18 INFO - Return code: 0 13:40:18 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:40:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:40:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:40:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:40:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:40:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:40:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:40:18 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 0x106271490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061e7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80d2580e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106085d30>, '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 0x105eebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10607db20>, '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.vyP2OiIY90/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.sv9PZKZdmR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:40:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 13:40:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 13:40:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vyP2OiIY90/Render', 13:40:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:40:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:40:18 INFO - 'HOME': '/Users/cltbld', 13:40:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:40:18 INFO - 'LOGNAME': 'cltbld', 13:40:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:40:18 INFO - 'MOZ_NO_REMOTE': '1', 13:40:18 INFO - 'NO_EM_RESTART': '1', 13:40:18 INFO - 'PAGER': '/bin/cat', 13:40:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:40:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:40:18 INFO - 'PWD': '/builds/slave/test', 13:40:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:40:18 INFO - 'SHELL': '/bin/bash', 13:40:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sv9PZKZdmR/Listeners', 13:40:18 INFO - 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', 13:40:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:40:18 INFO - 'USER': 'cltbld', 13:40:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:40:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:40:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:40:18 INFO - 'XPC_FLAGS': '0x0', 13:40:18 INFO - 'XPC_SERVICE_NAME': '0', 13:40:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:40:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:40:18 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:40:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:40:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:40:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:40:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:40:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:40:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:40:20 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:40:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:40:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:40:21 INFO - Installing collected packages: mozsystemmonitor 13:40:21 INFO - Running setup.py install for mozsystemmonitor 13:40:21 INFO - Successfully installed mozsystemmonitor 13:40:21 INFO - Cleaning up... 13:40:21 INFO - Return code: 0 13:40:21 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:40:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:40:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:40:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:40:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:40:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:40:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:40:21 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 0x106271490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061e7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80d2580e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106085d30>, '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 0x105eebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10607db20>, '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.vyP2OiIY90/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.sv9PZKZdmR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:40:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 13:40:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 13:40:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vyP2OiIY90/Render', 13:40:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:40:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:40:21 INFO - 'HOME': '/Users/cltbld', 13:40:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:40:21 INFO - 'LOGNAME': 'cltbld', 13:40:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:40:21 INFO - 'MOZ_NO_REMOTE': '1', 13:40:21 INFO - 'NO_EM_RESTART': '1', 13:40:21 INFO - 'PAGER': '/bin/cat', 13:40:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:40:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:40:21 INFO - 'PWD': '/builds/slave/test', 13:40:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:40:21 INFO - 'SHELL': '/bin/bash', 13:40:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sv9PZKZdmR/Listeners', 13:40:21 INFO - 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', 13:40:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:40:21 INFO - 'USER': 'cltbld', 13:40:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:40:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:40:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:40:21 INFO - 'XPC_FLAGS': '0x0', 13:40:21 INFO - 'XPC_SERVICE_NAME': '0', 13:40:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:40:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:40:21 INFO - Downloading/unpacking jsonschema==2.5.1 13:40: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 13:40: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 13:40: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 13:40: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 13:40: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 13:40: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 13:40:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:40:24 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:40:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:40:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:40:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:40:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:40:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:40:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:40:24 INFO - Downloading functools32-3.2.3-2.tar.gz 13:40:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:40:24 INFO - warning: no files found matching '*.txt' 13:40:24 INFO - no previously-included directories found matching 'build' 13:40:24 INFO - no previously-included directories found matching 'dist' 13:40:24 INFO - no previously-included directories found matching '.git*' 13:40:24 INFO - Installing collected packages: jsonschema, functools32 13:40:24 INFO - Running setup.py install for functools32 13:40:24 INFO - warning: no files found matching '*.txt' 13:40:24 INFO - no previously-included directories found matching 'build' 13:40:24 INFO - no previously-included directories found matching 'dist' 13:40:24 INFO - no previously-included directories found matching '.git*' 13:40:24 INFO - Successfully installed jsonschema functools32 13:40:24 INFO - Cleaning up... 13:40:24 INFO - Return code: 0 13:40:24 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 13:40:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:40:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:40:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:40:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:40:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:40:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:40:24 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 0x106271490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061e7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80d2580e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106085d30>, '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 0x105eebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10607db20>, '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.vyP2OiIY90/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.sv9PZKZdmR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:40:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 13:40:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 13:40:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vyP2OiIY90/Render', 13:40:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:40:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:40:24 INFO - 'HOME': '/Users/cltbld', 13:40:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:40:24 INFO - 'LOGNAME': 'cltbld', 13:40:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:40:24 INFO - 'MOZ_NO_REMOTE': '1', 13:40:24 INFO - 'NO_EM_RESTART': '1', 13:40:24 INFO - 'PAGER': '/bin/cat', 13:40:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:40:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:40:24 INFO - 'PWD': '/builds/slave/test', 13:40:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:40:24 INFO - 'SHELL': '/bin/bash', 13:40:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sv9PZKZdmR/Listeners', 13:40:24 INFO - 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', 13:40:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:40:24 INFO - 'USER': 'cltbld', 13:40:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:40:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:40:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:40:24 INFO - 'XPC_FLAGS': '0x0', 13:40:24 INFO - 'XPC_SERVICE_NAME': '0', 13:40:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:40:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:40:25 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 13:40:25 INFO - Cleaning up... 13:40:25 INFO - Return code: 0 13:40:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:40: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')]} 13:40:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:40:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:40: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')]} 13:40:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:40:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:40:25 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 0x106271490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061e7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80d2580e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106085d30>, '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 0x105eebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10607db20>, '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.vyP2OiIY90/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.sv9PZKZdmR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:40:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 13:40:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 13:40:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vyP2OiIY90/Render', 13:40:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:40:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:40:25 INFO - 'HOME': '/Users/cltbld', 13:40:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:40:25 INFO - 'LOGNAME': 'cltbld', 13:40:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:40:25 INFO - 'MOZ_NO_REMOTE': '1', 13:40:25 INFO - 'NO_EM_RESTART': '1', 13:40:25 INFO - 'PAGER': '/bin/cat', 13:40:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:40:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:40:25 INFO - 'PWD': '/builds/slave/test', 13:40:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:40:25 INFO - 'SHELL': '/bin/bash', 13:40:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sv9PZKZdmR/Listeners', 13:40:25 INFO - 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', 13:40:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:40:25 INFO - 'USER': 'cltbld', 13:40:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:40:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:40:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:40:25 INFO - 'XPC_FLAGS': '0x0', 13:40:25 INFO - 'XPC_SERVICE_NAME': '0', 13:40:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:40:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:40:25 INFO - Downloading/unpacking blobuploader==1.2.4 13:40:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:40:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:40:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:40:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:40:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:40:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:40:28 INFO - Downloading blobuploader-1.2.4.tar.gz 13:40:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:40:28 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:40:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:40:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:40:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:40:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:40:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:40:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:40:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:40:28 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:40:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:40:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:40:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:40:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:40:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:40:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:40:29 INFO - Downloading docopt-0.6.1.tar.gz 13:40:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:40:29 INFO - Installing collected packages: blobuploader, requests, docopt 13:40:29 INFO - Running setup.py install for blobuploader 13:40:29 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:40:29 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:40:29 INFO - Running setup.py install for requests 13:40:30 INFO - Running setup.py install for docopt 13:40:30 INFO - Successfully installed blobuploader requests docopt 13:40:30 INFO - Cleaning up... 13:40:30 INFO - Return code: 0 13:40:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:40:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:40:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:40:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:40:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:40:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:40:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:40:30 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 0x106271490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061e7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80d2580e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106085d30>, '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 0x105eebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10607db20>, '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.vyP2OiIY90/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.sv9PZKZdmR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:40:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 13:40:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 13:40:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vyP2OiIY90/Render', 13:40:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:40:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:40:30 INFO - 'HOME': '/Users/cltbld', 13:40:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:40:30 INFO - 'LOGNAME': 'cltbld', 13:40:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:40:30 INFO - 'MOZ_NO_REMOTE': '1', 13:40:30 INFO - 'NO_EM_RESTART': '1', 13:40:30 INFO - 'PAGER': '/bin/cat', 13:40:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:40:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:40:30 INFO - 'PWD': '/builds/slave/test', 13:40:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:40:30 INFO - 'SHELL': '/bin/bash', 13:40:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sv9PZKZdmR/Listeners', 13:40:30 INFO - 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', 13:40:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:40:30 INFO - 'USER': 'cltbld', 13:40:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:40:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:40:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:40:30 INFO - 'XPC_FLAGS': '0x0', 13:40:30 INFO - 'XPC_SERVICE_NAME': '0', 13:40:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:40:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:40:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:40:30 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-7sz_Un-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:40:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:40:30 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-poWJTw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:40:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:40:30 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-HbhwKZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:40:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:40:30 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-aupjMZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:40:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:40:30 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-IzOUcM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:40:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:40:31 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-dLZ3sR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:40:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:40:31 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-5T0lxE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:40:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:40:31 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-iGWyy5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:40:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:40:31 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-JUc5b0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:40:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:40:31 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-tx5Hqw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:40:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:40:31 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-TQfbku-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:40:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:40:31 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-tqSfhH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:40:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:40:31 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-8Dii02-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:40:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:40:31 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-90JaFl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:40:32 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-ecU9Rp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:40:32 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-7P6zHb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:40:32 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-UovyRV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:40:32 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:40:32 INFO - Running setup.py install for manifestparser 13:40:32 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:40:32 INFO - Running setup.py install for mozcrash 13:40:32 INFO - Running setup.py install for mozdebug 13:40:32 INFO - Running setup.py install for mozdevice 13:40:32 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:40:32 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:40:32 INFO - Running setup.py install for mozfile 13:40:33 INFO - Running setup.py install for mozhttpd 13:40:33 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:40:33 INFO - Running setup.py install for mozinfo 13:40:33 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:40:33 INFO - Running setup.py install for mozInstall 13:40:33 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:40:33 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:40:33 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:40:33 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:40:33 INFO - Running setup.py install for mozleak 13:40:33 INFO - Running setup.py install for mozlog 13:40:33 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:40:33 INFO - Running setup.py install for moznetwork 13:40:33 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:40:33 INFO - Running setup.py install for mozprocess 13:40:34 INFO - Running setup.py install for mozprofile 13:40:34 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:40:34 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:40:34 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:40:34 INFO - Running setup.py install for mozrunner 13:40:34 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:40:34 INFO - Running setup.py install for mozscreenshot 13:40:34 INFO - Running setup.py install for moztest 13:40:34 INFO - Running setup.py install for mozversion 13:40:34 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:40:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:40:34 INFO - Cleaning up... 13:40:34 INFO - Return code: 0 13:40:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:40:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:40:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:40:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:40:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:40:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:40:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:40:34 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 0x106271490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061e7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80d2580e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106085d30>, '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 0x105eebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10607db20>, '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.vyP2OiIY90/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.sv9PZKZdmR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:40:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 13:40:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 13:40:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vyP2OiIY90/Render', 13:40:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:40:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:40:34 INFO - 'HOME': '/Users/cltbld', 13:40:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:40:34 INFO - 'LOGNAME': 'cltbld', 13:40:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:40:34 INFO - 'MOZ_NO_REMOTE': '1', 13:40:34 INFO - 'NO_EM_RESTART': '1', 13:40:34 INFO - 'PAGER': '/bin/cat', 13:40:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:40:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:40:34 INFO - 'PWD': '/builds/slave/test', 13:40:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:40:34 INFO - 'SHELL': '/bin/bash', 13:40:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sv9PZKZdmR/Listeners', 13:40:34 INFO - 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', 13:40:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:40:34 INFO - 'USER': 'cltbld', 13:40:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:40:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:40:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:40:34 INFO - 'XPC_FLAGS': '0x0', 13:40:34 INFO - 'XPC_SERVICE_NAME': '0', 13:40:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:40:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:40:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:40:35 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-s1gtMB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:40:35 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 13:40:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:40:35 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-IBK2WY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:40:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:40:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:40:35 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-argyiG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:40:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 13:40:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:40:35 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-21l6zz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:40:35 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)) 13:40:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:40:35 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-hrGQlk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:40:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 13:40:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:40:35 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-YBIZCe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:40:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 13:40:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:40:35 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-mh5YaT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:40:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 13:40:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:40:35 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-xJrp2Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:40:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 13:40:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:40:36 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-EfESlb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:40:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 13:40:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:40:36 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-Bh_zli-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:40:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:40:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:40:36 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-Thgbb_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:40:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 13:40:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:40:36 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-qBqqud-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:40:36 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)) 13:40:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:40:36 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-1jjb1v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:40:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 13:40:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:40:36 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-w_a9ld-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:40:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 13:40:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:40:36 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-7borIw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:40:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 13:40:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:40:36 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-BJZDBw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:40:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 13:40:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:40:36 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-DfNR_i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:40:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 13:40:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:40:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:40:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 13:40:37 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)) 13:40:37 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)) 13:40:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:40:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:40:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:40:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:40:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:40:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:40:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:40:40 INFO - Downloading blessings-1.6.tar.gz 13:40:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:40:40 INFO - Installing collected packages: blessings 13:40:40 INFO - Running setup.py install for blessings 13:40:40 INFO - Successfully installed blessings 13:40:40 INFO - Cleaning up... 13:40:41 INFO - Return code: 0 13:40:41 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:40:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:40:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:40:41 INFO - Reading from file tmpfile_stdout 13:40:41 INFO - Current package versions: 13:40:41 INFO - blessings == 1.6 13:40:41 INFO - blobuploader == 1.2.4 13:40:41 INFO - docopt == 0.6.1 13:40:41 INFO - functools32 == 3.2.3-2 13:40:41 INFO - jsonschema == 2.5.1 13:40:41 INFO - manifestparser == 1.1 13:40:41 INFO - mozInstall == 1.12 13:40:41 INFO - mozcrash == 1.0 13:40:41 INFO - mozdebug == 0.1 13:40:41 INFO - mozdevice == 0.48 13:40:41 INFO - mozfile == 1.2 13:40:41 INFO - mozhttpd == 0.7 13:40:41 INFO - mozinfo == 0.9 13:40:41 INFO - mozleak == 0.1 13:40:41 INFO - mozlog == 3.4 13:40:41 INFO - moznetwork == 0.27 13:40:41 INFO - mozprocess == 0.23 13:40:41 INFO - mozprofile == 0.28 13:40:41 INFO - mozrunner == 6.13 13:40:41 INFO - mozscreenshot == 0.1 13:40:41 INFO - mozsystemmonitor == 0.3 13:40:41 INFO - moztest == 0.8 13:40:41 INFO - mozversion == 1.4 13:40:41 INFO - psutil == 3.1.1 13:40:41 INFO - requests == 1.2.3 13:40:41 INFO - wsgiref == 0.1.2 13:40:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:40:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:40:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:40:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:40:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:40:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:40:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:40:42 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 0x106271490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061e7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80d2580e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106085d30>, '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 0x105eebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10607db20>, '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.vyP2OiIY90/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.sv9PZKZdmR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:40:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 13:40:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 13:40:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vyP2OiIY90/Render', 13:40:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:40:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:40:42 INFO - 'HOME': '/Users/cltbld', 13:40:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:40:42 INFO - 'LOGNAME': 'cltbld', 13:40:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:40:42 INFO - 'MOZ_NO_REMOTE': '1', 13:40:42 INFO - 'NO_EM_RESTART': '1', 13:40:42 INFO - 'PAGER': '/bin/cat', 13:40:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:40:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:40:42 INFO - 'PWD': '/builds/slave/test', 13:40:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:40:42 INFO - 'SHELL': '/bin/bash', 13:40:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sv9PZKZdmR/Listeners', 13:40:42 INFO - 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', 13:40:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:40:42 INFO - 'USER': 'cltbld', 13:40:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:40:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:40:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:40:42 INFO - 'XPC_FLAGS': '0x0', 13:40:42 INFO - 'XPC_SERVICE_NAME': '0', 13:40:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:40:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:40:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 13:40:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 13:40:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 13:40:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 13:40:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 13:40:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 13:40:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 13:40:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 13:40:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 13:40:42 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 13:40:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 13:40:42 INFO - Cleaning up... 13:40:42 INFO - Return code: 0 13:40:42 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:40:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:40:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:40:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:40:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:40:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:40:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:40:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106271490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061e7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80d2580e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106085d30>, '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 0x105eebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10607db20>, '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.vyP2OiIY90/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.sv9PZKZdmR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:40:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 13:40:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 13:40:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vyP2OiIY90/Render', 13:40:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:40:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:40:42 INFO - 'HOME': '/Users/cltbld', 13:40:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:40:42 INFO - 'LOGNAME': 'cltbld', 13:40:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:40:42 INFO - 'MOZ_NO_REMOTE': '1', 13:40:42 INFO - 'NO_EM_RESTART': '1', 13:40:42 INFO - 'PAGER': '/bin/cat', 13:40:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:40:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:40:42 INFO - 'PWD': '/builds/slave/test', 13:40:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:40:42 INFO - 'SHELL': '/bin/bash', 13:40:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sv9PZKZdmR/Listeners', 13:40:42 INFO - 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', 13:40:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:40:42 INFO - 'USER': 'cltbld', 13:40:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:40:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:40:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:40:42 INFO - 'XPC_FLAGS': '0x0', 13:40:42 INFO - 'XPC_SERVICE_NAME': '0', 13:40:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:40:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:40:43 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:40:43 INFO - Cleaning up... 13:40:43 INFO - Return code: 0 13:40:43 INFO - Running post-action listener: _resource_record_post_action 13:40:43 INFO - Running post-action listener: _start_resource_monitoring 13:40:43 INFO - Starting resource monitoring. 13:40:43 INFO - [mozharness: 2017-01-10 21:40:43.066812Z] Finished create-virtualenv step (success) 13:40:43 INFO - [mozharness: 2017-01-10 21:40:43.067734Z] Running install step. 13:40:43 INFO - Running pre-action listener: _resource_record_pre_action 13:40:43 INFO - Running main action method: install 13:40:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:40:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:40:43 INFO - Reading from file tmpfile_stdout 13:40:43 INFO - Detecting whether we're running mozinstall >=1.0... 13:40:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:40:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:40:43 INFO - Reading from file tmpfile_stdout 13:40:43 INFO - Output received: 13:40:43 INFO - Usage: mozinstall [options] installer 13:40:43 INFO - Options: 13:40:43 INFO - -h, --help show this help message and exit 13:40:43 INFO - -d DEST, --destination=DEST 13:40:43 INFO - Directory to install application into. [default: 13:40:43 INFO - "/builds/slave/test"] 13:40:43 INFO - --app=APP Application being installed. [default: firefox] 13:40:43 INFO - mkdir: /builds/slave/test/build/application 13:40:43 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'] 13:40:43 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 13:41:15 INFO - Reading from file tmpfile_stdout 13:41:15 INFO - Output received: 13:41:15 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 13:41:15 INFO - Running post-action listener: _resource_record_post_action 13:41:15 INFO - [mozharness: 2017-01-10 21:41:15.275283Z] Finished install step (success) 13:41:15 INFO - [mozharness: 2017-01-10 21:41:15.275432Z] Running run-tests step. 13:41:15 INFO - Running pre-action listener: _resource_record_pre_action 13:41:15 INFO - Running main action method: run_tests 13:41:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:41:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:41:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:41:15 INFO - 2017-01-10 13:41:15.290 screenresolution[1909:15892] starting screenresolution argv=screenresolution get 13:41:15 INFO - 2017-01-10 13:41:15.292 screenresolution[1909:15892] Display 0: 1600x1200x32@60 13:41:15 INFO - 2017-01-10 13:41:15.298 screenresolution[1910:15894] starting screenresolution argv=screenresolution list 13:41:15 INFO - Available Modes on Display 0 13:41:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:41:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:41:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:41:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:41:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:41:15 INFO - Intel Iris: 13:41:15 INFO - Chipset Model: Intel Iris 13:41:15 INFO - Type: GPU 13:41:15 INFO - Bus: Built-In 13:41:15 INFO - VRAM (Dynamic, Max): 1536 MB 13:41:15 INFO - Vendor: Intel (0x8086) 13:41:15 INFO - Device ID: 0x0a2e 13:41:15 INFO - Revision ID: 0x0009 13:41:15 INFO - Displays: 13:41:15 INFO - Display: 13:41:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:41:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:41:15 INFO - Display Serial Number: 1600x1200 60 13:41:15 INFO - Main Display: Yes 13:41:15 INFO - Mirror: Off 13:41:15 INFO - Online: Yes 13:41:15 INFO - Rotation: Supported 13:41:15 INFO - Return code: 0 13:41:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:41:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:41:15 INFO - Python 2.7.3 13:41:15 INFO - Return code: 0 13:41:15 INFO - grabbing minidump binary from tooltool 13:41: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')]} 13:41:15 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 0x106085d30>, '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 0x105eebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10607db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:41:15 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 13:41:15 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 13:41:15 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 13:41:15 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:41:15 INFO - Return code: 0 13:41:15 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:41:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:41:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:41:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:41:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:41:15 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/ADUfEjJzRXOFSd-5BYi_hQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0100', '--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 13:41:15 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/ADUfEjJzRXOFSd-5BYi_hQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0100 --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 13:41:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vyP2OiIY90/Render', 13:41:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:41:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:41:15 INFO - 'HOME': '/Users/cltbld', 13:41:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:41:15 INFO - 'LOGNAME': 'cltbld', 13:41:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:41:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:41:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:41:15 INFO - 'MOZ_NO_REMOTE': '1', 13:41:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:41:15 INFO - 'NO_EM_RESTART': '1', 13:41:15 INFO - 'PAGER': '/bin/cat', 13:41:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:41:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:41:15 INFO - 'PWD': '/builds/slave/test', 13:41:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:41:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:41:15 INFO - 'SHELL': '/bin/bash', 13:41:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sv9PZKZdmR/Listeners', 13:41:15 INFO - 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', 13:41:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:41:15 INFO - 'USER': 'cltbld', 13:41:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:41:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:41:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:41:15 INFO - 'XPC_FLAGS': '0x0', 13:41:15 INFO - 'XPC_SERVICE_NAME': '0', 13:41:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:41:15 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/ADUfEjJzRXOFSd-5BYi_hQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0100', '--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 13:41:15 INFO - mozversion application_buildid: 20170110111005 13:41:15 INFO - mozversion application_changeset: 838e1c92ded6fff1e1366c81faab1ed3c41add60 13:41:15 INFO - mozversion application_display_name: FirefoxDeveloperEdition 13:41:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:41:15 INFO - mozversion application_name: Firefox 13:41:15 INFO - mozversion application_remotingname: firefox-dev 13:41:15 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 13:41:15 INFO - mozversion application_vendor: Mozilla 13:41:15 INFO - mozversion application_version: 52.0a2 13:41:15 INFO - mozversion platform_buildid: 20170110111005 13:41:15 INFO - mozversion platform_changeset: 838e1c92ded6fff1e1366c81faab1ed3c41add60 13:41:15 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 13:41:15 INFO - mozversion platform_version: 52.0a2 13:41:15 INFO - using testdate: 1484084475 13:41:15 INFO - actual date: 1484084475 13:41:15 INFO - starting webserver on 'localhost:52435' 13:41:15 INFO - SUITE-START | Running 2 tests 13:41:15 INFO - TEST-START | dromaeo_css 13:41:15 INFO - operating with platform_type : mac_ 13:41:15 INFO - Initialising browser for dromaeo_css test... 13:41:15 INFO - TEST-INFO | started process 1920 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/tmpGUMsHq/profile http://localhost:52435/getInfo.html) 13:41:18 INFO - PROCESS | 1920 | __metrics Screen width/height:1600/1200 13:41:18 INFO - PROCESS | 1920 | colorDepth:24 13:41:18 INFO - PROCESS | 1920 | Browser inner width/height: 1024/689 13:41:18 INFO - PROCESS | 1920 | __metrics 13:41:18 INFO - PROCESS | 1920 | 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" 13:41:18 INFO - PROCESS | 1920 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:41:19 INFO - TEST-INFO | 1920: exit 0 13:41:19 INFO - Browser initialized. 13:41:19 INFO - Running cycle 1/1 for dromaeo_css test... 13:41:19 INFO - TEST-INFO | started process 1922 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/tmpGUMsHq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:41:32 INFO - PROCESS | 1922 | RSS: Main: 222015488 13:41:32 INFO - PROCESS | 1922 | 13:44:36 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:52435/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52435/tests/dromaeo/cssquery-ext.html) 13:44:38 INFO - PROCESS | 1922 | RSS: Main: 318889984 13:44:38 INFO - PROCESS | 1922 | 13:47:41 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:52435/tests/dromaeo/cssquery-ext.html (next: http://localhost:52435/tests/dromaeo/cssquery-jquery.html) 13:47:42 INFO - PROCESS | 1922 | RSS: Main: 347836416 13:47:42 INFO - PROCESS | 1922 | 13:50:44 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:52435/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52435/tests/dromaeo/cssquery-mootools.html) 13:50:45 INFO - PROCESS | 1922 | RSS: Main: 309039104 13:50:45 INFO - PROCESS | 1922 | 13:53:47 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:52435/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52435/tests/dromaeo/cssquery-prototype.html) 13:53:48 INFO - PROCESS | 1922 | RSS: Main: 313802752 13:53:48 INFO - PROCESS | 1922 | 13:56:51 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:52435/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52435/tests/dromaeo/cssquery-yui.html) 13:56:52 INFO - PROCESS | 1922 | RSS: Main: 304754688 13:56:52 INFO - PROCESS | 1922 | 13:59:55 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:52435/tests/dromaeo/cssquery-yui.html (next: http://localhost:52435/tests/dromaeo/cssquery-dojo.html) 13:59:55 INFO - PROCESS | 1922 | RSS: Main: 370348032 13:59:55 INFO - PROCESS | 1922 | 13:59:55 INFO - PROCESS | 1922 | __start_tp_report 13:59:55 INFO - PROCESS | 1922 | _x_x_mozilla_page_load 13:59:55 INFO - PROCESS | 1922 | _x_x_mozilla_page_load_details 13:59:55 INFO - PROCESS | 1922 | |i|pagename|runs| 13:59:55 INFO - PROCESS | 1922 | |0;dojo.html;3313.9502907456385;3316.618588862281;3330.5670262865824;3355.228297491575;3388.3053973935343;5639.718014099294;5700.201971723962;5715.428457154282;5730.083186690131;5746.195532625436;5648.265725455685;5702.486776190152;5716.685582292983;5733.254676892008;5734.856628584287;5220.112580861262;5233.267342572043;5234.5027222414;5263.0526505229045;5302.681839089648;5160.354955630546;5174.172132458807;5178.1197196476505;5192;5343.038253114438;5267.947320526803;5299.364076310848;5316.441773613773;5316.441773613773;5343.599230057742;5282.683039017652;5311.203319502067;5314.973425132888;5322.733863306819;5369.919451208235;5046.823361182341;5165.819196328148;5251.028559716465;5254.8686282842855;5258.474152584749;5619.690916999563;5683.914741278884;5699.943000570024;5714.571407144481;5767.163761254595;6190.535709821781;6220.315765265817;6275.497960163221;6277.780277690259;6287.779927702509;6326.304106548276;6345.8413539661415;6352.078948552434;6358.809235722936;6361.045843123522;5658.91511627326;5723.59934804568;5742.10997295424;5748.798792042342;5871.3541510433815;6308.873822523524;6319.0837328587095;6330.113784070234;6333.841653958688;6346.17499725042;6202.658853763086;6203.534734894901;6207.6585787781205;6209;6215.12988181655;6127.7393367763425;6133.846653833601;6152.692365381802;6175.505959523227;6260.499160067184;6075.48358389533;6130.846728831815;6194.822684423181;6214.347493513116;6219.440250377488;6122.877542449215;6242.968785156136;6244.625322480666;6251.937480625135;6266.934621114421;4850.466448690641;4893.363862697826;4953.356063711766;4964.081644895706;4988.0772057168815;4401.317795741601;4433.534478879671;4451.376807246988;4453.643708503312;4519.141363140992;5812.854678633068;5821.18503409523;5831.562632802556;5839.416058394211;5847.093700476348;6285.308616052231;6306;6306.558540902093;6313.558050936482;6351.142595749515;5598.440155984369;5704.572157088235;5714;5714.428557144335;5770.711464426846;5444.3194600674915;5447.891042179213;5462.317210348707;5492.286002819608;5522;6334.334894836068;6348.143000694847;6351.682415879095;6358.491320694241;6377.362263773678;4794.928076078932;6127.816165515042;6153.446189842935;6306.9684651576445;6322.178116844781;6227.906581401373;6275.058741188675;6289.308176100717;6337.049442583465;6354;6136.754529818936;6214.098955651315;6215.06773983906;6218.720157593009;6219.253689557101;6107.480864126422;6252.5935814171935;6279.748810047729;6286.214223222098;6303.180586523906;5911.822645320647;5983.611065280743;6005.759769609342;6044.546659000681;6100.938990610037;5962.731677074456;6073.665948372882;6083.878322433615;6092.329843717099;6107.969460152671;4524.411826462539;5742.655440673573;5922.170896074467;5937.228160339128;5952.910706339495;5877.794277200277;5984.491318237999;6012.759489620366;6050.122732203894;6132.56967241757;5935.970320148372;5939.910901336397;5964.343922168471;5968.432998865101;6014.759409623741 13:59:55 INFO - PROCESS | 1922 | |1;ext.html;467.66094581428445;473.85310553728345;478.03198522990937;507.8324153029498;512.5284738041001;26675.733242667568;30931.536026959584;31040.689593104064;31041.223969400777;31053;40234.39648405276;41455;41582.58417415825;43536.56463435365;43645;9361.859572106423;9524.142827145555;9536.904630953706;9541.33210675253;9561.904380956206;8519.488830670149;9436.292278079138;9457.905420945772;9466.053394660548;9469.857952130724;8540.274076703488;8675.392722509427;8682.13178682133;8691.826163476726;8692.521911294876;11187.49656265466;11416.200865939387;11425.885741142565;11622.825657615143;11658.300501969909;11314.943425282903;11377.089832813355;11384.146189035855;11386.316820990683;11388.943055284753;38816;39190;39234.019149521206;39270.21459570793;39366.80316598398;2932.2669332666833;3248.886288979898;3338.6661333866664;3425.2978139481347;3673.559172899269;28581.57127643087;29353;29836.701632983608;29979.70020299791;30793.76824927;3107.7979931304617;3108.0831154809384;3185.840707964616;3204.903852884417;3229.7577681673733;494817;496631;497485;497572.0242797562;502635;33030.339393212;61272.693636531534;61519;61646.38353616496;61719.6914015427;33867.83066084703;34367.656323436444;35213.647863521546;35255.47116793199;35339.82330088384;14380.280985950661;14506.709865802624;14523.128612283297;14524.709505809824;14557.563273101612;12177.634670959886;12284.385780710929;12307;12308.50765969369;12318;7634.427417943676;7682.270184332479;7729.536227826348;7843.333316668147;7848.489848159851;2990.2524368907775;3072.8169654683106;3081.568580398746;3118;3172.904812855618;728.8032231297626;844.6072576252072;896.5036358202931;953.8616900549381;1025.3284098915221;28285.292867678472;28964.855175723987;29835.552466713016;30493.69506304953;30606.69393306083;941.4868896451451;1040.9687709368732;1053.7312985188728;1066.802641511323;1067.2529229539352;15064.171470569225;15356.155411452477;15375.154366509949;15392.076475411512;15702.607434814221;678591;681696;682413;682894;683239.1676083275;57300.71349643308;57758.7112064437;57834;57898.42101579015;57908.42091579115;1123.4102096399292;1236.0852968803201;1295.799151131576;1310.3947664111577;1339.8861096806882;28725.85637071801;28849.278768030126;29728.554071688508;30398.848005759828;31357.216069598442;49834.50165498299;50212.748936255084;50235;50259;50372.99999999854;557405;559749;560275;561936;562297;22446.77553224447;26297.342566435993;27184.864075679496;27676;27728.58407123935;45436.54563454323;45536.54463455323;46154.53845461502;46482.53517464782;46742.298865516364;37526.81236593799;38327.616723832405;38517.2296554073;38647.806760966014;38670;36759.63240367562;37873.431898522096;38450;39331.6066839328;39411.014724632114;37161.070973225884;37366;38222.61777382302;38261.61738382692;38277.04307392226;31962.68037319597;34085.82957085199;34151.31697366088;34225.315493689486;34498.82750586231;24952;25178.118765843108;25443;25847.741522584533;26016.60975085413 13:59:55 INFO - PROCESS | 1922 | |2;jquery.html;347.9599846017709;366.70297059381903;375.08292225508643;378.7481324918928;395.83968492760425;14943.327550260237;15074.92462537687;16033.358665653375;19958.301459448885;21193.258235961745;19287.421377358667;20010.59978800423;20448.284310049152;20566.280180193695;20815.375538733824;5140.511651393131;5693.715314234292;5968.820935371946;5990.8502287442725;5991.64050156992;4153.605407486299;4387.473503179623;5894.911576326359;5954.166416701665;5967;4344.152890186415;4412.867613971586;6230.190075290228;6505.154329937125;6518.608883466984;6308.337624549402;6480.967595162041;6577.967110164443;6581.769638062645;6588.736450541973;5709.62887412321;5709.714514274311;5719.370869204384;5721.141828725683;5723.799667011676;24716.5056698865;25687.972481100922;25718;25879.482410351688;26063;2159.362987918568;2276.4422716434415;2362.4566349739657;2371.8576885386933;2398.136670798511;14821.333040013225;15399.307031183624;15532.922335388364;15614.609634759108;16100.19499025044;1778.6709458750172;1820.1991123905439;1841.4015444980434;1846.5291350705618;1954.540682939508;114524.85475145225;118342.81657183405;118763;119186;120593;10634.627788027381;10721.35671859691;11164.944175279072;11317.377544235145;11354;11738.237014594195;12340.629781106582;13949.790753138712;13962.301884905713;14018.299085045706;7875.645595948196;7922.841543169105;8003.999500062492;8154.959225203836;8223.342132629376;8370.623321950527;8418.747437576883;8428.8314233715;8602.95698521516;8609.870851937227;3518.665726755954;3594.9281014379567;3895;3900.5124359455067;3963.5243770747693;1461.3716102075996;1551.8991265567702;1698.8640908727273;1823.450067442663;1888.1503323504478;714.3213946750608;816.9019717633922;884.8363052835246;896.7130518234233;989.2333441582687;20578;22137.335879923627;22147.557048858933;22275.777242227694;22314.665280020814;883.4919921045424;906.7007887397143;909.7498187998353;934.6261495401893;953.1516949914582;10854.891451085547;11517.136214783925;11518.539258429737;11523.654290371302;11560.075193984461;616268;620379;626064;637929;638439.807800958;136018;136230;139308;139496.30251848878;145616;974.8683927669815;980.6714750558642;995.9701208963743;998.1864780204255;1002.9398236105857;16013.599660008127;17877.463676089217;18628.720569191202;18722.625547489246;19056.237750490378;53401.65983401629;54909;55461;56503.8699226005;56824;79324.60337698275;80551;80778;83515;83670;7979.640916158671;8132.75601731949;8169.632366543638;8414.495130292202;8423.326133909395;11949;12906.641625917131;12954.352282386031;13197.538086166938;13212.735745285232;11290.548378065114;11290.661280161608;11515.884841151481;11592.1305902056;11662.067034637039;11101.278416903198;11137.944310278397;11141.72145696364;11644.475998579916;11719.472623732123;11549.769004619693;11580.942095289469;11743.647690569296;11999.76000479968;12056.397180141134;6874.793756187322;7398.371138453292;7507.174210836694;7866.881996769938;8669.089745576624;10926.59614884724;11389.658310250707;11487.597934072268;11569.421528923353;11787 13:59:55 INFO - PROCESS | 1922 | |3;mootools.html;1415.3206460898768;1433.856614338566;1555.9377624895005;1746.7991181014188;1765.1262625000634;7936.642851071701;8337.791555211123;8338.91661083389;8350.331973442133;8360.665573377057;7295.671694773735;7535.321821036106;7840.568768717718;8078.272955434009;8113.472624279418;2266.433391652087;2315;2315.536892621474;2315.8378913476063;2333.4166458385403;2316;2325.3721495196373;2326.813854891613;2362.787349138577;2365.2431222008963;2285.1773361589812;2290.2556669082614;2314.305708287507;2314.5602335556273;2326.5114325991563;2276.7950884420397;2308.9191878284346;2311.1217737259904;2311.653252012195;2315.733690625566;2299.8275129365197;2332.766723327654;2334.953300933989;2335.089315167086;2336.7546407627124;8090.757277281691;8267.586620668942;8271.917280827234;8283.461574997606;8287.792805179859;4993.975030124826;5157;5158.9484105158845;5165.896682066337;5173.81891633791;3276.918077048069;3299.340131973615;3444.7588668793196;3495.4756786481976;3505.456654218575;1042.687193841852;1061.6072053340313;1067.7063807452935;1085.913126949842;1092.579356947573;10728.980746829116;10773.138148948065;10928.890711092867;10931.344119352943;10948.562057517689;4441.666874984389;4558.407407037064;4671.416072990876;4694.342792009122;4713.34013238147;9217.769555761026;9239.491827949392;9259.490728010023;9276.675316363895;9288.210502107397;5144.71704056273;5163.3545806774155;5165.483451654804;5171.63798534099;5171.689698618095;3906.7851268180525;4101.938470922939;4722.984558319909;4735.171345014677;4735.95264047362;3747.7376583638884;3754.568224654145;3839.731218814712;3861.092643228839;3873.767573945516;1238.0809744895375;1241.3855141704914;1241.5530409052733;1250.9624711258496;1252.5177806544546;811.2293321344746;875.7329014650466;883.5670521444446;885.5704983083328;889.3463304471209;5447.428020057916;5455.645383050089;5565.276514053147;5696.943030569724;5719.170720245541;412.83899279281104;421.25648230872537;429.36025322270217;430.72433642468843;430.8815075854165;3708.2398108387715;3724.6275372462537;3728.7203459740626;3817.1602247505502;3922.3724204127775;9697.224222062217;10065.798684026278;10071.194304455626;10215.94892025535;10221.846672299922;9700.90299097014;9903.455309957877;10029.197664186848;10035.39787612731;10362.119219866396;7724.188960159215;7769.223077692185;7835.0989636191425;7848.13670496245;7850.312182880708;4381.430414046154;5336.546393556591;5437.374701909331;5440.891182176413;5576.721163941867;7685.884711729441;9188.678396256098;9340.159385655325;9440.669576564787;9451;9353.859692104488;9372.625094996123;9448.952755236454;9574.04259574037;9583.90416095858;6441.3880681335195;6646.60120392778;6649.900251496329;6913.0667359905765;7056.294370562902;6763.729450821912;7007.964960175166;7734.342580880688;7802.453828231969;7832.84334313322;6926.722931082701;7014.158301003912;7026.473014524034;7079.929200708133;7175.677094530863;6890.2420733720255;6914.377706006484;6934.514583979073;6934.930650693428;7020.719171233093;5410.891782164414;6521.282658907611;6611.438027767694;6611.504137189634;6633.303503132294;4637.976810115928;4737.336772851734;4737.739424331695;4774.30772537987;4815.398075240595;3934.488516492838;4645.233536466445;4743.691660042086;4752.144613969519;4866.537678920497 13:59:55 INFO - PROCESS | 1922 | |4;prototype.html;296.8233900829007;304.49301912316;304.5948887978988;309.86520863424397;311.8721324257055;4684.742339171345;5232.2674825524455;5239.86900327492;5267.65760225586;5325.946740532593;5154.170269554311;5197;5227.9738601307035;5236.502532259438;5243.816466423676;3402.8468718907584;3809.1048603578206;3864.0339915021245;3970.980145099285;3990.4413382126527;3502.9474557881654;4032.5967403259588;4035.899102522446;4058.269511487931;4063.516816362036;3976.681865450757;3998.580149084348;4005;4017.7790221537803;4029.657479114279;3625.3111908737255;3752.774833509998;4004.8786339824896;4031.4355990161403;4031.919361612766;3450.861965521376;3453.2748122894222;3493.1791029108394;3496.7377446691344;3552.91117722059;4374.232087025126;4424.247877860771;4934.210526315808;4943.530364615356;4971.45314015458;4151.875443736692;4216.325387937946;4524.502304746476;4526.234768440311;4545.250033744428;4337.934930976038;4665.393498845128;5465.754041068121;5471.7264136793;5475.397706252309;3339.8664053437833;3770.962290377116;3773.8301776419853;3776.3580191367532;3790.677792387623;5447.809326673548;5534.612577119563;5547.223388725582;5809.390014048548;5837.678927658977;3821.675157611634;3835.6547910688173;3847.576766555677;3848;3879.3599056155417;3881.611838816152;3901.8244179012013;3928.8232029558167;3930.6069393060466;3934.1738234970403;3964.742291751027;4506.954930450719;4516.390287311171;4519.276915693474;4537.047220083819;4934.432540257844;5148.253503242009;5312.495312945264;5428.90832268843;5580.1629755537;1127.370948978718;1244.7883859743683;1260.426505939795;1262.715888925003;1263.881464903567;817.9856977348046;852.2457625001917;860.3332417376583;860.9268212201907;865.5196366016795;4476.030546559504;4624.398828152386;4942.403564684878;4997.475265097185;5093.057784309841;4612.262038073892;5692.6299790513485;5725.1698503716725;5735.71321433935;5786.016377215799;4401.867943961686;5239.66388570912;5243.898781255979;5248.9737551312;5276.577873770089;2409.2410890569413;2421.8910149043336;2770.7367800058964;2810.9859450702616;2826.3781967967016;4705;5139.511746384088;5222.3472065991755;5646.8023619173955;5668.829935101871;5147.202183661539;5217.321748172713;5238.161894097002;5545.694986775686;5583.664980101207;5080.704420372856;5096.592272618182;5210.322658054429;5867.735951882176;6131.908021379683;4619.35329053926;5261.763220655003;5715.942840571541;5756.366799652119;5761.8559536011935;5119;5314;5481.506664400223;5640.464155905182;5653.858653533694;5289.497497737709;5853.8536536585225;5935.792247271324;5960;5998.970005149947;3413.948790768091;3756.699464042815;3797.822674970768;3823.369144091194;3846.3461211594654;4789.365848783085;5353.625246232727;5535.529479994085;5666.291713535808;5792.507636850914;4587.908241835212;4978.2034874419915;5100.821471248489;5111.7188554628365;5264.078611306769;4855.9074208302845;5579.386267510652;5597.524210441995;5612.719364031864;5631.408702086386;4392.363107349481;4923.483034281349;4961.700469187949;5463.508284254436;5474.479924407175;5262.552683021986;5459.836204913859;5475.835724928259;5555.527780138734;5582.302212223472;4844.813020809924;5257.290265814219;5316.734163291897;5346.732663366894;5354.089804733283 13:59:55 INFO - PROCESS | 1922 | |5;yui.html;673.6362364323265;728.6793810723282;755.9773206803798;777.7938846205753;779.2129948751759;11270.54917803288;14032.929835350835;14396.856031439682;14705.852941470583;14796.18620975846;12423.130380873343;12864.713528647131;12994.090413671047;12996.870031299684;13009.284489353082;654.6366766444621;818.1041759273586;823.4688625836332;826.4627991805314;827.3546633625782;791.4103992525577;819.1276290750361;820.5692011693849;823.6046697585163;824.4146655874306;871.2463718883153;871.3116637856118;872.9607167677469;873.1617647058799;873.6243415331405;864.369010622243;865.0786911938765;865.2558799432483;865.2991077227402;865.8917635295588;636.9777057802954;639.6737663791454;640.8301800022998;641.4579680170295;642.1235014205622;12214.411686825799;12262;12546.498140074385;12582.37088145589;12683.365831708377;9520.000399958044;11592.594259200887;11641.53433862653;11708.707282317924;11988.880111198863;9918.851217231748;9991.450470224136;10000.449975251358;10457.84313235302;10478.528466219039;3103.9844800776077;3268.0686004488844;3552.8045957472327;3606.386914224588;3701.2782507411066;85105.72341414822;85627;85816.7127493088;86969;91104;8519.39541063518;8868.645254189889;8874.51190184546;8887;9217.539123043822;18512.259509619464;18534.16596253172;18541;18542.44372668822;18603.162857671436;7063.434925206074;7253.746118885813;7574.924250757532;7895.802604934807;7989.800254993672;4152.273352163359;4178.582141785858;4188.769617671057;4267.850625228102;4269.380939763779;3231.88688395905;3344.832758362072;3375.4936759486272;3380.1042723678247;3385.23832137767;633.6989929783408;635.0759644716926;635.9300476947532;640.8333833203401;641.7850020243177;524.2686452398958;552.1689856765543;579.4495229531962;603.8474272245248;640.1006585747033;8320.334373250125;8546.957265213634;8565.700200493078;8663.523506207093;8689.391742578086;588.4880154265816;589.39586923403;598.5451057196475;602.3284038297267;611.8929187392278;4184.602462766033;4196.391523229115;4222.978885105555;4255.148970205972;4273.61045543766;68803.65598172077;94580.58129128069;102071;109551;116118;58612.82774344489;68234.97647535292;93305.53347233355;96604;96606;76686.46627067427;77255.61372193215;78421.60789196132;79633;81052;9046.683366082156;9525.476098814632;9532;9534;9595.28035397334;49455.01089978252;49473.25790113223;49975;53102.734486327325;53417;68786.65606671934;73452.63273683598;74625;74724;75176.49647006919;12076.21504602198;12178.57374991871;12185.634430967086;12275.815862762245;12305.446254918372;12424.440900159334;12534.937325313314;13655.931720341334;14514.419423222953;14683.41266349334;11877.406129693309;12564.497420103093;13356.532521361705;13487.527936522174;14082.859171408154;11013.229073965065;12666.366681666064;13346.532871349456;13387.330633468482;13663.863361366259;10448.582056717647;13364.198148111145;13578.388972496065;13722.725545489235;13895.235762033186;10309.639162629272;10387.163314689042;10539.15686745074;10809.945950270197;10998.175136864607;9695.418274903528;9996.350237234556;11207.551697931991;11251.38117403518;11264.211505194558 13:59:55 INFO - PROCESS | 1922 | __end_tp_report 13:59:55 INFO - PROCESS | 1922 | __start_cc_report 13:59:55 INFO - PROCESS | 1922 | _x_x_mozilla_cycle_collect,1704 13:59:55 INFO - PROCESS | 1922 | __end_cc_report 13:59:55 INFO - PROCESS | 1922 | __startTimestamp1484085595274__endTimestamp 13:59:55 INFO - PROCESS | 1922 | 13:59:55 INFO - PROCESS | 1922 | ------- Summary: start ------- 13:59:55 INFO - PROCESS | 1922 | Number of tests: 6 13:59:55 INFO - PROCESS | 1922 | 13:59:55 INFO - PROCESS | 1922 | [#0] dojo.html Cycles:165 Average:5745.00 Median:5937.23 stddev:645.74 (10.9%) stddev-sans-first:618.91 13:59:55 INFO - PROCESS | 1922 | Values: 3314.0 3316.6 3330.6 3355.2 3388.3 5639.7 5700.2 5715.4 5730.1 5746.2 5648.3 5702.5 5716.7 5733.3 5734.9 5220.1 5233.3 5234.5 5263.1 5302.7 5160.4 5174.2 5178.1 5192.0 5343.0 5267.9 5299.4 5316.4 5316.4 5343.6 5282.7 5311.2 5315.0 5322.7 5369.9 5046.8 5165.8 5251.0 5254.9 5258.5 5619.7 5683.9 5699.9 5714.6 5767.2 6190.5 6220.3 6275.5 6277.8 6287.8 6326.3 6345.8 6352.1 6358.8 6361.0 5658.9 5723.6 5742.1 5748.8 5871.4 6308.9 6319.1 6330.1 6333.8 6346.2 6202.7 6203.5 6207.7 6209.0 6215.1 6127.7 6133.8 6152.7 6175.5 6260.5 6075.5 6130.8 6194.8 6214.3 6219.4 6122.9 6243.0 6244.6 6251.9 6266.9 4850.5 4893.4 4953.4 4964.1 4988.1 4401.3 4433.5 4451.4 4453.6 4519.1 5812.9 5821.2 5831.6 5839.4 5847.1 6285.3 6306.0 6306.6 6313.6 6351.1 5598.4 5704.6 5714.0 5714.4 5770.7 5444.3 5447.9 5462.3 5492.3 5522.0 6334.3 6348.1 6351.7 6358.5 6377.4 4794.9 6127.8 6153.4 6307.0 6322.2 6227.9 6275.1 6289.3 6337.0 6354.0 6136.8 6214.1 6215.1 6218.7 6219.3 6107.5 6252.6 6279.7 6286.2 6303.2 5911.8 5983.6 6005.8 6044.5 6100.9 5962.7 6073.7 6083.9 6092.3 6108.0 4524.4 5742.7 5922.2 5937.2 5952.9 5877.8 5984.5 6012.8 6050.1 6132.6 5936.0 5939.9 5964.3 5968.4 6014.8 13:59:55 INFO - PROCESS | 1922 | 13:59:55 INFO - PROCESS | 1922 | [#1] ext.html Cycles:180 Average:69407.16 Median:20216.60 stddev:156834.25 (775.8%) stddev-sans-first:157188.80 13:59:55 INFO - PROCESS | 1922 | Values: 467.7 473.9 478.0 507.8 512.5 26675.7 30931.5 31040.7 31041.2 31053.0 40234.4 41455.0 41582.6 43536.6 43645.0 9361.9 9524.1 9536.9 9541.3 9561.9 8519.5 9436.3 9457.9 9466.1 9469.9 8540.3 8675.4 8682.1 8691.8 8692.5 11187.5 11416.2 11425.9 11622.8 11658.3 11314.9 11377.1 11384.1 11386.3 11388.9 38816.0 39190.0 39234.0 39270.2 39366.8 2932.3 3248.9 3338.7 3425.3 3673.6 28581.6 29353.0 29836.7 29979.7 30793.8 3107.8 3108.1 3185.8 3204.9 3229.8 494817.0 496631.0 497485.0 497572.0 502635.0 33030.3 61272.7 61519.0 61646.4 61719.7 33867.8 34367.7 35213.6 35255.5 35339.8 14380.3 14506.7 14523.1 14524.7 14557.6 12177.6 12284.4 12307.0 12308.5 12318.0 7634.4 7682.3 7729.5 7843.3 7848.5 2990.3 3072.8 3081.6 3118.0 3172.9 728.8 844.6 896.5 953.9 1025.3 28285.3 28964.9 29835.6 30493.7 30606.7 941.5 1041.0 1053.7 1066.8 1067.3 15064.2 15356.2 15375.2 15392.1 15702.6 678591.0 681696.0 682413.0 682894.0 683239.2 57300.7 57758.7 57834.0 57898.4 57908.4 1123.4 1236.1 1295.8 1310.4 1339.9 28725.9 28849.3 29728.6 30398.8 31357.2 49834.5 50212.7 50235.0 50259.0 50373.0 557405.0 559749.0 560275.0 561936.0 562297.0 22446.8 26297.3 27184.9 27676.0 27728.6 45436.5 45536.5 46154.5 46482.5 46742.3 37526.8 38327.6 38517.2 38647.8 38670.0 36759.6 37873.4 38450.0 39331.6 39411.0 37161.1 37366.0 38222.6 38261.6 38277.0 31962.7 34085.8 34151.3 34225.3 34498.8 24952.0 25178.1 25443.0 25847.7 26016.6 13:59:55 INFO - PROCESS | 1922 | 13:59:55 INFO - PROCESS | 1922 | [#2] jquery.html Cycles:180 Average:36380.17 Median:13557.32 stddev:104873.54 (773.6%) stddev-sans-first:105132.84 13:59:55 INFO - PROCESS | 1922 | Values: 348.0 366.7 375.1 378.7 395.8 14943.3 15074.9 16033.4 19958.3 21193.3 19287.4 20010.6 20448.3 20566.3 20815.4 5140.5 5693.7 5968.8 5990.9 5991.6 4153.6 4387.5 5894.9 5954.2 5967.0 4344.2 4412.9 6230.2 6505.2 6518.6 6308.3 6481.0 6578.0 6581.8 6588.7 5709.6 5709.7 5719.4 5721.1 5723.8 24716.5 25688.0 25718.0 25879.5 26063.0 2159.4 2276.4 2362.5 2371.9 2398.1 14821.3 15399.3 15532.9 15614.6 16100.2 1778.7 1820.2 1841.4 1846.5 1954.5 114524.9 118342.8 118763.0 119186.0 120593.0 10634.6 10721.4 11164.9 11317.4 11354.0 11738.2 12340.6 13949.8 13962.3 14018.3 7875.6 7922.8 8004.0 8155.0 8223.3 8370.6 8418.7 8428.8 8603.0 8609.9 3518.7 3594.9 3895.0 3900.5 3963.5 1461.4 1551.9 1698.9 1823.5 1888.2 714.3 816.9 884.8 896.7 989.2 20578.0 22137.3 22147.6 22275.8 22314.7 883.5 906.7 909.7 934.6 953.2 10854.9 11517.1 11518.5 11523.7 11560.1 616268.0 620379.0 626064.0 637929.0 638439.8 136018.0 136230.0 139308.0 139496.3 145616.0 974.9 980.7 996.0 998.2 1002.9 16013.6 17877.5 18628.7 18722.6 19056.2 53401.7 54909.0 55461.0 56503.9 56824.0 79324.6 80551.0 80778.0 83515.0 83670.0 7979.6 8132.8 8169.6 8414.5 8423.3 11949.0 12906.6 12954.4 13197.5 13212.7 11290.5 11290.7 11515.9 11592.1 11662.1 11101.3 11137.9 11141.7 11644.5 11719.5 11549.8 11580.9 11743.6 11999.8 12056.4 6874.8 7398.4 7507.2 7866.9 8669.1 10926.6 11389.7 11487.6 11569.4 11787.0 13:59:55 INFO - PROCESS | 1922 | 13:59:55 INFO - PROCESS | 1922 | [#3] mootools.html Cycles:180 Average:5348.09 Median:4794.85 stddev:2960.26 (61.7%) stddev-sans-first:2953.81 13:59:55 INFO - PROCESS | 1922 | Values: 1415.3 1433.9 1555.9 1746.8 1765.1 7936.6 8337.8 8338.9 8350.3 8360.7 7295.7 7535.3 7840.6 8078.3 8113.5 2266.4 2315.0 2315.5 2315.8 2333.4 2316.0 2325.4 2326.8 2362.8 2365.2 2285.2 2290.3 2314.3 2314.6 2326.5 2276.8 2308.9 2311.1 2311.7 2315.7 2299.8 2332.8 2335.0 2335.1 2336.8 8090.8 8267.6 8271.9 8283.5 8287.8 4994.0 5157.0 5158.9 5165.9 5173.8 3276.9 3299.3 3444.8 3495.5 3505.5 1042.7 1061.6 1067.7 1085.9 1092.6 10729.0 10773.1 10928.9 10931.3 10948.6 4441.7 4558.4 4671.4 4694.3 4713.3 9217.8 9239.5 9259.5 9276.7 9288.2 5144.7 5163.4 5165.5 5171.6 5171.7 3906.8 4101.9 4723.0 4735.2 4736.0 3747.7 3754.6 3839.7 3861.1 3873.8 1238.1 1241.4 1241.6 1251.0 1252.5 811.2 875.7 883.6 885.6 889.3 5447.4 5455.6 5565.3 5696.9 5719.2 412.8 421.3 429.4 430.7 430.9 3708.2 3724.6 3728.7 3817.2 3922.4 9697.2 10065.8 10071.2 10215.9 10221.8 9700.9 9903.5 10029.2 10035.4 10362.1 7724.2 7769.2 7835.1 7848.1 7850.3 4381.4 5336.5 5437.4 5440.9 5576.7 7685.9 9188.7 9340.2 9440.7 9451.0 9353.9 9372.6 9449.0 9574.0 9583.9 6441.4 6646.6 6649.9 6913.1 7056.3 6763.7 7008.0 7734.3 7802.5 7832.8 6926.7 7014.2 7026.5 7079.9 7175.7 6890.2 6914.4 6934.5 6934.9 7020.7 5410.9 6521.3 6611.4 6611.5 6633.3 4638.0 4737.3 4737.7 4774.3 4815.4 3934.5 4645.2 4743.7 4752.1 4866.5 13:59:55 INFO - PROCESS | 1922 | 13:59:55 INFO - PROCESS | 1922 | [#4] prototype.html Cycles:180 Average:4418.25 Median:4952.62 stddev:1354.66 (27.4%) stddev-sans-first:1322.67 13:59:55 INFO - PROCESS | 1922 | Values: 296.8 304.5 304.6 309.9 311.9 4684.7 5232.3 5239.9 5267.7 5325.9 5154.2 5197.0 5228.0 5236.5 5243.8 3402.8 3809.1 3864.0 3971.0 3990.4 3502.9 4032.6 4035.9 4058.3 4063.5 3976.7 3998.6 4005.0 4017.8 4029.7 3625.3 3752.8 4004.9 4031.4 4031.9 3450.9 3453.3 3493.2 3496.7 3552.9 4374.2 4424.2 4934.2 4943.5 4971.5 4151.9 4216.3 4524.5 4526.2 4545.3 4337.9 4665.4 5465.8 5471.7 5475.4 3339.9 3771.0 3773.8 3776.4 3790.7 5447.8 5534.6 5547.2 5809.4 5837.7 3821.7 3835.7 3847.6 3848.0 3879.4 3881.6 3901.8 3928.8 3930.6 3934.2 3964.7 4507.0 4516.4 4519.3 4537.0 4934.4 5148.3 5312.5 5428.9 5580.2 1127.4 1244.8 1260.4 1262.7 1263.9 818.0 852.2 860.3 860.9 865.5 4476.0 4624.4 4942.4 4997.5 5093.1 4612.3 5692.6 5725.2 5735.7 5786.0 4401.9 5239.7 5243.9 5249.0 5276.6 2409.2 2421.9 2770.7 2811.0 2826.4 4705.0 5139.5 5222.3 5646.8 5668.8 5147.2 5217.3 5238.2 5545.7 5583.7 5080.7 5096.6 5210.3 5867.7 6131.9 4619.4 5261.8 5715.9 5756.4 5761.9 5119.0 5314.0 5481.5 5640.5 5653.9 5289.5 5853.9 5935.8 5960.0 5999.0 3413.9 3756.7 3797.8 3823.4 3846.3 4789.4 5353.6 5535.5 5666.3 5792.5 4587.9 4978.2 5100.8 5111.7 5264.1 4855.9 5579.4 5597.5 5612.7 5631.4 4392.4 4923.5 4961.7 5463.5 5474.5 5262.6 5459.8 5475.8 5555.5 5582.3 4844.8 5257.3 5316.7 5346.7 5354.1 13:59:55 INFO - PROCESS | 1922 | 13:59:55 INFO - PROCESS | 1922 | [#5] yui.html Cycles:180 Average:19227.34 Median:29596.14 stddev:27947.44 (94.4%) stddev-sans-first:27991.11 13:59:55 INFO - PROCESS | 1922 | Values: 673.6 728.7 756.0 777.8 779.2 11270.5 14032.9 14396.9 14705.9 14796.2 12423.1 12864.7 12994.1 12996.9 13009.3 654.6 818.1 823.5 826.5 827.4 791.4 819.1 820.6 823.6 824.4 871.2 871.3 873.0 873.2 873.6 864.4 865.1 865.3 865.3 865.9 637.0 639.7 640.8 641.5 642.1 12214.4 12262.0 12546.5 12582.4 12683.4 9520.0 11592.6 11641.5 11708.7 11988.9 9918.9 9991.5 10000.4 10457.8 10478.5 3104.0 3268.1 3552.8 3606.4 3701.3 85105.7 85627.0 85816.7 86969.0 91104.0 8519.4 8868.6 8874.5 8887.0 9217.5 18512.3 18534.2 18541.0 18542.4 18603.2 7063.4 7253.7 7574.9 7895.8 7989.8 4152.3 4178.6 4188.8 4267.9 4269.4 3231.9 3344.8 3375.5 3380.1 3385.2 633.7 635.1 635.9 640.8 641.8 524.3 552.2 579.4 603.8 640.1 8320.3 8547.0 8565.7 8663.5 8689.4 588.5 589.4 598.5 602.3 611.9 4184.6 4196.4 4223.0 4255.1 4273.6 68803.7 94580.6 102071.0 109551.0 116118.0 58612.8 68235.0 93305.5 96604.0 96606.0 76686.5 77255.6 78421.6 79633.0 81052.0 9046.7 9525.5 9532.0 9534.0 9595.3 49455.0 49473.3 49975.0 53102.7 53417.0 68786.7 73452.6 74625.0 74724.0 75176.5 12076.2 12178.6 12185.6 12275.8 12305.4 12424.4 12534.9 13655.9 14514.4 14683.4 11877.4 12564.5 13356.5 13487.5 14082.9 11013.2 12666.4 13346.5 13387.3 13663.9 10448.6 13364.2 13578.4 13722.7 13895.2 10309.6 10387.2 10539.2 10809.9 10998.2 9695.4 9996.4 11207.6 11251.4 11264.2 13:59:55 INFO - PROCESS | 1922 | -------- Summary: end -------- 13:59:55 INFO - PROCESS | 1922 | 13:59:55 INFO - TEST-INFO | 1922: exit 0 13:59:55 INFO - TEST-OK | dromaeo_css | took 1120047ms 13:59:55 INFO - TEST-START | kraken 13:59:55 INFO - operating with platform_type : mac_ 13:59:55 INFO - Initialising browser for kraken test... 13:59:55 INFO - TEST-INFO | started process 1926 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/tmpaa2Hdl/profile http://localhost:52435/getInfo.html) 13:59:58 INFO - PROCESS | 1926 | __metrics Screen width/height:1600/1200 13:59:58 INFO - PROCESS | 1926 | colorDepth:24 13:59:58 INFO - PROCESS | 1926 | Browser inner width/height: 1024/689 13:59:58 INFO - PROCESS | 1926 | __metrics 13:59:58 INFO - PROCESS | 1926 | 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" 13:59:58 INFO - PROCESS | 1926 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:59:59 INFO - TEST-INFO | 1926: exit 0 13:59:59 INFO - Browser initialized. 13:59:59 INFO - Running cycle 1/1 for kraken test... 13:59:59 INFO - TEST-INFO | started process 1927 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/tmpaa2Hdl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:00:11 INFO - PROCESS | 1927 | RSS: Main: 222318592 14:00:11 INFO - PROCESS | 1927 | 14:00:14 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:52435/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52435/tests/kraken/driver.html?testName=audio-beat-detection) 14:00:15 INFO - PROCESS | 1927 | RSS: Main: 271949824 14:00:15 INFO - PROCESS | 1927 | 14:00:18 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:52435/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52435/tests/kraken/driver.html?testName=audio-dft) 14:00:18 INFO - PROCESS | 1927 | RSS: Main: 284385280 14:00:18 INFO - PROCESS | 1927 | 14:00:21 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:52435/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52435/tests/kraken/driver.html?testName=audio-fft) 14:00:22 INFO - PROCESS | 1927 | RSS: Main: 381276160 14:00:22 INFO - PROCESS | 1927 | 14:00:24 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:52435/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52435/tests/kraken/driver.html?testName=audio-oscillator) 14:00:25 INFO - PROCESS | 1927 | RSS: Main: 282726400 14:00:25 INFO - PROCESS | 1927 | 14:00:27 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:52435/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52435/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:00:27 INFO - PROCESS | 1927 | RSS: Main: 284762112 14:00:27 INFO - PROCESS | 1927 | 14:00:30 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:52435/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52435/tests/kraken/driver.html?testName=imaging-darkroom) 14:00:31 INFO - PROCESS | 1927 | RSS: Main: 324665344 14:00:31 INFO - PROCESS | 1927 | 14:00:34 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:52435/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52435/tests/kraken/driver.html?testName=imaging-desaturate) 14:00:34 INFO - PROCESS | 1927 | RSS: Main: 326004736 14:00:34 INFO - PROCESS | 1927 | 14:00:37 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:52435/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52435/tests/kraken/driver.html?testName=json-parse-financial) 14:00:38 INFO - PROCESS | 1927 | RSS: Main: 323796992 14:00:38 INFO - PROCESS | 1927 | 14:00:39 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:52435/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52435/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:00:40 INFO - PROCESS | 1927 | RSS: Main: 299012096 14:00:40 INFO - PROCESS | 1927 | 14:00:42 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:52435/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52435/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:00:42 INFO - PROCESS | 1927 | RSS: Main: 300675072 14:00:42 INFO - PROCESS | 1927 | 14:00:45 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:52435/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52435/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:00:45 INFO - PROCESS | 1927 | RSS: Main: 303587328 14:00:45 INFO - PROCESS | 1927 | 14:00:48 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:52435/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52435/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:00:49 INFO - PROCESS | 1927 | RSS: Main: 323596288 14:00:49 INFO - PROCESS | 1927 | 14:00:52 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:52435/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52435/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:00:53 INFO - PROCESS | 1927 | RSS: Main: 316891136 14:00:53 INFO - PROCESS | 1927 | 14:00:54 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:52435/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52435/tests/kraken/driver.html?testName=ai-astar) 14:00:55 INFO - PROCESS | 1927 | RSS: Main: 361123840 14:00:55 INFO - PROCESS | 1927 | 14:00:55 INFO - PROCESS | 1927 | __start_tp_report 14:00:55 INFO - PROCESS | 1927 | _x_x_mozilla_page_load 14:00:55 INFO - PROCESS | 1927 | _x_x_mozilla_page_load_details 14:00:55 INFO - PROCESS | 1927 | |i|pagename|runs| 14:00:55 INFO - PROCESS | 1927 | |0;ai-astar;117;113;114;115;113;114;114;114;114;114 14:00:55 INFO - PROCESS | 1927 | |1;audio-beat-detection;146;114;113;143;109;111;112;106;141;104 14:00:55 INFO - PROCESS | 1927 | |2;audio-dft;185;186;183;186;185;185;184;184;186;186 14:00:55 INFO - PROCESS | 1927 | |3;audio-fft;81;59;60;88;60;59;58;59;84;58 14:00:55 INFO - PROCESS | 1927 | |4;audio-oscillator;100;92;128;101;67;69;90;66;68;92 14:00:55 INFO - PROCESS | 1927 | |5;imaging-gaussian-blur;91;86;90;85;89;86;90;86;89;87 14:00:55 INFO - PROCESS | 1927 | |6;imaging-darkroom;137;151;94;104;141;112;95;94;95;95 14:00:55 INFO - PROCESS | 1927 | |7;imaging-desaturate;81;82;78;79;85;84;86;83;81;81 14:00:55 INFO - PROCESS | 1927 | |8;json-parse-financial;56;56;54;53;57;58;58;59;57;56 14:00:55 INFO - PROCESS | 1927 | |9;json-stringify-tinderbox;66;73;75;76;72;71;70;70;69;72 14:00:55 INFO - PROCESS | 1927 | |10;stanford-crypto-aes;84;83;87;92;102;103;83;84;84;85 14:00:55 INFO - PROCESS | 1927 | |11;stanford-crypto-ccm;161;167;151;141;143;138;156;170;196;173 14:00:55 INFO - PROCESS | 1927 | |12;stanford-crypto-pbkdf2;164;165;170;167;165;167;167;223;215;166 14:00:55 INFO - PROCESS | 1927 | |13;stanford-crypto-sha256-iterative;68;69;68;67;68;68;66;68;65;71 14:00:55 INFO - PROCESS | 1927 | __end_tp_report 14:00:55 INFO - PROCESS | 1927 | __start_cc_report 14:00:55 INFO - PROCESS | 1927 | _x_x_mozilla_cycle_collect,2005 14:00:55 INFO - PROCESS | 1927 | __end_cc_report 14:00:55 INFO - PROCESS | 1927 | __startTimestamp1484085655211__endTimestamp 14:00:55 INFO - PROCESS | 1927 | 14:00:55 INFO - PROCESS | 1927 | ------- Summary: start ------- 14:00:55 INFO - PROCESS | 1927 | Number of tests: 14 14:00:55 INFO - PROCESS | 1927 | 14:00:55 INFO - PROCESS | 1927 | [#0] ai-astar Cycles:10 Average:114.20 Median:114.00 stddev:1.14 (1.0%) stddev-sans-first:0.60 14:00:55 INFO - PROCESS | 1927 | Values: 117.0 113.0 114.0 115.0 113.0 114.0 114.0 114.0 114.0 114.0 14:00:55 INFO - PROCESS | 1927 | 14:00:55 INFO - PROCESS | 1927 | [#1] audio-beat-detection Cycles:10 Average:119.90 Median:113.50 stddev:16.50 (14.5%) stddev-sans-first:14.54 14:00:55 INFO - PROCESS | 1927 | Values: 146.0 114.0 113.0 143.0 109.0 111.0 112.0 106.0 141.0 104.0 14:00:55 INFO - PROCESS | 1927 | 14:00:55 INFO - PROCESS | 1927 | [#2] audio-dft Cycles:10 Average:185.00 Median:185.50 stddev:1.05 (0.6%) stddev-sans-first:1.12 14:00:55 INFO - PROCESS | 1927 | Values: 185.0 186.0 183.0 186.0 185.0 185.0 184.0 184.0 186.0 186.0 14:00:55 INFO - PROCESS | 1927 | 14:00:55 INFO - PROCESS | 1927 | [#3] audio-fft Cycles:10 Average:66.60 Median:60.00 stddev:12.37 (20.6%) stddev-sans-first:11.97 14:00:55 INFO - PROCESS | 1927 | Values: 81.0 59.0 60.0 88.0 60.0 59.0 58.0 59.0 84.0 58.0 14:00:55 INFO - PROCESS | 1927 | 14:00:55 INFO - PROCESS | 1927 | [#4] audio-oscillator Cycles:10 Average:87.30 Median:68.50 stddev:20.08 (29.3%) stddev-sans-first:20.77 14:00:55 INFO - PROCESS | 1927 | Values: 100.0 92.0 128.0 101.0 67.0 69.0 90.0 66.0 68.0 92.0 14:00:55 INFO - PROCESS | 1927 | 14:00:55 INFO - PROCESS | 1927 | [#5] imaging-gaussian-blur Cycles:10 Average:87.90 Median:89.00 stddev:2.13 (2.4%) stddev-sans-first:1.94 14:00:55 INFO - PROCESS | 1927 | Values: 91.0 86.0 90.0 85.0 89.0 86.0 90.0 86.0 89.0 87.0 14:00:55 INFO - PROCESS | 1927 | 14:00:55 INFO - PROCESS | 1927 | [#6] imaging-darkroom Cycles:10 Average:111.80 Median:94.00 stddev:22.52 (24.0%) stddev-sans-first:21.97 14:00:55 INFO - PROCESS | 1927 | Values: 137.0 151.0 94.0 104.0 141.0 112.0 95.0 94.0 95.0 95.0 14:00:55 INFO - PROCESS | 1927 | 14:00:55 INFO - PROCESS | 1927 | [#7] imaging-desaturate Cycles:10 Average:82.00 Median:82.50 stddev:2.54 (3.1%) stddev-sans-first:2.67 14:00:55 INFO - PROCESS | 1927 | Values: 81.0 82.0 78.0 79.0 85.0 84.0 86.0 83.0 81.0 81.0 14:00:55 INFO - PROCESS | 1927 | 14:00:55 INFO - PROCESS | 1927 | [#8] json-parse-financial Cycles:10 Average:56.40 Median:57.00 stddev:1.84 (3.2%) stddev-sans-first:1.94 14:00:55 INFO - PROCESS | 1927 | Values: 56.0 56.0 54.0 53.0 57.0 58.0 58.0 59.0 57.0 56.0 14:00:55 INFO - PROCESS | 1927 | 14:00:55 INFO - PROCESS | 1927 | [#9] json-stringify-tinderbox Cycles:10 Average:71.40 Median:72.00 stddev:2.91 (4.0%) stddev-sans-first:2.35 14:00:55 INFO - PROCESS | 1927 | Values: 66.0 73.0 75.0 76.0 72.0 71.0 70.0 70.0 69.0 72.0 14:00:55 INFO - PROCESS | 1927 | 14:00:55 INFO - PROCESS | 1927 | [#10] stanford-crypto-aes Cycles:10 Average:88.70 Median:84.00 stddev:7.75 (9.2%) stddev-sans-first:8.03 14:00:55 INFO - PROCESS | 1927 | Values: 84.0 83.0 87.0 92.0 102.0 103.0 83.0 84.0 84.0 85.0 14:00:55 INFO - PROCESS | 1927 | 14:00:55 INFO - PROCESS | 1927 | [#11] stanford-crypto-ccm Cycles:10 Average:159.60 Median:164.00 stddev:17.78 (10.8%) stddev-sans-first:18.85 14:00:55 INFO - PROCESS | 1927 | Values: 161.0 167.0 151.0 141.0 143.0 138.0 156.0 170.0 196.0 173.0 14:00:55 INFO - PROCESS | 1927 | 14:00:55 INFO - PROCESS | 1927 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.90 Median:167.00 stddev:22.33 (13.4%) stddev-sans-first:23.19 14:00:55 INFO - PROCESS | 1927 | Values: 164.0 165.0 170.0 167.0 165.0 167.0 167.0 223.0 215.0 166.0 14:00:55 INFO - PROCESS | 1927 | 14:00:55 INFO - PROCESS | 1927 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.80 Median:68.00 stddev:1.62 (2.4%) stddev-sans-first:1.72 14:00:55 INFO - PROCESS | 1927 | Values: 68.0 69.0 68.0 67.0 68.0 68.0 66.0 68.0 65.0 71.0 14:00:55 INFO - PROCESS | 1927 | -------- Summary: end -------- 14:00:55 INFO - PROCESS | 1927 | 14:00:56 INFO - TEST-INFO | 1927: exit 0 14:00:56 INFO - TEST-OK | kraken | took 60496ms 14:00:56 INFO - SUITE-END | took 1180s 14:00:56 INFO - Completed test suite (00:19:40) 14:00:56 INFO - javascript benchmark 14:00:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3313.9502907456385, 3316.618588862281, 3330.5670262865824, 3355.228297491575, 3388.3053973935343, 5639.718014099294, 5700.201971723962, 5715.428457154282, 5730.083186690131, 5746.195532625436, 5648.265725455685, 5702.486776190152, 5716.685582292983, 5733.254676892008, 5734.856628584287, 5220.112580861262, 5233.267342572043, 5234.5027222414, 5263.0526505229045, 5302.681839089648, 5160.354955630546, 5174.172132458807, 5178.1197196476505, 5192.0, 5343.038253114438, 5267.947320526803, 5299.364076310848, 5316.441773613773, 5316.441773613773, 5343.599230057742, 5282.683039017652, 5311.203319502067, 5314.973425132888, 5322.733863306819, 5369.919451208235, 5046.823361182341, 5165.819196328148, 5251.028559716465, 5254.8686282842855, 5258.474152584749, 5619.690916999563, 5683.914741278884, 5699.943000570024, 5714.571407144481, 5767.163761254595, 6190.535709821781, 6220.315765265817, 6275.497960163221, 6277.780277690259, 6287.779927702509, 6326.304106548276, 6345.8413539661415, 6352.078948552434, 6358.809235722936, 6361.045843123522, 5658.91511627326, 5723.59934804568, 5742.10997295424, 5748.798792042342, 5871.3541510433815, 6308.873822523524, 6319.0837328587095, 6330.113784070234, 6333.841653958688, 6346.17499725042, 6202.658853763086, 6203.534734894901, 6207.6585787781205, 6209.0, 6215.12988181655, 6127.7393367763425, 6133.846653833601, 6152.692365381802, 6175.505959523227, 6260.499160067184, 6075.48358389533, 6130.846728831815, 6194.822684423181, 6214.347493513116, 6219.440250377488, 6122.877542449215, 6242.968785156136, 6244.625322480666, 6251.937480625135, 6266.934621114421, 4850.466448690641, 4893.363862697826, 4953.356063711766, 4964.081644895706, 4988.0772057168815, 4401.317795741601, 4433.534478879671, 4451.376807246988, 4453.643708503312, 4519.141363140992, 5812.854678633068, 5821.18503409523, 5831.562632802556, 5839.416058394211, 5847.093700476348, 6285.308616052231, 6306.0, 6306.558540902093, 6313.558050936482, 6351.142595749515, 5598.440155984369, 5704.572157088235, 5714.0, 5714.428557144335, 5770.711464426846, 5444.3194600674915, 5447.891042179213, 5462.317210348707, 5492.286002819608, 5522.0, 6334.334894836068, 6348.143000694847, 6351.682415879095, 6358.491320694241, 6377.362263773678, 4794.928076078932, 6127.816165515042, 6153.446189842935, 6306.9684651576445, 6322.178116844781, 6227.906581401373, 6275.058741188675, 6289.308176100717, 6337.049442583465, 6354.0, 6136.754529818936, 6214.098955651315, 6215.06773983906, 6218.720157593009, 6219.253689557101, 6107.480864126422, 6252.5935814171935, 6279.748810047729, 6286.214223222098, 6303.180586523906, 5911.822645320647, 5983.611065280743, 6005.759769609342, 6044.546659000681, 6100.938990610037, 5962.731677074456, 6073.665948372882, 6083.878322433615, 6092.329843717099, 6107.969460152671, 4524.411826462539, 5742.655440673573, 5922.170896074467, 5937.228160339128, 5952.910706339495, 5877.794277200277, 5984.491318237999, 6012.759489620366, 6050.122732203894, 6132.56967241757, 5935.970320148372, 5939.910901336397, 5964.343922168471, 5968.432998865101, 6014.759409623741], "value": 5703.993050956208, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [467.66094581428445, 473.85310553728345, 478.03198522990937, 507.8324153029498, 512.5284738041001, 26675.733242667568, 30931.536026959584, 31040.689593104064, 31041.223969400777, 31053.0, 40234.39648405276, 41455.0, 41582.58417415825, 43536.56463435365, 43645.0, 9361.859572106423, 9524.142827145555, 9536.904630953706, 9541.33210675253, 9561.904380956206, 8519.488830670149, 9436.292278079138, 9457.905420945772, 9466.053394660548, 9469.857952130724, 8540.274076703488, 8675.392722509427, 8682.13178682133, 8691.826163476726, 8692.521911294876, 11187.49656265466, 11416.200865939387, 11425.885741142565, 11622.825657615143, 11658.300501969909, 11314.943425282903, 11377.089832813355, 11384.146189035855, 11386.316820990683, 11388.943055284753, 38816.0, 39190.0, 39234.019149521206, 39270.21459570793, 39366.80316598398, 2932.2669332666833, 3248.886288979898, 3338.6661333866664, 3425.2978139481347, 3673.559172899269, 28581.57127643087, 29353.0, 29836.701632983608, 29979.70020299791, 30793.76824927, 3107.7979931304617, 3108.0831154809384, 3185.840707964616, 3204.903852884417, 3229.7577681673733, 494817.0, 496631.0, 497485.0, 497572.0242797562, 502635.0, 33030.339393212, 61272.693636531534, 61519.0, 61646.38353616496, 61719.6914015427, 33867.83066084703, 34367.656323436444, 35213.647863521546, 35255.47116793199, 35339.82330088384, 14380.280985950661, 14506.709865802624, 14523.128612283297, 14524.709505809824, 14557.563273101612, 12177.634670959886, 12284.385780710929, 12307.0, 12308.50765969369, 12318.0, 7634.427417943676, 7682.270184332479, 7729.536227826348, 7843.333316668147, 7848.489848159851, 2990.2524368907775, 3072.8169654683106, 3081.568580398746, 3118.0, 3172.904812855618, 728.8032231297626, 844.6072576252072, 896.5036358202931, 953.8616900549381, 1025.3284098915221, 28285.292867678472, 28964.855175723987, 29835.552466713016, 30493.69506304953, 30606.69393306083, 941.4868896451451, 1040.9687709368732, 1053.7312985188728, 1066.802641511323, 1067.2529229539352, 15064.171470569225, 15356.155411452477, 15375.154366509949, 15392.076475411512, 15702.607434814221, 678591.0, 681696.0, 682413.0, 682894.0, 683239.1676083275, 57300.71349643308, 57758.7112064437, 57834.0, 57898.42101579015, 57908.42091579115, 1123.4102096399292, 1236.0852968803201, 1295.799151131576, 1310.3947664111577, 1339.8861096806882, 28725.85637071801, 28849.278768030126, 29728.554071688508, 30398.848005759828, 31357.216069598442, 49834.50165498299, 50212.748936255084, 50235.0, 50259.0, 50372.99999999854, 557405.0, 559749.0, 560275.0, 561936.0, 562297.0, 22446.77553224447, 26297.342566435993, 27184.864075679496, 27676.0, 27728.58407123935, 45436.54563454323, 45536.54463455323, 46154.53845461502, 46482.53517464782, 46742.298865516364, 37526.81236593799, 38327.616723832405, 38517.2296554073, 38647.806760966014, 38670.0, 36759.63240367562, 37873.431898522096, 38450.0, 39331.6066839328, 39411.014724632114, 37161.070973225884, 37366.0, 38222.61777382302, 38261.61738382692, 38277.04307392226, 31962.68037319597, 34085.82957085199, 34151.31697366088, 34225.315493689486, 34498.82750586231, 24952.0, 25178.118765843108, 25443.0, 25847.741522584533, 26016.60975085413], "value": 18360.80112296489, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.9599846017709, 366.70297059381903, 375.08292225508643, 378.7481324918928, 395.83968492760425, 14943.327550260237, 15074.92462537687, 16033.358665653375, 19958.301459448885, 21193.258235961745, 19287.421377358667, 20010.59978800423, 20448.284310049152, 20566.280180193695, 20815.375538733824, 5140.511651393131, 5693.715314234292, 5968.820935371946, 5990.8502287442725, 5991.64050156992, 4153.605407486299, 4387.473503179623, 5894.911576326359, 5954.166416701665, 5967.0, 4344.152890186415, 4412.867613971586, 6230.190075290228, 6505.154329937125, 6518.608883466984, 6308.337624549402, 6480.967595162041, 6577.967110164443, 6581.769638062645, 6588.736450541973, 5709.62887412321, 5709.714514274311, 5719.370869204384, 5721.141828725683, 5723.799667011676, 24716.5056698865, 25687.972481100922, 25718.0, 25879.482410351688, 26063.0, 2159.362987918568, 2276.4422716434415, 2362.4566349739657, 2371.8576885386933, 2398.136670798511, 14821.333040013225, 15399.307031183624, 15532.922335388364, 15614.609634759108, 16100.19499025044, 1778.6709458750172, 1820.1991123905439, 1841.4015444980434, 1846.5291350705618, 1954.540682939508, 114524.85475145225, 118342.81657183405, 118763.0, 119186.0, 120593.0, 10634.627788027381, 10721.35671859691, 11164.944175279072, 11317.377544235145, 11354.0, 11738.237014594195, 12340.629781106582, 13949.790753138712, 13962.301884905713, 14018.299085045706, 7875.645595948196, 7922.841543169105, 8003.999500062492, 8154.959225203836, 8223.342132629376, 8370.623321950527, 8418.747437576883, 8428.8314233715, 8602.95698521516, 8609.870851937227, 3518.665726755954, 3594.9281014379567, 3895.0, 3900.5124359455067, 3963.5243770747693, 1461.3716102075996, 1551.8991265567702, 1698.8640908727273, 1823.450067442663, 1888.1503323504478, 714.3213946750608, 816.9019717633922, 884.8363052835246, 896.7130518234233, 989.2333441582687, 20578.0, 22137.335879923627, 22147.557048858933, 22275.777242227694, 22314.665280020814, 883.4919921045424, 906.7007887397143, 909.7498187998353, 934.6261495401893, 953.1516949914582, 10854.891451085547, 11517.136214783925, 11518.539258429737, 11523.654290371302, 11560.075193984461, 616268.0, 620379.0, 626064.0, 637929.0, 638439.807800958, 136018.0, 136230.0, 139308.0, 139496.30251848878, 145616.0, 974.8683927669815, 980.6714750558642, 995.9701208963743, 998.1864780204255, 1002.9398236105857, 16013.599660008127, 17877.463676089217, 18628.720569191202, 18722.625547489246, 19056.237750490378, 53401.65983401629, 54909.0, 55461.0, 56503.8699226005, 56824.0, 79324.60337698275, 80551.0, 80778.0, 83515.0, 83670.0, 7979.640916158671, 8132.75601731949, 8169.632366543638, 8414.495130292202, 8423.326133909395, 11949.0, 12906.641625917131, 12954.352282386031, 13197.538086166938, 13212.735745285232, 11290.548378065114, 11290.661280161608, 11515.884841151481, 11592.1305902056, 11662.067034637039, 11101.278416903198, 11137.944310278397, 11141.72145696364, 11644.475998579916, 11719.472623732123, 11549.769004619693, 11580.942095289469, 11743.647690569296, 11999.76000479968, 12056.397180141134, 6874.793756187322, 7398.371138453292, 7507.174210836694, 7866.881996769938, 8669.089745576624, 10926.59614884724, 11389.658310250707, 11487.597934072268, 11569.421528923353, 11787.0], "value": 9575.81432631194, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1415.3206460898768, 1433.856614338566, 1555.9377624895005, 1746.7991181014188, 1765.1262625000634, 7936.642851071701, 8337.791555211123, 8338.91661083389, 8350.331973442133, 8360.665573377057, 7295.671694773735, 7535.321821036106, 7840.568768717718, 8078.272955434009, 8113.472624279418, 2266.433391652087, 2315.0, 2315.536892621474, 2315.8378913476063, 2333.4166458385403, 2316.0, 2325.3721495196373, 2326.813854891613, 2362.787349138577, 2365.2431222008963, 2285.1773361589812, 2290.2556669082614, 2314.305708287507, 2314.5602335556273, 2326.5114325991563, 2276.7950884420397, 2308.9191878284346, 2311.1217737259904, 2311.653252012195, 2315.733690625566, 2299.8275129365197, 2332.766723327654, 2334.953300933989, 2335.089315167086, 2336.7546407627124, 8090.757277281691, 8267.586620668942, 8271.917280827234, 8283.461574997606, 8287.792805179859, 4993.975030124826, 5157.0, 5158.9484105158845, 5165.896682066337, 5173.81891633791, 3276.918077048069, 3299.340131973615, 3444.7588668793196, 3495.4756786481976, 3505.456654218575, 1042.687193841852, 1061.6072053340313, 1067.7063807452935, 1085.913126949842, 1092.579356947573, 10728.980746829116, 10773.138148948065, 10928.890711092867, 10931.344119352943, 10948.562057517689, 4441.666874984389, 4558.407407037064, 4671.416072990876, 4694.342792009122, 4713.34013238147, 9217.769555761026, 9239.491827949392, 9259.490728010023, 9276.675316363895, 9288.210502107397, 5144.71704056273, 5163.3545806774155, 5165.483451654804, 5171.63798534099, 5171.689698618095, 3906.7851268180525, 4101.938470922939, 4722.984558319909, 4735.171345014677, 4735.95264047362, 3747.7376583638884, 3754.568224654145, 3839.731218814712, 3861.092643228839, 3873.767573945516, 1238.0809744895375, 1241.3855141704914, 1241.5530409052733, 1250.9624711258496, 1252.5177806544546, 811.2293321344746, 875.7329014650466, 883.5670521444446, 885.5704983083328, 889.3463304471209, 5447.428020057916, 5455.645383050089, 5565.276514053147, 5696.943030569724, 5719.170720245541, 412.83899279281104, 421.25648230872537, 429.36025322270217, 430.72433642468843, 430.8815075854165, 3708.2398108387715, 3724.6275372462537, 3728.7203459740626, 3817.1602247505502, 3922.3724204127775, 9697.224222062217, 10065.798684026278, 10071.194304455626, 10215.94892025535, 10221.846672299922, 9700.90299097014, 9903.455309957877, 10029.197664186848, 10035.39787612731, 10362.119219866396, 7724.188960159215, 7769.223077692185, 7835.0989636191425, 7848.13670496245, 7850.312182880708, 4381.430414046154, 5336.546393556591, 5437.374701909331, 5440.891182176413, 5576.721163941867, 7685.884711729441, 9188.678396256098, 9340.159385655325, 9440.669576564787, 9451.0, 9353.859692104488, 9372.625094996123, 9448.952755236454, 9574.04259574037, 9583.90416095858, 6441.3880681335195, 6646.60120392778, 6649.900251496329, 6913.0667359905765, 7056.294370562902, 6763.729450821912, 7007.964960175166, 7734.342580880688, 7802.453828231969, 7832.84334313322, 6926.722931082701, 7014.158301003912, 7026.473014524034, 7079.929200708133, 7175.677094530863, 6890.2420733720255, 6914.377706006484, 6934.514583979073, 6934.930650693428, 7020.719171233093, 5410.891782164414, 6521.282658907611, 6611.438027767694, 6611.504137189634, 6633.303503132294, 4637.976810115928, 4737.336772851734, 4737.739424331695, 4774.30772537987, 4815.398075240595, 3934.488516492838, 4645.233536466445, 4743.691660042086, 4752.144613969519, 4866.537678920497], "value": 4268.966173161314, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [296.8233900829007, 304.49301912316, 304.5948887978988, 309.86520863424397, 311.8721324257055, 4684.742339171345, 5232.2674825524455, 5239.86900327492, 5267.65760225586, 5325.946740532593, 5154.170269554311, 5197.0, 5227.9738601307035, 5236.502532259438, 5243.816466423676, 3402.8468718907584, 3809.1048603578206, 3864.0339915021245, 3970.980145099285, 3990.4413382126527, 3502.9474557881654, 4032.5967403259588, 4035.899102522446, 4058.269511487931, 4063.516816362036, 3976.681865450757, 3998.580149084348, 4005.0, 4017.7790221537803, 4029.657479114279, 3625.3111908737255, 3752.774833509998, 4004.8786339824896, 4031.4355990161403, 4031.919361612766, 3450.861965521376, 3453.2748122894222, 3493.1791029108394, 3496.7377446691344, 3552.91117722059, 4374.232087025126, 4424.247877860771, 4934.210526315808, 4943.530364615356, 4971.45314015458, 4151.875443736692, 4216.325387937946, 4524.502304746476, 4526.234768440311, 4545.250033744428, 4337.934930976038, 4665.393498845128, 5465.754041068121, 5471.7264136793, 5475.397706252309, 3339.8664053437833, 3770.962290377116, 3773.8301776419853, 3776.3580191367532, 3790.677792387623, 5447.809326673548, 5534.612577119563, 5547.223388725582, 5809.390014048548, 5837.678927658977, 3821.675157611634, 3835.6547910688173, 3847.576766555677, 3848.0, 3879.3599056155417, 3881.611838816152, 3901.8244179012013, 3928.8232029558167, 3930.6069393060466, 3934.1738234970403, 3964.742291751027, 4506.954930450719, 4516.390287311171, 4519.276915693474, 4537.047220083819, 4934.432540257844, 5148.253503242009, 5312.495312945264, 5428.90832268843, 5580.1629755537, 1127.370948978718, 1244.7883859743683, 1260.426505939795, 1262.715888925003, 1263.881464903567, 817.9856977348046, 852.2457625001917, 860.3332417376583, 860.9268212201907, 865.5196366016795, 4476.030546559504, 4624.398828152386, 4942.403564684878, 4997.475265097185, 5093.057784309841, 4612.262038073892, 5692.6299790513485, 5725.1698503716725, 5735.71321433935, 5786.016377215799, 4401.867943961686, 5239.66388570912, 5243.898781255979, 5248.9737551312, 5276.577873770089, 2409.2410890569413, 2421.8910149043336, 2770.7367800058964, 2810.9859450702616, 2826.3781967967016, 4705.0, 5139.511746384088, 5222.3472065991755, 5646.8023619173955, 5668.829935101871, 5147.202183661539, 5217.321748172713, 5238.161894097002, 5545.694986775686, 5583.664980101207, 5080.704420372856, 5096.592272618182, 5210.322658054429, 5867.735951882176, 6131.908021379683, 4619.35329053926, 5261.763220655003, 5715.942840571541, 5756.366799652119, 5761.8559536011935, 5119.0, 5314.0, 5481.506664400223, 5640.464155905182, 5653.858653533694, 5289.497497737709, 5853.8536536585225, 5935.792247271324, 5960.0, 5998.970005149947, 3413.948790768091, 3756.699464042815, 3797.822674970768, 3823.369144091194, 3846.3461211594654, 4789.365848783085, 5353.625246232727, 5535.529479994085, 5666.291713535808, 5792.507636850914, 4587.908241835212, 4978.2034874419915, 5100.821471248489, 5111.7188554628365, 5264.078611306769, 4855.9074208302845, 5579.386267510652, 5597.524210441995, 5612.719364031864, 5631.408702086386, 4392.363107349481, 4923.483034281349, 4961.700469187949, 5463.508284254436, 5474.479924407175, 5262.552683021986, 5459.836204913859, 5475.835724928259, 5555.527780138734, 5582.302212223472, 4844.813020809924, 5257.290265814219, 5316.734163291897, 5346.732663366894, 5354.089804733283], "value": 3984.0324012297715, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [673.6362364323265, 728.6793810723282, 755.9773206803798, 777.7938846205753, 779.2129948751759, 11270.54917803288, 14032.929835350835, 14396.856031439682, 14705.852941470583, 14796.18620975846, 12423.130380873343, 12864.713528647131, 12994.090413671047, 12996.870031299684, 13009.284489353082, 654.6366766444621, 818.1041759273586, 823.4688625836332, 826.4627991805314, 827.3546633625782, 791.4103992525577, 819.1276290750361, 820.5692011693849, 823.6046697585163, 824.4146655874306, 871.2463718883153, 871.3116637856118, 872.9607167677469, 873.1617647058799, 873.6243415331405, 864.369010622243, 865.0786911938765, 865.2558799432483, 865.2991077227402, 865.8917635295588, 636.9777057802954, 639.6737663791454, 640.8301800022998, 641.4579680170295, 642.1235014205622, 12214.411686825799, 12262.0, 12546.498140074385, 12582.37088145589, 12683.365831708377, 9520.000399958044, 11592.594259200887, 11641.53433862653, 11708.707282317924, 11988.880111198863, 9918.851217231748, 9991.450470224136, 10000.449975251358, 10457.84313235302, 10478.528466219039, 3103.9844800776077, 3268.0686004488844, 3552.8045957472327, 3606.386914224588, 3701.2782507411066, 85105.72341414822, 85627.0, 85816.7127493088, 86969.0, 91104.0, 8519.39541063518, 8868.645254189889, 8874.51190184546, 8887.0, 9217.539123043822, 18512.259509619464, 18534.16596253172, 18541.0, 18542.44372668822, 18603.162857671436, 7063.434925206074, 7253.746118885813, 7574.924250757532, 7895.802604934807, 7989.800254993672, 4152.273352163359, 4178.582141785858, 4188.769617671057, 4267.850625228102, 4269.380939763779, 3231.88688395905, 3344.832758362072, 3375.4936759486272, 3380.1042723678247, 3385.23832137767, 633.6989929783408, 635.0759644716926, 635.9300476947532, 640.8333833203401, 641.7850020243177, 524.2686452398958, 552.1689856765543, 579.4495229531962, 603.8474272245248, 640.1006585747033, 8320.334373250125, 8546.957265213634, 8565.700200493078, 8663.523506207093, 8689.391742578086, 588.4880154265816, 589.39586923403, 598.5451057196475, 602.3284038297267, 611.8929187392278, 4184.602462766033, 4196.391523229115, 4222.978885105555, 4255.148970205972, 4273.61045543766, 68803.65598172077, 94580.58129128069, 102071.0, 109551.0, 116118.0, 58612.82774344489, 68234.97647535292, 93305.53347233355, 96604.0, 96606.0, 76686.46627067427, 77255.61372193215, 78421.60789196132, 79633.0, 81052.0, 9046.683366082156, 9525.476098814632, 9532.0, 9534.0, 9595.28035397334, 49455.01089978252, 49473.25790113223, 49975.0, 53102.734486327325, 53417.0, 68786.65606671934, 73452.63273683598, 74625.0, 74724.0, 75176.49647006919, 12076.21504602198, 12178.57374991871, 12185.634430967086, 12275.815862762245, 12305.446254918372, 12424.440900159334, 12534.937325313314, 13655.931720341334, 14514.419423222953, 14683.41266349334, 11877.406129693309, 12564.497420103093, 13356.532521361705, 13487.527936522174, 14082.859171408154, 11013.229073965065, 12666.366681666064, 13346.532871349456, 13387.330633468482, 13663.863361366259, 10448.582056717647, 13364.198148111145, 13578.388972496065, 13722.725545489235, 13895.235762033186, 10309.639162629272, 10387.163314689042, 10539.15686745074, 10809.945950270197, 10998.175136864607, 9695.418274903528, 9996.350237234556, 11207.551697931991, 11251.38117403518, 11264.211505194558], "value": 6972.832916312907, "unit": "score"}], "name": "dromaeo_css", "value": 7012.797388009637, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 113.0, 114.0, 115.0, 113.0, 114.0, 114.0, 114.0, 114.0, 114.0], "value": 114.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 114.0, 113.0, 143.0, 109.0, 111.0, 112.0, 106.0, 141.0, 104.0], "value": 119.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 186.0, 183.0, 186.0, 185.0, 185.0, 184.0, 184.0, 186.0, 186.0], "value": 185.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 60.0, 88.0, 60.0, 59.0, 58.0, 59.0, 84.0, 58.0], "value": 66.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 92.0, 128.0, 101.0, 67.0, 69.0, 90.0, 66.0, 68.0, 92.0], "value": 87.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 86.0, 90.0, 85.0, 89.0, 86.0, 90.0, 86.0, 89.0, 87.0], "value": 87.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 151.0, 94.0, 104.0, 141.0, 112.0, 95.0, 94.0, 95.0, 95.0], "value": 111.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 78.0, 79.0, 85.0, 84.0, 86.0, 83.0, 81.0, 81.0], "value": 82.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 54.0, 53.0, 57.0, 58.0, 58.0, 59.0, 57.0, 56.0], "value": 56.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 73.0, 75.0, 76.0, 72.0, 71.0, 70.0, 70.0, 69.0, 72.0], "value": 71.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 87.0, 92.0, 102.0, 103.0, 83.0, 84.0, 84.0, 85.0], "value": 88.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 167.0, 151.0, 141.0, 143.0, 138.0, 156.0, 170.0, 196.0, 173.0], "value": 159.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 165.0, 170.0, 167.0, 165.0, 167.0, 167.0, 223.0, 215.0, 166.0], "value": 176.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 68.0, 67.0, 68.0, 68.0, 66.0, 68.0, 65.0, 71.0], "value": 67.8, "unit": "score"}], "name": "kraken", "value": 1475.4999999999998, "alertThreshold": 2.0}]} 14:00:56 INFO - Return code: 0 14:00:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:00:56 INFO - # TBPL SUCCESS # 14:00:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:00:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:00:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:00:57 INFO - 2017-01-10 14:00:57.001 screenresolution[1931:18125] starting screenresolution argv=screenresolution get 14:00:57 INFO - 2017-01-10 14:00:57.004 screenresolution[1931:18125] Display 0: 1600x1200x32@60 14:00:57 INFO - 2017-01-10 14:00:57.010 screenresolution[1932:18128] starting screenresolution argv=screenresolution list 14:00:57 INFO - Available Modes on Display 0 14:00:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:00:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:00:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:00:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:00:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:00:57 INFO - Intel Iris: 14:00:57 INFO - Chipset Model: Intel Iris 14:00:57 INFO - Type: GPU 14:00:57 INFO - Bus: Built-In 14:00:57 INFO - VRAM (Dynamic, Max): 1536 MB 14:00:57 INFO - Vendor: Intel (0x8086) 14:00:57 INFO - Device ID: 0x0a2e 14:00:57 INFO - Revision ID: 0x0009 14:00:57 INFO - Displays: 14:00:57 INFO - Display: 14:00:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:00:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:00:57 INFO - Display Serial Number: 1600x1200 60 14:00:57 INFO - Main Display: Yes 14:00:57 INFO - Mirror: Off 14:00:57 INFO - Online: Yes 14:00:57 INFO - Rotation: Supported 14:00:57 INFO - Return code: 0 14:00:57 INFO - Running post-action listener: _resource_record_post_action 14:00:57 INFO - [mozharness: 2017-01-10 22:00:57.249104Z] Finished run-tests step (success) 14:00:57 INFO - Running post-run listener: _resource_record_post_run 14:00:57 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 745472; Write bytes: 658258944; Read time: 10; Write time: 2052 14:00:57 INFO - TinderboxPrint: CPU usage
53.4% 14:00:57 INFO - TinderboxPrint: I/O read bytes / time
745,472 / 10 14:00:57 INFO - TinderboxPrint: I/O write bytes / time
658,258,944 / 2,052 14:00:57 INFO - TinderboxPrint: CPU idle
2,259.4 (46.6%) 14:00:57 INFO - TinderboxPrint: CPU system
973.8 (20.1%) 14:00:57 INFO - TinderboxPrint: CPU user
1,620.5 (33.4%) 14:00:57 INFO - TinderboxPrint: Swap in / out
349,941,760 / 0 14:00:57 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 348125696; Write bytes: 346232320; Read time: 27744; Write time: 713 14:00:57 INFO - run-tests - Wall time: 1182s; CPU: 53.0%; Read bytes: 614400; Write bytes: 304788992; Read time: 8; Write time: 1292 14:00:57 INFO - Running post-run listener: _upload_blobber_files 14:00:57 INFO - Blob upload gear active. 14:00:57 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:00:57 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 14:00:57 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'] 14:00:57 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 14:00:58 INFO - (blobuploader) - INFO - Open directory for files ... 14:00:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:00:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:00:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:00:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:00:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:00:58 INFO - (blobuploader) - INFO - Done attempting. 14:00:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:00:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:00:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:01:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:01:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:01:00 INFO - (blobuploader) - INFO - Done attempting. 14:01:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:01:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:01:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:01:02 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:01:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:01:02 INFO - (blobuploader) - INFO - Done attempting. 14:01:02 INFO - (blobuploader) - INFO - Iteration through files over. 14:01:02 INFO - Return code: 0 14:01:02 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:01:02 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:01:02 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b53470f06b772b95c49a27b4dc3adb6f7564e1717b2992b42d9f52667a93fa7ef4ce088bf130bae1718886ac42f36a9bb16add364c10383862bf706afcc57070", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/838dc62bef190bc2e87b1210379ac13e0649175e91f98054efbc31b5fdea73b430d1a85fe220bbc0062a2a607778a9f8abe6de80a27c5a85879d55c73788b260", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c3b3a0e66a7ad031b86df51848d5f4e8e07706ae71ad590ec0cde88d343bbc0471578a0ba9cd688f12f5184f91b597a36fa248f6924214785139cc8be20b51d7"} 14:01:02 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:01:02 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:01:02 INFO - Contents: 14:01:02 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b53470f06b772b95c49a27b4dc3adb6f7564e1717b2992b42d9f52667a93fa7ef4ce088bf130bae1718886ac42f36a9bb16add364c10383862bf706afcc57070", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/838dc62bef190bc2e87b1210379ac13e0649175e91f98054efbc31b5fdea73b430d1a85fe220bbc0062a2a607778a9f8abe6de80a27c5a85879d55c73788b260", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c3b3a0e66a7ad031b86df51848d5f4e8e07706ae71ad590ec0cde88d343bbc0471578a0ba9cd688f12f5184f91b597a36fa248f6924214785139cc8be20b51d7"} 14:01:02 INFO - Running post-run listener: copy_logs_to_upload_dir 14:01:02 INFO - Copying logs to upload dir... 14:01:02 INFO - mkdir: /builds/slave/test/build/upload/logs 14:01:02 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.166530 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2017-01-10 14:01:02.373413) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-10 14:01:02.376679) ========= 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.vyP2OiIY90/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.sv9PZKZdmR/Listeners TMPDIR=/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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/b53470f06b772b95c49a27b4dc3adb6f7564e1717b2992b42d9f52667a93fa7ef4ce088bf130bae1718886ac42f36a9bb16add364c10383862bf706afcc57070", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/838dc62bef190bc2e87b1210379ac13e0649175e91f98054efbc31b5fdea73b430d1a85fe220bbc0062a2a607778a9f8abe6de80a27c5a85879d55c73788b260", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c3b3a0e66a7ad031b86df51848d5f4e8e07706ae71ad590ec0cde88d343bbc0471578a0ba9cd688f12f5184f91b597a36fa248f6924214785139cc8be20b51d7"} build_url:https://queue.taskcluster.net/v1/task/ADUfEjJzRXOFSd-5BYi_hQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011553 build_url: 'https://queue.taskcluster.net/v1/task/ADUfEjJzRXOFSd-5BYi_hQ/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/b53470f06b772b95c49a27b4dc3adb6f7564e1717b2992b42d9f52667a93fa7ef4ce088bf130bae1718886ac42f36a9bb16add364c10383862bf706afcc57070", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/838dc62bef190bc2e87b1210379ac13e0649175e91f98054efbc31b5fdea73b430d1a85fe220bbc0062a2a607778a9f8abe6de80a27c5a85879d55c73788b260", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c3b3a0e66a7ad031b86df51848d5f4e8e07706ae71ad590ec0cde88d343bbc0471578a0ba9cd688f12f5184f91b597a36fa248f6924214785139cc8be20b51d7"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-10 14:01:02.412425) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-10 14:01:02.412815) ========= 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.vyP2OiIY90/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.sv9PZKZdmR/Listeners TMPDIR=/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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.005269 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-10 14:01:02.463043) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-10 14:01:02.463626) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-10 14:01:02.464247) ========= ========= Total master_lag: 0.20 =========