builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0115 starttime: 1476621114.39 results: success (0) buildid: 20161016030205 builduid: 28b9c4243357490dbd090a07db4a8c87 revision: a1768c915756429b74f73fac2e8dd4e7b4919730 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-16 05:31:54.386284) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-16 05:31:54.386714) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-16 05:31:54.387041) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-16 05:31:54.417240) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-16 05:31:54.417638) ========= 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.JQj5xAnJUM/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.PY0z2DJeqU/Listeners TMPDIR=/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-10-16 05:31:54-- 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.94M=0.001s 2016-10-16 05:31:54 (7.94 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.165984 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-16 05:31:54.607190) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-16 05:31:54.607785) ========= 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.JQj5xAnJUM/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.PY0z2DJeqU/Listeners TMPDIR=/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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.022159 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-16 05:31:54.656697) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-16 05:31:54.657072) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev a1768c915756429b74f73fac2e8dd4e7b4919730 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev a1768c915756429b74f73fac2e8dd4e7b4919730 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.JQj5xAnJUM/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.PY0z2DJeqU/Listeners TMPDIR=/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-10-16 05:31:54,733 truncating revision to first 12 chars 2016-10-16 05:31:54,733 Setting DEBUG logging. 2016-10-16 05:31:54,733 attempt 1/10 2016-10-16 05:31:54,733 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/a1768c915756?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-16 05:31:55,788 unpacking tar archive at: mozilla-central-a1768c915756/testing/mozharness/ program finished with exit code 0 elapsedTime=1.311268 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-16 05:31:55.994459) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-16 05:31:55.995196) ========= script_repo_revision: a1768c915756429b74f73fac2e8dd4e7b4919730 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-16 05:31:55.995622) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-16 05:31:55.995952) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-16 05:31:56.018332) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-10-16 05:31:56.019098) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.JQj5xAnJUM/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.PY0z2DJeqU/Listeners TMPDIR=/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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 05:31:56 INFO - MultiFileLogger online at 20161016 05:31:56 in /builds/slave/test 05:31:56 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:31:56 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:31:56 INFO - {'append_to_log': False, 05:31:56 INFO - 'base_work_dir': '/builds/slave/test', 05:31:56 INFO - 'blob_upload_branch': 'Firefox', 05:31:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:31:56 INFO - 'branch': 'Firefox', 05:31:56 INFO - 'buildbot_json_path': 'buildprops.json', 05:31:56 INFO - 'config_files': ('talos/mac_config.py',), 05:31:56 INFO - 'default_actions': ('clobber', 05:31:56 INFO - 'read-buildbot-config', 05:31:56 INFO - 'download-and-extract', 05:31:56 INFO - 'populate-webroot', 05:31:56 INFO - 'create-virtualenv', 05:31:56 INFO - 'install', 05:31:56 INFO - 'run-tests'), 05:31:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:31:56 INFO - 'download_minidump_stackwalk': True, 05:31:56 INFO - 'download_symbols': 'ondemand', 05:31:56 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:31:56 INFO - 'tooltool.py': '/tools/tooltool.py', 05:31:56 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:31:56 INFO - '/tools/misc-python/virtualenv.py')}, 05:31:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:31:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:31:56 INFO - 'installer_path': 'installer.exe', 05:31:56 INFO - 'log_level': 'info', 05:31:56 INFO - 'log_name': 'talos', 05:31:56 INFO - 'log_to_console': True, 05:31:56 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:31:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:31:56 INFO - 'opt_config_files': (), 05:31:56 INFO - 'pip_index': False, 05:31:56 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:31:56 INFO - 'cmd': ('bash', 05:31:56 INFO - '-c', 05:31:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:31:56 INFO - 'enabled': True, 05:31:56 INFO - 'halt_on_failure': False, 05:31:56 INFO - 'name': 'check_screen_resolution'},), 05:31:56 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:31:56 INFO - 'cmd': ('bash', 05:31:56 INFO - '-c', 05:31:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:31:56 INFO - 'enabled': True, 05:31:56 INFO - 'halt_on_failure': False, 05:31:56 INFO - 'name': 'check_screen_resolution'},), 05:31:56 INFO - 'run_cmd_checks_enabled': True, 05:31:56 INFO - 'sps_profile': False, 05:31:56 INFO - 'sps_profile_interval': 0, 05:31:56 INFO - 'suite': 'dromaeojs', 05:31:56 INFO - 'system_bits': '32', 05:31:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:31:56 INFO - 'title': 't-yosemite-r7-0115', 05:31:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:31:56 INFO - 'use_talos_json': True, 05:31:56 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:31:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:31:56 INFO - 'work_dir': 'build'} 05:31:56 INFO - [mozharness: 2016-10-16 12:31:56.227201Z] Running clobber step. 05:31:56 INFO - Running pre-action listener: _resource_record_pre_action 05:31:56 INFO - Running main action method: clobber 05:31:56 INFO - rmtree: /builds/slave/test/build 05:31:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:31:57 INFO - Running post-action listener: _resource_record_post_action 05:31:57 INFO - [mozharness: 2016-10-16 12:31:57.787034Z] Finished clobber step (success) 05:31:57 INFO - [mozharness: 2016-10-16 12:31:57.787183Z] Running read-buildbot-config step. 05:31:57 INFO - Running pre-action listener: _resource_record_pre_action 05:31:57 INFO - Running main action method: read_buildbot_config 05:31:57 INFO - Using buildbot properties: 05:31:57 INFO - { 05:31:57 INFO - "project": "", 05:31:57 INFO - "product": "firefox", 05:31:57 INFO - "script_repo_revision": "production", 05:31:57 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 05:31:57 INFO - "repository": "", 05:31:57 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 05:31:57 INFO - "buildid": "20161016030205", 05:31:57 INFO - "stage_platform": "macosx64", 05:31:57 INFO - "basedir": "/builds/slave/test", 05:31:57 INFO - "nightly_build": "True", 05:31:57 INFO - "buildnumber": 8, 05:31:57 INFO - "slavename": "t-yosemite-r7-0115", 05:31:57 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 05:31:57 INFO - "platform": "yosemite_r7", 05:31:57 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:31:57 INFO - "branch": "mozilla-central", 05:31:57 INFO - "repo_path": "mozilla-central", 05:31:57 INFO - "revision": "a1768c915756429b74f73fac2e8dd4e7b4919730", 05:31:57 INFO - "pgo_build": "False", 05:31:57 INFO - "builduid": "28b9c4243357490dbd090a07db4a8c87", 05:31:57 INFO - "slavebuilddir": "test" 05:31:57 INFO - } 05:31:57 INFO - Found installer url https://queue.taskcluster.net/v1/task/DhtNfV_WTBel5DDtM-2kLQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 05:31:57 INFO - Running post-action listener: _resource_record_post_action 05:31:57 INFO - [mozharness: 2016-10-16 12:31:57.790601Z] Finished read-buildbot-config step (success) 05:31:57 INFO - [mozharness: 2016-10-16 12:31:57.790708Z] Running download-and-extract step. 05:31:57 INFO - Running pre-action listener: _resource_record_pre_action 05:31:57 INFO - Running main action method: download_and_extract 05:31:57 INFO - mkdir: /builds/slave/test/build/tests 05:31:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:31:57 INFO - trying https://queue.taskcluster.net/v1/task/DhtNfV_WTBel5DDtM-2kLQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 05:31:57 INFO - Downloading https://queue.taskcluster.net/v1/task/DhtNfV_WTBel5DDtM-2kLQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 05:31:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DhtNfV_WTBel5DDtM-2kLQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 05:31:58 INFO - Downloaded 1199 bytes. 05:31:58 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 05:31:58 INFO - Using the following test package requirements: 05:31:58 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 05:31:58 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:31:58 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 05:31:58 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:31:58 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 05:31:58 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:31:58 INFO - u'jsshell-mac.zip'], 05:31:58 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:31:58 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 05:31:58 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 05:31:58 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:31:58 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 05:31:58 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:31:58 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 05:31:58 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:31:58 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 05:31:58 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:31:58 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 05:31:58 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:31:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DhtNfV_WTBel5DDtM-2kLQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 05:31:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DhtNfV_WTBel5DDtM-2kLQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:31:58 INFO - Fetch https://queue.taskcluster.net/v1/task/DhtNfV_WTBel5DDtM-2kLQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 05:31:59 INFO - Http code: 200 05:31:59 INFO - via: 1.1 37af14ee951b12568cf1cc160d08abe6.cloudfront.net (CloudFront) 05:31:59 INFO - x-amz-cf-id: JndXOAvvyWBqxQfHWMsNN-h3hKwXvLpmPWQYbh-CxVhfY7On47U3Mw== 05:31:59 INFO - x-amz-version-id: ccObSGQezWSx0qDCkOTJx6BjZws2v9_3 05:31:59 INFO - Expected file size: 16516864 05:31:59 INFO - Obtained file size: 16516864 05:32:06 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:32:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DhtNfV_WTBel5DDtM-2kLQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 05:32:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DhtNfV_WTBel5DDtM-2kLQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:32:06 INFO - Fetch https://queue.taskcluster.net/v1/task/DhtNfV_WTBel5DDtM-2kLQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 05:32:06 INFO - Http code: 200 05:32:06 INFO - via: 1.1 e2af8a85927835558866752f53562ecd.cloudfront.net (CloudFront) 05:32:06 INFO - x-amz-cf-id: 295BqUjbVoZWE2EhSvOSqPUdQV6N2oYs-9d_eiuuv94ZQNJdfZJlhA== 05:32:06 INFO - x-amz-version-id: ccObSGQezWSx0qDCkOTJx6BjZws2v9_3 05:32:07 INFO - Expected file size: 16516864 05:32:07 INFO - Obtained file size: 16516864 05:32:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DhtNfV_WTBel5DDtM-2kLQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 05:32:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DhtNfV_WTBel5DDtM-2kLQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:32:13 INFO - Fetch https://queue.taskcluster.net/v1/task/DhtNfV_WTBel5DDtM-2kLQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 05:32:14 INFO - Http code: 200 05:32:14 INFO - via: 1.1 0cf1d7257c633ae75623c5e75bf3805e.cloudfront.net (CloudFront) 05:32:14 INFO - x-amz-cf-id: KkrlTVkloXG_3ARiNlKqm1tasvHS9Obotp2M158r6N5obz33iLNdUQ== 05:32:14 INFO - x-amz-version-id: 9wV0P63eu7yVkHH7l610nfvJZ0RaltmE 05:32:15 INFO - Expected file size: 14409028 05:32:15 INFO - Obtained file size: 14409028 05:32:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:16 INFO - trying https://queue.taskcluster.net/v1/task/DhtNfV_WTBel5DDtM-2kLQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:32:16 INFO - Downloading https://queue.taskcluster.net/v1/task/DhtNfV_WTBel5DDtM-2kLQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 05:32:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DhtNfV_WTBel5DDtM-2kLQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 05:32:18 INFO - Downloaded 112124623 bytes. 05:32:18 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DhtNfV_WTBel5DDtM-2kLQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:32:18 INFO - mkdir: /builds/slave/test/properties 05:32:18 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:32:18 INFO - Writing to file /builds/slave/test/properties/build_url 05:32:18 INFO - Contents: 05:32:18 INFO - build_url:https://queue.taskcluster.net/v1/task/DhtNfV_WTBel5DDtM-2kLQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:32:19 INFO - Running post-action listener: _resource_record_post_action 05:32:19 INFO - Running post-action listener: set_extra_try_arguments 05:32:19 INFO - [mozharness: 2016-10-16 12:32:19.484246Z] Finished download-and-extract step (success) 05:32:19 INFO - [mozharness: 2016-10-16 12:32:19.484365Z] Running populate-webroot step. 05:32:19 INFO - Running pre-action listener: _resource_record_pre_action 05:32:19 INFO - Running main action method: populate_webroot 05:32:19 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:32:19 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:32:19 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:32:19 INFO - '--tppagecycles', 05:32:19 INFO - '7'], 05:32:19 INFO - 'tests': ['tsvgm']}}, 05:32:19 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:32:19 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:32:19 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:32:19 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:32:19 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:32:19 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:32:19 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:32:19 INFO - 'talos_options': ['--disable-e10s'], 05:32:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:32:19 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:32:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:32:19 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:32:19 INFO - 'talos_options': ['--disable-e10s'], 05:32:19 INFO - 'tests': ['damp', 'tps']}, 05:32:19 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:32:19 INFO - 'tests': ['damp', 'tps']}, 05:32:19 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:32:19 INFO - 'tests': ['dromaeo_dom']}, 05:32:19 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:32:19 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:32:19 INFO - 'tests': ['basic_compositor_video']}, 05:32:19 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:32:19 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:32:19 INFO - 'tests': ['a11yr', 05:32:19 INFO - 'ts_paint', 05:32:19 INFO - 'tpaint', 05:32:19 INFO - 'sessionrestore', 05:32:19 INFO - 'sessionrestore_no_auto_restore', 05:32:19 INFO - 'tabpaint']}, 05:32:19 INFO - 'other-e10s': {'tests': ['a11yr', 05:32:19 INFO - 'ts_paint', 05:32:19 INFO - 'tpaint', 05:32:19 INFO - 'sessionrestore', 05:32:19 INFO - 'sessionrestore_no_auto_restore', 05:32:19 INFO - 'tabpaint']}, 05:32:19 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:32:19 INFO - 'ts_paint', 05:32:19 INFO - 'tpaint', 05:32:19 INFO - 'sessionrestore', 05:32:19 INFO - 'sessionrestore_no_auto_restore', 05:32:19 INFO - 'tabpaint']}, 05:32:19 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:32:19 INFO - 'ts_paint', 05:32:19 INFO - 'tpaint', 05:32:19 INFO - 'sessionrestore', 05:32:19 INFO - 'sessionrestore_no_auto_restore', 05:32:19 INFO - 'tabpaint']}, 05:32:19 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:32:19 INFO - 'tests': ['a11yr', 05:32:19 INFO - 'ts_paint', 05:32:19 INFO - 'tpaint', 05:32:19 INFO - 'sessionrestore', 05:32:19 INFO - 'sessionrestore_no_auto_restore', 05:32:19 INFO - 'tabpaint']}, 05:32:19 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:32:19 INFO - 'tests': ['a11yr', 05:32:19 INFO - 'ts_paint', 05:32:19 INFO - 'tpaint', 05:32:19 INFO - 'sessionrestore', 05:32:19 INFO - 'sessionrestore_no_auto_restore', 05:32:19 INFO - 'tabpaint']}, 05:32:19 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:32:19 INFO - 'tests': ['tsvgx', 05:32:19 INFO - 'tsvgr_opacity', 05:32:19 INFO - 'tart', 05:32:19 INFO - 'tscrollx', 05:32:19 INFO - 'cart']}, 05:32:19 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:32:19 INFO - 'tsvgr_opacity', 05:32:19 INFO - 'tart', 05:32:19 INFO - 'tscrollx', 05:32:19 INFO - 'cart']}, 05:32:19 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:32:19 INFO - 'talos_options': ['--disable-e10s'], 05:32:19 INFO - 'tests': ['tp5o']}, 05:32:19 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:32:19 INFO - 'tests': ['tp5o']}, 05:32:19 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:32:19 INFO - 'talos_options': ['--disable-e10s', 05:32:19 INFO - '--xperf_path', 05:32:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:32:19 INFO - 'tests': ['tp5n']}, 05:32:19 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:32:19 INFO - 'talos_options': ['--xperf_path', 05:32:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:32:19 INFO - 'tests': ['tp5n']}}, 05:32:19 INFO - 'talos.zip': {'path': '', 05:32:19 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:32:19 INFO - Running post-action listener: _resource_record_post_action 05:32:19 INFO - [mozharness: 2016-10-16 12:32:19.504258Z] Finished populate-webroot step (success) 05:32:19 INFO - [mozharness: 2016-10-16 12:32:19.504401Z] Running create-virtualenv step. 05:32:19 INFO - Running pre-action listener: _resource_record_pre_action 05:32:19 INFO - Running main action method: create_virtualenv 05:32:19 INFO - Creating virtualenv /builds/slave/test/build/venv 05:32:19 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 05:32:19 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:32:19 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:32:19 INFO - Using real prefix '/tools/python27' 05:32:19 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:32:20 INFO - Installing distribute.............................................................................................................................................................................................done. 05:32:23 INFO - Installing pip.................done. 05:32:23 INFO - Return code: 0 05:32:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:32:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:32:23 INFO - Reading from file tmpfile_stdout 05:32:23 INFO - Output received: 05:32:23 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) 05:32:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:32:23 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')]} 05:32:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:23 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')]} 05:32:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:23 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 0x10acaf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac25570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffcc255e200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aac3d30>, '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 0x10a8e9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aabbb20>, '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.JQj5xAnJUM/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.PY0z2DJeqU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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 05:32:23 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 05:32:23 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 05:32:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JQj5xAnJUM/Render', 05:32:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:32:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:32:23 INFO - 'HOME': '/Users/cltbld', 05:32:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:32:23 INFO - 'LOGNAME': 'cltbld', 05:32:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:23 INFO - 'MOZ_NO_REMOTE': '1', 05:32:23 INFO - 'NO_EM_RESTART': '1', 05:32:23 INFO - 'PAGER': '/bin/cat', 05:32:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:32:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:23 INFO - 'PWD': '/builds/slave/test', 05:32:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:32:23 INFO - 'SHELL': '/bin/bash', 05:32:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PY0z2DJeqU/Listeners', 05:32:23 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 05:32:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:32:23 INFO - 'USER': 'cltbld', 05:32:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:32:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:32:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:23 INFO - 'XPC_FLAGS': '0x0', 05:32:23 INFO - 'XPC_SERVICE_NAME': '0', 05:32:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:32:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:23 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 05:32:23 INFO - Cleaning up... 05:32:23 INFO - Return code: 0 05:32:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:32:23 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')]} 05:32:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:23 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')]} 05:32:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:23 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 0x10acaf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac25570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffcc255e200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aac3d30>, '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 0x10a8e9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aabbb20>, '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.JQj5xAnJUM/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.PY0z2DJeqU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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 05:32:23 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 05:32:23 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 05:32:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JQj5xAnJUM/Render', 05:32:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:32:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:32:23 INFO - 'HOME': '/Users/cltbld', 05:32:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:32:23 INFO - 'LOGNAME': 'cltbld', 05:32:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:23 INFO - 'MOZ_NO_REMOTE': '1', 05:32:23 INFO - 'NO_EM_RESTART': '1', 05:32:23 INFO - 'PAGER': '/bin/cat', 05:32:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:32:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:23 INFO - 'PWD': '/builds/slave/test', 05:32:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:32:23 INFO - 'SHELL': '/bin/bash', 05:32:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PY0z2DJeqU/Listeners', 05:32:23 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 05:32:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:32:23 INFO - 'USER': 'cltbld', 05:32:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:32:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:32:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:23 INFO - 'XPC_FLAGS': '0x0', 05:32:23 INFO - 'XPC_SERVICE_NAME': '0', 05:32:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:32:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:23 INFO - Downloading/unpacking psutil>=3.1.1 05:32:23 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 05:32:23 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 05:32:23 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 05:32:23 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 05:32:23 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 05:32:23 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 05:32:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:32:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:32:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:32:28 INFO - Installing collected packages: psutil 05:32:28 INFO - Running setup.py install for psutil 05:32:28 INFO - building 'psutil._psutil_osx' extension 05:32:28 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 05:32:28 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 05:32:28 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 05:32:28 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 05:32:28 INFO - building 'psutil._psutil_posix' extension 05:32:28 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 05:32:28 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:32:28 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:32:28 INFO - ^ 05:32:28 INFO - 1 warning generated. 05:32:28 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 05:32:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:32:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:32:28 INFO - Successfully installed psutil 05:32:28 INFO - Cleaning up... 05:32:28 INFO - Return code: 0 05:32:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:32:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:28 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 0x10acaf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac25570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffcc255e200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aac3d30>, '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 0x10a8e9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aabbb20>, '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.JQj5xAnJUM/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.PY0z2DJeqU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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 05:32:28 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 05:32:28 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 05:32:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JQj5xAnJUM/Render', 05:32:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:32:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:32:28 INFO - 'HOME': '/Users/cltbld', 05:32:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:32:28 INFO - 'LOGNAME': 'cltbld', 05:32:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:28 INFO - 'MOZ_NO_REMOTE': '1', 05:32:28 INFO - 'NO_EM_RESTART': '1', 05:32:28 INFO - 'PAGER': '/bin/cat', 05:32:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:32:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:28 INFO - 'PWD': '/builds/slave/test', 05:32:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:32:28 INFO - 'SHELL': '/bin/bash', 05:32:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PY0z2DJeqU/Listeners', 05:32:28 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 05:32:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:32:28 INFO - 'USER': 'cltbld', 05:32:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:32:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:32:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:28 INFO - 'XPC_FLAGS': '0x0', 05:32:28 INFO - 'XPC_SERVICE_NAME': '0', 05:32:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:32:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:32:29 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 05:32:29 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 05:32:29 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 05:32:29 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 05:32:29 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 05:32:29 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 05:32:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:32:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:32:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:32:31 INFO - Installing collected packages: mozsystemmonitor 05:32:31 INFO - Running setup.py install for mozsystemmonitor 05:32:31 INFO - Successfully installed mozsystemmonitor 05:32:31 INFO - Cleaning up... 05:32:31 INFO - Return code: 0 05:32:31 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:32:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10acaf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac25570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffcc255e200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aac3d30>, '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 0x10a8e9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aabbb20>, '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.JQj5xAnJUM/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.PY0z2DJeqU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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 05:32:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:32:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:32:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JQj5xAnJUM/Render', 05:32:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:32:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:32:31 INFO - 'HOME': '/Users/cltbld', 05:32:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:32:31 INFO - 'LOGNAME': 'cltbld', 05:32:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:31 INFO - 'MOZ_NO_REMOTE': '1', 05:32:31 INFO - 'NO_EM_RESTART': '1', 05:32:31 INFO - 'PAGER': '/bin/cat', 05:32:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:32:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:31 INFO - 'PWD': '/builds/slave/test', 05:32:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:32:31 INFO - 'SHELL': '/bin/bash', 05:32:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PY0z2DJeqU/Listeners', 05:32:31 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 05:32:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:32:31 INFO - 'USER': 'cltbld', 05:32:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:32:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:32:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:31 INFO - 'XPC_FLAGS': '0x0', 05:32:31 INFO - 'XPC_SERVICE_NAME': '0', 05:32:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:32:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:32 INFO - Downloading/unpacking jsonschema==2.5.1 05:32:32 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 05:32:32 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 05:32:32 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 05:32:32 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 05:32:32 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 05:32:32 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 05:32:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:32:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:32:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:32:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:32:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:32:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:32:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:32:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:32:35 INFO - Downloading functools32-3.2.3-2.tar.gz 05:32:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:32:35 INFO - warning: no files found matching '*.txt' 05:32:35 INFO - no previously-included directories found matching 'build' 05:32:35 INFO - no previously-included directories found matching 'dist' 05:32:35 INFO - no previously-included directories found matching '.git*' 05:32:35 INFO - Installing collected packages: jsonschema, functools32 05:32:35 INFO - Running setup.py install for functools32 05:32:35 INFO - warning: no files found matching '*.txt' 05:32:35 INFO - no previously-included directories found matching 'build' 05:32:35 INFO - no previously-included directories found matching 'dist' 05:32:35 INFO - no previously-included directories found matching '.git*' 05:32:35 INFO - Successfully installed jsonschema functools32 05:32:35 INFO - Cleaning up... 05:32:35 INFO - Return code: 0 05:32:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:32:35 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')]} 05:32:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:35 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')]} 05:32:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:35 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 0x10acaf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac25570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffcc255e200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aac3d30>, '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 0x10a8e9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aabbb20>, '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.JQj5xAnJUM/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.PY0z2DJeqU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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 05:32:35 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 05:32:35 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 05:32:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JQj5xAnJUM/Render', 05:32:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:32:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:32:35 INFO - 'HOME': '/Users/cltbld', 05:32:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:32:35 INFO - 'LOGNAME': 'cltbld', 05:32:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:35 INFO - 'MOZ_NO_REMOTE': '1', 05:32:35 INFO - 'NO_EM_RESTART': '1', 05:32:35 INFO - 'PAGER': '/bin/cat', 05:32:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:32:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:35 INFO - 'PWD': '/builds/slave/test', 05:32:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:32:35 INFO - 'SHELL': '/bin/bash', 05:32:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PY0z2DJeqU/Listeners', 05:32:35 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 05:32:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:32:35 INFO - 'USER': 'cltbld', 05:32:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:32:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:32:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:35 INFO - 'XPC_FLAGS': '0x0', 05:32:35 INFO - 'XPC_SERVICE_NAME': '0', 05:32:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:32:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:35 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:32:35 INFO - Cleaning up... 05:32:35 INFO - Return code: 0 05:32:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:32:35 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')]} 05:32:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:35 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')]} 05:32:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:35 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 0x10acaf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac25570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffcc255e200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aac3d30>, '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 0x10a8e9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aabbb20>, '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.JQj5xAnJUM/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.PY0z2DJeqU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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 05:32:35 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 05:32:35 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 05:32:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JQj5xAnJUM/Render', 05:32:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:32:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:32:35 INFO - 'HOME': '/Users/cltbld', 05:32:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:32:35 INFO - 'LOGNAME': 'cltbld', 05:32:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:35 INFO - 'MOZ_NO_REMOTE': '1', 05:32:35 INFO - 'NO_EM_RESTART': '1', 05:32:35 INFO - 'PAGER': '/bin/cat', 05:32:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:32:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:35 INFO - 'PWD': '/builds/slave/test', 05:32:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:32:35 INFO - 'SHELL': '/bin/bash', 05:32:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PY0z2DJeqU/Listeners', 05:32:35 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 05:32:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:32:35 INFO - 'USER': 'cltbld', 05:32:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:32:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:32:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:35 INFO - 'XPC_FLAGS': '0x0', 05:32:35 INFO - 'XPC_SERVICE_NAME': '0', 05:32:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:32:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:36 INFO - Downloading/unpacking blobuploader==1.2.4 05:32:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:32:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:32:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:32:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:32:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:32:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:32:38 INFO - Downloading blobuploader-1.2.4.tar.gz 05:32:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:32:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:32:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:32:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:32:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:32:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:32:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:32:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:32:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:32:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:32:39 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 05:32:39 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 05:32:39 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 05:32:39 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 05:32:39 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 05:32:39 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 05:32:39 INFO - Downloading docopt-0.6.1.tar.gz 05:32:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:32:39 INFO - Installing collected packages: blobuploader, requests, docopt 05:32:39 INFO - Running setup.py install for blobuploader 05:32:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:32:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:32:39 INFO - Running setup.py install for requests 05:32:40 INFO - Running setup.py install for docopt 05:32:40 INFO - Successfully installed blobuploader requests docopt 05:32:40 INFO - Cleaning up... 05:32:40 INFO - Return code: 0 05:32:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:32:40 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')]} 05:32:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:40 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')]} 05:32:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:40 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 0x10acaf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac25570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffcc255e200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aac3d30>, '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 0x10a8e9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aabbb20>, '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.JQj5xAnJUM/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.PY0z2DJeqU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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 05:32:40 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 05:32:40 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 05:32:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JQj5xAnJUM/Render', 05:32:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:32:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:32:40 INFO - 'HOME': '/Users/cltbld', 05:32:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:32:40 INFO - 'LOGNAME': 'cltbld', 05:32:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:40 INFO - 'MOZ_NO_REMOTE': '1', 05:32:40 INFO - 'NO_EM_RESTART': '1', 05:32:40 INFO - 'PAGER': '/bin/cat', 05:32:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:32:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:40 INFO - 'PWD': '/builds/slave/test', 05:32:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:32:40 INFO - 'SHELL': '/bin/bash', 05:32:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PY0z2DJeqU/Listeners', 05:32:40 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 05:32:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:32:40 INFO - 'USER': 'cltbld', 05:32:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:32:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:32:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:40 INFO - 'XPC_FLAGS': '0x0', 05:32:40 INFO - 'XPC_SERVICE_NAME': '0', 05:32:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:32:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:32:41 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-orqreV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:32:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:32:41 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-QZ4FXg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:32:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:32:41 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-6ri_aN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:32:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:32:41 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-DFQ5k_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:32:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:32:41 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-36kF3Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:32:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:32:41 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-WYkH9p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:32:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:32:41 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-4hYmH5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:32:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:32:41 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-cy8SZh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:32:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:32:41 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-ZPTqDq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:32:42 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-vldH8K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:32:42 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-PqYRlA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:32:42 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-4np5oU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:32:42 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-46dOSY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:32:42 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-NUjPaC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:32:42 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-fsKyq5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:32:42 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-KGVOOZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:32:42 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-4Nw_8g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:32:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:32:42 INFO - Running setup.py install for manifestparser 05:32:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:32:43 INFO - Running setup.py install for mozcrash 05:32:43 INFO - Running setup.py install for mozdebug 05:32:43 INFO - Running setup.py install for mozdevice 05:32:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:32:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:32:43 INFO - Running setup.py install for mozfile 05:32:43 INFO - Running setup.py install for mozhttpd 05:32:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:32:43 INFO - Running setup.py install for mozinfo 05:32:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:32:43 INFO - Running setup.py install for mozInstall 05:32:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:32:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:32:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:32:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:32:44 INFO - Running setup.py install for mozleak 05:32:44 INFO - Running setup.py install for mozlog 05:32:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:32:44 INFO - Running setup.py install for moznetwork 05:32:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:32:44 INFO - Running setup.py install for mozprocess 05:32:44 INFO - Running setup.py install for mozprofile 05:32:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:32:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:32:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:32:44 INFO - Running setup.py install for mozrunner 05:32:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:32:45 INFO - Running setup.py install for mozscreenshot 05:32:45 INFO - Running setup.py install for moztest 05:32:45 INFO - Running setup.py install for mozversion 05:32:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:32:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:32:45 INFO - Cleaning up... 05:32:45 INFO - Return code: 0 05:32:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:32:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:45 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 0x10acaf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac25570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffcc255e200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aac3d30>, '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 0x10a8e9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aabbb20>, '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.JQj5xAnJUM/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.PY0z2DJeqU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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 05:32:45 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 05:32:45 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 05:32:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JQj5xAnJUM/Render', 05:32:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:32:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:32:45 INFO - 'HOME': '/Users/cltbld', 05:32:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:32:45 INFO - 'LOGNAME': 'cltbld', 05:32:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:45 INFO - 'MOZ_NO_REMOTE': '1', 05:32:45 INFO - 'NO_EM_RESTART': '1', 05:32:45 INFO - 'PAGER': '/bin/cat', 05:32:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:32:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:45 INFO - 'PWD': '/builds/slave/test', 05:32:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:32:45 INFO - 'SHELL': '/bin/bash', 05:32:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PY0z2DJeqU/Listeners', 05:32:45 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 05:32:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:32:45 INFO - 'USER': 'cltbld', 05:32:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:32:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:32:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:45 INFO - 'XPC_FLAGS': '0x0', 05:32:45 INFO - 'XPC_SERVICE_NAME': '0', 05:32:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:32:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:32:45 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-JpvHBJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:32:45 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)) 05:32:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:32:45 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-bgM6rg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:32:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:32:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:32:45 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-gAEw9d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:32:46 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)) 05:32:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:32:46 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-tQhel6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:32:46 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)) 05:32:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:32:46 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-3wSYdL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:32:46 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)) 05:32:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:32:46 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-ZD6udR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:32:46 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)) 05:32:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:32:46 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-TMopPH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:32:46 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)) 05:32:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:32:46 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-3okwUe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:32:46 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)) 05:32:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:32:46 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-14zWS6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:32:46 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)) 05:32:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:32:46 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-bFYiOB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:32:46 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)) 05:32:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:32:46 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-zDUfc7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:32:46 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)) 05:32:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:32:46 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-SRqhne-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:32:47 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)) 05:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:32:47 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-aYGMLW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:32:47 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)) 05:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:32:47 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-ovTK74-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:32:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:32:47 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-X3tPfw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:32:47 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)) 05:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:32:47 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-pLYFxV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:32:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:32:47 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-tX34o0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:32:47 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)) 05:32:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:32:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:32:47 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)) 05:32:47 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)) 05:32:47 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)) 05:32:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:32:47 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 05:32:47 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 05:32:47 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 05:32:47 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 05:32:47 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 05:32:47 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 05:32:50 INFO - Downloading blessings-1.6.tar.gz 05:32:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:32:50 INFO - Installing collected packages: blessings 05:32:50 INFO - Running setup.py install for blessings 05:32:50 INFO - Successfully installed blessings 05:32:50 INFO - Cleaning up... 05:32:50 INFO - Return code: 0 05:32:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:32:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:32:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:32:50 INFO - Reading from file tmpfile_stdout 05:32:50 INFO - Current package versions: 05:32:50 INFO - blessings == 1.6 05:32:50 INFO - blobuploader == 1.2.4 05:32:50 INFO - docopt == 0.6.1 05:32:50 INFO - functools32 == 3.2.3-2 05:32:50 INFO - jsonschema == 2.5.1 05:32:50 INFO - manifestparser == 1.1 05:32:50 INFO - mozInstall == 1.12 05:32:50 INFO - mozcrash == 0.17 05:32:50 INFO - mozdebug == 0.1 05:32:50 INFO - mozdevice == 0.48 05:32:50 INFO - mozfile == 1.2 05:32:50 INFO - mozhttpd == 0.7 05:32:50 INFO - mozinfo == 0.9 05:32:50 INFO - mozleak == 0.1 05:32:50 INFO - mozlog == 3.4 05:32:50 INFO - moznetwork == 0.27 05:32:50 INFO - mozprocess == 0.23 05:32:50 INFO - mozprofile == 0.28 05:32:50 INFO - mozrunner == 6.12 05:32:50 INFO - mozscreenshot == 0.1 05:32:50 INFO - mozsystemmonitor == 0.3 05:32:50 INFO - moztest == 0.7 05:32:50 INFO - mozversion == 1.4 05:32:50 INFO - psutil == 3.1.1 05:32:50 INFO - requests == 1.2.3 05:32:50 INFO - wsgiref == 0.1.2 05:32:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:32:50 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')]} 05:32:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:50 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')]} 05:32:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:50 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 0x10acaf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac25570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffcc255e200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aac3d30>, '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 0x10a8e9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aabbb20>, '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.JQj5xAnJUM/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.PY0z2DJeqU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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 05:32:50 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 05:32:50 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 05:32:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JQj5xAnJUM/Render', 05:32:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:32:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:32:50 INFO - 'HOME': '/Users/cltbld', 05:32:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:32:50 INFO - 'LOGNAME': 'cltbld', 05:32:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:50 INFO - 'MOZ_NO_REMOTE': '1', 05:32:50 INFO - 'NO_EM_RESTART': '1', 05:32:50 INFO - 'PAGER': '/bin/cat', 05:32:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:32:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:50 INFO - 'PWD': '/builds/slave/test', 05:32:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:32:50 INFO - 'SHELL': '/bin/bash', 05:32:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PY0z2DJeqU/Listeners', 05:32:50 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 05:32:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:32:50 INFO - 'USER': 'cltbld', 05:32:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:32:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:32:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:50 INFO - 'XPC_FLAGS': '0x0', 05:32:50 INFO - 'XPC_SERVICE_NAME': '0', 05:32:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:32:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:50 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)) 05:32:50 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)) 05:32:50 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)) 05:32:50 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)) 05:32:50 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)) 05:32:50 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)) 05:32:50 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)) 05:32:50 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)) 05:32:50 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)) 05:32:50 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)) 05:32:50 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)) 05:32:50 INFO - Cleaning up... 05:32:51 INFO - Return code: 0 05:32:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:32:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:51 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 0x10acaf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac25570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffcc255e200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aac3d30>, '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 0x10a8e9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aabbb20>, '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.JQj5xAnJUM/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.PY0z2DJeqU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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 05:32:51 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 05:32:51 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 05:32:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JQj5xAnJUM/Render', 05:32:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:32:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:32:51 INFO - 'HOME': '/Users/cltbld', 05:32:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:32:51 INFO - 'LOGNAME': 'cltbld', 05:32:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:51 INFO - 'MOZ_NO_REMOTE': '1', 05:32:51 INFO - 'NO_EM_RESTART': '1', 05:32:51 INFO - 'PAGER': '/bin/cat', 05:32:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:32:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:51 INFO - 'PWD': '/builds/slave/test', 05:32:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:32:51 INFO - 'SHELL': '/bin/bash', 05:32:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PY0z2DJeqU/Listeners', 05:32:51 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 05:32:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:32:51 INFO - 'USER': 'cltbld', 05:32:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:32:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:32:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:51 INFO - 'XPC_FLAGS': '0x0', 05:32:51 INFO - 'XPC_SERVICE_NAME': '0', 05:32:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:32:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:51 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:32:51 INFO - Cleaning up... 05:32:51 INFO - Return code: 0 05:32:51 INFO - Running post-action listener: _resource_record_post_action 05:32:51 INFO - Running post-action listener: _start_resource_monitoring 05:32:51 INFO - Starting resource monitoring. 05:32:51 INFO - [mozharness: 2016-10-16 12:32:51.299306Z] Finished create-virtualenv step (success) 05:32:51 INFO - [mozharness: 2016-10-16 12:32:51.300303Z] Running install step. 05:32:51 INFO - Running pre-action listener: _resource_record_pre_action 05:32:51 INFO - Running main action method: install 05:32:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:32:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:32:51 INFO - Reading from file tmpfile_stdout 05:32:51 INFO - Detecting whether we're running mozinstall >=1.0... 05:32:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:32:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:32:51 INFO - Reading from file tmpfile_stdout 05:32:51 INFO - Output received: 05:32:51 INFO - Usage: mozinstall [options] installer 05:32:51 INFO - Options: 05:32:51 INFO - -h, --help show this help message and exit 05:32:51 INFO - -d DEST, --destination=DEST 05:32:51 INFO - Directory to install application into. [default: 05:32:51 INFO - "/builds/slave/test"] 05:32:51 INFO - --app=APP Application being installed. [default: firefox] 05:32:51 INFO - mkdir: /builds/slave/test/build/application 05:32:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:32:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:33:26 INFO - Reading from file tmpfile_stdout 05:33:26 INFO - Output received: 05:33:26 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:33:26 INFO - Running post-action listener: _resource_record_post_action 05:33:26 INFO - [mozharness: 2016-10-16 12:33:26.104365Z] Finished install step (success) 05:33:26 INFO - [mozharness: 2016-10-16 12:33:26.104521Z] Running run-tests step. 05:33:26 INFO - Running pre-action listener: _resource_record_pre_action 05:33:26 INFO - Running main action method: run_tests 05:33:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:33:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:33:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:33:26 INFO - 2016-10-16 05:33:26.119 screenresolution[1959:33130] starting screenresolution argv=screenresolution get 05:33:26 INFO - 2016-10-16 05:33:26.121 screenresolution[1959:33130] Display 0: 1600x1200x32@60 05:33:26 INFO - 2016-10-16 05:33:26.127 screenresolution[1960:33132] starting screenresolution argv=screenresolution list 05:33:26 INFO - Available Modes on Display 0 05:33:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:33:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:33:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:33:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:33:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:33:26 INFO - Intel Iris: 05:33:26 INFO - Chipset Model: Intel Iris 05:33:26 INFO - Type: GPU 05:33:26 INFO - Bus: Built-In 05:33:26 INFO - VRAM (Dynamic, Max): 1536 MB 05:33:26 INFO - Vendor: Intel (0x8086) 05:33:26 INFO - Device ID: 0x0a2e 05:33:26 INFO - Revision ID: 0x0009 05:33:26 INFO - Displays: 05:33:26 INFO - Display: 05:33:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:33:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:33:26 INFO - Display Serial Number: 1600x1200 60 05:33:26 INFO - Main Display: Yes 05:33:26 INFO - Mirror: Off 05:33:26 INFO - Online: Yes 05:33:26 INFO - Rotation: Supported 05:33:26 INFO - Return code: 0 05:33:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:33:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:33:26 INFO - Python 2.7.3 05:33:26 INFO - Return code: 0 05:33:26 INFO - grabbing minidump binary from tooltool 05:33:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:33:26 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 0x10aac3d30>, '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 0x10a8e9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aabbb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:33:26 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 05:33:26 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 05:33:26 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 05:33:26 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:33:26 INFO - Return code: 0 05:33:26 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:33:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:33:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:33:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:33:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:33:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DhtNfV_WTBel5DDtM-2kLQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0115', '--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 05:33:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/DhtNfV_WTBel5DDtM-2kLQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0115 --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 05:33:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JQj5xAnJUM/Render', 05:33:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:33:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:33:26 INFO - 'HOME': '/Users/cltbld', 05:33:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:33:26 INFO - 'LOGNAME': 'cltbld', 05:33:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:33:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:33:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:33:26 INFO - 'MOZ_NO_REMOTE': '1', 05:33:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:33:26 INFO - 'NO_EM_RESTART': '1', 05:33:26 INFO - 'PAGER': '/bin/cat', 05:33:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:33:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:33:26 INFO - 'PWD': '/builds/slave/test', 05:33:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:33:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:33:26 INFO - 'SHELL': '/bin/bash', 05:33:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PY0z2DJeqU/Listeners', 05:33:26 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 05:33:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:33:26 INFO - 'USER': 'cltbld', 05:33:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:33:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:33:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:33:26 INFO - 'XPC_FLAGS': '0x0', 05:33:26 INFO - 'XPC_SERVICE_NAME': '0', 05:33:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:33:26 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DhtNfV_WTBel5DDtM-2kLQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0115', '--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 05:33:26 INFO - mozversion application_buildid: 20161016030205 05:33:26 INFO - mozversion application_changeset: a1768c915756429b74f73fac2e8dd4e7b4919730 05:33:26 INFO - mozversion application_display_name: Nightly 05:33:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:33:26 INFO - mozversion application_name: Firefox 05:33:26 INFO - mozversion application_remotingname: firefox 05:33:26 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:33:26 INFO - mozversion application_vendor: Mozilla 05:33:26 INFO - mozversion application_version: 52.0a1 05:33:26 INFO - mozversion platform_buildid: 20161016030205 05:33:26 INFO - mozversion platform_changeset: a1768c915756429b74f73fac2e8dd4e7b4919730 05:33:26 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:33:26 INFO - mozversion platform_version: 52.0a1 05:33:26 INFO - using testdate: 1476621206 05:33:26 INFO - actual date: 1476621206 05:33:26 INFO - starting webserver on 'localhost:56566' 05:33:26 INFO - SUITE-START | Running 2 tests 05:33:26 INFO - TEST-START | dromaeo_css 05:33:26 INFO - operating with platform_type : mac_ 05:33:26 INFO - Initialising browser for dromaeo_css test... 05:33:26 INFO - TEST-INFO | started process 1970 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/tmplMKpbv/profile http://localhost:56566/getInfo.html) 05:33:29 INFO - PROCESS | 1970 | __metrics Screen width/height:1600/1200 05:33:29 INFO - PROCESS | 1970 | colorDepth:24 05:33:29 INFO - PROCESS | 1970 | Browser inner width/height: 1024/689 05:33:29 INFO - PROCESS | 1970 | __metrics 05:33:29 INFO - PROCESS | 1970 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:33:29 INFO - PROCESS | 1970 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:33:30 INFO - TEST-INFO | 1970: exit 0 05:33:30 INFO - Browser initialized. 05:33:30 INFO - Running cycle 1/1 for dromaeo_css test... 05:33:30 INFO - TEST-INFO | started process 1972 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/tmplMKpbv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:33:42 INFO - PROCESS | 1972 | RSS: Main: 232153088 05:33:42 INFO - PROCESS | 1972 | 05:34:32 INFO - PROCESS | 1972 | 1476621272895 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:36:47 INFO - PROCESS | 1972 | Cycle 1(1): loaded http://localhost:56566/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56566/tests/dromaeo/cssquery-ext.html) 05:36:49 INFO - PROCESS | 1972 | RSS: Main: 413679616 05:36:49 INFO - PROCESS | 1972 | 05:39:52 INFO - PROCESS | 1972 | Cycle 1(1): loaded http://localhost:56566/tests/dromaeo/cssquery-ext.html (next: http://localhost:56566/tests/dromaeo/cssquery-jquery.html) 05:39:52 INFO - PROCESS | 1972 | RSS: Main: 372203520 05:39:52 INFO - PROCESS | 1972 | 05:42:55 INFO - PROCESS | 1972 | Cycle 1(1): loaded http://localhost:56566/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56566/tests/dromaeo/cssquery-mootools.html) 05:42:55 INFO - PROCESS | 1972 | RSS: Main: 340811776 05:42:55 INFO - PROCESS | 1972 | 05:45:58 INFO - PROCESS | 1972 | Cycle 1(1): loaded http://localhost:56566/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56566/tests/dromaeo/cssquery-prototype.html) 05:45:58 INFO - PROCESS | 1972 | RSS: Main: 333508608 05:45:58 INFO - PROCESS | 1972 | 05:49:02 INFO - PROCESS | 1972 | Cycle 1(1): loaded http://localhost:56566/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56566/tests/dromaeo/cssquery-yui.html) 05:49:02 INFO - PROCESS | 1972 | RSS: Main: 328306688 05:49:02 INFO - PROCESS | 1972 | 05:52:05 INFO - PROCESS | 1972 | Cycle 1(1): loaded http://localhost:56566/tests/dromaeo/cssquery-yui.html (next: http://localhost:56566/tests/dromaeo/cssquery-dojo.html) 05:52:06 INFO - PROCESS | 1972 | RSS: Main: 512798720 05:52:06 INFO - PROCESS | 1972 | 05:52:06 INFO - PROCESS | 1972 | __start_tp_report 05:52:06 INFO - PROCESS | 1972 | _x_x_mozilla_page_load 05:52:06 INFO - PROCESS | 1972 | _x_x_mozilla_page_load_details 05:52:06 INFO - PROCESS | 1972 | |i|pagename|runs| 05:52:06 INFO - PROCESS | 1972 | |0;dojo.html;3235.854386552605;3263.053714422816;3269.7057264846158;3283.8850640227565;3324.4348460761667;5428.212909128174;5454.100073487874;5511.448855114487;5515.779368825247;5550.833474995747;5523.061079616465;5534.667919924797;5610.66336019838;5616.550675945925;5660.037793575081;5041.521055499722;5050.393952725678;5054.241863720435;5114.565261952738;5116.513931176532;5128.640995130342;5174.974125129369;5218.008578370116;5239.633225674204;5295.099833028395;5163.948360516384;5208.713520756357;5209.400918894337;5247.606429517782;5285.973570132163;4929.975350123263;5031.396232452093;5072.391313042459;5077.771500282459;5110.514501122388;5048.29323894655;5086.389633243997;5097.133487307166;5112.769925353331;5114.105031619451;5505;5516.834494965157;5530.972345138289;5545.750441230154;5548.334199896039;5840.474357307863;5988;6021.126936594169;6083.878322433571;6095.725692343855;5904.143899134644;5986.132010858445;6021.909671354933;6056.4851987581105;6062.848428789272;5488.067028605146;5538.141588053858;5551.750171242223;5561.4716601922755;5574.10814269719;6105.358937311607;6123.724432400552;6124.74712746768;6125.632462052291;6131.289237556246;5953.702314884238;6004.579679422399;6011.609245399035;6021.5182785377065;6026.608270462406;6110.297315808739;6114.5414093942245;6128.1114238435175;6133.754649814046;6134.601250918676;5944.989351810203;5985.790497332572;6043.425874541911;6054.636721796706;6071.2714474263375;6014.729337179838;6030;6048.909266361008;6084.908726369108;6089.604175728652;4789.712617242976;4856.635752318591;4870.220764677634;4875.878103047452;4909.950900491021;4367.366731823868;4412.823487060546;4412.955870441318;4414.161309351213;4427.317619304644;5537.889242215214;5587.357453892854;5595.776168953278;5680.062789639664;5688.0330343840815;6096.556923351615;6097.9179172227305;6118.174046503665;6130.29501607312;6137.324894261627;5658.85852853682;5671.404502527258;5677.432256774372;5682.829515114553;5686.147077938343;5265.236540701577;5308.893822123613;5319.962607291618;5327.920081198785;5328.067588172132;6151.446369826737;6186.876262474725;6194.876102477835;6217.067439884054;6217.502599792097;6081.8968076473475;6100.187591190282;6119.2350956130485;6131.785387511416;6136;6116.293191840968;6145.310039738929;6169.938300617116;6176.938230617637;6201.751929922752;6081.047468385937;6088.543359248163;6093.177421048102;6115.392460733998;6121.173641558333;5990.1314309424015;6006.429389208019;6135.294441139326;6147.385261473817;6168.105624684305;5743.052396354555;5879.911801322898;5943.524518038633;5949.137375080502;5963.2844058713235;5989.3112292085225;6004.42957918997;6019.458248757633;6023.1266466363;6069.696515174135;5797.101449275369;5822.70886455667;5830.300363956352;5840.2115714378015;5847.210626565359;5679.744411501575;5795.449432303924;5974.2233509643465;5979.252593425822;6005.519558435402;5675.460831221028;5692.117721753135;5700.771969121189;5744.741486633196;5812.883742325214 05:52:06 INFO - PROCESS | 1972 | |1;ext.html;492.98937178783495;502.9396472423309;511.5600583498193;513.3403576404321;525.7949399734105;30914.690853091462;40758.38862417062;42205;42364.729058128265;44439.77780111095;43846.68459946199;44214.557854421444;44294.33558496617;44370;44579.55420445795;9397.81204375912;9651.324407291493;9653;9672.419654820698;9672.613095476172;8577.871331929995;8796.604152813106;8809.95595022024;8907.910920890772;8934.821303573925;11477.311361318305;11841.881581184207;11842;12042;12045.698857528545;11742.471588778482;11804.40977951099;11805.173637845353;11816.172867899251;11830.526778928832;11489.597864074802;11528.481218345194;11617.302961822317;11618.70953226176;11631.47658355376;41787;42024.57975420268;42077.368839467126;42084.368734469004;42175.57824421778;3684.797336146538;3999.2201520703475;3999.7000224983244;4041.898952526181;4050.797460126982;29603.555946660817;31522;32187;32215.51676724851;32493.350132997206;3082.830444325584;3187.681231876817;3231.4991176367466;3258;3337.3158502506926;456938;462794;465769;468334;474345;60416.39583604196;62040.68979655073;63004.73990520256;63412.68293658502;63459.36540634534;35033.29933401318;35634.64365356365;35748.285034299166;36226.637733622854;36675.899723008355;16589.253483593267;16709.248083836257;16724.6655066898;16746.162691865356;16748.24632891523;12775.552855650008;12850.485980560672;12862.871371286168;12872.356382180853;12890.419931103123;17261.101278859565;17766.555836103944;19357.804059564038;19664.311749088713;19903.701444478345;6840.973853921852;7117.832564508847;7779.688812447439;7909.406794490436;8224.794380140544;864.4942708515589;868.86966954956;992.4541502173776;1017.5726194998118;1055.2876808154465;29438.558421623693;29910.25224369408;30702;31021.224469387995;31098.067057988297;1058.6823952814125;1089.2266490791626;1097.8078836203633;1100.6697990602788;1126.0878688262533;16008.039517628747;16091.919540402458;16278.593035173979;16366.754498682529;16691.666166676598;666796;674863;676344.6182769153;676475;676809;51517.227241591405;52726;52774.472255277724;58864.411355885895;60857;1397.1756663568544;1411.5906387147613;1429.3210724905587;1429.7354989327007;1430.8497607751037;25537.744622553535;31124.308273721257;31563.264020478175;32259.032229033168;32283.031509054767;44317;53679.731601341744;54341.641458963844;54388.45611543834;54396.728016361245;479530;652575;652893.6527616526;668463;856775;31783.298419633666;34076.488852666735;41989;42634.78682606567;42852.78573607112;47445.81385465391;47518.287225690954;47695;48039.519604804904;48326.51673483361;34661.30677386488;39304.606953930095;39568;40402.59597403988;41225;38640.22719545537;39168;39186.02034949149;39918.60081399265;40092.39861402023;38443.615563845124;38913.805430972665;39217;39471.013224668466;39573.60426395814;33536.16159596029;33964;35130;35216.64783352134;35406.11484712903;23264.883675581514;24285.63571546393;25101.372465688502;25343.36641583975;25809.870950645127 05:52:06 INFO - PROCESS | 1972 | |2;jquery.html;333.7130068141399;358.3656927238789;371.2463698691656;380.4445509556048;412.0666689947268;14159.362828672722;15107.471238506654;20012.49968750782;20183.798162018375;20510.410678131306;19717.211311547522;19751.604967900632;20188.69716954247;20232.898835505803;20444.28445004425;5715.685637289957;6000.279966404038;6042.637441753487;6064.787732429366;6068.635881847081;5728.713564321788;5918.1418694289305;5971.253593300838;6065.60573562717;6107.389261073879;6105.572609917308;6128.509719222474;6132.877342453148;6141.662208578527;6165;5997.37027612101;6593.307702691221;6662.20053593567;6667.433268172163;6696.330366963313;5693.174489699012;5870.178175055496;5955.315138759055;5956.463918247336;5971.313298970631;13203.89708418521;13399.53101641438;13441.932790336083;13462.711339072282;18631.53421164468;2317.165820304689;2324.070371851256;2330.7203135623836;2342.1802369170823;2345.1557439322;9866.60533578656;9913.603455861758;9979.80040399188;10428.322159059713;10455.58177672892;1712.135371637315;1770.9203085861168;1829.2042961311738;1879.1731638079202;1906.65680177568;116526;120652;122707;124305.5138897217;124444;10833.78332433347;11206.103511719042;11208.943955280172;11290.073329644367;11577.247478913843;12705;13246.933765331112;13281.933590331986;13315.40080696371;14166.008379413548;8462.661493540189;8608.139186081342;8696.434731742416;8771.868421973675;8774.780630484162;6928.930710692929;8423.452475589067;8425.747227583182;8504.574771261412;8690.478571285743;4156.397322388237;4329.675274354436;4337.1976184405985;4512.819487220474;4538.841140560065;1762.3250457545055;1825.2516468247954;1832.9541761455803;1843.5557407710737;1939.3890924358782;854.8504011798035;902.2466240689098;943.2312665177858;980.7352014955922;1004.5931397783909;19463.416097517096;20977.37067887966;21097.789022109584;21152;21183.470413239484;941.449252187478;956.550421301987;1030.2221822523945;1053.1048608682559;1068.625980906692;10791.730206744895;11286.604968826052;11317.320960742381;11339.886601133883;11371.374574398487;491925;492344;496311;534875;594444;133212;140812.591874082;140965;141964.8705269422;143208;998.1116806042628;1005.406809982114;1009.2632378363688;1020.7346090016649;1025.0620682075967;18642.627147457246;18996.71504927455;18997.525061873563;19063.809361906202;19086.33197838069;51324.48675513197;55843.72078139583;56070.71964640151;57965.420345796;58294;76766.0910518056;81533;82034;82230;84291.73562396446;6459.354064593691;7676.961615191888;7897;8055.275025247756;8269.503829770232;11847.526098955945;12789;12984.091113621957;13168.144070635379;13188.472461101448;10862.34825910448;11132.220744548127;11329.376884271443;11339.886601133883;11481.770364592829;10983.670489885317;11101.777964440504;11197;11298.717532061764;11465.312081274817;8892;11384.260023098472;11465.426728663366;11678.6496405108;11681.941590291994;7028.996735930235;7800.726974555863;8076.83846323082;8077.111517733162;8085.070216925129;10448.962452321724;11238.943805280922;11361.431928403712;11385.430728463709;11434.371109589052 05:52:06 INFO - PROCESS | 1972 | |3;mootools.html;1528.7248295306845;1549.1092621742498;1639.1722180298948;1758.8680848936324;1924.3168675120332;8217.506949583028;8730.432521886072;8738.082501337356;8746.387752857287;8750;7663.5785031823225;7908.209179082089;7913;8246.175382461752;8565.614547345369;2353.976460235401;2387.9641805372935;2395.688560487134;2399.892004859777;2405;2354.7409784923643;2379.2624286471164;2382.5711371953043;2400.7239167495786;2402.1232250228627;2358.50471401006;2374.7269064057596;2383.940401489968;2386.4033991502124;2394.0663141374876;2342.7188737351457;2354.75275096115;2358.6226203807482;2359.9528009439714;2364.9645255321184;2357.398863289867;2362.102401087593;2363.468219650582;2363.787259146685;2379.7501262367377;8546.401751877309;8606.5696715164;8684.392092553524;8688.218060374596;8694.130586941319;5148.150555158394;5310.123829568117;5315.3621565412395;5322.20166974953;5324;3428.8971330859863;3518.313928783888;3588.6411358864166;3650.123970247148;3651.415773476231;1065.898739619735;1066.653337665262;1067.412922892414;1082.696844883426;1087.6791346552707;11114.277571957879;11120.55517779296;11251.60619378326;11264.774704505946;11275.985161335519;4504.25679762842;4630.166570017362;4753.952460475421;4754.690945088558;4769.928451073237;9664.613415463302;9666.75833104178;9667.081627245401;9675.90324096764;9706.563204655808;5307.9734601326745;5308.256844041837;5313.123334649817;5316.282301889196;5339.679619222781;4201.075763332062;4267.76527291002;4787.54518320759;4824.421069471686;4887.9490909454635;3877.205172939597;3882.8446862125766;3988.7606743594897;3988.9883138230452;4015.8393664253103;1227.6439832448668;1236.863944966053;1245.2092920995233;1270.136307311019;1294.58573256557;792.1247022040677;872.8559787634969;877.5217506458995;905.800723840754;918.2883265469181;5173.301604283373;5432.837014889559;5904.911426328608;6023.789167379121;6076.54425918049;871.4074429388077;956.0439560439561;956.067833861979;957.3107362698846;958.7603099225194;3834.731568790158;3859.9421008684894;3869.28418242626;3963.7027222958395;3984.521857377133;10039.749006274902;10528.420936848393;10537.736556586147;10540.420276884692;10569.471526423647;10477.42374169428;10485.161187105014;10494.790104197873;10521.368717876952;10544.103751181083;7631;8012.879806802903;8048.235417635316;8209.548474833822;8216.917830821616;5354.544863686475;5577.693226872561;5964.124003788908;6016.338202797776;6054.243219597551;9421.623135074795;9454.858177127211;9562.18721408688;9675.177609903238;9748.805023899624;9787.36382135159;9933.056359645821;9981.750456238362;10040.397576145455;10085.848712269468;6872.140982377203;6977.755778547727;7119.572825630479;7283.417326613752;7452.888206677013;8206.712765053195;8333.250007499111;8411.579421029046;8437.451565648213;8457.788555286166;7358.374538164316;7455.850882982201;7462;7518.8496230074;7616.428767842323;7256.9274307256255;7288.198298187302;7308.853822923618;7404.481686281909;7464.992226049414;6835.760748373783;7000.824979375557;7007.509474336747;7015.789526314219;7056.682449289691;4098.6516146127915;4217.725847820005;4888.633352498506;5017.874553136201;5080.263361812443;4310.655147588248;4913.533214344631;4975.825846095369;5028.823991160292;5201.42784293735 05:52:06 INFO - PROCESS | 1972 | |4;prototype.html;254.97960163186946;301.56574532672937;312.64670921858294;315.39129480103406;320.624868903383;4193.182329445759;4376.868693939187;5321;5387.622866399353;5435.315052333769;4751.40607424072;5050.676688723192;5078.74606269686;5208.374995000596;5364.839054828351;3490.2321489272317;4010.9598904011023;4024.215278020787;4029.959700402995;4042.7372220805555;3699.7595156314887;4014.8594799182033;4026.7583944963394;4029.1941611677635;4103.974006498375;3794.0704527390826;3996.2606917720314;4022.612198791412;4040.616141466556;4050.655694265991;4007.73949693269;4020.296448121567;4030.2544029354663;4087.7547347159116;4122.91754164918;2927.92680182995;3084.9511166203597;3359.7648164628486;3479.1302174456387;3520.436730123168;4253.978730106361;4453.465584129925;5049.343585333883;5153.8919132386345;5218.269442278084;4230.725002874803;4563.612092972101;4566.862994110182;4589.646054413923;4619.284010978304;5225.033368826779;5473.7263136843;5590.692511911843;5604.523615492688;5610.943890561083;3772.8868133956025;3777.9244415111543;3785.5268091488565;3811.6950643948417;3817.6945844332386;5643.858903527444;5856.560757943172;5884.2350494436305;5898.525368657835;5909.586328956974;3847.5960024197057;3860.397934857059;3869.245497128033;3880.20455806559;3890.8054597270025;3873.663586062804;3900.9077458311717;3911.7848518331766;3912.5109361329833;3953.5453422856162;3948.3485224937563;4013.2776100301644;4277.465316835395;4486.842960496367;4595.563421474954;4712.552307530779;4894.902101957941;5125.897482050413;5638.633488823295;5646.153077038477;1151.3034614058374;1260.7541529401863;1336.9438143866432;1340.4973135074347;1341.3494455189225;831.8003679116969;851.199872120205;888.4447220487195;890.8218356328761;898.9661888827769;4165.042040330741;5202.4277329493725;5314.0700377433795;5318.989392015505;5389.164679474688;5479.91763130016;5729.140628905698;5768.163616275617;5826.902315166537;5853.30747855991;4287;5018.673786203885;5188.870278242998;5199;5200.557952574073;2670.7329267073137;2751.614773931651;2937.3831495386207;2978.74680652147;2989.566512855624;5648.119459075819;5844.853878653068;5875.823725288248;5909.320428150818;5913.344263606197;4809.374781278411;5692.430756924275;5763.85869701014;5779.994402910547;5853.853653658607;4922.680025798311;4977.776000079933;5841.2698412699265;5853.30747855991;5855.824325270163;4746.5490778376;4872.120532934878;5367.19635925852;5551.8889622208135;5642.85892852682;4525.162844873709;4673.369095172245;4933.9013219736125;5228.424873264014;5340.825018495924;5028.9245661315845;5349.903269829598;5824.5631577631;5853.09277062056;5873.9412605873395;3340.398728228942;3585.229175727231;3710.0336821484657;3759.4548790424687;3784.4512545681273;4662.691967476121;5573.160856917289;5613.214150019082;5686.118651609007;5688.886222275449;4272.487301523837;4875.536824001714;5123.513266239701;5404.027275090364;5540;4787.3537072496165;5107.012397747211;5164.741762911764;5643.319423273912;5646.971765141148;5219.982103489783;5416.783328666809;5434.809781657623;5505.476979686923;5525.557955363642;4396.956030439655;5015.699058056528;5300.522952934254;5478.698671573102;5509.3664228614225;5263.841954769944;5518.117101263779;5546.72266386671;5583.218349431164;5583.609147359646 05:52:06 INFO - PROCESS | 1972 | |5;yui.html;589.3666780386748;743.3607097895812;775.11249619186;807.1121766057335;825.562748708202;15048.473303434383;15686.607834804136;15730.134842583653;15739;15753.763693544606;13845.376958036888;13891.652708682263;13905.652358691037;13907.235102069382;13917.512887048955;703.152700995302;726.6475759256756;873.2010210657257;894.025512191711;923.5659240157149;910.804177101923;913.5888850017485;914.2868562521211;914.3371055984392;916.4134953629682;982.2485798364257;982.9606815727398;983.6360546597768;984;984.3257368702461;745.5414919824296;769.2653515892309;919.7516670498928;933.6358820060182;971.6987733802508;711.989320160198;712.0209711646486;712.2272334517055;712.5226098514008;714.1893950366319;14747.410103595843;14908.925455372762;14950.102993820406;15010.399584016626;15016;13545.187288762705;13782.379792909343;13833.79249311261;13859.861401386059;14005;10378.948105259426;12298.323592202514;12305.753884922251;12471.376431178405;12489.625311240678;4342.6742994275555;4361.629261512775;4364.105358401552;4375.540568240321;4532.637389008872;110339.44830275797;110712.89287107106;111006;111542;111621;10648.893511064945;10801.351918884891;10871.782564348669;10949.178811589003;11319.943400282946;20823;21387.465313366978;21536.784632153478;21537.138514459246;21580.56838863214;7683.807904802425;7689.769306920802;7717.1896950819355;7745.806354841061;7864.646090925922;4288.614024737789;4294.699371044059;4356.651467882625;4389.648828093745;4484.103179364075;3340.8496617652263;3449.9827500862334;3453.102193429676;3453.4992426098074;3460.2041530447636;630.8012975912573;649.6491894377078;652.083822229768;665.8867992441296;672.5325898500495;546.368943869831;606.6504300400615;610.3774150366601;618;627.1752645271454;8681;8945.865812012826;9804.669503574183;9832.950835245778;10314.908742361938;620.2340109964191;627.9654618995908;631.4466412624994;670.1924181361447;692.2489099327186;4494.1461122386645;4639.304104384302;4650.116477869194;4704.623630109583;4731.574158325767;126797.36601316935;130070;131982.34008829895;141129.5887041137;148445.25777371044;124498;125023.37488312682;125583;125852;133082;100705;102098.74815022097;103020.48489757504;103748.44377334346;110790;8696.695615653422;8874.689385871463;8897.332700047393;8956.026715675298;9089.954550227207;44616.10767784561;54659.72670136624;56659.716701416226;57952.71023644855;59018.22945311647;72314.68381136237;87313;89171;91303;94819;11229.831552526883;11350.205485615928;11351.716207094889;11915.76168476643;12076.879231207575;13022.869771302545;13384.397702103233;13785;14442.855571444152;15037.624059398166;12226.510939562673;12417.751644967231;12450.214633025204;14197.858021419654;14424.711505769616;13982.370793314529;14231.003829731819;14313.069650472686;14482.275886205858;14964.625884352978;12592.685182870502;13780.242086684928;13832.170069795844;14045.183079620301;14562.708745825235;8256.917430825615;11321.377324247245;11780.52877884875;11888.940555297515;12092.516299347928;10284.2801003929;10503.264771465923;11271.436428178722;11645.6506304811;11672.533098676298 05:52:06 INFO - PROCESS | 1972 | __end_tp_report 05:52:06 INFO - PROCESS | 1972 | __start_cc_report 05:52:06 INFO - PROCESS | 1972 | _x_x_mozilla_cycle_collect,1854 05:52:06 INFO - PROCESS | 1972 | __end_cc_report 05:52:06 INFO - PROCESS | 1972 | __startTimestamp1476622326248__endTimestamp 05:52:06 INFO - PROCESS | 1972 | 05:52:06 INFO - PROCESS | 1972 | ------- Summary: start ------- 05:52:06 INFO - PROCESS | 1972 | Number of tests: 6 05:52:06 INFO - PROCESS | 1972 | 05:52:06 INFO - PROCESS | 1972 | [#0] dojo.html Cycles:165 Average:5618.55 Median:5797.10 stddev:610.04 (10.5%) stddev-sans-first:582.57 05:52:06 INFO - PROCESS | 1972 | Values: 3235.9 3263.1 3269.7 3283.9 3324.4 5428.2 5454.1 5511.4 5515.8 5550.8 5523.1 5534.7 5610.7 5616.6 5660.0 5041.5 5050.4 5054.2 5114.6 5116.5 5128.6 5175.0 5218.0 5239.6 5295.1 5163.9 5208.7 5209.4 5247.6 5286.0 4930.0 5031.4 5072.4 5077.8 5110.5 5048.3 5086.4 5097.1 5112.8 5114.1 5505.0 5516.8 5531.0 5545.8 5548.3 5840.5 5988.0 6021.1 6083.9 6095.7 5904.1 5986.1 6021.9 6056.5 6062.8 5488.1 5538.1 5551.8 5561.5 5574.1 6105.4 6123.7 6124.7 6125.6 6131.3 5953.7 6004.6 6011.6 6021.5 6026.6 6110.3 6114.5 6128.1 6133.8 6134.6 5945.0 5985.8 6043.4 6054.6 6071.3 6014.7 6030.0 6048.9 6084.9 6089.6 4789.7 4856.6 4870.2 4875.9 4910.0 4367.4 4412.8 4413.0 4414.2 4427.3 5537.9 5587.4 5595.8 5680.1 5688.0 6096.6 6097.9 6118.2 6130.3 6137.3 5658.9 5671.4 5677.4 5682.8 5686.1 5265.2 5308.9 5320.0 5327.9 5328.1 6151.4 6186.9 6194.9 6217.1 6217.5 6081.9 6100.2 6119.2 6131.8 6136.0 6116.3 6145.3 6169.9 6176.9 6201.8 6081.0 6088.5 6093.2 6115.4 6121.2 5990.1 6006.4 6135.3 6147.4 6168.1 5743.1 5879.9 5943.5 5949.1 5963.3 5989.3 6004.4 6019.5 6023.1 6069.7 5797.1 5822.7 5830.3 5840.2 5847.2 5679.7 5795.4 5974.2 5979.3 6005.5 5675.5 5692.1 5700.8 5744.7 5812.9 05:52:06 INFO - PROCESS | 1972 | 05:52:06 INFO - PROCESS | 1972 | [#1] ext.html Cycles:180 Average:72980.84 Median:39177.01 stddev:164056.71 (418.8%) stddev-sans-first:164426.66 05:52:06 INFO - PROCESS | 1972 | Values: 493.0 502.9 511.6 513.3 525.8 30914.7 40758.4 42205.0 42364.7 44439.8 43846.7 44214.6 44294.3 44370.0 44579.6 9397.8 9651.3 9653.0 9672.4 9672.6 8577.9 8796.6 8810.0 8907.9 8934.8 11477.3 11841.9 11842.0 12042.0 12045.7 11742.5 11804.4 11805.2 11816.2 11830.5 11489.6 11528.5 11617.3 11618.7 11631.5 41787.0 42024.6 42077.4 42084.4 42175.6 3684.8 3999.2 3999.7 4041.9 4050.8 29603.6 31522.0 32187.0 32215.5 32493.4 3082.8 3187.7 3231.5 3258.0 3337.3 456938.0 462794.0 465769.0 468334.0 474345.0 60416.4 62040.7 63004.7 63412.7 63459.4 35033.3 35634.6 35748.3 36226.6 36675.9 16589.3 16709.2 16724.7 16746.2 16748.2 12775.6 12850.5 12862.9 12872.4 12890.4 17261.1 17766.6 19357.8 19664.3 19903.7 6841.0 7117.8 7779.7 7909.4 8224.8 864.5 868.9 992.5 1017.6 1055.3 29438.6 29910.3 30702.0 31021.2 31098.1 1058.7 1089.2 1097.8 1100.7 1126.1 16008.0 16091.9 16278.6 16366.8 16691.7 666796.0 674863.0 676344.6 676475.0 676809.0 51517.2 52726.0 52774.5 58864.4 60857.0 1397.2 1411.6 1429.3 1429.7 1430.8 25537.7 31124.3 31563.3 32259.0 32283.0 44317.0 53679.7 54341.6 54388.5 54396.7 479530.0 652575.0 652893.7 668463.0 856775.0 31783.3 34076.5 41989.0 42634.8 42852.8 47445.8 47518.3 47695.0 48039.5 48326.5 34661.3 39304.6 39568.0 40402.6 41225.0 38640.2 39168.0 39186.0 39918.6 40092.4 38443.6 38913.8 39217.0 39471.0 39573.6 33536.2 33964.0 35130.0 35216.6 35406.1 23264.9 24285.6 25101.4 25343.4 25809.9 05:52:06 INFO - PROCESS | 1972 | 05:52:06 INFO - PROCESS | 1972 | [#2] jquery.html Cycles:180 Average:33141.67 Median:21037.58 stddev:88771.45 (422.0%) stddev-sans-first:88986.30 05:52:06 INFO - PROCESS | 1972 | Values: 333.7 358.4 371.2 380.4 412.1 14159.4 15107.5 20012.5 20183.8 20510.4 19717.2 19751.6 20188.7 20232.9 20444.3 5715.7 6000.3 6042.6 6064.8 6068.6 5728.7 5918.1 5971.3 6065.6 6107.4 6105.6 6128.5 6132.9 6141.7 6165.0 5997.4 6593.3 6662.2 6667.4 6696.3 5693.2 5870.2 5955.3 5956.5 5971.3 13203.9 13399.5 13441.9 13462.7 18631.5 2317.2 2324.1 2330.7 2342.2 2345.2 9866.6 9913.6 9979.8 10428.3 10455.6 1712.1 1770.9 1829.2 1879.2 1906.7 116526.0 120652.0 122707.0 124305.5 124444.0 10833.8 11206.1 11208.9 11290.1 11577.2 12705.0 13246.9 13281.9 13315.4 14166.0 8462.7 8608.1 8696.4 8771.9 8774.8 6928.9 8423.5 8425.7 8504.6 8690.5 4156.4 4329.7 4337.2 4512.8 4538.8 1762.3 1825.3 1833.0 1843.6 1939.4 854.9 902.2 943.2 980.7 1004.6 19463.4 20977.4 21097.8 21152.0 21183.5 941.4 956.6 1030.2 1053.1 1068.6 10791.7 11286.6 11317.3 11339.9 11371.4 491925.0 492344.0 496311.0 534875.0 594444.0 133212.0 140812.6 140965.0 141964.9 143208.0 998.1 1005.4 1009.3 1020.7 1025.1 18642.6 18996.7 18997.5 19063.8 19086.3 51324.5 55843.7 56070.7 57965.4 58294.0 76766.1 81533.0 82034.0 82230.0 84291.7 6459.4 7677.0 7897.0 8055.3 8269.5 11847.5 12789.0 12984.1 13168.1 13188.5 10862.3 11132.2 11329.4 11339.9 11481.8 10983.7 11101.8 11197.0 11298.7 11465.3 8892.0 11384.3 11465.4 11678.6 11681.9 7029.0 7800.7 8076.8 8077.1 8085.1 10449.0 11238.9 11361.4 11385.4 11434.4 05:52:06 INFO - PROCESS | 1972 | 05:52:06 INFO - PROCESS | 1972 | [#3] mootools.html Cycles:180 Average:5614.11 Median:5114.21 stddev:3101.16 (60.6%) stddev-sans-first:3094.66 05:52:06 INFO - PROCESS | 1972 | Values: 1528.7 1549.1 1639.2 1758.9 1924.3 8217.5 8730.4 8738.1 8746.4 8750.0 7663.6 7908.2 7913.0 8246.2 8565.6 2354.0 2388.0 2395.7 2399.9 2405.0 2354.7 2379.3 2382.6 2400.7 2402.1 2358.5 2374.7 2383.9 2386.4 2394.1 2342.7 2354.8 2358.6 2360.0 2365.0 2357.4 2362.1 2363.5 2363.8 2379.8 8546.4 8606.6 8684.4 8688.2 8694.1 5148.2 5310.1 5315.4 5322.2 5324.0 3428.9 3518.3 3588.6 3650.1 3651.4 1065.9 1066.7 1067.4 1082.7 1087.7 11114.3 11120.6 11251.6 11264.8 11276.0 4504.3 4630.2 4754.0 4754.7 4769.9 9664.6 9666.8 9667.1 9675.9 9706.6 5308.0 5308.3 5313.1 5316.3 5339.7 4201.1 4267.8 4787.5 4824.4 4887.9 3877.2 3882.8 3988.8 3989.0 4015.8 1227.6 1236.9 1245.2 1270.1 1294.6 792.1 872.9 877.5 905.8 918.3 5173.3 5432.8 5904.9 6023.8 6076.5 871.4 956.0 956.1 957.3 958.8 3834.7 3859.9 3869.3 3963.7 3984.5 10039.7 10528.4 10537.7 10540.4 10569.5 10477.4 10485.2 10494.8 10521.4 10544.1 7631.0 8012.9 8048.2 8209.5 8216.9 5354.5 5577.7 5964.1 6016.3 6054.2 9421.6 9454.9 9562.2 9675.2 9748.8 9787.4 9933.1 9981.8 10040.4 10085.8 6872.1 6977.8 7119.6 7283.4 7452.9 8206.7 8333.3 8411.6 8437.5 8457.8 7358.4 7455.9 7462.0 7518.8 7616.4 7256.9 7288.2 7308.9 7404.5 7465.0 6835.8 7000.8 7007.5 7015.8 7056.7 4098.7 4217.7 4888.6 5017.9 5080.3 4310.7 4913.5 4975.8 5028.8 5201.4 05:52:06 INFO - PROCESS | 1972 | 05:52:06 INFO - PROCESS | 1972 | [#4] prototype.html Cycles:180 Average:4449.88 Median:4955.84 stddev:1366.89 (27.6%) stddev-sans-first:1333.97 05:52:06 INFO - PROCESS | 1972 | Values: 255.0 301.6 312.6 315.4 320.6 4193.2 4376.9 5321.0 5387.6 5435.3 4751.4 5050.7 5078.7 5208.4 5364.8 3490.2 4011.0 4024.2 4030.0 4042.7 3699.8 4014.9 4026.8 4029.2 4104.0 3794.1 3996.3 4022.6 4040.6 4050.7 4007.7 4020.3 4030.3 4087.8 4122.9 2927.9 3085.0 3359.8 3479.1 3520.4 4254.0 4453.5 5049.3 5153.9 5218.3 4230.7 4563.6 4566.9 4589.6 4619.3 5225.0 5473.7 5590.7 5604.5 5610.9 3772.9 3777.9 3785.5 3811.7 3817.7 5643.9 5856.6 5884.2 5898.5 5909.6 3847.6 3860.4 3869.2 3880.2 3890.8 3873.7 3900.9 3911.8 3912.5 3953.5 3948.3 4013.3 4277.5 4486.8 4595.6 4712.6 4894.9 5125.9 5638.6 5646.2 1151.3 1260.8 1336.9 1340.5 1341.3 831.8 851.2 888.4 890.8 899.0 4165.0 5202.4 5314.1 5319.0 5389.2 5479.9 5729.1 5768.2 5826.9 5853.3 4287.0 5018.7 5188.9 5199.0 5200.6 2670.7 2751.6 2937.4 2978.7 2989.6 5648.1 5844.9 5875.8 5909.3 5913.3 4809.4 5692.4 5763.9 5780.0 5853.9 4922.7 4977.8 5841.3 5853.3 5855.8 4746.5 4872.1 5367.2 5551.9 5642.9 4525.2 4673.4 4933.9 5228.4 5340.8 5028.9 5349.9 5824.6 5853.1 5873.9 3340.4 3585.2 3710.0 3759.5 3784.5 4662.7 5573.2 5613.2 5686.1 5688.9 4272.5 4875.5 5123.5 5404.0 5540.0 4787.4 5107.0 5164.7 5643.3 5647.0 5220.0 5416.8 5434.8 5505.5 5525.6 4397.0 5015.7 5300.5 5478.7 5509.4 5263.8 5518.1 5546.7 5583.2 5583.6 05:52:06 INFO - PROCESS | 1972 | 05:52:06 INFO - PROCESS | 1972 | [#5] yui.html Cycles:180 Average:23905.07 Median:3395.42 stddev:37678.21 (1109.7%) stddev-sans-first:37743.24 05:52:06 INFO - PROCESS | 1972 | Values: 589.4 743.4 775.1 807.1 825.6 15048.5 15686.6 15730.1 15739.0 15753.8 13845.4 13891.7 13905.7 13907.2 13917.5 703.2 726.6 873.2 894.0 923.6 910.8 913.6 914.3 914.3 916.4 982.2 983.0 983.6 984.0 984.3 745.5 769.3 919.8 933.6 971.7 712.0 712.0 712.2 712.5 714.2 14747.4 14908.9 14950.1 15010.4 15016.0 13545.2 13782.4 13833.8 13859.9 14005.0 10378.9 12298.3 12305.8 12471.4 12489.6 4342.7 4361.6 4364.1 4375.5 4532.6 110339.4 110712.9 111006.0 111542.0 111621.0 10648.9 10801.4 10871.8 10949.2 11319.9 20823.0 21387.5 21536.8 21537.1 21580.6 7683.8 7689.8 7717.2 7745.8 7864.6 4288.6 4294.7 4356.7 4389.6 4484.1 3340.8 3450.0 3453.1 3453.5 3460.2 630.8 649.6 652.1 665.9 672.5 546.4 606.7 610.4 618.0 627.2 8681.0 8945.9 9804.7 9833.0 10314.9 620.2 628.0 631.4 670.2 692.2 4494.1 4639.3 4650.1 4704.6 4731.6 126797.4 130070.0 131982.3 141129.6 148445.3 124498.0 125023.4 125583.0 125852.0 133082.0 100705.0 102098.7 103020.5 103748.4 110790.0 8696.7 8874.7 8897.3 8956.0 9090.0 44616.1 54659.7 56659.7 57952.7 59018.2 72314.7 87313.0 89171.0 91303.0 94819.0 11229.8 11350.2 11351.7 11915.8 12076.9 13022.9 13384.4 13785.0 14442.9 15037.6 12226.5 12417.8 12450.2 14197.9 14424.7 13982.4 14231.0 14313.1 14482.3 14964.6 12592.7 13780.2 13832.2 14045.2 14562.7 8256.9 11321.4 11780.5 11888.9 12092.5 10284.3 10503.3 11271.4 11645.7 11672.5 05:52:06 INFO - PROCESS | 1972 | -------- Summary: end -------- 05:52:06 INFO - PROCESS | 1972 | 05:52:06 INFO - TEST-INFO | 1972: exit 0 05:52:06 INFO - TEST-OK | dromaeo_css | took 1120220ms 05:52:06 INFO - TEST-START | kraken 05:52:06 INFO - operating with platform_type : mac_ 05:52:06 INFO - Initialising browser for kraken test... 05:52:06 INFO - TEST-INFO | started process 1976 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/tmpnVOEuU/profile http://localhost:56566/getInfo.html) 05:52:09 INFO - PROCESS | 1976 | __metrics Screen width/height:1600/1200 05:52:09 INFO - PROCESS | 1976 | colorDepth:24 05:52:09 INFO - PROCESS | 1976 | Browser inner width/height: 1024/689 05:52:09 INFO - PROCESS | 1976 | __metrics 05:52:09 INFO - PROCESS | 1976 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:52:09 INFO - PROCESS | 1976 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:52:09 INFO - TEST-INFO | 1976: exit 0 05:52:09 INFO - Browser initialized. 05:52:09 INFO - Running cycle 1/1 for kraken test... 05:52:09 INFO - TEST-INFO | started process 1978 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/tmpnVOEuU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:52:22 INFO - PROCESS | 1978 | RSS: Main: 232132608 05:52:22 INFO - PROCESS | 1978 | 05:52:24 INFO - PROCESS | 1978 | Cycle 1(1): loaded http://localhost:56566/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56566/tests/kraken/driver.html?testName=audio-beat-detection) 05:52:25 INFO - PROCESS | 1978 | RSS: Main: 291164160 05:52:25 INFO - PROCESS | 1978 | 05:52:28 INFO - PROCESS | 1978 | Cycle 1(1): loaded http://localhost:56566/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56566/tests/kraken/driver.html?testName=audio-dft) 05:52:28 INFO - PROCESS | 1978 | RSS: Main: 292319232 05:52:28 INFO - PROCESS | 1978 | 05:52:32 INFO - PROCESS | 1978 | Cycle 1(1): loaded http://localhost:56566/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56566/tests/kraken/driver.html?testName=audio-fft) 05:52:33 INFO - PROCESS | 1978 | RSS: Main: 386830336 05:52:33 INFO - PROCESS | 1978 | 05:52:35 INFO - PROCESS | 1978 | Cycle 1(1): loaded http://localhost:56566/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56566/tests/kraken/driver.html?testName=audio-oscillator) 05:52:35 INFO - PROCESS | 1978 | RSS: Main: 288059392 05:52:35 INFO - PROCESS | 1978 | 05:52:37 INFO - PROCESS | 1978 | Cycle 1(1): loaded http://localhost:56566/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56566/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:52:38 INFO - PROCESS | 1978 | RSS: Main: 290701312 05:52:38 INFO - PROCESS | 1978 | 05:52:41 INFO - PROCESS | 1978 | Cycle 1(1): loaded http://localhost:56566/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56566/tests/kraken/driver.html?testName=imaging-darkroom) 05:52:41 INFO - PROCESS | 1978 | RSS: Main: 328482816 05:52:41 INFO - PROCESS | 1978 | 05:52:44 INFO - PROCESS | 1978 | Cycle 1(1): loaded http://localhost:56566/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56566/tests/kraken/driver.html?testName=imaging-desaturate) 05:52:45 INFO - PROCESS | 1978 | RSS: Main: 330543104 05:52:45 INFO - PROCESS | 1978 | 05:52:47 INFO - PROCESS | 1978 | Cycle 1(1): loaded http://localhost:56566/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56566/tests/kraken/driver.html?testName=json-parse-financial) 05:52:48 INFO - PROCESS | 1978 | RSS: Main: 326815744 05:52:48 INFO - PROCESS | 1978 | 05:52:50 INFO - PROCESS | 1978 | Cycle 1(1): loaded http://localhost:56566/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56566/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:52:50 INFO - PROCESS | 1978 | RSS: Main: 303017984 05:52:50 INFO - PROCESS | 1978 | 05:52:52 INFO - PROCESS | 1978 | Cycle 1(1): loaded http://localhost:56566/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56566/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:52:53 INFO - PROCESS | 1978 | RSS: Main: 305737728 05:52:53 INFO - PROCESS | 1978 | 05:52:55 INFO - PROCESS | 1978 | Cycle 1(1): loaded http://localhost:56566/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56566/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:52:56 INFO - PROCESS | 1978 | RSS: Main: 309063680 05:52:56 INFO - PROCESS | 1978 | 05:52:59 INFO - PROCESS | 1978 | Cycle 1(1): loaded http://localhost:56566/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56566/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:53:00 INFO - PROCESS | 1978 | RSS: Main: 319836160 05:53:00 INFO - PROCESS | 1978 | 05:53:02 INFO - PROCESS | 1978 | Cycle 1(1): loaded http://localhost:56566/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56566/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:53:03 INFO - PROCESS | 1978 | RSS: Main: 319238144 05:53:03 INFO - PROCESS | 1978 | 05:53:05 INFO - PROCESS | 1978 | Cycle 1(1): loaded http://localhost:56566/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56566/tests/kraken/driver.html?testName=ai-astar) 05:53:05 INFO - PROCESS | 1978 | RSS: Main: 363466752 05:53:05 INFO - PROCESS | 1978 | 05:53:05 INFO - PROCESS | 1978 | __start_tp_report 05:53:05 INFO - PROCESS | 1978 | _x_x_mozilla_page_load 05:53:05 INFO - PROCESS | 1978 | _x_x_mozilla_page_load_details 05:53:05 INFO - PROCESS | 1978 | |i|pagename|runs| 05:53:05 INFO - PROCESS | 1978 | |0;ai-astar;102;113;113;112;112;114;111;113;111;111 05:53:05 INFO - PROCESS | 1978 | |1;audio-beat-detection;149;114;114;154;114;113;115;114;164;141 05:53:05 INFO - PROCESS | 1978 | |2;audio-dft;183;186;182;186;256;225;185;184;179;203 05:53:05 INFO - PROCESS | 1978 | |3;audio-fft;78;58;58;86;62;60;73;80;82;59 05:53:05 INFO - PROCESS | 1978 | |4;audio-oscillator;85;72;94;68;68;70;92;68;69;90 05:53:05 INFO - PROCESS | 1978 | |5;imaging-gaussian-blur;87;83;87;84;87;83;86;83;86;84 05:53:05 INFO - PROCESS | 1978 | |6;imaging-darkroom;93;93;93;92;93;93;93;99;151;93 05:53:05 INFO - PROCESS | 1978 | |7;imaging-desaturate;82;82;81;81;81;82;84;86;81;81 05:53:05 INFO - PROCESS | 1978 | |8;json-parse-financial;56;56;55;56;55;56;57;56;56;56 05:53:05 INFO - PROCESS | 1978 | |9;json-stringify-tinderbox;71;72;64;72;71;69;68;71;70;71 05:53:05 INFO - PROCESS | 1978 | |10;stanford-crypto-aes;88;84;91;87;93;92;106;85;88;88 05:53:05 INFO - PROCESS | 1978 | |11;stanford-crypto-ccm;151;155;157;158;157;152;146;147;180;145 05:53:05 INFO - PROCESS | 1978 | |12;stanford-crypto-pbkdf2;175;176;175;175;174;175;174;174;180;174 05:53:05 INFO - PROCESS | 1978 | |13;stanford-crypto-sha256-iterative;70;68;70;70;68;68;69;69;69;69 05:53:05 INFO - PROCESS | 1978 | __end_tp_report 05:53:05 INFO - PROCESS | 1978 | __start_cc_report 05:53:05 INFO - PROCESS | 1978 | _x_x_mozilla_cycle_collect,1957 05:53:05 INFO - PROCESS | 1978 | __end_cc_report 05:53:05 INFO - PROCESS | 1978 | __startTimestamp1476622385522__endTimestamp 05:53:05 INFO - PROCESS | 1978 | 05:53:05 INFO - PROCESS | 1978 | ------- Summary: start ------- 05:53:05 INFO - PROCESS | 1978 | Number of tests: 14 05:53:05 INFO - PROCESS | 1978 | 05:53:05 INFO - PROCESS | 1978 | [#0] ai-astar Cycles:10 Average:111.20 Median:112.50 stddev:3.39 (3.0%) stddev-sans-first:1.09 05:53:05 INFO - PROCESS | 1978 | Values: 102.0 113.0 113.0 112.0 112.0 114.0 111.0 113.0 111.0 111.0 05:53:05 INFO - PROCESS | 1978 | 05:53:05 INFO - PROCESS | 1978 | [#1] audio-beat-detection Cycles:10 Average:129.20 Median:128.00 stddev:20.40 (15.9%) stddev-sans-first:20.34 05:53:05 INFO - PROCESS | 1978 | Values: 149.0 114.0 114.0 154.0 114.0 113.0 115.0 114.0 164.0 141.0 05:53:05 INFO - PROCESS | 1978 | 05:53:05 INFO - PROCESS | 1978 | [#2] audio-dft Cycles:10 Average:196.90 Median:186.00 stddev:24.95 (13.4%) stddev-sans-first:25.95 05:53:05 INFO - PROCESS | 1978 | Values: 183.0 186.0 182.0 186.0 256.0 225.0 185.0 184.0 179.0 203.0 05:53:05 INFO - PROCESS | 1978 | 05:53:05 INFO - PROCESS | 1978 | [#3] audio-fft Cycles:10 Average:69.60 Median:75.50 stddev:11.28 (14.9%) stddev-sans-first:11.54 05:53:05 INFO - PROCESS | 1978 | Values: 78.0 58.0 58.0 86.0 62.0 60.0 73.0 80.0 82.0 59.0 05:53:05 INFO - PROCESS | 1978 | 05:53:05 INFO - PROCESS | 1978 | [#4] audio-oscillator Cycles:10 Average:77.60 Median:78.50 stddev:11.18 (14.2%) stddev-sans-first:11.53 05:53:05 INFO - PROCESS | 1978 | Values: 85.0 72.0 94.0 68.0 68.0 70.0 92.0 68.0 69.0 90.0 05:53:05 INFO - PROCESS | 1978 | 05:53:05 INFO - PROCESS | 1978 | [#5] imaging-gaussian-blur Cycles:10 Average:85.00 Median:86.00 stddev:1.76 (2.1%) stddev-sans-first:1.72 05:53:05 INFO - PROCESS | 1978 | Values: 87.0 83.0 87.0 84.0 87.0 83.0 86.0 83.0 86.0 84.0 05:53:05 INFO - PROCESS | 1978 | 05:53:05 INFO - PROCESS | 1978 | [#6] imaging-darkroom Cycles:10 Average:99.30 Median:93.00 stddev:18.27 (19.6%) stddev-sans-first:19.24 05:53:05 INFO - PROCESS | 1978 | Values: 93.0 93.0 93.0 92.0 93.0 93.0 93.0 99.0 151.0 93.0 05:53:05 INFO - PROCESS | 1978 | 05:53:05 INFO - PROCESS | 1978 | [#7] imaging-desaturate Cycles:10 Average:82.10 Median:82.00 stddev:1.66 (2.0%) stddev-sans-first:1.76 05:53:05 INFO - PROCESS | 1978 | Values: 82.0 82.0 81.0 81.0 81.0 82.0 84.0 86.0 81.0 81.0 05:53:05 INFO - PROCESS | 1978 | 05:53:05 INFO - PROCESS | 1978 | [#8] json-parse-financial Cycles:10 Average:55.90 Median:56.00 stddev:0.57 (1.0%) stddev-sans-first:0.60 05:53:05 INFO - PROCESS | 1978 | Values: 56.0 56.0 55.0 56.0 55.0 56.0 57.0 56.0 56.0 56.0 05:53:05 INFO - PROCESS | 1978 | 05:53:05 INFO - PROCESS | 1978 | [#9] json-stringify-tinderbox Cycles:10 Average:69.90 Median:71.00 stddev:2.42 (3.4%) stddev-sans-first:2.54 05:53:05 INFO - PROCESS | 1978 | Values: 71.0 72.0 64.0 72.0 71.0 69.0 68.0 71.0 70.0 71.0 05:53:05 INFO - PROCESS | 1978 | 05:53:05 INFO - PROCESS | 1978 | [#10] stanford-crypto-aes Cycles:10 Average:90.20 Median:88.00 stddev:6.25 (7.1%) stddev-sans-first:6.58 05:53:05 INFO - PROCESS | 1978 | Values: 88.0 84.0 91.0 87.0 93.0 92.0 106.0 85.0 88.0 88.0 05:53:05 INFO - PROCESS | 1978 | 05:53:05 INFO - PROCESS | 1978 | [#11] stanford-crypto-ccm Cycles:10 Average:154.80 Median:156.00 stddev:10.06 (6.5%) stddev-sans-first:10.58 05:53:05 INFO - PROCESS | 1978 | Values: 151.0 155.0 157.0 158.0 157.0 152.0 146.0 147.0 180.0 145.0 05:53:05 INFO - PROCESS | 1978 | 05:53:05 INFO - PROCESS | 1978 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.20 Median:175.00 stddev:1.81 (1.0%) stddev-sans-first:1.92 05:53:05 INFO - PROCESS | 1978 | Values: 175.0 176.0 175.0 175.0 174.0 175.0 174.0 174.0 180.0 174.0 05:53:05 INFO - PROCESS | 1978 | 05:53:05 INFO - PROCESS | 1978 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.00 Median:69.00 stddev:0.82 (1.2%) stddev-sans-first:0.78 05:53:05 INFO - PROCESS | 1978 | Values: 70.0 68.0 70.0 70.0 68.0 68.0 69.0 69.0 69.0 69.0 05:53:05 INFO - PROCESS | 1978 | -------- Summary: end -------- 05:53:05 INFO - PROCESS | 1978 | 05:53:06 INFO - TEST-INFO | 1978: exit 0 05:53:06 INFO - TEST-OK | kraken | took 59541ms 05:53:06 INFO - SUITE-END | took 1179s 05:53:06 INFO - Completed test suite (00:19:39) 05:53:06 INFO - javascript benchmark 05:53:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3235.854386552605, 3263.053714422816, 3269.7057264846158, 3283.8850640227565, 3324.4348460761667, 5428.212909128174, 5454.100073487874, 5511.448855114487, 5515.779368825247, 5550.833474995747, 5523.061079616465, 5534.667919924797, 5610.66336019838, 5616.550675945925, 5660.037793575081, 5041.521055499722, 5050.393952725678, 5054.241863720435, 5114.565261952738, 5116.513931176532, 5128.640995130342, 5174.974125129369, 5218.008578370116, 5239.633225674204, 5295.099833028395, 5163.948360516384, 5208.713520756357, 5209.400918894337, 5247.606429517782, 5285.973570132163, 4929.975350123263, 5031.396232452093, 5072.391313042459, 5077.771500282459, 5110.514501122388, 5048.29323894655, 5086.389633243997, 5097.133487307166, 5112.769925353331, 5114.105031619451, 5505.0, 5516.834494965157, 5530.972345138289, 5545.750441230154, 5548.334199896039, 5840.474357307863, 5988.0, 6021.126936594169, 6083.878322433571, 6095.725692343855, 5904.143899134644, 5986.132010858445, 6021.909671354933, 6056.4851987581105, 6062.848428789272, 5488.067028605146, 5538.141588053858, 5551.750171242223, 5561.4716601922755, 5574.10814269719, 6105.358937311607, 6123.724432400552, 6124.74712746768, 6125.632462052291, 6131.289237556246, 5953.702314884238, 6004.579679422399, 6011.609245399035, 6021.5182785377065, 6026.608270462406, 6110.297315808739, 6114.5414093942245, 6128.1114238435175, 6133.754649814046, 6134.601250918676, 5944.989351810203, 5985.790497332572, 6043.425874541911, 6054.636721796706, 6071.2714474263375, 6014.729337179838, 6030.0, 6048.909266361008, 6084.908726369108, 6089.604175728652, 4789.712617242976, 4856.635752318591, 4870.220764677634, 4875.878103047452, 4909.950900491021, 4367.366731823868, 4412.823487060546, 4412.955870441318, 4414.161309351213, 4427.317619304644, 5537.889242215214, 5587.357453892854, 5595.776168953278, 5680.062789639664, 5688.0330343840815, 6096.556923351615, 6097.9179172227305, 6118.174046503665, 6130.29501607312, 6137.324894261627, 5658.85852853682, 5671.404502527258, 5677.432256774372, 5682.829515114553, 5686.147077938343, 5265.236540701577, 5308.893822123613, 5319.962607291618, 5327.920081198785, 5328.067588172132, 6151.446369826737, 6186.876262474725, 6194.876102477835, 6217.067439884054, 6217.502599792097, 6081.8968076473475, 6100.187591190282, 6119.2350956130485, 6131.785387511416, 6136.0, 6116.293191840968, 6145.310039738929, 6169.938300617116, 6176.938230617637, 6201.751929922752, 6081.047468385937, 6088.543359248163, 6093.177421048102, 6115.392460733998, 6121.173641558333, 5990.1314309424015, 6006.429389208019, 6135.294441139326, 6147.385261473817, 6168.105624684305, 5743.052396354555, 5879.911801322898, 5943.524518038633, 5949.137375080502, 5963.2844058713235, 5989.3112292085225, 6004.42957918997, 6019.458248757633, 6023.1266466363, 6069.696515174135, 5797.101449275369, 5822.70886455667, 5830.300363956352, 5840.2115714378015, 5847.210626565359, 5679.744411501575, 5795.449432303924, 5974.2233509643465, 5979.252593425822, 6005.519558435402, 5675.460831221028, 5692.117721753135, 5700.771969121189, 5744.741486633196, 5812.883742325214], "value": 5579.30455051694, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [492.98937178783495, 502.9396472423309, 511.5600583498193, 513.3403576404321, 525.7949399734105, 30914.690853091462, 40758.38862417062, 42205.0, 42364.729058128265, 44439.77780111095, 43846.68459946199, 44214.557854421444, 44294.33558496617, 44370.0, 44579.55420445795, 9397.81204375912, 9651.324407291493, 9653.0, 9672.419654820698, 9672.613095476172, 8577.871331929995, 8796.604152813106, 8809.95595022024, 8907.910920890772, 8934.821303573925, 11477.311361318305, 11841.881581184207, 11842.0, 12042.0, 12045.698857528545, 11742.471588778482, 11804.40977951099, 11805.173637845353, 11816.172867899251, 11830.526778928832, 11489.597864074802, 11528.481218345194, 11617.302961822317, 11618.70953226176, 11631.47658355376, 41787.0, 42024.57975420268, 42077.368839467126, 42084.368734469004, 42175.57824421778, 3684.797336146538, 3999.2201520703475, 3999.7000224983244, 4041.898952526181, 4050.797460126982, 29603.555946660817, 31522.0, 32187.0, 32215.51676724851, 32493.350132997206, 3082.830444325584, 3187.681231876817, 3231.4991176367466, 3258.0, 3337.3158502506926, 456938.0, 462794.0, 465769.0, 468334.0, 474345.0, 60416.39583604196, 62040.68979655073, 63004.73990520256, 63412.68293658502, 63459.36540634534, 35033.29933401318, 35634.64365356365, 35748.285034299166, 36226.637733622854, 36675.899723008355, 16589.253483593267, 16709.248083836257, 16724.6655066898, 16746.162691865356, 16748.24632891523, 12775.552855650008, 12850.485980560672, 12862.871371286168, 12872.356382180853, 12890.419931103123, 17261.101278859565, 17766.555836103944, 19357.804059564038, 19664.311749088713, 19903.701444478345, 6840.973853921852, 7117.832564508847, 7779.688812447439, 7909.406794490436, 8224.794380140544, 864.4942708515589, 868.86966954956, 992.4541502173776, 1017.5726194998118, 1055.2876808154465, 29438.558421623693, 29910.25224369408, 30702.0, 31021.224469387995, 31098.067057988297, 1058.6823952814125, 1089.2266490791626, 1097.8078836203633, 1100.6697990602788, 1126.0878688262533, 16008.039517628747, 16091.919540402458, 16278.593035173979, 16366.754498682529, 16691.666166676598, 666796.0, 674863.0, 676344.6182769153, 676475.0, 676809.0, 51517.227241591405, 52726.0, 52774.472255277724, 58864.411355885895, 60857.0, 1397.1756663568544, 1411.5906387147613, 1429.3210724905587, 1429.7354989327007, 1430.8497607751037, 25537.744622553535, 31124.308273721257, 31563.264020478175, 32259.032229033168, 32283.031509054767, 44317.0, 53679.731601341744, 54341.641458963844, 54388.45611543834, 54396.728016361245, 479530.0, 652575.0, 652893.6527616526, 668463.0, 856775.0, 31783.298419633666, 34076.488852666735, 41989.0, 42634.78682606567, 42852.78573607112, 47445.81385465391, 47518.287225690954, 47695.0, 48039.519604804904, 48326.51673483361, 34661.30677386488, 39304.606953930095, 39568.0, 40402.59597403988, 41225.0, 38640.22719545537, 39168.0, 39186.02034949149, 39918.60081399265, 40092.39861402023, 38443.615563845124, 38913.805430972665, 39217.0, 39471.013224668466, 39573.60426395814, 33536.16159596029, 33964.0, 35130.0, 35216.64783352134, 35406.11484712903, 23264.883675581514, 24285.63571546393, 25101.372465688502, 25343.36641583975, 25809.870950645127], "value": 20562.966416214465, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [333.7130068141399, 358.3656927238789, 371.2463698691656, 380.4445509556048, 412.0666689947268, 14159.362828672722, 15107.471238506654, 20012.49968750782, 20183.798162018375, 20510.410678131306, 19717.211311547522, 19751.604967900632, 20188.69716954247, 20232.898835505803, 20444.28445004425, 5715.685637289957, 6000.279966404038, 6042.637441753487, 6064.787732429366, 6068.635881847081, 5728.713564321788, 5918.1418694289305, 5971.253593300838, 6065.60573562717, 6107.389261073879, 6105.572609917308, 6128.509719222474, 6132.877342453148, 6141.662208578527, 6165.0, 5997.37027612101, 6593.307702691221, 6662.20053593567, 6667.433268172163, 6696.330366963313, 5693.174489699012, 5870.178175055496, 5955.315138759055, 5956.463918247336, 5971.313298970631, 13203.89708418521, 13399.53101641438, 13441.932790336083, 13462.711339072282, 18631.53421164468, 2317.165820304689, 2324.070371851256, 2330.7203135623836, 2342.1802369170823, 2345.1557439322, 9866.60533578656, 9913.603455861758, 9979.80040399188, 10428.322159059713, 10455.58177672892, 1712.135371637315, 1770.9203085861168, 1829.2042961311738, 1879.1731638079202, 1906.65680177568, 116526.0, 120652.0, 122707.0, 124305.5138897217, 124444.0, 10833.78332433347, 11206.103511719042, 11208.943955280172, 11290.073329644367, 11577.247478913843, 12705.0, 13246.933765331112, 13281.933590331986, 13315.40080696371, 14166.008379413548, 8462.661493540189, 8608.139186081342, 8696.434731742416, 8771.868421973675, 8774.780630484162, 6928.930710692929, 8423.452475589067, 8425.747227583182, 8504.574771261412, 8690.478571285743, 4156.397322388237, 4329.675274354436, 4337.1976184405985, 4512.819487220474, 4538.841140560065, 1762.3250457545055, 1825.2516468247954, 1832.9541761455803, 1843.5557407710737, 1939.3890924358782, 854.8504011798035, 902.2466240689098, 943.2312665177858, 980.7352014955922, 1004.5931397783909, 19463.416097517096, 20977.37067887966, 21097.789022109584, 21152.0, 21183.470413239484, 941.449252187478, 956.550421301987, 1030.2221822523945, 1053.1048608682559, 1068.625980906692, 10791.730206744895, 11286.604968826052, 11317.320960742381, 11339.886601133883, 11371.374574398487, 491925.0, 492344.0, 496311.0, 534875.0, 594444.0, 133212.0, 140812.591874082, 140965.0, 141964.8705269422, 143208.0, 998.1116806042628, 1005.406809982114, 1009.2632378363688, 1020.7346090016649, 1025.0620682075967, 18642.627147457246, 18996.71504927455, 18997.525061873563, 19063.809361906202, 19086.33197838069, 51324.48675513197, 55843.72078139583, 56070.71964640151, 57965.420345796, 58294.0, 76766.0910518056, 81533.0, 82034.0, 82230.0, 84291.73562396446, 6459.354064593691, 7676.961615191888, 7897.0, 8055.275025247756, 8269.503829770232, 11847.526098955945, 12789.0, 12984.091113621957, 13168.144070635379, 13188.472461101448, 10862.34825910448, 11132.220744548127, 11329.376884271443, 11339.886601133883, 11481.770364592829, 10983.670489885317, 11101.777964440504, 11197.0, 11298.717532061764, 11465.312081274817, 8892.0, 11384.260023098472, 11465.426728663366, 11678.6496405108, 11681.941590291994, 7028.996735930235, 7800.726974555863, 8076.83846323082, 8077.111517733162, 8085.070216925129, 10448.962452321724, 11238.943805280922, 11361.431928403712, 11385.430728463709, 11434.371109589052], "value": 9433.153431023502, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1528.7248295306845, 1549.1092621742498, 1639.1722180298948, 1758.8680848936324, 1924.3168675120332, 8217.506949583028, 8730.432521886072, 8738.082501337356, 8746.387752857287, 8750.0, 7663.5785031823225, 7908.209179082089, 7913.0, 8246.175382461752, 8565.614547345369, 2353.976460235401, 2387.9641805372935, 2395.688560487134, 2399.892004859777, 2405.0, 2354.7409784923643, 2379.2624286471164, 2382.5711371953043, 2400.7239167495786, 2402.1232250228627, 2358.50471401006, 2374.7269064057596, 2383.940401489968, 2386.4033991502124, 2394.0663141374876, 2342.7188737351457, 2354.75275096115, 2358.6226203807482, 2359.9528009439714, 2364.9645255321184, 2357.398863289867, 2362.102401087593, 2363.468219650582, 2363.787259146685, 2379.7501262367377, 8546.401751877309, 8606.5696715164, 8684.392092553524, 8688.218060374596, 8694.130586941319, 5148.150555158394, 5310.123829568117, 5315.3621565412395, 5322.20166974953, 5324.0, 3428.8971330859863, 3518.313928783888, 3588.6411358864166, 3650.123970247148, 3651.415773476231, 1065.898739619735, 1066.653337665262, 1067.412922892414, 1082.696844883426, 1087.6791346552707, 11114.277571957879, 11120.55517779296, 11251.60619378326, 11264.774704505946, 11275.985161335519, 4504.25679762842, 4630.166570017362, 4753.952460475421, 4754.690945088558, 4769.928451073237, 9664.613415463302, 9666.75833104178, 9667.081627245401, 9675.90324096764, 9706.563204655808, 5307.9734601326745, 5308.256844041837, 5313.123334649817, 5316.282301889196, 5339.679619222781, 4201.075763332062, 4267.76527291002, 4787.54518320759, 4824.421069471686, 4887.9490909454635, 3877.205172939597, 3882.8446862125766, 3988.7606743594897, 3988.9883138230452, 4015.8393664253103, 1227.6439832448668, 1236.863944966053, 1245.2092920995233, 1270.136307311019, 1294.58573256557, 792.1247022040677, 872.8559787634969, 877.5217506458995, 905.800723840754, 918.2883265469181, 5173.301604283373, 5432.837014889559, 5904.911426328608, 6023.789167379121, 6076.54425918049, 871.4074429388077, 956.0439560439561, 956.067833861979, 957.3107362698846, 958.7603099225194, 3834.731568790158, 3859.9421008684894, 3869.28418242626, 3963.7027222958395, 3984.521857377133, 10039.749006274902, 10528.420936848393, 10537.736556586147, 10540.420276884692, 10569.471526423647, 10477.42374169428, 10485.161187105014, 10494.790104197873, 10521.368717876952, 10544.103751181083, 7631.0, 8012.879806802903, 8048.235417635316, 8209.548474833822, 8216.917830821616, 5354.544863686475, 5577.693226872561, 5964.124003788908, 6016.338202797776, 6054.243219597551, 9421.623135074795, 9454.858177127211, 9562.18721408688, 9675.177609903238, 9748.805023899624, 9787.36382135159, 9933.056359645821, 9981.750456238362, 10040.397576145455, 10085.848712269468, 6872.140982377203, 6977.755778547727, 7119.572825630479, 7283.417326613752, 7452.888206677013, 8206.712765053195, 8333.250007499111, 8411.579421029046, 8437.451565648213, 8457.788555286166, 7358.374538164316, 7455.850882982201, 7462.0, 7518.8496230074, 7616.428767842323, 7256.9274307256255, 7288.198298187302, 7308.853822923618, 7404.481686281909, 7464.992226049414, 6835.760748373783, 7000.824979375557, 7007.509474336747, 7015.789526314219, 7056.682449289691, 4098.6516146127915, 4217.725847820005, 4888.633352498506, 5017.874553136201, 5080.263361812443, 4310.655147588248, 4913.533214344631, 4975.825846095369, 5028.823991160292, 5201.42784293735], "value": 4538.0037477709575, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [254.97960163186946, 301.56574532672937, 312.64670921858294, 315.39129480103406, 320.624868903383, 4193.182329445759, 4376.868693939187, 5321.0, 5387.622866399353, 5435.315052333769, 4751.40607424072, 5050.676688723192, 5078.74606269686, 5208.374995000596, 5364.839054828351, 3490.2321489272317, 4010.9598904011023, 4024.215278020787, 4029.959700402995, 4042.7372220805555, 3699.7595156314887, 4014.8594799182033, 4026.7583944963394, 4029.1941611677635, 4103.974006498375, 3794.0704527390826, 3996.2606917720314, 4022.612198791412, 4040.616141466556, 4050.655694265991, 4007.73949693269, 4020.296448121567, 4030.2544029354663, 4087.7547347159116, 4122.91754164918, 2927.92680182995, 3084.9511166203597, 3359.7648164628486, 3479.1302174456387, 3520.436730123168, 4253.978730106361, 4453.465584129925, 5049.343585333883, 5153.8919132386345, 5218.269442278084, 4230.725002874803, 4563.612092972101, 4566.862994110182, 4589.646054413923, 4619.284010978304, 5225.033368826779, 5473.7263136843, 5590.692511911843, 5604.523615492688, 5610.943890561083, 3772.8868133956025, 3777.9244415111543, 3785.5268091488565, 3811.6950643948417, 3817.6945844332386, 5643.858903527444, 5856.560757943172, 5884.2350494436305, 5898.525368657835, 5909.586328956974, 3847.5960024197057, 3860.397934857059, 3869.245497128033, 3880.20455806559, 3890.8054597270025, 3873.663586062804, 3900.9077458311717, 3911.7848518331766, 3912.5109361329833, 3953.5453422856162, 3948.3485224937563, 4013.2776100301644, 4277.465316835395, 4486.842960496367, 4595.563421474954, 4712.552307530779, 4894.902101957941, 5125.897482050413, 5638.633488823295, 5646.153077038477, 1151.3034614058374, 1260.7541529401863, 1336.9438143866432, 1340.4973135074347, 1341.3494455189225, 831.8003679116969, 851.199872120205, 888.4447220487195, 890.8218356328761, 898.9661888827769, 4165.042040330741, 5202.4277329493725, 5314.0700377433795, 5318.989392015505, 5389.164679474688, 5479.91763130016, 5729.140628905698, 5768.163616275617, 5826.902315166537, 5853.30747855991, 4287.0, 5018.673786203885, 5188.870278242998, 5199.0, 5200.557952574073, 2670.7329267073137, 2751.614773931651, 2937.3831495386207, 2978.74680652147, 2989.566512855624, 5648.119459075819, 5844.853878653068, 5875.823725288248, 5909.320428150818, 5913.344263606197, 4809.374781278411, 5692.430756924275, 5763.85869701014, 5779.994402910547, 5853.853653658607, 4922.680025798311, 4977.776000079933, 5841.2698412699265, 5853.30747855991, 5855.824325270163, 4746.5490778376, 4872.120532934878, 5367.19635925852, 5551.8889622208135, 5642.85892852682, 4525.162844873709, 4673.369095172245, 4933.9013219736125, 5228.424873264014, 5340.825018495924, 5028.9245661315845, 5349.903269829598, 5824.5631577631, 5853.09277062056, 5873.9412605873395, 3340.398728228942, 3585.229175727231, 3710.0336821484657, 3759.4548790424687, 3784.4512545681273, 4662.691967476121, 5573.160856917289, 5613.214150019082, 5686.118651609007, 5688.886222275449, 4272.487301523837, 4875.536824001714, 5123.513266239701, 5404.027275090364, 5540.0, 4787.3537072496165, 5107.012397747211, 5164.741762911764, 5643.319423273912, 5646.971765141148, 5219.982103489783, 5416.783328666809, 5434.809781657623, 5505.476979686923, 5525.557955363642, 4396.956030439655, 5015.699058056528, 5300.522952934254, 5478.698671573102, 5509.3664228614225, 5263.841954769944, 5518.117101263779, 5546.72266386671, 5583.218349431164, 5583.609147359646], "value": 4015.1755767921522, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [589.3666780386748, 743.3607097895812, 775.11249619186, 807.1121766057335, 825.562748708202, 15048.473303434383, 15686.607834804136, 15730.134842583653, 15739.0, 15753.763693544606, 13845.376958036888, 13891.652708682263, 13905.652358691037, 13907.235102069382, 13917.512887048955, 703.152700995302, 726.6475759256756, 873.2010210657257, 894.025512191711, 923.5659240157149, 910.804177101923, 913.5888850017485, 914.2868562521211, 914.3371055984392, 916.4134953629682, 982.2485798364257, 982.9606815727398, 983.6360546597768, 984.0, 984.3257368702461, 745.5414919824296, 769.2653515892309, 919.7516670498928, 933.6358820060182, 971.6987733802508, 711.989320160198, 712.0209711646486, 712.2272334517055, 712.5226098514008, 714.1893950366319, 14747.410103595843, 14908.925455372762, 14950.102993820406, 15010.399584016626, 15016.0, 13545.187288762705, 13782.379792909343, 13833.79249311261, 13859.861401386059, 14005.0, 10378.948105259426, 12298.323592202514, 12305.753884922251, 12471.376431178405, 12489.625311240678, 4342.6742994275555, 4361.629261512775, 4364.105358401552, 4375.540568240321, 4532.637389008872, 110339.44830275797, 110712.89287107106, 111006.0, 111542.0, 111621.0, 10648.893511064945, 10801.351918884891, 10871.782564348669, 10949.178811589003, 11319.943400282946, 20823.0, 21387.465313366978, 21536.784632153478, 21537.138514459246, 21580.56838863214, 7683.807904802425, 7689.769306920802, 7717.1896950819355, 7745.806354841061, 7864.646090925922, 4288.614024737789, 4294.699371044059, 4356.651467882625, 4389.648828093745, 4484.103179364075, 3340.8496617652263, 3449.9827500862334, 3453.102193429676, 3453.4992426098074, 3460.2041530447636, 630.8012975912573, 649.6491894377078, 652.083822229768, 665.8867992441296, 672.5325898500495, 546.368943869831, 606.6504300400615, 610.3774150366601, 618.0, 627.1752645271454, 8681.0, 8945.865812012826, 9804.669503574183, 9832.950835245778, 10314.908742361938, 620.2340109964191, 627.9654618995908, 631.4466412624994, 670.1924181361447, 692.2489099327186, 4494.1461122386645, 4639.304104384302, 4650.116477869194, 4704.623630109583, 4731.574158325767, 126797.36601316935, 130070.0, 131982.34008829895, 141129.5887041137, 148445.25777371044, 124498.0, 125023.37488312682, 125583.0, 125852.0, 133082.0, 100705.0, 102098.74815022097, 103020.48489757504, 103748.44377334346, 110790.0, 8696.695615653422, 8874.689385871463, 8897.332700047393, 8956.026715675298, 9089.954550227207, 44616.10767784561, 54659.72670136624, 56659.716701416226, 57952.71023644855, 59018.22945311647, 72314.68381136237, 87313.0, 89171.0, 91303.0, 94819.0, 11229.831552526883, 11350.205485615928, 11351.716207094889, 11915.76168476643, 12076.879231207575, 13022.869771302545, 13384.397702103233, 13785.0, 14442.855571444152, 15037.624059398166, 12226.510939562673, 12417.751644967231, 12450.214633025204, 14197.858021419654, 14424.711505769616, 13982.370793314529, 14231.003829731819, 14313.069650472686, 14482.275886205858, 14964.625884352978, 12592.685182870502, 13780.242086684928, 13832.170069795844, 14045.183079620301, 14562.708745825235, 8256.917430825615, 11321.377324247245, 11780.52877884875, 11888.940555297515, 12092.516299347928, 10284.2801003929, 10503.264771465923, 11271.436428178722, 11645.6506304811, 11672.533098676298], "value": 7758.248869054, "unit": "score"}], "name": "dromaeo_css", "value": 7313.367426912448, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 113.0, 113.0, 112.0, 112.0, 114.0, 111.0, 113.0, 111.0, 111.0], "value": 111.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 114.0, 114.0, 154.0, 114.0, 113.0, 115.0, 114.0, 164.0, 141.0], "value": 129.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 186.0, 182.0, 186.0, 256.0, 225.0, 185.0, 184.0, 179.0, 203.0], "value": 196.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 58.0, 58.0, 86.0, 62.0, 60.0, 73.0, 80.0, 82.0, 59.0], "value": 69.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 72.0, 94.0, 68.0, 68.0, 70.0, 92.0, 68.0, 69.0, 90.0], "value": 77.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 83.0, 87.0, 84.0, 87.0, 83.0, 86.0, 83.0, 86.0, 84.0], "value": 85.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 92.0, 93.0, 93.0, 93.0, 99.0, 151.0, 93.0], "value": 99.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 81.0, 81.0, 81.0, 82.0, 84.0, 86.0, 81.0, 81.0], "value": 82.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 55.0, 56.0, 55.0, 56.0, 57.0, 56.0, 56.0, 56.0], "value": 55.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 72.0, 64.0, 72.0, 71.0, 69.0, 68.0, 71.0, 70.0, 71.0], "value": 69.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 84.0, 91.0, 87.0, 93.0, 92.0, 106.0, 85.0, 88.0, 88.0], "value": 90.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 155.0, 157.0, 158.0, 157.0, 152.0, 146.0, 147.0, 180.0, 145.0], "value": 154.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 176.0, 175.0, 175.0, 174.0, 175.0, 174.0, 174.0, 180.0, 174.0], "value": 175.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 68.0, 70.0, 70.0, 68.0, 68.0, 69.0, 69.0, 69.0, 69.0], "value": 69.0, "unit": "score"}], "name": "kraken", "value": 1465.8999999999999, "alertThreshold": 2.0}]} 05:53:06 INFO - Return code: 0 05:53:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:53:06 INFO - # TBPL SUCCESS # 05:53:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:53:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:53:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:53:06 INFO - 2016-10-16 05:53:06.848 screenresolution[1982:35245] starting screenresolution argv=screenresolution get 05:53:06 INFO - 2016-10-16 05:53:06.851 screenresolution[1982:35245] Display 0: 1600x1200x32@60 05:53:06 INFO - 2016-10-16 05:53:06.857 screenresolution[1983:35248] starting screenresolution argv=screenresolution list 05:53:06 INFO - Available Modes on Display 0 05:53:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:53:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:53:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:53:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:53:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:53:07 INFO - Intel Iris: 05:53:07 INFO - Chipset Model: Intel Iris 05:53:07 INFO - Type: GPU 05:53:07 INFO - Bus: Built-In 05:53:07 INFO - VRAM (Dynamic, Max): 1536 MB 05:53:07 INFO - Vendor: Intel (0x8086) 05:53:07 INFO - Device ID: 0x0a2e 05:53:07 INFO - Revision ID: 0x0009 05:53:07 INFO - Displays: 05:53:07 INFO - Display: 05:53:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:53:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:53:07 INFO - Display Serial Number: 1600x1200 60 05:53:07 INFO - Main Display: Yes 05:53:07 INFO - Mirror: Off 05:53:07 INFO - Online: Yes 05:53:07 INFO - Rotation: Supported 05:53:07 INFO - Return code: 0 05:53:07 INFO - Running post-action listener: _resource_record_post_action 05:53:07 INFO - [mozharness: 2016-10-16 12:53:07.098359Z] Finished run-tests step (success) 05:53:07 INFO - Running post-run listener: _resource_record_post_run 05:53:07 INFO - Total resource usage - Wall time: 1215s; CPU: 53.0%; Read bytes: 1724416; Write bytes: 708770816; Read time: 13; Write time: 2322 05:53:07 INFO - TinderboxPrint: CPU usage
53.4% 05:53:07 INFO - TinderboxPrint: I/O read bytes / time
1,724,416 / 13 05:53:07 INFO - TinderboxPrint: I/O write bytes / time
708,770,816 / 2,322 05:53:07 INFO - TinderboxPrint: CPU idle
2,266.1 (46.6%) 05:53:07 INFO - TinderboxPrint: CPU system
966.3 (19.9%) 05:53:07 INFO - TinderboxPrint: CPU user
1,629.2 (33.5%) 05:53:07 INFO - TinderboxPrint: Swap in / out
409,587,712 / 0 05:53:07 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 390625792; Write bytes: 385267200; Read time: 29557; Write time: 704 05:53:07 INFO - run-tests - Wall time: 1181s; CPU: 53.0%; Read bytes: 1724416; Write bytes: 297657856; Read time: 13; Write time: 1563 05:53:07 INFO - Running post-run listener: _upload_blobber_files 05:53:07 INFO - Blob upload gear active. 05:53:07 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:53:07 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:53:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:53:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:53:07 INFO - (blobuploader) - INFO - Open directory for files ... 05:53:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:53:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:53:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:53:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:53:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:53:08 INFO - (blobuploader) - INFO - Done attempting. 05:53:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:53:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:53:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:53:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:53:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:53:09 INFO - (blobuploader) - INFO - Done attempting. 05:53:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:53:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:53:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:53:11 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:53:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:53:11 INFO - (blobuploader) - INFO - Done attempting. 05:53:11 INFO - (blobuploader) - INFO - Iteration through files over. 05:53:11 INFO - Return code: 0 05:53:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:53:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:53:11 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b8bd85773568749ac118eade3fe390ea8c8454ea560eb5fb21b772c1209254c1f2e96eb8f403ef96c899f508c535f328f1f43b74a48a14a2edc8ef5d417acd9d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8737767dd402988bd675b79d1413fa52a10444acc0312ef906da822db09469753deae899ad13d1f61fb3a1a80f9bcc756ed9451d08df0a4d6dab0eec2803cc71", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/335033022c51d56eed8f00b46674aeeeb3ccf5f3def3fe2d9c54fb7cb06b51d3a0dee394f7722d188347f34ba1f8eeab6549d6e4b6b0d2a1d8d7caec5c6c09fb"} 05:53:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:53:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:53:11 INFO - Contents: 05:53:11 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b8bd85773568749ac118eade3fe390ea8c8454ea560eb5fb21b772c1209254c1f2e96eb8f403ef96c899f508c535f328f1f43b74a48a14a2edc8ef5d417acd9d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8737767dd402988bd675b79d1413fa52a10444acc0312ef906da822db09469753deae899ad13d1f61fb3a1a80f9bcc756ed9451d08df0a4d6dab0eec2803cc71", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/335033022c51d56eed8f00b46674aeeeb3ccf5f3def3fe2d9c54fb7cb06b51d3a0dee394f7722d188347f34ba1f8eeab6549d6e4b6b0d2a1d8d7caec5c6c09fb"} 05:53:11 INFO - Running post-run listener: copy_logs_to_upload_dir 05:53:11 INFO - Copying logs to upload dir... 05:53:11 INFO - mkdir: /builds/slave/test/build/upload/logs 05:53:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.825160 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-10-16 05:53:11.886912) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-16 05:53:11.890339) ========= 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.JQj5xAnJUM/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.PY0z2DJeqU/Listeners TMPDIR=/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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/Firefox/sha512/b8bd85773568749ac118eade3fe390ea8c8454ea560eb5fb21b772c1209254c1f2e96eb8f403ef96c899f508c535f328f1f43b74a48a14a2edc8ef5d417acd9d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8737767dd402988bd675b79d1413fa52a10444acc0312ef906da822db09469753deae899ad13d1f61fb3a1a80f9bcc756ed9451d08df0a4d6dab0eec2803cc71", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/335033022c51d56eed8f00b46674aeeeb3ccf5f3def3fe2d9c54fb7cb06b51d3a0dee394f7722d188347f34ba1f8eeab6549d6e4b6b0d2a1d8d7caec5c6c09fb"} build_url:https://queue.taskcluster.net/v1/task/DhtNfV_WTBel5DDtM-2kLQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010866 build_url: 'https://queue.taskcluster.net/v1/task/DhtNfV_WTBel5DDtM-2kLQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b8bd85773568749ac118eade3fe390ea8c8454ea560eb5fb21b772c1209254c1f2e96eb8f403ef96c899f508c535f328f1f43b74a48a14a2edc8ef5d417acd9d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8737767dd402988bd675b79d1413fa52a10444acc0312ef906da822db09469753deae899ad13d1f61fb3a1a80f9bcc756ed9451d08df0a4d6dab0eec2803cc71", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/335033022c51d56eed8f00b46674aeeeb3ccf5f3def3fe2d9c54fb7cb06b51d3a0dee394f7722d188347f34ba1f8eeab6549d6e4b6b0d2a1d8d7caec5c6c09fb"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-16 05:53:11.922911) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-16 05:53:11.923288) ========= 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.JQj5xAnJUM/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.PY0z2DJeqU/Listeners TMPDIR=/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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.005275 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-16 05:53:11.971839) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-16 05:53:11.972194) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-16 05:53:11.972561) ========= ========= Total master_lag: 0.18 =========