builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0115 starttime: 1477401472.51 results: success (0) buildid: 20161025033504 builduid: 1ea53ccbd6aa43cbbf3f2c8241d08b09 revision: c6ccd71126ff514bfc44b53e2217562e29a0cc38 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-25 06:17:52.508605) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-25 06:17:52.509037) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-25 06:17:52.509370) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-25 06:17:52.709228) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-25 06:17:52.710016) ========= 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.bqC0yHTOCk/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.gNFkWmQuXp/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-25 06:17:52-- 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% 30.5M=0s 2016-10-25 06:17:52 (30.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.138920 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-25 06:17:52.913751) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-25 06:17:52.914104) ========= 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.bqC0yHTOCk/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.gNFkWmQuXp/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.026809 ========= master_lag: 0.07 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-25 06:17:53.013554) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-25 06:17:53.013955) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c6ccd71126ff514bfc44b53e2217562e29a0cc38 --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 c6ccd71126ff514bfc44b53e2217562e29a0cc38 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.bqC0yHTOCk/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.gNFkWmQuXp/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-25 06:17:53,106 truncating revision to first 12 chars 2016-10-25 06:17:53,107 Setting DEBUG logging. 2016-10-25 06:17:53,107 attempt 1/10 2016-10-25 06:17:53,107 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c6ccd71126ff?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-25 06:17:54,187 unpacking tar archive at: mozilla-central-c6ccd71126ff/testing/mozharness/ program finished with exit code 0 elapsedTime=1.346679 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-25 06:17:54.405947) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-25 06:17:54.406312) ========= script_repo_revision: c6ccd71126ff514bfc44b53e2217562e29a0cc38 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-25 06:17:54.406755) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-25 06:17:54.407158) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-25 06:17:54.429003) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-10-25 06:17:54.429347) ========= /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.bqC0yHTOCk/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.gNFkWmQuXp/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 06:17:54 INFO - MultiFileLogger online at 20161025 06:17:54 in /builds/slave/test 06:17:54 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 06:17:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:17:54 INFO - {'append_to_log': False, 06:17:54 INFO - 'base_work_dir': '/builds/slave/test', 06:17:54 INFO - 'blob_upload_branch': 'Firefox', 06:17:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:17:54 INFO - 'branch': 'Firefox', 06:17:54 INFO - 'buildbot_json_path': 'buildprops.json', 06:17:54 INFO - 'config_files': ('talos/mac_config.py',), 06:17:54 INFO - 'default_actions': ('clobber', 06:17:54 INFO - 'read-buildbot-config', 06:17:54 INFO - 'download-and-extract', 06:17:54 INFO - 'populate-webroot', 06:17:54 INFO - 'create-virtualenv', 06:17:54 INFO - 'install', 06:17:54 INFO - 'run-tests'), 06:17:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:17:54 INFO - 'download_minidump_stackwalk': True, 06:17:54 INFO - 'download_symbols': 'ondemand', 06:17:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:17:54 INFO - 'tooltool.py': '/tools/tooltool.py', 06:17:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:17:54 INFO - '/tools/misc-python/virtualenv.py')}, 06:17:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:17:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:17:54 INFO - 'installer_path': 'installer.exe', 06:17:54 INFO - 'log_level': 'info', 06:17:54 INFO - 'log_name': 'talos', 06:17:54 INFO - 'log_to_console': True, 06:17:54 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:17:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:17:54 INFO - 'opt_config_files': (), 06:17:54 INFO - 'pip_index': False, 06:17:54 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:17:54 INFO - 'cmd': ('bash', 06:17:54 INFO - '-c', 06:17:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:17:54 INFO - 'enabled': True, 06:17:54 INFO - 'halt_on_failure': False, 06:17:54 INFO - 'name': 'check_screen_resolution'},), 06:17:54 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:17:54 INFO - 'cmd': ('bash', 06:17:54 INFO - '-c', 06:17:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:17:54 INFO - 'enabled': True, 06:17:54 INFO - 'halt_on_failure': False, 06:17:54 INFO - 'name': 'check_screen_resolution'},), 06:17:54 INFO - 'run_cmd_checks_enabled': True, 06:17:54 INFO - 'sps_profile': False, 06:17:54 INFO - 'sps_profile_interval': 0, 06:17:54 INFO - 'suite': 'dromaeojs', 06:17:54 INFO - 'system_bits': '32', 06:17:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:17:54 INFO - 'title': 't-yosemite-r7-0115', 06:17:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:17:54 INFO - 'use_talos_json': True, 06:17:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:17:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:17:54 INFO - 'work_dir': 'build'} 06:17:54 INFO - [mozharness: 2016-10-25 13:17:54.633175Z] Running clobber step. 06:17:54 INFO - Running pre-action listener: _resource_record_pre_action 06:17:54 INFO - Running main action method: clobber 06:17:54 INFO - rmtree: /builds/slave/test/build 06:17:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:17:56 INFO - Running post-action listener: _resource_record_post_action 06:17:56 INFO - [mozharness: 2016-10-25 13:17:56.843532Z] Finished clobber step (success) 06:17:56 INFO - [mozharness: 2016-10-25 13:17:56.843681Z] Running read-buildbot-config step. 06:17:56 INFO - Running pre-action listener: _resource_record_pre_action 06:17:56 INFO - Running main action method: read_buildbot_config 06:17:56 INFO - Using buildbot properties: 06:17:56 INFO - { 06:17:56 INFO - "project": "", 06:17:56 INFO - "product": "firefox", 06:17:56 INFO - "script_repo_revision": "production", 06:17:56 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 06:17:56 INFO - "repository": "", 06:17:56 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 06:17:56 INFO - "buildid": "20161025033504", 06:17:56 INFO - "pgo_build": "False", 06:17:56 INFO - "basedir": "/builds/slave/test", 06:17:56 INFO - "buildnumber": 16, 06:17:56 INFO - "slavename": "t-yosemite-r7-0115", 06:17:56 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 06:17:56 INFO - "platform": "yosemite_r7", 06:17:56 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 06:17:56 INFO - "branch": "mozilla-central", 06:17:56 INFO - "repo_path": "mozilla-central", 06:17:56 INFO - "revision": "c6ccd71126ff514bfc44b53e2217562e29a0cc38", 06:17:56 INFO - "stage_platform": "macosx64", 06:17:56 INFO - "builduid": "1ea53ccbd6aa43cbbf3f2c8241d08b09", 06:17:56 INFO - "slavebuilddir": "test" 06:17:56 INFO - } 06:17:56 INFO - Found installer url https://queue.taskcluster.net/v1/task/bJ1MPhptTY-3XRESFarC8Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 06:17:56 INFO - Running post-action listener: _resource_record_post_action 06:17:56 INFO - [mozharness: 2016-10-25 13:17:56.846995Z] Finished read-buildbot-config step (success) 06:17:56 INFO - [mozharness: 2016-10-25 13:17:56.847127Z] Running download-and-extract step. 06:17:56 INFO - Running pre-action listener: _resource_record_pre_action 06:17:56 INFO - Running main action method: download_and_extract 06:17:56 INFO - mkdir: /builds/slave/test/build/tests 06:17:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:17:56 INFO - trying https://queue.taskcluster.net/v1/task/bJ1MPhptTY-3XRESFarC8Q/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 06:17:56 INFO - Downloading https://queue.taskcluster.net/v1/task/bJ1MPhptTY-3XRESFarC8Q/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 06:17:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bJ1MPhptTY-3XRESFarC8Q/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 06:17:57 INFO - Downloaded 1199 bytes. 06:17:57 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 06:17:57 INFO - Using the following test package requirements: 06:17:57 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 06:17:57 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:17:57 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 06:17:57 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:17:57 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 06:17:57 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:17:57 INFO - u'jsshell-mac.zip'], 06:17:57 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:17:57 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 06:17:57 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 06:17:57 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:17:57 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 06:17:57 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:17:57 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 06:17:57 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:17:57 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 06:17:57 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:17:57 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 06:17:57 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 06:17:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bJ1MPhptTY-3XRESFarC8Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 06:17:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bJ1MPhptTY-3XRESFarC8Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:17:57 INFO - Fetch https://queue.taskcluster.net/v1/task/bJ1MPhptTY-3XRESFarC8Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 06:17:58 INFO - Http code: 200 06:17:58 INFO - via: 1.1 6cd88b9fd84ad5daa3b3867fb8c5c91a.cloudfront.net (CloudFront) 06:17:58 INFO - x-amz-cf-id: UVbzvMuGh75-cxkoDml6JNppUoGRf4XqzDCkLsg4oREbU1Guc7tl-Q== 06:17:58 INFO - x-amz-version-id: VbxRQ1jEWl0D5dkKX3l.85b9Ked1MzZL 06:17:58 INFO - Expected file size: 16423395 06:17:58 INFO - Obtained file size: 16423395 06:18:05 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 06:18:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bJ1MPhptTY-3XRESFarC8Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 06:18:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bJ1MPhptTY-3XRESFarC8Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:18:05 INFO - Fetch https://queue.taskcluster.net/v1/task/bJ1MPhptTY-3XRESFarC8Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 06:18:06 INFO - Http code: 200 06:18:06 INFO - via: 1.1 e1eb7447ab46f530f6009f1d93917c54.cloudfront.net (CloudFront) 06:18:06 INFO - x-amz-cf-id: drk2Up9lcOdbeiU9dtFpj4jZcciPFr3JGyJG7Myy7UrcqOWZgTUhsA== 06:18:06 INFO - x-amz-version-id: VbxRQ1jEWl0D5dkKX3l.85b9Ked1MzZL 06:18:06 INFO - Expected file size: 16423395 06:18:06 INFO - Obtained file size: 16423395 06:18:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bJ1MPhptTY-3XRESFarC8Q/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 06:18:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bJ1MPhptTY-3XRESFarC8Q/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 06:18:12 INFO - Fetch https://queue.taskcluster.net/v1/task/bJ1MPhptTY-3XRESFarC8Q/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 06:18:13 INFO - Http code: 200 06:18:13 INFO - via: 1.1 0302e8c462515ae281b72d9c815a5698.cloudfront.net (CloudFront) 06:18:13 INFO - x-amz-cf-id: Dr4cGZMB-M_DQy1ecEFyuEezlBWYhLCPHPvKFPtY9qc8Sf_D7TT4uA== 06:18:13 INFO - x-amz-version-id: 6IyYwyB1sr3byECpXPJ5Y4GqnoN5TMtE 06:18:13 INFO - Expected file size: 14409028 06:18:13 INFO - Obtained file size: 14409028 06:18:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:18:13 INFO - trying https://queue.taskcluster.net/v1/task/bJ1MPhptTY-3XRESFarC8Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 06:18:13 INFO - Downloading https://queue.taskcluster.net/v1/task/bJ1MPhptTY-3XRESFarC8Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 06:18:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bJ1MPhptTY-3XRESFarC8Q/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 06:18:18 INFO - Downloaded 111192198 bytes. 06:18:18 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bJ1MPhptTY-3XRESFarC8Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 06:18:18 INFO - mkdir: /builds/slave/test/properties 06:18:18 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:18:18 INFO - Writing to file /builds/slave/test/properties/build_url 06:18:18 INFO - Contents: 06:18:18 INFO - build_url:https://queue.taskcluster.net/v1/task/bJ1MPhptTY-3XRESFarC8Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 06:18:19 INFO - Running post-action listener: _resource_record_post_action 06:18:19 INFO - Running post-action listener: set_extra_try_arguments 06:18:19 INFO - [mozharness: 2016-10-25 13:18:19.605766Z] Finished download-and-extract step (success) 06:18:19 INFO - [mozharness: 2016-10-25 13:18:19.605887Z] Running populate-webroot step. 06:18:19 INFO - Running pre-action listener: _resource_record_pre_action 06:18:19 INFO - Running main action method: populate_webroot 06:18:19 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:18:19 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:18:19 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:18:19 INFO - '--tppagecycles', 06:18:19 INFO - '7'], 06:18:19 INFO - 'tests': ['tsvgm']}}, 06:18:19 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:18:19 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:18:19 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:18:19 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:18:19 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:18:19 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:18:19 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:18:19 INFO - 'talos_options': ['--disable-e10s'], 06:18:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:18:19 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:18:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:18:19 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:18:19 INFO - 'talos_options': ['--disable-e10s'], 06:18:19 INFO - 'tests': ['damp', 'tps']}, 06:18:19 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:18:19 INFO - 'tests': ['damp', 'tps']}, 06:18:19 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:18:19 INFO - 'tests': ['dromaeo_dom']}, 06:18:19 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:18:19 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:18:19 INFO - 'tests': ['basic_compositor_video']}, 06:18:19 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 06:18:19 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:18:19 INFO - 'tests': ['a11yr', 06:18:19 INFO - 'ts_paint', 06:18:19 INFO - 'tpaint', 06:18:19 INFO - 'sessionrestore', 06:18:19 INFO - 'sessionrestore_no_auto_restore', 06:18:19 INFO - 'tabpaint']}, 06:18:19 INFO - 'other-e10s': {'tests': ['a11yr', 06:18:19 INFO - 'ts_paint', 06:18:19 INFO - 'tpaint', 06:18:19 INFO - 'sessionrestore', 06:18:19 INFO - 'sessionrestore_no_auto_restore', 06:18:19 INFO - 'tabpaint']}, 06:18:19 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:18:19 INFO - 'ts_paint', 06:18:19 INFO - 'tpaint', 06:18:19 INFO - 'sessionrestore', 06:18:19 INFO - 'sessionrestore_no_auto_restore', 06:18:19 INFO - 'tabpaint']}, 06:18:19 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:18:19 INFO - 'ts_paint', 06:18:19 INFO - 'tpaint', 06:18:19 INFO - 'sessionrestore', 06:18:19 INFO - 'sessionrestore_no_auto_restore', 06:18:19 INFO - 'tabpaint']}, 06:18:19 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:18:19 INFO - 'tests': ['a11yr', 06:18:19 INFO - 'ts_paint', 06:18:19 INFO - 'tpaint', 06:18:19 INFO - 'sessionrestore', 06:18:19 INFO - 'sessionrestore_no_auto_restore', 06:18:19 INFO - 'tabpaint']}, 06:18:19 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:18:19 INFO - 'tests': ['a11yr', 06:18:19 INFO - 'ts_paint', 06:18:19 INFO - 'tpaint', 06:18:19 INFO - 'sessionrestore', 06:18:19 INFO - 'sessionrestore_no_auto_restore', 06:18:19 INFO - 'tabpaint']}, 06:18:19 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:18:19 INFO - 'tests': ['tsvgx', 06:18:19 INFO - 'tsvgr_opacity', 06:18:19 INFO - 'tart', 06:18:19 INFO - 'tscrollx', 06:18:19 INFO - 'cart']}, 06:18:19 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:18:19 INFO - 'tsvgr_opacity', 06:18:19 INFO - 'tart', 06:18:19 INFO - 'tscrollx', 06:18:19 INFO - 'cart']}, 06:18:19 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:18:19 INFO - 'talos_options': ['--disable-e10s'], 06:18:19 INFO - 'tests': ['tp5o']}, 06:18:19 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:18:19 INFO - 'tests': ['tp5o']}, 06:18:19 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:18:19 INFO - 'talos_options': ['--disable-e10s', 06:18:19 INFO - '--xperf_path', 06:18:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:18:19 INFO - 'tests': ['tp5n']}, 06:18:19 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:18:19 INFO - 'talos_options': ['--xperf_path', 06:18:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:18:19 INFO - 'tests': ['tp5n']}}, 06:18:19 INFO - 'talos.zip': {'path': '', 06:18:19 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:18:19 INFO - Running post-action listener: _resource_record_post_action 06:18:19 INFO - [mozharness: 2016-10-25 13:18:19.624358Z] Finished populate-webroot step (success) 06:18:19 INFO - [mozharness: 2016-10-25 13:18:19.624477Z] Running create-virtualenv step. 06:18:19 INFO - Running pre-action listener: _resource_record_pre_action 06:18:19 INFO - Running main action method: create_virtualenv 06:18:19 INFO - Creating virtualenv /builds/slave/test/build/venv 06:18: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 06:18:19 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:18:19 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:18:19 INFO - Using real prefix '/tools/python27' 06:18:19 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:18:20 INFO - Installing distribute.............................................................................................................................................................................................done. 06:18:23 INFO - Installing pip.................done. 06:18:23 INFO - Return code: 0 06:18:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 06:18:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 06:18:23 INFO - Reading from file tmpfile_stdout 06:18:23 INFO - Output received: 06:18: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) 06:18:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:18: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')]} 06:18:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:18:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:18: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')]} 06:18:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:18:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:18: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 0x10cf20490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce96570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4e2d68350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd34d30>, '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 0x10cb5adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd2cb20>, '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.bqC0yHTOCk/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.gNFkWmQuXp/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 06:18: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 06:18: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 06:18:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bqC0yHTOCk/Render', 06:18:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:18:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:18:23 INFO - 'HOME': '/Users/cltbld', 06:18:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:18:23 INFO - 'LOGNAME': 'cltbld', 06:18:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:18:23 INFO - 'MOZ_NO_REMOTE': '1', 06:18:23 INFO - 'NO_EM_RESTART': '1', 06:18:23 INFO - 'PAGER': '/bin/cat', 06:18:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:18:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:18:23 INFO - 'PWD': '/builds/slave/test', 06:18:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:18:23 INFO - 'SHELL': '/bin/bash', 06:18:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gNFkWmQuXp/Listeners', 06:18:23 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 06:18:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:18:23 INFO - 'USER': 'cltbld', 06:18:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:18:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:18:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:18:23 INFO - 'XPC_FLAGS': '0x0', 06:18:23 INFO - 'XPC_SERVICE_NAME': '0', 06:18:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:18:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:18: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 06:18:23 INFO - Cleaning up... 06:18:23 INFO - Return code: 0 06:18:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:18: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')]} 06:18:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:18:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:18: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')]} 06:18:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:18:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:18: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 0x10cf20490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce96570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4e2d68350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd34d30>, '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 0x10cb5adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd2cb20>, '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.bqC0yHTOCk/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.gNFkWmQuXp/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 06:18: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 06:18: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 06:18:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bqC0yHTOCk/Render', 06:18:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:18:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:18:23 INFO - 'HOME': '/Users/cltbld', 06:18:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:18:23 INFO - 'LOGNAME': 'cltbld', 06:18:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:18:23 INFO - 'MOZ_NO_REMOTE': '1', 06:18:23 INFO - 'NO_EM_RESTART': '1', 06:18:23 INFO - 'PAGER': '/bin/cat', 06:18:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:18:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:18:23 INFO - 'PWD': '/builds/slave/test', 06:18:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:18:23 INFO - 'SHELL': '/bin/bash', 06:18:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gNFkWmQuXp/Listeners', 06:18:23 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 06:18:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:18:23 INFO - 'USER': 'cltbld', 06:18:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:18:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:18:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:18:23 INFO - 'XPC_FLAGS': '0x0', 06:18:23 INFO - 'XPC_SERVICE_NAME': '0', 06:18:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:18:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:18:24 INFO - Downloading/unpacking psutil>=3.1.1 06:18:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:18:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:18:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:18:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:18:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:18:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:18:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:18:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:18:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:18:27 INFO - Installing collected packages: psutil 06:18:27 INFO - Running setup.py install for psutil 06:18:27 INFO - building 'psutil._psutil_osx' extension 06:18:27 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 06:18: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 06:18: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 06:18: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 06:18:28 INFO - building 'psutil._psutil_posix' extension 06:18: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 06:18:28 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:18:28 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:18:28 INFO - ^ 06:18:28 INFO - 1 warning generated. 06:18: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 06:18:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:18:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:18:28 INFO - Successfully installed psutil 06:18:28 INFO - Cleaning up... 06:18:29 INFO - Return code: 0 06:18:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:18:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:18:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:18:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:18:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:18:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:18:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:18:29 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 0x10cf20490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce96570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4e2d68350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd34d30>, '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 0x10cb5adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd2cb20>, '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.bqC0yHTOCk/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.gNFkWmQuXp/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 06:18:29 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 06:18:29 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 06:18:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bqC0yHTOCk/Render', 06:18:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:18:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:18:29 INFO - 'HOME': '/Users/cltbld', 06:18:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:18:29 INFO - 'LOGNAME': 'cltbld', 06:18:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:18:29 INFO - 'MOZ_NO_REMOTE': '1', 06:18:29 INFO - 'NO_EM_RESTART': '1', 06:18:29 INFO - 'PAGER': '/bin/cat', 06:18:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:18:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:18:29 INFO - 'PWD': '/builds/slave/test', 06:18:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:18:29 INFO - 'SHELL': '/bin/bash', 06:18:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gNFkWmQuXp/Listeners', 06:18:29 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 06:18:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:18:29 INFO - 'USER': 'cltbld', 06:18:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:18:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:18:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:18:29 INFO - 'XPC_FLAGS': '0x0', 06:18:29 INFO - 'XPC_SERVICE_NAME': '0', 06:18:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:18:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:18:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:18: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 06:18: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 06:18: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 06:18: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 06:18: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 06:18: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 06:18:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:18:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:18:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:18:31 INFO - Installing collected packages: mozsystemmonitor 06:18:31 INFO - Running setup.py install for mozsystemmonitor 06:18:32 INFO - Successfully installed mozsystemmonitor 06:18:32 INFO - Cleaning up... 06:18:32 INFO - Return code: 0 06:18:32 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:18:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:18:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:18:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:18:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:18:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:18:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:18:32 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 0x10cf20490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce96570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4e2d68350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd34d30>, '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 0x10cb5adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd2cb20>, '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.bqC0yHTOCk/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.gNFkWmQuXp/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 06:18:32 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 06:18:32 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 06:18:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bqC0yHTOCk/Render', 06:18:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:18:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:18:32 INFO - 'HOME': '/Users/cltbld', 06:18:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:18:32 INFO - 'LOGNAME': 'cltbld', 06:18:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:18:32 INFO - 'MOZ_NO_REMOTE': '1', 06:18:32 INFO - 'NO_EM_RESTART': '1', 06:18:32 INFO - 'PAGER': '/bin/cat', 06:18:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:18:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:18:32 INFO - 'PWD': '/builds/slave/test', 06:18:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:18:32 INFO - 'SHELL': '/bin/bash', 06:18:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gNFkWmQuXp/Listeners', 06:18:32 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 06:18:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:18:32 INFO - 'USER': 'cltbld', 06:18:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:18:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:18:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:18:32 INFO - 'XPC_FLAGS': '0x0', 06:18:32 INFO - 'XPC_SERVICE_NAME': '0', 06:18:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:18:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:18:32 INFO - Downloading/unpacking jsonschema==2.5.1 06:18: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 06:18: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 06:18: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 06:18: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 06:18: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 06:18: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 06:18:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:18:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:18: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 06:18: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 06:18: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 06:18: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 06:18: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 06:18: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 06:18:35 INFO - Downloading functools32-3.2.3-2.tar.gz 06:18:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:18:35 INFO - warning: no files found matching '*.txt' 06:18:35 INFO - no previously-included directories found matching 'build' 06:18:35 INFO - no previously-included directories found matching 'dist' 06:18:35 INFO - no previously-included directories found matching '.git*' 06:18:35 INFO - Installing collected packages: jsonschema, functools32 06:18:35 INFO - Running setup.py install for functools32 06:18:35 INFO - warning: no files found matching '*.txt' 06:18:35 INFO - no previously-included directories found matching 'build' 06:18:35 INFO - no previously-included directories found matching 'dist' 06:18:35 INFO - no previously-included directories found matching '.git*' 06:18:35 INFO - Successfully installed jsonschema functools32 06:18:35 INFO - Cleaning up... 06:18:35 INFO - Return code: 0 06:18:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:18: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')]} 06:18:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:18:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:18: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')]} 06:18:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:18:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:18: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 0x10cf20490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce96570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4e2d68350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd34d30>, '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 0x10cb5adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd2cb20>, '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.bqC0yHTOCk/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.gNFkWmQuXp/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 06:18: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 06:18: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 06:18:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bqC0yHTOCk/Render', 06:18:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:18:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:18:35 INFO - 'HOME': '/Users/cltbld', 06:18:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:18:35 INFO - 'LOGNAME': 'cltbld', 06:18:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:18:35 INFO - 'MOZ_NO_REMOTE': '1', 06:18:35 INFO - 'NO_EM_RESTART': '1', 06:18:35 INFO - 'PAGER': '/bin/cat', 06:18:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:18:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:18:35 INFO - 'PWD': '/builds/slave/test', 06:18:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:18:35 INFO - 'SHELL': '/bin/bash', 06:18:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gNFkWmQuXp/Listeners', 06:18:35 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 06:18:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:18:35 INFO - 'USER': 'cltbld', 06:18:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:18:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:18:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:18:35 INFO - 'XPC_FLAGS': '0x0', 06:18:35 INFO - 'XPC_SERVICE_NAME': '0', 06:18:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:18:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:18:36 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:18:36 INFO - Cleaning up... 06:18:36 INFO - Return code: 0 06:18:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:18:36 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')]} 06:18:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:18:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:18:36 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')]} 06:18:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:18:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:18:36 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 0x10cf20490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce96570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4e2d68350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd34d30>, '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 0x10cb5adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd2cb20>, '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.bqC0yHTOCk/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.gNFkWmQuXp/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 06:18:36 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 06:18:36 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 06:18:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bqC0yHTOCk/Render', 06:18:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:18:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:18:36 INFO - 'HOME': '/Users/cltbld', 06:18:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:18:36 INFO - 'LOGNAME': 'cltbld', 06:18:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:18:36 INFO - 'MOZ_NO_REMOTE': '1', 06:18:36 INFO - 'NO_EM_RESTART': '1', 06:18:36 INFO - 'PAGER': '/bin/cat', 06:18:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:18:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:18:36 INFO - 'PWD': '/builds/slave/test', 06:18:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:18:36 INFO - 'SHELL': '/bin/bash', 06:18:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gNFkWmQuXp/Listeners', 06:18:36 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 06:18:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:18:36 INFO - 'USER': 'cltbld', 06:18:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:18:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:18:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:18:36 INFO - 'XPC_FLAGS': '0x0', 06:18:36 INFO - 'XPC_SERVICE_NAME': '0', 06:18:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:18:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:18:36 INFO - Downloading/unpacking blobuploader==1.2.4 06:18: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 06:18: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 06:18: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 06:18: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 06:18: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 06:18: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 06:18:38 INFO - Downloading blobuploader-1.2.4.tar.gz 06:18:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:18:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:18: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 06:18: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 06:18: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 06:18: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 06:18: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 06:18: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 06:18:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:18:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:18: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 06:18: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 06:18: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 06:18: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 06:18: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 06:18: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 06:18:39 INFO - Downloading docopt-0.6.1.tar.gz 06:18:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:18:40 INFO - Installing collected packages: blobuploader, requests, docopt 06:18:40 INFO - Running setup.py install for blobuploader 06:18:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:18:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:18:40 INFO - Running setup.py install for requests 06:18:40 INFO - Running setup.py install for docopt 06:18:40 INFO - Successfully installed blobuploader requests docopt 06:18:40 INFO - Cleaning up... 06:18:41 INFO - Return code: 0 06:18:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:18:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:18:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:18:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:18:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:18:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:18:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:18:41 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 0x10cf20490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce96570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4e2d68350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd34d30>, '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 0x10cb5adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd2cb20>, '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.bqC0yHTOCk/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.gNFkWmQuXp/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 06:18:41 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 06:18:41 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 06:18:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bqC0yHTOCk/Render', 06:18:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:18:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:18:41 INFO - 'HOME': '/Users/cltbld', 06:18:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:18:41 INFO - 'LOGNAME': 'cltbld', 06:18:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:18:41 INFO - 'MOZ_NO_REMOTE': '1', 06:18:41 INFO - 'NO_EM_RESTART': '1', 06:18:41 INFO - 'PAGER': '/bin/cat', 06:18:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:18:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:18:41 INFO - 'PWD': '/builds/slave/test', 06:18:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:18:41 INFO - 'SHELL': '/bin/bash', 06:18:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gNFkWmQuXp/Listeners', 06:18:41 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 06:18:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:18:41 INFO - 'USER': 'cltbld', 06:18:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:18:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:18:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:18:41 INFO - 'XPC_FLAGS': '0x0', 06:18:41 INFO - 'XPC_SERVICE_NAME': '0', 06:18:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:18:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:18:41 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-RZ3yWt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:18:41 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-KeaifP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:18:41 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-BqGXBx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:18:41 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-unBQQB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:18:41 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-gyCH0i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:18:41 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-SoCP9u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:18:41 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-tQYEUG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:18:42 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-6AtriN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:18:42 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-LKGdKT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:18:42 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-Hzyw4h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:18:42 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-VGuO4z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:18:42 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-OScZyJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:18:42 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-ELpdGc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:18:42 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-N9Gczw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:18:42 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-xuAfO1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:18:42 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-VF1loH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:18:42 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-AI63No-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:18:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:18:43 INFO - Running setup.py install for manifestparser 06:18:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:18:43 INFO - Running setup.py install for mozcrash 06:18:43 INFO - Running setup.py install for mozdebug 06:18:43 INFO - Running setup.py install for mozdevice 06:18:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:18:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:18:43 INFO - Running setup.py install for mozfile 06:18:43 INFO - Running setup.py install for mozhttpd 06:18:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:18:43 INFO - Running setup.py install for mozinfo 06:18:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:18:44 INFO - Running setup.py install for mozInstall 06:18:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:18:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:18:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:18:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:18:44 INFO - Running setup.py install for mozleak 06:18:44 INFO - Running setup.py install for mozlog 06:18:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:18:44 INFO - Running setup.py install for moznetwork 06:18:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:18:44 INFO - Running setup.py install for mozprocess 06:18:44 INFO - Running setup.py install for mozprofile 06:18:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:18:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:18:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:18:45 INFO - Running setup.py install for mozrunner 06:18:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:18:45 INFO - Running setup.py install for mozscreenshot 06:18:45 INFO - Running setup.py install for moztest 06:18:45 INFO - Running setup.py install for mozversion 06:18:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:18:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:18:45 INFO - Cleaning up... 06:18:45 INFO - Return code: 0 06:18:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:18: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')]} 06:18:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:18:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:18: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')]} 06:18:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:18:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:18: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 0x10cf20490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce96570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4e2d68350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd34d30>, '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 0x10cb5adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd2cb20>, '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.bqC0yHTOCk/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.gNFkWmQuXp/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 06:18: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 06:18: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 06:18:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bqC0yHTOCk/Render', 06:18:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:18:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:18:45 INFO - 'HOME': '/Users/cltbld', 06:18:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:18:45 INFO - 'LOGNAME': 'cltbld', 06:18:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:18:45 INFO - 'MOZ_NO_REMOTE': '1', 06:18:45 INFO - 'NO_EM_RESTART': '1', 06:18:45 INFO - 'PAGER': '/bin/cat', 06:18:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:18:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:18:45 INFO - 'PWD': '/builds/slave/test', 06:18:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:18:45 INFO - 'SHELL': '/bin/bash', 06:18:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gNFkWmQuXp/Listeners', 06:18:45 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 06:18:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:18:45 INFO - 'USER': 'cltbld', 06:18:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:18:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:18:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:18:45 INFO - 'XPC_FLAGS': '0x0', 06:18:45 INFO - 'XPC_SERVICE_NAME': '0', 06:18:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:18:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:18:45 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-FxJqct-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:18:46 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)) 06:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:18:46 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-MiUm8F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:18:46 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)) 06:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:18:46 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-KVOpWo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:18: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)) 06:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:18:46 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-jxZCnC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:18: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)) 06:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:18:46 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-HYiMXM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:18: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)) 06:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:18:46 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-Q4qbn0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:18: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)) 06:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:18:46 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-CDMc3R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:18: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)) 06:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:18:46 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-knElPO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:18: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)) 06:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:18:46 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-8KCr1S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:18: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)) 06:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:18:46 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-eYLm5f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:18: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)) 06:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:18:46 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-GH0cXH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:18:47 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)) 06:18:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:18:47 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-t2e2rG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:18: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)) 06:18:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:18:47 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-Zn_qGo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:18: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)) 06:18:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:18:47 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-kw6_NN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:18: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)) 06:18:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:18:47 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-x1Kro8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:18: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)) 06:18:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:18:47 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-3svTFm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:18: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)) 06:18:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:18:47 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-eb8tuT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:18: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)) 06:18: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)) 06:18: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)) 06:18: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)) 06:18: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)) 06:18: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)) 06:18:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:18: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 06:18: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 06:18: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 06:18: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 06:18: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 06:18: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 06:18:50 INFO - Downloading blessings-1.6.tar.gz 06:18:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:18:50 INFO - Installing collected packages: blessings 06:18:50 INFO - Running setup.py install for blessings 06:18:50 INFO - Successfully installed blessings 06:18:50 INFO - Cleaning up... 06:18:50 INFO - Return code: 0 06:18:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:18:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:18:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:18:50 INFO - Reading from file tmpfile_stdout 06:18:50 INFO - Current package versions: 06:18:50 INFO - blessings == 1.6 06:18:50 INFO - blobuploader == 1.2.4 06:18:50 INFO - docopt == 0.6.1 06:18:50 INFO - functools32 == 3.2.3-2 06:18:50 INFO - jsonschema == 2.5.1 06:18:50 INFO - manifestparser == 1.1 06:18:50 INFO - mozInstall == 1.12 06:18:50 INFO - mozcrash == 0.17 06:18:50 INFO - mozdebug == 0.1 06:18:50 INFO - mozdevice == 0.48 06:18:50 INFO - mozfile == 1.2 06:18:50 INFO - mozhttpd == 0.7 06:18:50 INFO - mozinfo == 0.9 06:18:50 INFO - mozleak == 0.1 06:18:50 INFO - mozlog == 3.4 06:18:50 INFO - moznetwork == 0.27 06:18:50 INFO - mozprocess == 0.23 06:18:50 INFO - mozprofile == 0.28 06:18:50 INFO - mozrunner == 6.12 06:18:50 INFO - mozscreenshot == 0.1 06:18:50 INFO - mozsystemmonitor == 0.3 06:18:50 INFO - moztest == 0.7 06:18:50 INFO - mozversion == 1.4 06:18:50 INFO - psutil == 3.1.1 06:18:50 INFO - requests == 1.2.3 06:18:50 INFO - wsgiref == 0.1.2 06:18:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:18: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')]} 06:18:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:18:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:18: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')]} 06:18:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:18:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:18: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 0x10cf20490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce96570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4e2d68350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd34d30>, '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 0x10cb5adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd2cb20>, '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.bqC0yHTOCk/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.gNFkWmQuXp/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 06:18: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 06:18: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 06:18:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bqC0yHTOCk/Render', 06:18:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:18:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:18:50 INFO - 'HOME': '/Users/cltbld', 06:18:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:18:50 INFO - 'LOGNAME': 'cltbld', 06:18:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:18:50 INFO - 'MOZ_NO_REMOTE': '1', 06:18:50 INFO - 'NO_EM_RESTART': '1', 06:18:50 INFO - 'PAGER': '/bin/cat', 06:18:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:18:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:18:50 INFO - 'PWD': '/builds/slave/test', 06:18:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:18:50 INFO - 'SHELL': '/bin/bash', 06:18:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gNFkWmQuXp/Listeners', 06:18:50 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 06:18:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:18:50 INFO - 'USER': 'cltbld', 06:18:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:18:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:18:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:18:50 INFO - 'XPC_FLAGS': '0x0', 06:18:50 INFO - 'XPC_SERVICE_NAME': '0', 06:18:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:18:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:18:51 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)) 06:18:51 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)) 06:18:51 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)) 06:18:51 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)) 06:18:51 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)) 06:18:51 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)) 06:18:51 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)) 06:18:51 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)) 06:18:51 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)) 06:18:51 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)) 06:18:51 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)) 06:18:51 INFO - Cleaning up... 06:18:51 INFO - Return code: 0 06:18:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:18: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')]} 06:18:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:18:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:18: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')]} 06:18:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:18:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:18: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 0x10cf20490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce96570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4e2d68350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd34d30>, '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 0x10cb5adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd2cb20>, '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.bqC0yHTOCk/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.gNFkWmQuXp/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 06:18: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 06:18: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 06:18:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bqC0yHTOCk/Render', 06:18:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:18:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:18:51 INFO - 'HOME': '/Users/cltbld', 06:18:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:18:51 INFO - 'LOGNAME': 'cltbld', 06:18:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:18:51 INFO - 'MOZ_NO_REMOTE': '1', 06:18:51 INFO - 'NO_EM_RESTART': '1', 06:18:51 INFO - 'PAGER': '/bin/cat', 06:18:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:18:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:18:51 INFO - 'PWD': '/builds/slave/test', 06:18:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:18:51 INFO - 'SHELL': '/bin/bash', 06:18:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gNFkWmQuXp/Listeners', 06:18:51 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 06:18:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:18:51 INFO - 'USER': 'cltbld', 06:18:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:18:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:18:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:18:51 INFO - 'XPC_FLAGS': '0x0', 06:18:51 INFO - 'XPC_SERVICE_NAME': '0', 06:18:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:18:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:18:51 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:18:51 INFO - Cleaning up... 06:18:51 INFO - Return code: 0 06:18:51 INFO - Running post-action listener: _resource_record_post_action 06:18:51 INFO - Running post-action listener: _start_resource_monitoring 06:18:51 INFO - Starting resource monitoring. 06:18:51 INFO - [mozharness: 2016-10-25 13:18:51.387354Z] Finished create-virtualenv step (success) 06:18:51 INFO - [mozharness: 2016-10-25 13:18:51.388350Z] Running install step. 06:18:51 INFO - Running pre-action listener: _resource_record_pre_action 06:18:51 INFO - Running main action method: install 06:18:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:18:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:18:51 INFO - Reading from file tmpfile_stdout 06:18:51 INFO - Detecting whether we're running mozinstall >=1.0... 06:18:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:18:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:18:51 INFO - Reading from file tmpfile_stdout 06:18:51 INFO - Output received: 06:18:51 INFO - Usage: mozinstall [options] installer 06:18:51 INFO - Options: 06:18:51 INFO - -h, --help show this help message and exit 06:18:51 INFO - -d DEST, --destination=DEST 06:18:51 INFO - Directory to install application into. [default: 06:18:51 INFO - "/builds/slave/test"] 06:18:51 INFO - --app=APP Application being installed. [default: firefox] 06:18:51 INFO - mkdir: /builds/slave/test/build/application 06:18: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'] 06:18: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 06:19:25 INFO - Reading from file tmpfile_stdout 06:19:25 INFO - Output received: 06:19:25 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 06:19:25 INFO - Running post-action listener: _resource_record_post_action 06:19:25 INFO - [mozharness: 2016-10-25 13:19:25.517068Z] Finished install step (success) 06:19:25 INFO - [mozharness: 2016-10-25 13:19:25.517199Z] Running run-tests step. 06:19:25 INFO - Running pre-action listener: _resource_record_pre_action 06:19:25 INFO - Running main action method: run_tests 06:19:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:19:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:19:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:19:25 INFO - 2016-10-25 06:19:25.531 screenresolution[1647:8090] starting screenresolution argv=screenresolution get 06:19:25 INFO - 2016-10-25 06:19:25.534 screenresolution[1647:8090] Display 0: 1600x1200x32@60 06:19:25 INFO - 2016-10-25 06:19:25.540 screenresolution[1648:8092] starting screenresolution argv=screenresolution list 06:19:25 INFO - Available Modes on Display 0 06:19:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:19:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:19:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:19:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:19:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:19:25 INFO - Intel Iris: 06:19:25 INFO - Chipset Model: Intel Iris 06:19:25 INFO - Type: GPU 06:19:25 INFO - Bus: Built-In 06:19:25 INFO - VRAM (Dynamic, Max): 1536 MB 06:19:25 INFO - Vendor: Intel (0x8086) 06:19:25 INFO - Device ID: 0x0a2e 06:19:25 INFO - Revision ID: 0x0009 06:19:25 INFO - Displays: 06:19:25 INFO - Display: 06:19:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:19:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:19:25 INFO - Display Serial Number: 1600x1200 60 06:19:25 INFO - Main Display: Yes 06:19:25 INFO - Mirror: Off 06:19:25 INFO - Online: Yes 06:19:25 INFO - Rotation: Supported 06:19:25 INFO - Return code: 0 06:19:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:19:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:19:25 INFO - Python 2.7.3 06:19:25 INFO - Return code: 0 06:19:25 INFO - grabbing minidump binary from tooltool 06:19:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:19:25 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 0x10cd34d30>, '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 0x10cb5adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd2cb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:19:25 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 06:19:25 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 06:19:25 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 06:19:25 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:19:25 INFO - Return code: 0 06:19:25 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:19:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:19:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:19:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:19:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:19:25 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/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bJ1MPhptTY-3XRESFarC8Q/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 06:19:25 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/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/bJ1MPhptTY-3XRESFarC8Q/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 06:19:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bqC0yHTOCk/Render', 06:19:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:19:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:19:25 INFO - 'HOME': '/Users/cltbld', 06:19:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:19:25 INFO - 'LOGNAME': 'cltbld', 06:19:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:19:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:19:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:19:25 INFO - 'MOZ_NO_REMOTE': '1', 06:19:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:19:25 INFO - 'NO_EM_RESTART': '1', 06:19:25 INFO - 'PAGER': '/bin/cat', 06:19:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:19:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:19:25 INFO - 'PWD': '/builds/slave/test', 06:19:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:19:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:19:25 INFO - 'SHELL': '/bin/bash', 06:19:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gNFkWmQuXp/Listeners', 06:19:25 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 06:19:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:19:25 INFO - 'USER': 'cltbld', 06:19:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:19:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:19:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:19:25 INFO - 'XPC_FLAGS': '0x0', 06:19:25 INFO - 'XPC_SERVICE_NAME': '0', 06:19:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:19:25 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/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bJ1MPhptTY-3XRESFarC8Q/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 06:19:26 INFO - mozversion application_buildid: 20161025033504 06:19:26 INFO - mozversion application_changeset: c6ccd71126ff514bfc44b53e2217562e29a0cc38 06:19:26 INFO - mozversion application_display_name: Nightly 06:19:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:19:26 INFO - mozversion application_name: Firefox 06:19:26 INFO - mozversion application_remotingname: firefox 06:19:26 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 06:19:26 INFO - mozversion application_vendor: Mozilla 06:19:26 INFO - mozversion application_version: 52.0a1 06:19:26 INFO - mozversion platform_buildid: 20161025033504 06:19:26 INFO - mozversion platform_changeset: c6ccd71126ff514bfc44b53e2217562e29a0cc38 06:19:26 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 06:19:26 INFO - mozversion platform_version: 52.0a1 06:19:26 INFO - using testdate: 1477401566 06:19:26 INFO - actual date: 1477401566 06:19:26 INFO - starting webserver on 'localhost:49266' 06:19:26 INFO - SUITE-START | Running 2 tests 06:19:26 INFO - TEST-START | dromaeo_css 06:19:26 INFO - operating with platform_type : mac_ 06:19:26 INFO - Initialising browser for dromaeo_css test... 06:19:26 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/tmp7z_6G5/profile http://localhost:49266/getInfo.html) 06:19:28 INFO - PROCESS | 1658 | __metrics Screen width/height:1600/1200 06:19:28 INFO - PROCESS | 1658 | colorDepth:24 06:19:28 INFO - PROCESS | 1658 | Browser inner width/height: 1024/689 06:19:28 INFO - PROCESS | 1658 | __metrics 06:19:28 INFO - PROCESS | 1658 | 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" 06:19:28 INFO - PROCESS | 1658 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:19:29 INFO - TEST-INFO | 1658: exit 0 06:19:29 INFO - Browser initialized. 06:19:29 INFO - Running cycle 1/1 for dromaeo_css test... 06:19:29 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/tmp7z_6G5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:19:41 INFO - PROCESS | 1661 | RSS: Main: 231092224 06:19:41 INFO - PROCESS | 1661 | 06:20:32 INFO - PROCESS | 1661 | 1477401632195 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] 06:22:45 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49266/tests/dromaeo/cssquery-ext.html) 06:22:47 INFO - PROCESS | 1661 | RSS: Main: 403705856 06:22:47 INFO - PROCESS | 1661 | 06:25:50 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-ext.html (next: http://localhost:49266/tests/dromaeo/cssquery-jquery.html) 06:25:50 INFO - PROCESS | 1661 | RSS: Main: 370597888 06:25:50 INFO - PROCESS | 1661 | 06:28:53 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49266/tests/dromaeo/cssquery-mootools.html) 06:28:54 INFO - PROCESS | 1661 | RSS: Main: 336674816 06:28:54 INFO - PROCESS | 1661 | 06:31:56 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49266/tests/dromaeo/cssquery-prototype.html) 06:31:56 INFO - PROCESS | 1661 | RSS: Main: 354045952 06:31:56 INFO - PROCESS | 1661 | 06:35:00 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49266/tests/dromaeo/cssquery-yui.html) 06:35:00 INFO - PROCESS | 1661 | RSS: Main: 335048704 06:35:00 INFO - PROCESS | 1661 | 06:38:04 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-yui.html (next: http://localhost:49266/tests/dromaeo/cssquery-dojo.html) 06:38:04 INFO - PROCESS | 1661 | RSS: Main: 473309184 06:38:04 INFO - PROCESS | 1661 | 06:38:04 INFO - PROCESS | 1661 | __start_tp_report 06:38:04 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 06:38:04 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 06:38:04 INFO - PROCESS | 1661 | |i|pagename|runs| 06:38:04 INFO - PROCESS | 1661 | |0;dojo.html;3318.7510936679746;3341.2482191506915;3342.247994201304;3367.3602015617016;3405.625381208068;5558.722063896809;5689.2603961485;5695.572832037604;5756.654600723954;5762.711864406773;5627.352854421742;5653.575981801361;5707.857303567413;5710.14347847822;5755.913661295084;5136.512031357015;5148.639595228336;5225.085610018251;5227.660202086871;5233.947660523384;5247.1866860636665;5263.473652634725;5263.789448422078;5282.392524859632;5284.075286824809;4348.026600994316;5119.360079990001;5243.580513558906;5286.2070689396705;5347.679139251638;5076.263941728465;5105.566026887719;5207.3490813648295;5226.372835259755;5244.265802787594;5111.105556527593;5156;5163.251328557376;5169.0954083035695;5173.3016042834115;5635.915461268084;5642.238297829782;5653.293338332708;5654.688992105433;5664.971675141639;6095.213525956478;6097.010520468062;6110.674531727158;6129.080637904305;6131.866048757612;6141.263048434172;6153;6212.937870621281;6226.470749986257;6232.1898153240245;5854.736536855852;5859.062549991981;5871.148683440877;5885.529157667376;5904.498117660004;6160.630362178194;6168.814935551851;6211.937880621136;6213.440790328892;6246.281677607042;6071.362506936796;6124.301675977689;6131.669965413114;6131.877362452726;6141.969290153521;6201.503879689614;6208.72060757267;6222.844428889223;6223.471004964538;6228.252609686776;6007.909881351783;6091.573589848668;6104.481119104926;6179.196704428396;6180.47465965389;6126.8230438726505;6191.380861913863;6193.1329613854095;6207.6275423474735;6230.971889638249;4893.339399181136;4895.755212239374;4901.313816065754;4919.631027672939;4937.136001199776;4397.868063958086;4416.447944006999;4417.249067658506;4418.823247070145;4475.462944446687;5688.203651488795;5766.452187042224;5766.625169363978;5768.711564421762;5778.075507918713;6183.536234782409;6197.752089916443;6200.720967556381;6203.410675985774;6203.596766210182;5744.51171650406;5759.481646651821;5818.6217895836635;5881.970590147107;5889.4993925515955;4745.501722319176;5205.7397130143345;5387.949349876815;5417.59368047398;5437.891242175135;6284.622922624566;6298.118263443122;6302.65335406557;6304.05439184126;6307.716152773136;6123.357047510125;6137.2544124621345;6142.744345496893;6148.754049837957;6262.405071518198;6207.534434917489;6283.37166283368;6291.937080629135;6295.307516173309;6298.244210694746;6149.538784591084;6169.1054797053275;6169.537284703755;6175.722092505938;6180.258368995749;6121.204243448323;6131.77428366204;6230.158928544589;6248.031555108965;6261.436470717657;5991.7902873399225;6020;6059.787907423219;6062.878742425215;6134.294556125924;6061.909071363845;6076.179715738319;6078.878422431615;6089.573729839046;6090.908636370369;5893.286955135066;5915.526757859447;5934.732937017759;5966.970165149321;5976.731047102804;4634.976825115853;5413.160960051198;5986.820395388146;6003.309619393651;6075.635461872125;5915.556333274935;5922.407759224216;5948.643081415129;5969.462748352669;5982.52139828804 06:38:04 INFO - PROCESS | 1661 | |1;ext.html;468.25781136898013;497.98755031124216;514.331369220014;522.932018837551;525.0260766278553;30486.542701859464;31789.84105079477;31828.36343273133;31893.68106318936;31914.68085319146;33008.50487242687;33517.16207094824;42246.57753422465;43168.56831431692;43383.915402114886;9436.622535098604;9540.856887146698;9588.760280992996;9590.376625519353;9594.760130996747;9422.575984080733;9502.049795020512;9503.3369160397;9505.904940950606;9513.476758778264;9477.763055923622;9521.71434856952;9527.475988820612;9546.42721436716;9611.855822162674;9420.057994200559;9472.289578281656;9487.952560237223;9489.620415183384;9499.715008549754;9429.29280303973;9504.619815207383;9513.001134880808;9517.857232141454;9546.90453095474;40766.184676306606;41150;41159.1768164635;41203.1759364811;41237.79381103075;3364.091695242271;3370.427027405347;3380.2225488137865;3557.7331700122595;3590.8025058621765;30843.691563084307;31196.532052019236;31520.684793152235;31695;31720.682793172004;3104.099811054791;3126.640436349803;3145.1822526143137;3169.4928811390296;3255.9186020349443;466219.6689016567;469460;469481;470480.64759675984;470942;61395.69302153461;61566.69216653888;61608.69195653993;61629.691851540454;61651.691741541006;34931.650683493346;64035;64249.357506424334;64296.67851660712;64305.678471607345;15878.9206053969;16482.42311519115;16613.41853035138;16637.25132369022;16671.416500422427;10790.838137427787;11455.713607159887;11961.521539138512;12001.939990299992;12011.699707507207;19397.590960384605;19529.821028971266;19535.004287053303;19564.826649624778;19585.263820790708;7861.292483676497;7869.409794265339;7884.329831964347;7887.290143886963;7964.681412743541;850.6597361055627;854.239726643288;1002.4887307473095;1009.8131845608586;1063.2132222155549;27985.860070699517;29442.263943401158;30214.848925755232;30312.84843575768;30576;828.8176601147737;1008.2035192198229;1021.6373187518324;1028.2442404832443;1067.236925598201;14929;15566.45517406908;15612.76580851288;15768.605784855241;15796.198690304916;623961;627072;627308;628978;631282;58005.70997145072;58728;58783.70608146932;58847.41152588505;58998.410015900154;1217.8234156047324;1249.0881656391068;1250.849898012226;1346.1855577375743;1367.8495365509787;30507.389852203276;31342.216444589067;31856.68143318537;32322.676773231968;32503;54488.72755636355;55084.44915550954;55288.17067744068;55289.44710553004;55360;551655;556792;558082;559512.8341016253;561249;26636.60045099364;26785.196444106707;27037.324066898484;27041.86479067592;27369.45261094807;46693.76653116713;47086.058278833545;47371.163954199175;47896.5210347906;48288.758556206994;39247.2150556993;39277;39287.2142557153;40772.79613601913;40890.59109408987;38795.6120438792;39155.804220978716;39454.210915782096;40307.79846100751;40717.38923916203;37719.81140094282;38881;39078.6092139075;39097.804510977265;39177;31133.8443307782;34988.47517287294;35061;35110.82444587761;35115.82442087773;25340.746592534575;25509.744902550738;25531;25884.482310354066;26329.21012369632 06:38:04 INFO - PROCESS | 1661 | |2;jquery.html;351.2570912520021;358.7668015789736;375.55496736367405;380.63459079283876;403.19562472866636;15005.474808381708;15254.618634534128;15851.524454266362;20573.485662858435;20972.685409718866;20966.37100886972;21216.57566848662;21759.45601359967;22141.446463838372;22339.888300558476;5773.797917072882;5789.565782566303;5956.910646340309;6097.146399504073;6110.175126357929;4197.412362269285;5890.970545147268;6026.427489388501;6028.065649824284;6036.275646922353;4392.714473559225;6386.680665966706;6692.9665351673175;6725.899111513307;6781.321867813203;6412.583182093149;6625.668716564152;6702.631355275458;6777.932220677804;6794.286599907037;5812;5813.505852002585;5819.6508209507565;5823.708814559255;5825.126231065332;25362.492750145077;26371.604425933627;26381.20856374312;26418.339541511425;26636.733632663618;2318.652202169688;2328.161861729776;2412.3969007748065;2584.44434446593;2815.8873645054173;15687.921560392126;15776.921115394349;15795.21023948798;16164;16495.752563711554;1771.4242871066954;1825.3702472646914;1846.3814622101477;1891.5460289530552;1948.230448972662;117062.41468792602;117120.41439792747;118479.75709507993;121267.39366303201;122103;9478.668246611336;11266;11429.371244613785;11501.252418592765;11644.3595602241;14783.556493305003;14786.408543658348;14792.964492485422;14816.407343706347;14820.62948426298;6709.630970296681;7303.963480182565;7328.853422931512;7847.686092556348;8165.02019757621;7104.325089116525;7511.737089201852;7990.481808456375;8232.50604963692;8386.622601982925;4472.642188624902;4559.179347717442;4565.406497155349;4622.86131416058;4654.301854721819;1584.627612511059;1855.8422534084484;1900.4773687235845;1900.6768849295654;1907.7233801099042;743.605888878895;858.6007506509503;901.2609660078673;985.0641890204334;994.6518718448485;18961.620767584573;19806;20887.8955605221;21765;21938.780612193994;894.6600291889041;928.3547934185676;947.6541062512133;962.7593101724569;971.4365667912593;10992.560297588167;11304.71738206535;11516.769664606829;11561.537538498535;11615.76768464643;518245;554854;571604;583082;589233.0538347281;91455;115798.05048737816;132631;137971.31014345065;140108.29945850206;937.8311903857372;996.7259003773875;1012.6759436980095;1019;1019.3934608907688;18864.6227075455;19226.80773192306;19789.27031360472;20252.069505556203;20428.59142817165;56246.71876640591;56406.795252523014;56892;57440.563985900684;58121.70939145277;79832.60083699776;82539.7619035727;84156.7376489341;84282;84986.57506712427;6719.496037797283;7988.360931125612;8123.147069557612;8192.508449493049;8193.549354785544;12197.51209951592;12489.375531223584;12868.22790632565;13019.414126364147;13101.410436530188;10059.748506287402;11196.440177991231;11226.382548959558;11244.325340479598;11366.261193022427;10783.784324313312;10971.83542246851;11145.035886657144;11275.759666436521;11301.604443844426;11146.275492092987;11226;11274.887251127384;11478.196526243082;11527.308361498337;8087.452452477451;8108.472949258279;8113;8133.67465301405;8214.671413143407;9098.772530686521;10907.83638245411;11173.385463799568;11258.38078905668;11289.266197697123 06:38:04 INFO - PROCESS | 1661 | |3;mootools.html;1383.100984360165;1530.0590137065703;1561.9062856228627;1737.4353335166074;1891.0733740467176;7682.116556595993;8145.837083258339;8539.444936079151;8787.516686582252;8799.824003519925;8608.354373421987;8682.782930426727;8689;8696.782580435492;8701.869471957925;2306.238941149421;2334.404726794665;2336.8714720690355;2336.9298921032396;2338.9064437422485;2259.830512711545;2294.655801629752;2307.4346785037687;2308.3998160478227;2324.3027091872455;2191.51786606947;2293.8738369389675;2312.8612283262974;2329.7087864017;2329.743728189898;2280.1677387753516;2307.8384513084093;2312.375658572193;2313.8148948084113;2315.4558678710573;2282.4978504728933;2285.6685780561725;2286.919957801469;2294.403455101669;2294.655801629752;7978.045378882153;8024.077231118379;8619.698310559163;8685.956570217171;8749.781255468664;5245.921311180336;5434.3207099112615;5444.292242008513;5461.42655021225;5467.507924286793;3458.360203362361;3611.512445819807;3639.7816131032223;3672.320620685182;3674.2730916469486;1034.591336422109;1040.4173662748885;1049.071571659087;1055.2244100586063;1065.4938904020605;11414.201005929588;11430.599929002445;11516.942415287953;11520.078393728565;11536.30782153065;3580.838862251205;4458.7324760514475;4528.388667529907;4534.251848445036;4535.478419981679;8984;8992.055834137453;8993.550322483981;9018.729438116736;9021.007689154188;5197.480251974848;5226.947730522722;5228.764705588182;5244.056069907453;5248.448912864171;4060.7563546187325;4375.868723938287;4506.003686911451;4886.60907127429;4914.287428322873;3594.3350480161253;3626.927461450809;3703.2593481303306;3721.813909304524;3746.363118269846;1140.3600664461744;1142.1833389126814;1144.70237738187;1144.822552504363;1150.9769804603861;625.8779537990048;764.289211033744;906.6826610686207;938.0900526489295;941.6392270214792;5523.530499907472;5625.38120806711;5649.152627105967;5832.883342333214;5857.912131318034;464.3383178969954;474.75075585317984;477.6656340561621;479.62828807674333;637.5823835387829;3365.8317084145697;3686.9078273043388;3812.7712337259854;3933.783641899666;3972.880813575597;10059.295849290625;10568.418736969386;10578.52396642153;10587.25889187765;10597.788044239227;10508.842367364496;10516.634676903317;10533.262671612914;10535.473226338652;10547.156227501782;7692.115406728186;8062.879056814152;8231.05342885564;8241;8253.628586713612;5589.608727389206;5610.551155907599;5629.099344104923;5669.206311116364;5731.312242530923;8025.5184688916515;9771.560279787285;9776.511174441108;9797.55910983993;9889.50552472388;9424.528773561431;9816.26378021637;9866.753331166492;9887.901120988985;9962.402255864672;7325;7505.024346834876;7506.061742282215;7509.887351689838;7512.023436953161;8090.474119182234;8160.469569477965;8162.551059691774;8162.6734930602115;8176.264136227768;7134.2152363238965;7260.89108663381;7385.544991798833;7405.703771849281;7439.40484761207;6365.6313892513335;7098.041764361951;7117.430605551647;7286.96356518214;7467.178610352748;6290.056491526308;6500.70746816385;6740;6902.689378977858;6909.136357955255;4912.877178070434;4925.113479573711;4979.502049794992;4992.975035124801;5010.574101201439;4858.659893807399;4909.410870695397;5005.299258103795;5150.8197213098865;5171.198464238049 06:38:04 INFO - PROCESS | 1661 | |4;prototype.html;287.3405534298785;297.1842292905973;304.92376905773557;308.8039095174565;312.7676952805946;3853.633904779045;4567.862964111077;4645.55867192617;5110.003549307887;5129.487051294874;4372.628326592236;4487.169873573391;4590.78344238777;5167.9483205167935;5176.50823171798;3976.7216294859372;3976.960230397702;4002.6597739192202;4025.9733767889293;4038.940140528337;2946.8673909674008;3462.9653703463023;3940.7044471664594;4002.8198731057173;4016.076302450438;2984.5523171524337;3602.2975519773654;3858;3938.921221575581;3973.443717879499;3363.494196823358;3860.285847118292;3937.7243592948507;3970.344893092622;3980.20395920817;2841.9289517762013;2920.6933272006345;3403.2172600301824;3439.521005967433;3455.671711187433;5033.496650334974;5062.367204099488;5096.210087436453;5120.436751957282;5130.4356520782685;4495.3931219285305;4544.159330523864;4549;4563.201439748031;4574.931376029363;4730.486244401793;4758.428988521365;5459.454054594548;5473.972630136824;5497.092979658391;3190.7287880530184;3542.1321776165005;3659.4081574514903;3781.9432708509394;3803.9429408558894;4958.553730164303;5345.1714984177515;5529.944700553024;5651.208830763697;5692.658440493584;3660.213054193308;3730.7388482806487;3865.768053916774;3875.8255878485534;3877.7673339599714;3363.747718921091;3869.59369266223;3888.8833334999995;3966.841326346914;3978.5822488638278;4461.776911154429;4484.618807401343;4521.864344069683;4536.56902594253;4558.156741002858;5175.404828444702;5403.054465468527;5438.592105592096;5470.097433923438;5484.808031718871;1180.527788884453;1307.7842156044337;1330.5808670268834;1337.411538922871;1339.5445548513553;772.3550835052794;784.6586734770393;793.484235247094;839.5424493650975;844.6241422567022;3444.983729799665;3676.632336766302;4058.8361541878608;4189.748615083105;4202.516710578261;3986.920261594752;4128.4632997710105;4179.853705120307;4589.177655741695;4601.12578610063;3622.8007459589976;3906.160175562268;4203.453551038346;4228.78856057202;4244.0901594282695;2046.5372924025687;2281.2357860117045;2356.988215058948;2398.7286738028874;2433.7809597136343;3909.6090390960676;4250.53244143144;4724.929126063112;4753.738544380023;4758.976205118952;4243.596858298457;4608.308753686973;4646.558576935186;4698.694584852042;4729.196036673705;4129.1625226773795;4650.209464391061;4755.812326329858;5232.142589380791;5904.5238690327415;5354.437784032621;5633.563441322601;5670.74481648335;5676.631018983753;5679;5195.571217915103;5236.39781425141;5627.915581266202;5629.579280909248;5750;5477.315335583052;5821.330546987151;5823.505002074701;5825.563082768725;5859.355470898113;3765.1904840459433;3775.7356985010783;3783.6027217141805;3789.829457674356;3802.16352402471;5431.528654462131;5724.599278050663;5752.698920431861;5764.711764411679;5775.306963164448;4711.858644240679;5263;5353.357597088374;5420.539254163283;5573.972130139324;4567.1550763108935;5195.142801437721;5476.507114359727;5491.341039075177;5507.421720719267;4492.573205545468;5370.489803468664;5381.408045115112;5422.559848068307;5478.753456094406;4819.228923372244;4938.530839570235;5413.215083812746;5442.55772220354;5444.727763611724;4418.983633764316;4510.293823705751;4727.905441891074;5298.178782288752;5436.293281873332 06:38:04 INFO - PROCESS | 1661 | |5;yui.html;713.375796178344;724.1563578431125;759.6467642546215;808.4704518540358;810.9472884262524;12779.233246005246;13019.088663793538;13022.088453808237;13072.934635326821;13391.330433478324;13524.459021639123;13612.93193534031;13623.386947587358;13633.454661813541;13648.38582263798;715.6028404235647;733.5525329548971;740.078602140336;877.7191298784392;885.9778505537348;905.8414777413959;908.1554154636186;912.6212621761961;913.4427998920653;922.7416323429451;964.8263312603729;965.6378857928277;966.4974213409023;968.0174622757906;971.0483725948575;748.3825843678986;959.4531117263163;961.274237950343;961.7499450142943;962.6438217859367;575.0626478839507;694.6144889586277;712.7398499547679;715.2061212054615;716.5342527357208;14264.429422823074;15101.697966040727;15107.697846043018;15109.320080596399;15111.09333439986;13577.321133943362;13589.524366647218;13653.931730341385;13675.726485470235;13675.931620341935;12290.692732681666;12306.200096993667;12377.443015064253;12596.937015314956;12981;4085.775282359469;4388.49532303786;4535.705179163377;4589.586937175637;4591.219492686218;74918;93582.5320873398;96149.51925240329;96565.51717241439;101113;10032.64885728996;10692.62575809843;10775.407352595526;11021.283616564897;11255.606053788242;19700.70448943267;19886;20381.694274585894;21470.355889323346;21614.567708646053;7477.289657482531;7519.135299440525;7577.041850689732;7592.126905405838;7730.072391313079;4073.5315438724333;4084.509858816961;4085.754854708727;4106.609872062149;4152.730072545275;3341.599008119041;3424.7088997435;3450.0857272822723;3457.377672019061;3462;634.5906890055888;638.7700427845972;639.6418005916702;654.7086546486863;657.8750037492861;493.785288191054;509.34294759759496;552.9004779139793;575;584.7923986984645;8189.262966333059;8411.957940210259;8433.241008309282;8546.871796923051;8569.914300857037;558.891016251827;601.9006863867501;613.9815805525841;623.3647823674027;636.395424346863;3969.4442778011103;4152.4601801765575;4163.7293575917465;4249.575042495726;4294.398784170218;64780;101287;110512;114230;114565.42717286361;72492.55014899673;87415;101197;102044;104011;83011.5849420749;86000.68994135443;86545;88130.55934720286;88361.23277534414;8075.757727268191;8076.27313541784;8163.469374490639;8230.259276665129;8230.711925082833;48636.784080398276;49141.508584913696;50980.235296469844;51671.44985650437;53750.19374709304;57083.71458142683;73106;82193;82256;84635.5768221155;10509.9474502627;10777.353358798497;10790.1367890567;10825.621103261348;10838.078763304891;11348.205625606459;11718.06255499541;11896.286222826659;11950.521979120738;12225.816612750994;10599.523021464207;10974.396408197626;11131.165162612675;11261.7747645045;11372.658820235407;8506.957465212634;10867.945660271964;10952.233343665866;11180.552777888794;11190.440477976232;10298;10757.9462102689;10832.187585931244;10980.835287470854;10989.835152472879;8996.55017249122;9128.132827381389;9227.35408521397;9262.583183756613;9622;6755;7986;8589.8711519326;9268.3487040191;9307.255419566554 06:38:04 INFO - PROCESS | 1661 | __end_tp_report 06:38:04 INFO - PROCESS | 1661 | __start_cc_report 06:38:04 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,1915 06:38:04 INFO - PROCESS | 1661 | __end_cc_report 06:38:04 INFO - PROCESS | 1661 | __startTimestamp1477402684582__endTimestamp 06:38:04 INFO - PROCESS | 1661 | 06:38:04 INFO - PROCESS | 1661 | ------- Summary: start ------- 06:38:04 INFO - PROCESS | 1661 | Number of tests: 6 06:38:04 INFO - PROCESS | 1661 | 06:38:04 INFO - PROCESS | 1661 | [#0] dojo.html Cycles:165 Average:5707.81 Median:5948.64 stddev:641.63 (10.8%) stddev-sans-first:615.62 06:38:04 INFO - PROCESS | 1661 | Values: 3318.8 3341.2 3342.2 3367.4 3405.6 5558.7 5689.3 5695.6 5756.7 5762.7 5627.4 5653.6 5707.9 5710.1 5755.9 5136.5 5148.6 5225.1 5227.7 5233.9 5247.2 5263.5 5263.8 5282.4 5284.1 4348.0 5119.4 5243.6 5286.2 5347.7 5076.3 5105.6 5207.3 5226.4 5244.3 5111.1 5156.0 5163.3 5169.1 5173.3 5635.9 5642.2 5653.3 5654.7 5665.0 6095.2 6097.0 6110.7 6129.1 6131.9 6141.3 6153.0 6212.9 6226.5 6232.2 5854.7 5859.1 5871.1 5885.5 5904.5 6160.6 6168.8 6211.9 6213.4 6246.3 6071.4 6124.3 6131.7 6131.9 6142.0 6201.5 6208.7 6222.8 6223.5 6228.3 6007.9 6091.6 6104.5 6179.2 6180.5 6126.8 6191.4 6193.1 6207.6 6231.0 4893.3 4895.8 4901.3 4919.6 4937.1 4397.9 4416.4 4417.2 4418.8 4475.5 5688.2 5766.5 5766.6 5768.7 5778.1 6183.5 6197.8 6200.7 6203.4 6203.6 5744.5 5759.5 5818.6 5882.0 5889.5 4745.5 5205.7 5387.9 5417.6 5437.9 6284.6 6298.1 6302.7 6304.1 6307.7 6123.4 6137.3 6142.7 6148.8 6262.4 6207.5 6283.4 6291.9 6295.3 6298.2 6149.5 6169.1 6169.5 6175.7 6180.3 6121.2 6131.8 6230.2 6248.0 6261.4 5991.8 6020.0 6059.8 6062.9 6134.3 6061.9 6076.2 6078.9 6089.6 6090.9 5893.3 5915.5 5934.7 5967.0 5976.7 4635.0 5413.2 5986.8 6003.3 6075.6 5915.6 5922.4 5948.6 5969.5 5982.5 06:38:04 INFO - PROCESS | 1661 | 06:38:04 INFO - PROCESS | 1661 | [#1] ext.html Cycles:180 Average:68737.05 Median:39881.00 stddev:148264.12 (371.8%) stddev-sans-first:148591.44 06:38:04 INFO - PROCESS | 1661 | Values: 468.3 498.0 514.3 522.9 525.0 30486.5 31789.8 31828.4 31893.7 31914.7 33008.5 33517.2 42246.6 43168.6 43383.9 9436.6 9540.9 9588.8 9590.4 9594.8 9422.6 9502.0 9503.3 9505.9 9513.5 9477.8 9521.7 9527.5 9546.4 9611.9 9420.1 9472.3 9488.0 9489.6 9499.7 9429.3 9504.6 9513.0 9517.9 9546.9 40766.2 41150.0 41159.2 41203.2 41237.8 3364.1 3370.4 3380.2 3557.7 3590.8 30843.7 31196.5 31520.7 31695.0 31720.7 3104.1 3126.6 3145.2 3169.5 3255.9 466219.7 469460.0 469481.0 470480.6 470942.0 61395.7 61566.7 61608.7 61629.7 61651.7 34931.7 64035.0 64249.4 64296.7 64305.7 15878.9 16482.4 16613.4 16637.3 16671.4 10790.8 11455.7 11961.5 12001.9 12011.7 19397.6 19529.8 19535.0 19564.8 19585.3 7861.3 7869.4 7884.3 7887.3 7964.7 850.7 854.2 1002.5 1009.8 1063.2 27985.9 29442.3 30214.8 30312.8 30576.0 828.8 1008.2 1021.6 1028.2 1067.2 14929.0 15566.5 15612.8 15768.6 15796.2 623961.0 627072.0 627308.0 628978.0 631282.0 58005.7 58728.0 58783.7 58847.4 58998.4 1217.8 1249.1 1250.8 1346.2 1367.8 30507.4 31342.2 31856.7 32322.7 32503.0 54488.7 55084.4 55288.2 55289.4 55360.0 551655.0 556792.0 558082.0 559512.8 561249.0 26636.6 26785.2 27037.3 27041.9 27369.5 46693.8 47086.1 47371.2 47896.5 48288.8 39247.2 39277.0 39287.2 40772.8 40890.6 38795.6 39155.8 39454.2 40307.8 40717.4 37719.8 38881.0 39078.6 39097.8 39177.0 31133.8 34988.5 35061.0 35110.8 35115.8 25340.7 25509.7 25531.0 25884.5 26329.2 06:38:04 INFO - PROCESS | 1661 | 06:38:04 INFO - PROCESS | 1661 | [#2] jquery.html Cycles:180 Average:34386.16 Median:26376.41 stddev:94489.72 (358.2%) stddev-sans-first:94720.23 06:38:04 INFO - PROCESS | 1661 | Values: 351.3 358.8 375.6 380.6 403.2 15005.5 15254.6 15851.5 20573.5 20972.7 20966.4 21216.6 21759.5 22141.4 22339.9 5773.8 5789.6 5956.9 6097.1 6110.2 4197.4 5891.0 6026.4 6028.1 6036.3 4392.7 6386.7 6693.0 6725.9 6781.3 6412.6 6625.7 6702.6 6777.9 6794.3 5812.0 5813.5 5819.7 5823.7 5825.1 25362.5 26371.6 26381.2 26418.3 26636.7 2318.7 2328.2 2412.4 2584.4 2815.9 15687.9 15776.9 15795.2 16164.0 16495.8 1771.4 1825.4 1846.4 1891.5 1948.2 117062.4 117120.4 118479.8 121267.4 122103.0 9478.7 11266.0 11429.4 11501.3 11644.4 14783.6 14786.4 14793.0 14816.4 14820.6 6709.6 7304.0 7328.9 7847.7 8165.0 7104.3 7511.7 7990.5 8232.5 8386.6 4472.6 4559.2 4565.4 4622.9 4654.3 1584.6 1855.8 1900.5 1900.7 1907.7 743.6 858.6 901.3 985.1 994.7 18961.6 19806.0 20887.9 21765.0 21938.8 894.7 928.4 947.7 962.8 971.4 10992.6 11304.7 11516.8 11561.5 11615.8 518245.0 554854.0 571604.0 583082.0 589233.1 91455.0 115798.1 132631.0 137971.3 140108.3 937.8 996.7 1012.7 1019.0 1019.4 18864.6 19226.8 19789.3 20252.1 20428.6 56246.7 56406.8 56892.0 57440.6 58121.7 79832.6 82539.8 84156.7 84282.0 84986.6 6719.5 7988.4 8123.1 8192.5 8193.5 12197.5 12489.4 12868.2 13019.4 13101.4 10059.7 11196.4 11226.4 11244.3 11366.3 10783.8 10971.8 11145.0 11275.8 11301.6 11146.3 11226.0 11274.9 11478.2 11527.3 8087.5 8108.5 8113.0 8133.7 8214.7 9098.8 10907.8 11173.4 11258.4 11289.3 06:38:04 INFO - PROCESS | 1661 | 06:38:04 INFO - PROCESS | 1661 | [#3] mootools.html Cycles:180 Average:5543.12 Median:5007.94 stddev:3126.18 (62.4%) stddev-sans-first:3119.32 06:38:04 INFO - PROCESS | 1661 | Values: 1383.1 1530.1 1561.9 1737.4 1891.1 7682.1 8145.8 8539.4 8787.5 8799.8 8608.4 8682.8 8689.0 8696.8 8701.9 2306.2 2334.4 2336.9 2336.9 2338.9 2259.8 2294.7 2307.4 2308.4 2324.3 2191.5 2293.9 2312.9 2329.7 2329.7 2280.2 2307.8 2312.4 2313.8 2315.5 2282.5 2285.7 2286.9 2294.4 2294.7 7978.0 8024.1 8619.7 8686.0 8749.8 5245.9 5434.3 5444.3 5461.4 5467.5 3458.4 3611.5 3639.8 3672.3 3674.3 1034.6 1040.4 1049.1 1055.2 1065.5 11414.2 11430.6 11516.9 11520.1 11536.3 3580.8 4458.7 4528.4 4534.3 4535.5 8984.0 8992.1 8993.6 9018.7 9021.0 5197.5 5226.9 5228.8 5244.1 5248.4 4060.8 4375.9 4506.0 4886.6 4914.3 3594.3 3626.9 3703.3 3721.8 3746.4 1140.4 1142.2 1144.7 1144.8 1151.0 625.9 764.3 906.7 938.1 941.6 5523.5 5625.4 5649.2 5832.9 5857.9 464.3 474.8 477.7 479.6 637.6 3365.8 3686.9 3812.8 3933.8 3972.9 10059.3 10568.4 10578.5 10587.3 10597.8 10508.8 10516.6 10533.3 10535.5 10547.2 7692.1 8062.9 8231.1 8241.0 8253.6 5589.6 5610.6 5629.1 5669.2 5731.3 8025.5 9771.6 9776.5 9797.6 9889.5 9424.5 9816.3 9866.8 9887.9 9962.4 7325.0 7505.0 7506.1 7509.9 7512.0 8090.5 8160.5 8162.6 8162.7 8176.3 7134.2 7260.9 7385.5 7405.7 7439.4 6365.6 7098.0 7117.4 7287.0 7467.2 6290.1 6500.7 6740.0 6902.7 6909.1 4912.9 4925.1 4979.5 4993.0 5010.6 4858.7 4909.4 5005.3 5150.8 5171.2 06:38:04 INFO - PROCESS | 1661 | 06:38:04 INFO - PROCESS | 1661 | [#4] prototype.html Cycles:180 Average:4230.45 Median:4560.68 stddev:1328.50 (29.1%) stddev-sans-first:1298.84 06:38:04 INFO - PROCESS | 1661 | Values: 287.3 297.2 304.9 308.8 312.8 3853.6 4567.9 4645.6 5110.0 5129.5 4372.6 4487.2 4590.8 5167.9 5176.5 3976.7 3977.0 4002.7 4026.0 4038.9 2946.9 3463.0 3940.7 4002.8 4016.1 2984.6 3602.3 3858.0 3938.9 3973.4 3363.5 3860.3 3937.7 3970.3 3980.2 2841.9 2920.7 3403.2 3439.5 3455.7 5033.5 5062.4 5096.2 5120.4 5130.4 4495.4 4544.2 4549.0 4563.2 4574.9 4730.5 4758.4 5459.5 5474.0 5497.1 3190.7 3542.1 3659.4 3781.9 3803.9 4958.6 5345.2 5529.9 5651.2 5692.7 3660.2 3730.7 3865.8 3875.8 3877.8 3363.7 3869.6 3888.9 3966.8 3978.6 4461.8 4484.6 4521.9 4536.6 4558.2 5175.4 5403.1 5438.6 5470.1 5484.8 1180.5 1307.8 1330.6 1337.4 1339.5 772.4 784.7 793.5 839.5 844.6 3445.0 3676.6 4058.8 4189.7 4202.5 3986.9 4128.5 4179.9 4589.2 4601.1 3622.8 3906.2 4203.5 4228.8 4244.1 2046.5 2281.2 2357.0 2398.7 2433.8 3909.6 4250.5 4724.9 4753.7 4759.0 4243.6 4608.3 4646.6 4698.7 4729.2 4129.2 4650.2 4755.8 5232.1 5904.5 5354.4 5633.6 5670.7 5676.6 5679.0 5195.6 5236.4 5627.9 5629.6 5750.0 5477.3 5821.3 5823.5 5825.6 5859.4 3765.2 3775.7 3783.6 3789.8 3802.2 5431.5 5724.6 5752.7 5764.7 5775.3 4711.9 5263.0 5353.4 5420.5 5574.0 4567.2 5195.1 5476.5 5491.3 5507.4 4492.6 5370.5 5381.4 5422.6 5478.8 4819.2 4938.5 5413.2 5442.6 5444.7 4419.0 4510.3 4727.9 5298.2 5436.3 06:38:04 INFO - PROCESS | 1661 | 06:38:04 INFO - PROCESS | 1661 | [#5] yui.html Cycles:180 Average:19969.43 Median:25744.79 stddev:29956.41 (116.4%) stddev-sans-first:30005.55 06:38:04 INFO - PROCESS | 1661 | Values: 713.4 724.2 759.6 808.5 810.9 12779.2 13019.1 13022.1 13072.9 13391.3 13524.5 13612.9 13623.4 13633.5 13648.4 715.6 733.6 740.1 877.7 886.0 905.8 908.2 912.6 913.4 922.7 964.8 965.6 966.5 968.0 971.0 748.4 959.5 961.3 961.7 962.6 575.1 694.6 712.7 715.2 716.5 14264.4 15101.7 15107.7 15109.3 15111.1 13577.3 13589.5 13653.9 13675.7 13675.9 12290.7 12306.2 12377.4 12596.9 12981.0 4085.8 4388.5 4535.7 4589.6 4591.2 74918.0 93582.5 96149.5 96565.5 101113.0 10032.6 10692.6 10775.4 11021.3 11255.6 19700.7 19886.0 20381.7 21470.4 21614.6 7477.3 7519.1 7577.0 7592.1 7730.1 4073.5 4084.5 4085.8 4106.6 4152.7 3341.6 3424.7 3450.1 3457.4 3462.0 634.6 638.8 639.6 654.7 657.9 493.8 509.3 552.9 575.0 584.8 8189.3 8412.0 8433.2 8546.9 8569.9 558.9 601.9 614.0 623.4 636.4 3969.4 4152.5 4163.7 4249.6 4294.4 64780.0 101287.0 110512.0 114230.0 114565.4 72492.6 87415.0 101197.0 102044.0 104011.0 83011.6 86000.7 86545.0 88130.6 88361.2 8075.8 8076.3 8163.5 8230.3 8230.7 48636.8 49141.5 50980.2 51671.4 53750.2 57083.7 73106.0 82193.0 82256.0 84635.6 10509.9 10777.4 10790.1 10825.6 10838.1 11348.2 11718.1 11896.3 11950.5 12225.8 10599.5 10974.4 11131.2 11261.8 11372.7 8507.0 10867.9 10952.2 11180.6 11190.4 10298.0 10757.9 10832.2 10980.8 10989.8 8996.6 9128.1 9227.4 9262.6 9622.0 6755.0 7986.0 8589.9 9268.3 9307.3 06:38:04 INFO - PROCESS | 1661 | -------- Summary: end -------- 06:38:04 INFO - PROCESS | 1661 | 06:38:05 INFO - TEST-INFO | 1661: exit 0 06:38:05 INFO - TEST-OK | dromaeo_css | took 1119121ms 06:38:05 INFO - TEST-START | kraken 06:38:05 INFO - operating with platform_type : mac_ 06:38:05 INFO - Initialising browser for kraken test... 06:38:05 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/tmpcfuJWt/profile http://localhost:49266/getInfo.html) 06:38:07 INFO - PROCESS | 1667 | __metrics Screen width/height:1600/1200 06:38:07 INFO - PROCESS | 1667 | colorDepth:24 06:38:07 INFO - PROCESS | 1667 | Browser inner width/height: 1024/689 06:38:07 INFO - PROCESS | 1667 | __metrics 06:38:07 INFO - PROCESS | 1667 | 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" 06:38:07 INFO - PROCESS | 1667 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:38:08 INFO - TEST-INFO | 1667: exit 0 06:38:08 INFO - Browser initialized. 06:38:08 INFO - Running cycle 1/1 for kraken test... 06:38:08 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/tmpcfuJWt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:38:20 INFO - PROCESS | 1668 | RSS: Main: 229785600 06:38:20 INFO - PROCESS | 1668 | 06:38:23 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection) 06:38:23 INFO - PROCESS | 1668 | RSS: Main: 290123776 06:38:23 INFO - PROCESS | 1668 | 06:38:26 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-dft) 06:38:27 INFO - PROCESS | 1668 | RSS: Main: 290762752 06:38:27 INFO - PROCESS | 1668 | 06:38:30 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-fft) 06:38:31 INFO - PROCESS | 1668 | RSS: Main: 384147456 06:38:31 INFO - PROCESS | 1668 | 06:38:33 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator) 06:38:33 INFO - PROCESS | 1668 | RSS: Main: 284553216 06:38:33 INFO - PROCESS | 1668 | 06:38:35 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:38:36 INFO - PROCESS | 1668 | RSS: Main: 288489472 06:38:36 INFO - PROCESS | 1668 | 06:38:39 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom) 06:38:39 INFO - PROCESS | 1668 | RSS: Main: 326475776 06:38:39 INFO - PROCESS | 1668 | 06:38:42 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate) 06:38:43 INFO - PROCESS | 1668 | RSS: Main: 326307840 06:38:43 INFO - PROCESS | 1668 | 06:38:45 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial) 06:38:46 INFO - PROCESS | 1668 | RSS: Main: 324911104 06:38:46 INFO - PROCESS | 1668 | 06:38:48 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:38:48 INFO - PROCESS | 1668 | RSS: Main: 302166016 06:38:48 INFO - PROCESS | 1668 | 06:38:50 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:38:51 INFO - PROCESS | 1668 | RSS: Main: 304529408 06:38:51 INFO - PROCESS | 1668 | 06:38:53 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:38:54 INFO - PROCESS | 1668 | RSS: Main: 310140928 06:38:54 INFO - PROCESS | 1668 | 06:38:57 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:38:57 INFO - PROCESS | 1668 | RSS: Main: 335769600 06:38:57 INFO - PROCESS | 1668 | 06:39:00 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:39:01 INFO - PROCESS | 1668 | RSS: Main: 325390336 06:39:01 INFO - PROCESS | 1668 | 06:39:02 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49266/tests/kraken/driver.html?testName=ai-astar) 06:39:03 INFO - PROCESS | 1668 | RSS: Main: 369270784 06:39:03 INFO - PROCESS | 1668 | 06:39:03 INFO - PROCESS | 1668 | __start_tp_report 06:39:03 INFO - PROCESS | 1668 | _x_x_mozilla_page_load 06:39:03 INFO - PROCESS | 1668 | _x_x_mozilla_page_load_details 06:39:03 INFO - PROCESS | 1668 | |i|pagename|runs| 06:39:03 INFO - PROCESS | 1668 | |0;ai-astar;110;113;112;113;114;113;113;121;166;150 06:39:03 INFO - PROCESS | 1668 | |1;audio-beat-detection;148;110;111;148;110;111;111;112;141;111 06:39:03 INFO - PROCESS | 1668 | |2;audio-dft;183;184;183;184;182;184;182;181;182;185 06:39:03 INFO - PROCESS | 1668 | |3;audio-fft;79;58;57;85;62;60;57;60;81;57 06:39:03 INFO - PROCESS | 1668 | |4;audio-oscillator;86;68;90;69;67;69;92;68;67;90 06:39:03 INFO - PROCESS | 1668 | |5;imaging-gaussian-blur;87;85;87;85;89;130;131;85;88;84 06:39:03 INFO - PROCESS | 1668 | |6;imaging-darkroom;93;93;93;93;93;93;92;92;93;93 06:39:03 INFO - PROCESS | 1668 | |7;imaging-desaturate;91;85;81;79;84;80;85;84;81;83 06:39:03 INFO - PROCESS | 1668 | |8;json-parse-financial;58;54;53;52;54;54;55;55;55;55 06:39:03 INFO - PROCESS | 1668 | |9;json-stringify-tinderbox;64;65;70;79;75;71;69;70;70;71 06:39:03 INFO - PROCESS | 1668 | |10;stanford-crypto-aes;91;88;86;91;90;90;91;91;88;136 06:39:03 INFO - PROCESS | 1668 | |11;stanford-crypto-ccm;148;143;148;145;145;144;142;156;139;144 06:39:03 INFO - PROCESS | 1668 | |12;stanford-crypto-pbkdf2;170;171;171;173;170;172;170;172;172;171 06:39:03 INFO - PROCESS | 1668 | |13;stanford-crypto-sha256-iterative;70;70;72;69;70;70;70;69;66;68 06:39:03 INFO - PROCESS | 1668 | __end_tp_report 06:39:03 INFO - PROCESS | 1668 | __start_cc_report 06:39:03 INFO - PROCESS | 1668 | _x_x_mozilla_cycle_collect,2058 06:39:03 INFO - PROCESS | 1668 | __end_cc_report 06:39:03 INFO - PROCESS | 1668 | __startTimestamp1477402743225__endTimestamp 06:39:03 INFO - PROCESS | 1668 | 06:39:03 INFO - PROCESS | 1668 | ------- Summary: start ------- 06:39:03 INFO - PROCESS | 1668 | Number of tests: 14 06:39:03 INFO - PROCESS | 1668 | 06:39:03 INFO - PROCESS | 1668 | [#0] ai-astar Cycles:10 Average:122.50 Median:113.50 stddev:19.29 (17.0%) stddev-sans-first:19.93 06:39:03 INFO - PROCESS | 1668 | Values: 110.0 113.0 112.0 113.0 114.0 113.0 113.0 121.0 166.0 150.0 06:39:03 INFO - PROCESS | 1668 | 06:39:03 INFO - PROCESS | 1668 | [#1] audio-beat-detection Cycles:10 Average:121.30 Median:111.50 stddev:16.93 (15.2%) stddev-sans-first:14.95 06:39:03 INFO - PROCESS | 1668 | Values: 148.0 110.0 111.0 148.0 110.0 111.0 111.0 112.0 141.0 111.0 06:39:03 INFO - PROCESS | 1668 | 06:39:03 INFO - PROCESS | 1668 | [#2] audio-dft Cycles:10 Average:183.00 Median:183.50 stddev:1.25 (0.7%) stddev-sans-first:1.32 06:39:03 INFO - PROCESS | 1668 | Values: 183.0 184.0 183.0 184.0 182.0 184.0 182.0 181.0 182.0 185.0 06:39:03 INFO - PROCESS | 1668 | 06:39:03 INFO - PROCESS | 1668 | [#3] audio-fft Cycles:10 Average:65.60 Median:61.00 stddev:11.30 (18.5%) stddev-sans-first:10.89 06:39:03 INFO - PROCESS | 1668 | Values: 79.0 58.0 57.0 85.0 62.0 60.0 57.0 60.0 81.0 57.0 06:39:03 INFO - PROCESS | 1668 | 06:39:03 INFO - PROCESS | 1668 | [#4] audio-oscillator Cycles:10 Average:76.60 Median:77.50 stddev:11.22 (14.5%) stddev-sans-first:11.37 06:39:03 INFO - PROCESS | 1668 | Values: 86.0 68.0 90.0 69.0 67.0 69.0 92.0 68.0 67.0 90.0 06:39:03 INFO - PROCESS | 1668 | 06:39:03 INFO - PROCESS | 1668 | [#5] imaging-gaussian-blur Cycles:10 Average:95.10 Median:86.00 stddev:18.72 (21.8%) stddev-sans-first:19.63 06:39:03 INFO - PROCESS | 1668 | Values: 87.0 85.0 87.0 85.0 89.0 130.0 131.0 85.0 88.0 84.0 06:39:03 INFO - PROCESS | 1668 | 06:39:03 INFO - PROCESS | 1668 | [#6] imaging-darkroom Cycles:10 Average:92.80 Median:93.00 stddev:0.42 (0.5%) stddev-sans-first:0.44 06:39:03 INFO - PROCESS | 1668 | Values: 93.0 93.0 93.0 93.0 93.0 93.0 92.0 92.0 93.0 93.0 06:39:03 INFO - PROCESS | 1668 | 06:39:03 INFO - PROCESS | 1668 | [#7] imaging-desaturate Cycles:10 Average:83.30 Median:84.00 stddev:3.43 (4.1%) stddev-sans-first:2.24 06:39:03 INFO - PROCESS | 1668 | Values: 91.0 85.0 81.0 79.0 84.0 80.0 85.0 84.0 81.0 83.0 06:39:03 INFO - PROCESS | 1668 | 06:39:03 INFO - PROCESS | 1668 | [#8] json-parse-financial Cycles:10 Average:54.50 Median:55.00 stddev:1.58 (2.9%) stddev-sans-first:1.05 06:39:03 INFO - PROCESS | 1668 | Values: 58.0 54.0 53.0 52.0 54.0 54.0 55.0 55.0 55.0 55.0 06:39:03 INFO - PROCESS | 1668 | 06:39:03 INFO - PROCESS | 1668 | [#9] json-stringify-tinderbox Cycles:10 Average:70.40 Median:70.50 stddev:4.33 (6.1%) stddev-sans-first:3.92 06:39:03 INFO - PROCESS | 1668 | Values: 64.0 65.0 70.0 79.0 75.0 71.0 69.0 70.0 70.0 71.0 06:39:03 INFO - PROCESS | 1668 | 06:39:03 INFO - PROCESS | 1668 | [#10] stanford-crypto-aes Cycles:10 Average:94.20 Median:90.50 stddev:14.79 (16.3%) stddev-sans-first:15.64 06:39:03 INFO - PROCESS | 1668 | Values: 91.0 88.0 86.0 91.0 90.0 90.0 91.0 91.0 88.0 136.0 06:39:03 INFO - PROCESS | 1668 | 06:39:03 INFO - PROCESS | 1668 | [#11] stanford-crypto-ccm Cycles:10 Average:145.40 Median:145.00 stddev:4.58 (3.2%) stddev-sans-first:4.76 06:39:03 INFO - PROCESS | 1668 | Values: 148.0 143.0 148.0 145.0 145.0 144.0 142.0 156.0 139.0 144.0 06:39:03 INFO - PROCESS | 1668 | 06:39:03 INFO - PROCESS | 1668 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.20 Median:171.50 stddev:1.03 (0.6%) stddev-sans-first:1.00 06:39:03 INFO - PROCESS | 1668 | Values: 170.0 171.0 171.0 173.0 170.0 172.0 170.0 172.0 172.0 171.0 06:39:03 INFO - PROCESS | 1668 | 06:39:03 INFO - PROCESS | 1668 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.40 Median:70.00 stddev:1.58 (2.3%) stddev-sans-first:1.66 06:39:03 INFO - PROCESS | 1668 | Values: 70.0 70.0 72.0 69.0 70.0 70.0 70.0 69.0 66.0 68.0 06:39:03 INFO - PROCESS | 1668 | -------- Summary: end -------- 06:39:03 INFO - PROCESS | 1668 | 06:39:04 INFO - TEST-INFO | 1668: exit 0 06:39:04 INFO - TEST-OK | kraken | took 58894ms 06:39:04 INFO - SUITE-END | took 1178s 06:39:04 INFO - Completed test suite (00:19:38) 06:39:04 INFO - javascript benchmark 06:39:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3318.7510936679746, 3341.2482191506915, 3342.247994201304, 3367.3602015617016, 3405.625381208068, 5558.722063896809, 5689.2603961485, 5695.572832037604, 5756.654600723954, 5762.711864406773, 5627.352854421742, 5653.575981801361, 5707.857303567413, 5710.14347847822, 5755.913661295084, 5136.512031357015, 5148.639595228336, 5225.085610018251, 5227.660202086871, 5233.947660523384, 5247.1866860636665, 5263.473652634725, 5263.789448422078, 5282.392524859632, 5284.075286824809, 4348.026600994316, 5119.360079990001, 5243.580513558906, 5286.2070689396705, 5347.679139251638, 5076.263941728465, 5105.566026887719, 5207.3490813648295, 5226.372835259755, 5244.265802787594, 5111.105556527593, 5156.0, 5163.251328557376, 5169.0954083035695, 5173.3016042834115, 5635.915461268084, 5642.238297829782, 5653.293338332708, 5654.688992105433, 5664.971675141639, 6095.213525956478, 6097.010520468062, 6110.674531727158, 6129.080637904305, 6131.866048757612, 6141.263048434172, 6153.0, 6212.937870621281, 6226.470749986257, 6232.1898153240245, 5854.736536855852, 5859.062549991981, 5871.148683440877, 5885.529157667376, 5904.498117660004, 6160.630362178194, 6168.814935551851, 6211.937880621136, 6213.440790328892, 6246.281677607042, 6071.362506936796, 6124.301675977689, 6131.669965413114, 6131.877362452726, 6141.969290153521, 6201.503879689614, 6208.72060757267, 6222.844428889223, 6223.471004964538, 6228.252609686776, 6007.909881351783, 6091.573589848668, 6104.481119104926, 6179.196704428396, 6180.47465965389, 6126.8230438726505, 6191.380861913863, 6193.1329613854095, 6207.6275423474735, 6230.971889638249, 4893.339399181136, 4895.755212239374, 4901.313816065754, 4919.631027672939, 4937.136001199776, 4397.868063958086, 4416.447944006999, 4417.249067658506, 4418.823247070145, 4475.462944446687, 5688.203651488795, 5766.452187042224, 5766.625169363978, 5768.711564421762, 5778.075507918713, 6183.536234782409, 6197.752089916443, 6200.720967556381, 6203.410675985774, 6203.596766210182, 5744.51171650406, 5759.481646651821, 5818.6217895836635, 5881.970590147107, 5889.4993925515955, 4745.501722319176, 5205.7397130143345, 5387.949349876815, 5417.59368047398, 5437.891242175135, 6284.622922624566, 6298.118263443122, 6302.65335406557, 6304.05439184126, 6307.716152773136, 6123.357047510125, 6137.2544124621345, 6142.744345496893, 6148.754049837957, 6262.405071518198, 6207.534434917489, 6283.37166283368, 6291.937080629135, 6295.307516173309, 6298.244210694746, 6149.538784591084, 6169.1054797053275, 6169.537284703755, 6175.722092505938, 6180.258368995749, 6121.204243448323, 6131.77428366204, 6230.158928544589, 6248.031555108965, 6261.436470717657, 5991.7902873399225, 6020.0, 6059.787907423219, 6062.878742425215, 6134.294556125924, 6061.909071363845, 6076.179715738319, 6078.878422431615, 6089.573729839046, 6090.908636370369, 5893.286955135066, 5915.526757859447, 5934.732937017759, 5966.970165149321, 5976.731047102804, 4634.976825115853, 5413.160960051198, 5986.820395388146, 6003.309619393651, 6075.635461872125, 5915.556333274935, 5922.407759224216, 5948.643081415129, 5969.462748352669, 5982.52139828804], "value": 5666.859321876608, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [468.25781136898013, 497.98755031124216, 514.331369220014, 522.932018837551, 525.0260766278553, 30486.542701859464, 31789.84105079477, 31828.36343273133, 31893.68106318936, 31914.68085319146, 33008.50487242687, 33517.16207094824, 42246.57753422465, 43168.56831431692, 43383.915402114886, 9436.622535098604, 9540.856887146698, 9588.760280992996, 9590.376625519353, 9594.760130996747, 9422.575984080733, 9502.049795020512, 9503.3369160397, 9505.904940950606, 9513.476758778264, 9477.763055923622, 9521.71434856952, 9527.475988820612, 9546.42721436716, 9611.855822162674, 9420.057994200559, 9472.289578281656, 9487.952560237223, 9489.620415183384, 9499.715008549754, 9429.29280303973, 9504.619815207383, 9513.001134880808, 9517.857232141454, 9546.90453095474, 40766.184676306606, 41150.0, 41159.1768164635, 41203.1759364811, 41237.79381103075, 3364.091695242271, 3370.427027405347, 3380.2225488137865, 3557.7331700122595, 3590.8025058621765, 30843.691563084307, 31196.532052019236, 31520.684793152235, 31695.0, 31720.682793172004, 3104.099811054791, 3126.640436349803, 3145.1822526143137, 3169.4928811390296, 3255.9186020349443, 466219.6689016567, 469460.0, 469481.0, 470480.64759675984, 470942.0, 61395.69302153461, 61566.69216653888, 61608.69195653993, 61629.691851540454, 61651.691741541006, 34931.650683493346, 64035.0, 64249.357506424334, 64296.67851660712, 64305.678471607345, 15878.9206053969, 16482.42311519115, 16613.41853035138, 16637.25132369022, 16671.416500422427, 10790.838137427787, 11455.713607159887, 11961.521539138512, 12001.939990299992, 12011.699707507207, 19397.590960384605, 19529.821028971266, 19535.004287053303, 19564.826649624778, 19585.263820790708, 7861.292483676497, 7869.409794265339, 7884.329831964347, 7887.290143886963, 7964.681412743541, 850.6597361055627, 854.239726643288, 1002.4887307473095, 1009.8131845608586, 1063.2132222155549, 27985.860070699517, 29442.263943401158, 30214.848925755232, 30312.84843575768, 30576.0, 828.8176601147737, 1008.2035192198229, 1021.6373187518324, 1028.2442404832443, 1067.236925598201, 14929.0, 15566.45517406908, 15612.76580851288, 15768.605784855241, 15796.198690304916, 623961.0, 627072.0, 627308.0, 628978.0, 631282.0, 58005.70997145072, 58728.0, 58783.70608146932, 58847.41152588505, 58998.410015900154, 1217.8234156047324, 1249.0881656391068, 1250.849898012226, 1346.1855577375743, 1367.8495365509787, 30507.389852203276, 31342.216444589067, 31856.68143318537, 32322.676773231968, 32503.0, 54488.72755636355, 55084.44915550954, 55288.17067744068, 55289.44710553004, 55360.0, 551655.0, 556792.0, 558082.0, 559512.8341016253, 561249.0, 26636.60045099364, 26785.196444106707, 27037.324066898484, 27041.86479067592, 27369.45261094807, 46693.76653116713, 47086.058278833545, 47371.163954199175, 47896.5210347906, 48288.758556206994, 39247.2150556993, 39277.0, 39287.2142557153, 40772.79613601913, 40890.59109408987, 38795.6120438792, 39155.804220978716, 39454.210915782096, 40307.79846100751, 40717.38923916203, 37719.81140094282, 38881.0, 39078.6092139075, 39097.804510977265, 39177.0, 31133.8443307782, 34988.47517287294, 35061.0, 35110.82444587761, 35115.82442087773, 25340.746592534575, 25509.744902550738, 25531.0, 25884.482310354066, 26329.21012369632], "value": 19799.89270591352, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.2570912520021, 358.7668015789736, 375.55496736367405, 380.63459079283876, 403.19562472866636, 15005.474808381708, 15254.618634534128, 15851.524454266362, 20573.485662858435, 20972.685409718866, 20966.37100886972, 21216.57566848662, 21759.45601359967, 22141.446463838372, 22339.888300558476, 5773.797917072882, 5789.565782566303, 5956.910646340309, 6097.146399504073, 6110.175126357929, 4197.412362269285, 5890.970545147268, 6026.427489388501, 6028.065649824284, 6036.275646922353, 4392.714473559225, 6386.680665966706, 6692.9665351673175, 6725.899111513307, 6781.321867813203, 6412.583182093149, 6625.668716564152, 6702.631355275458, 6777.932220677804, 6794.286599907037, 5812.0, 5813.505852002585, 5819.6508209507565, 5823.708814559255, 5825.126231065332, 25362.492750145077, 26371.604425933627, 26381.20856374312, 26418.339541511425, 26636.733632663618, 2318.652202169688, 2328.161861729776, 2412.3969007748065, 2584.44434446593, 2815.8873645054173, 15687.921560392126, 15776.921115394349, 15795.21023948798, 16164.0, 16495.752563711554, 1771.4242871066954, 1825.3702472646914, 1846.3814622101477, 1891.5460289530552, 1948.230448972662, 117062.41468792602, 117120.41439792747, 118479.75709507993, 121267.39366303201, 122103.0, 9478.668246611336, 11266.0, 11429.371244613785, 11501.252418592765, 11644.3595602241, 14783.556493305003, 14786.408543658348, 14792.964492485422, 14816.407343706347, 14820.62948426298, 6709.630970296681, 7303.963480182565, 7328.853422931512, 7847.686092556348, 8165.02019757621, 7104.325089116525, 7511.737089201852, 7990.481808456375, 8232.50604963692, 8386.622601982925, 4472.642188624902, 4559.179347717442, 4565.406497155349, 4622.86131416058, 4654.301854721819, 1584.627612511059, 1855.8422534084484, 1900.4773687235845, 1900.6768849295654, 1907.7233801099042, 743.605888878895, 858.6007506509503, 901.2609660078673, 985.0641890204334, 994.6518718448485, 18961.620767584573, 19806.0, 20887.8955605221, 21765.0, 21938.780612193994, 894.6600291889041, 928.3547934185676, 947.6541062512133, 962.7593101724569, 971.4365667912593, 10992.560297588167, 11304.71738206535, 11516.769664606829, 11561.537538498535, 11615.76768464643, 518245.0, 554854.0, 571604.0, 583082.0, 589233.0538347281, 91455.0, 115798.05048737816, 132631.0, 137971.31014345065, 140108.29945850206, 937.8311903857372, 996.7259003773875, 1012.6759436980095, 1019.0, 1019.3934608907688, 18864.6227075455, 19226.80773192306, 19789.27031360472, 20252.069505556203, 20428.59142817165, 56246.71876640591, 56406.795252523014, 56892.0, 57440.563985900684, 58121.70939145277, 79832.60083699776, 82539.7619035727, 84156.7376489341, 84282.0, 84986.57506712427, 6719.496037797283, 7988.360931125612, 8123.147069557612, 8192.508449493049, 8193.549354785544, 12197.51209951592, 12489.375531223584, 12868.22790632565, 13019.414126364147, 13101.410436530188, 10059.748506287402, 11196.440177991231, 11226.382548959558, 11244.325340479598, 11366.261193022427, 10783.784324313312, 10971.83542246851, 11145.035886657144, 11275.759666436521, 11301.604443844426, 11146.275492092987, 11226.0, 11274.887251127384, 11478.196526243082, 11527.308361498337, 8087.452452477451, 8108.472949258279, 8113.0, 8133.67465301405, 8214.671413143407, 9098.772530686521, 10907.83638245411, 11173.385463799568, 11258.38078905668, 11289.266197697123], "value": 9699.694892145506, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1383.100984360165, 1530.0590137065703, 1561.9062856228627, 1737.4353335166074, 1891.0733740467176, 7682.116556595993, 8145.837083258339, 8539.444936079151, 8787.516686582252, 8799.824003519925, 8608.354373421987, 8682.782930426727, 8689.0, 8696.782580435492, 8701.869471957925, 2306.238941149421, 2334.404726794665, 2336.8714720690355, 2336.9298921032396, 2338.9064437422485, 2259.830512711545, 2294.655801629752, 2307.4346785037687, 2308.3998160478227, 2324.3027091872455, 2191.51786606947, 2293.8738369389675, 2312.8612283262974, 2329.7087864017, 2329.743728189898, 2280.1677387753516, 2307.8384513084093, 2312.375658572193, 2313.8148948084113, 2315.4558678710573, 2282.4978504728933, 2285.6685780561725, 2286.919957801469, 2294.403455101669, 2294.655801629752, 7978.045378882153, 8024.077231118379, 8619.698310559163, 8685.956570217171, 8749.781255468664, 5245.921311180336, 5434.3207099112615, 5444.292242008513, 5461.42655021225, 5467.507924286793, 3458.360203362361, 3611.512445819807, 3639.7816131032223, 3672.320620685182, 3674.2730916469486, 1034.591336422109, 1040.4173662748885, 1049.071571659087, 1055.2244100586063, 1065.4938904020605, 11414.201005929588, 11430.599929002445, 11516.942415287953, 11520.078393728565, 11536.30782153065, 3580.838862251205, 4458.7324760514475, 4528.388667529907, 4534.251848445036, 4535.478419981679, 8984.0, 8992.055834137453, 8993.550322483981, 9018.729438116736, 9021.007689154188, 5197.480251974848, 5226.947730522722, 5228.764705588182, 5244.056069907453, 5248.448912864171, 4060.7563546187325, 4375.868723938287, 4506.003686911451, 4886.60907127429, 4914.287428322873, 3594.3350480161253, 3626.927461450809, 3703.2593481303306, 3721.813909304524, 3746.363118269846, 1140.3600664461744, 1142.1833389126814, 1144.70237738187, 1144.822552504363, 1150.9769804603861, 625.8779537990048, 764.289211033744, 906.6826610686207, 938.0900526489295, 941.6392270214792, 5523.530499907472, 5625.38120806711, 5649.152627105967, 5832.883342333214, 5857.912131318034, 464.3383178969954, 474.75075585317984, 477.6656340561621, 479.62828807674333, 637.5823835387829, 3365.8317084145697, 3686.9078273043388, 3812.7712337259854, 3933.783641899666, 3972.880813575597, 10059.295849290625, 10568.418736969386, 10578.52396642153, 10587.25889187765, 10597.788044239227, 10508.842367364496, 10516.634676903317, 10533.262671612914, 10535.473226338652, 10547.156227501782, 7692.115406728186, 8062.879056814152, 8231.05342885564, 8241.0, 8253.628586713612, 5589.608727389206, 5610.551155907599, 5629.099344104923, 5669.206311116364, 5731.312242530923, 8025.5184688916515, 9771.560279787285, 9776.511174441108, 9797.55910983993, 9889.50552472388, 9424.528773561431, 9816.26378021637, 9866.753331166492, 9887.901120988985, 9962.402255864672, 7325.0, 7505.024346834876, 7506.061742282215, 7509.887351689838, 7512.023436953161, 8090.474119182234, 8160.469569477965, 8162.551059691774, 8162.6734930602115, 8176.264136227768, 7134.2152363238965, 7260.89108663381, 7385.544991798833, 7405.703771849281, 7439.40484761207, 6365.6313892513335, 7098.041764361951, 7117.430605551647, 7286.96356518214, 7467.178610352748, 6290.056491526308, 6500.70746816385, 6740.0, 6902.689378977858, 6909.136357955255, 4912.877178070434, 4925.113479573711, 4979.502049794992, 4992.975035124801, 5010.574101201439, 4858.659893807399, 4909.410870695397, 5005.299258103795, 5150.8197213098865, 5171.198464238049], "value": 4386.994035048977, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [287.3405534298785, 297.1842292905973, 304.92376905773557, 308.8039095174565, 312.7676952805946, 3853.633904779045, 4567.862964111077, 4645.55867192617, 5110.003549307887, 5129.487051294874, 4372.628326592236, 4487.169873573391, 4590.78344238777, 5167.9483205167935, 5176.50823171798, 3976.7216294859372, 3976.960230397702, 4002.6597739192202, 4025.9733767889293, 4038.940140528337, 2946.8673909674008, 3462.9653703463023, 3940.7044471664594, 4002.8198731057173, 4016.076302450438, 2984.5523171524337, 3602.2975519773654, 3858.0, 3938.921221575581, 3973.443717879499, 3363.494196823358, 3860.285847118292, 3937.7243592948507, 3970.344893092622, 3980.20395920817, 2841.9289517762013, 2920.6933272006345, 3403.2172600301824, 3439.521005967433, 3455.671711187433, 5033.496650334974, 5062.367204099488, 5096.210087436453, 5120.436751957282, 5130.4356520782685, 4495.3931219285305, 4544.159330523864, 4549.0, 4563.201439748031, 4574.931376029363, 4730.486244401793, 4758.428988521365, 5459.454054594548, 5473.972630136824, 5497.092979658391, 3190.7287880530184, 3542.1321776165005, 3659.4081574514903, 3781.9432708509394, 3803.9429408558894, 4958.553730164303, 5345.1714984177515, 5529.944700553024, 5651.208830763697, 5692.658440493584, 3660.213054193308, 3730.7388482806487, 3865.768053916774, 3875.8255878485534, 3877.7673339599714, 3363.747718921091, 3869.59369266223, 3888.8833334999995, 3966.841326346914, 3978.5822488638278, 4461.776911154429, 4484.618807401343, 4521.864344069683, 4536.56902594253, 4558.156741002858, 5175.404828444702, 5403.054465468527, 5438.592105592096, 5470.097433923438, 5484.808031718871, 1180.527788884453, 1307.7842156044337, 1330.5808670268834, 1337.411538922871, 1339.5445548513553, 772.3550835052794, 784.6586734770393, 793.484235247094, 839.5424493650975, 844.6241422567022, 3444.983729799665, 3676.632336766302, 4058.8361541878608, 4189.748615083105, 4202.516710578261, 3986.920261594752, 4128.4632997710105, 4179.853705120307, 4589.177655741695, 4601.12578610063, 3622.8007459589976, 3906.160175562268, 4203.453551038346, 4228.78856057202, 4244.0901594282695, 2046.5372924025687, 2281.2357860117045, 2356.988215058948, 2398.7286738028874, 2433.7809597136343, 3909.6090390960676, 4250.53244143144, 4724.929126063112, 4753.738544380023, 4758.976205118952, 4243.596858298457, 4608.308753686973, 4646.558576935186, 4698.694584852042, 4729.196036673705, 4129.1625226773795, 4650.209464391061, 4755.812326329858, 5232.142589380791, 5904.5238690327415, 5354.437784032621, 5633.563441322601, 5670.74481648335, 5676.631018983753, 5679.0, 5195.571217915103, 5236.39781425141, 5627.915581266202, 5629.579280909248, 5750.0, 5477.315335583052, 5821.330546987151, 5823.505002074701, 5825.563082768725, 5859.355470898113, 3765.1904840459433, 3775.7356985010783, 3783.6027217141805, 3789.829457674356, 3802.16352402471, 5431.528654462131, 5724.599278050663, 5752.698920431861, 5764.711764411679, 5775.306963164448, 4711.858644240679, 5263.0, 5353.357597088374, 5420.539254163283, 5573.972130139324, 4567.1550763108935, 5195.142801437721, 5476.507114359727, 5491.341039075177, 5507.421720719267, 4492.573205545468, 5370.489803468664, 5381.408045115112, 5422.559848068307, 5478.753456094406, 4819.228923372244, 4938.530839570235, 5413.215083812746, 5442.55772220354, 5444.727763611724, 4418.983633764316, 4510.293823705751, 4727.905441891074, 5298.178782288752, 5436.293281873332], "value": 3815.1356172705237, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [713.375796178344, 724.1563578431125, 759.6467642546215, 808.4704518540358, 810.9472884262524, 12779.233246005246, 13019.088663793538, 13022.088453808237, 13072.934635326821, 13391.330433478324, 13524.459021639123, 13612.93193534031, 13623.386947587358, 13633.454661813541, 13648.38582263798, 715.6028404235647, 733.5525329548971, 740.078602140336, 877.7191298784392, 885.9778505537348, 905.8414777413959, 908.1554154636186, 912.6212621761961, 913.4427998920653, 922.7416323429451, 964.8263312603729, 965.6378857928277, 966.4974213409023, 968.0174622757906, 971.0483725948575, 748.3825843678986, 959.4531117263163, 961.274237950343, 961.7499450142943, 962.6438217859367, 575.0626478839507, 694.6144889586277, 712.7398499547679, 715.2061212054615, 716.5342527357208, 14264.429422823074, 15101.697966040727, 15107.697846043018, 15109.320080596399, 15111.09333439986, 13577.321133943362, 13589.524366647218, 13653.931730341385, 13675.726485470235, 13675.931620341935, 12290.692732681666, 12306.200096993667, 12377.443015064253, 12596.937015314956, 12981.0, 4085.775282359469, 4388.49532303786, 4535.705179163377, 4589.586937175637, 4591.219492686218, 74918.0, 93582.5320873398, 96149.51925240329, 96565.51717241439, 101113.0, 10032.64885728996, 10692.62575809843, 10775.407352595526, 11021.283616564897, 11255.606053788242, 19700.70448943267, 19886.0, 20381.694274585894, 21470.355889323346, 21614.567708646053, 7477.289657482531, 7519.135299440525, 7577.041850689732, 7592.126905405838, 7730.072391313079, 4073.5315438724333, 4084.509858816961, 4085.754854708727, 4106.609872062149, 4152.730072545275, 3341.599008119041, 3424.7088997435, 3450.0857272822723, 3457.377672019061, 3462.0, 634.5906890055888, 638.7700427845972, 639.6418005916702, 654.7086546486863, 657.8750037492861, 493.785288191054, 509.34294759759496, 552.9004779139793, 575.0, 584.7923986984645, 8189.262966333059, 8411.957940210259, 8433.241008309282, 8546.871796923051, 8569.914300857037, 558.891016251827, 601.9006863867501, 613.9815805525841, 623.3647823674027, 636.395424346863, 3969.4442778011103, 4152.4601801765575, 4163.7293575917465, 4249.575042495726, 4294.398784170218, 64780.0, 101287.0, 110512.0, 114230.0, 114565.42717286361, 72492.55014899673, 87415.0, 101197.0, 102044.0, 104011.0, 83011.5849420749, 86000.68994135443, 86545.0, 88130.55934720286, 88361.23277534414, 8075.757727268191, 8076.27313541784, 8163.469374490639, 8230.259276665129, 8230.711925082833, 48636.784080398276, 49141.508584913696, 50980.235296469844, 51671.44985650437, 53750.19374709304, 57083.71458142683, 73106.0, 82193.0, 82256.0, 84635.5768221155, 10509.9474502627, 10777.353358798497, 10790.1367890567, 10825.621103261348, 10838.078763304891, 11348.205625606459, 11718.06255499541, 11896.286222826659, 11950.521979120738, 12225.816612750994, 10599.523021464207, 10974.396408197626, 11131.165162612675, 11261.7747645045, 11372.658820235407, 8506.957465212634, 10867.945660271964, 10952.233343665866, 11180.552777888794, 11190.440477976232, 10298.0, 10757.9462102689, 10832.187585931244, 10980.835287470854, 10989.835152472879, 8996.55017249122, 9128.132827381389, 9227.35408521397, 9262.583183756613, 9622.0, 6755.0, 7986.0, 8589.8711519326, 9268.3487040191, 9307.255419566554], "value": 7054.898594559128, "unit": "score"}], "name": "dromaeo_css", "value": 7103.902779222972, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 113.0, 112.0, 113.0, 114.0, 113.0, 113.0, 121.0, 166.0, 150.0], "value": 122.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 110.0, 111.0, 148.0, 110.0, 111.0, 111.0, 112.0, 141.0, 111.0], "value": 121.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 184.0, 183.0, 184.0, 182.0, 184.0, 182.0, 181.0, 182.0, 185.0], "value": 183.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 58.0, 57.0, 85.0, 62.0, 60.0, 57.0, 60.0, 81.0, 57.0], "value": 65.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 68.0, 90.0, 69.0, 67.0, 69.0, 92.0, 68.0, 67.0, 90.0], "value": 76.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 87.0, 85.0, 89.0, 130.0, 131.0, 85.0, 88.0, 84.0], "value": 95.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 93.0, 93.0, 93.0, 92.0, 92.0, 93.0, 93.0], "value": 92.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 85.0, 81.0, 79.0, 84.0, 80.0, 85.0, 84.0, 81.0, 83.0], "value": 83.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 54.0, 53.0, 52.0, 54.0, 54.0, 55.0, 55.0, 55.0, 55.0], "value": 54.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 70.0, 79.0, 75.0, 71.0, 69.0, 70.0, 70.0, 71.0], "value": 70.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 88.0, 86.0, 91.0, 90.0, 90.0, 91.0, 91.0, 88.0, 136.0], "value": 94.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 143.0, 148.0, 145.0, 145.0, 144.0, 142.0, 156.0, 139.0, 144.0], "value": 145.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 171.0, 171.0, 173.0, 170.0, 172.0, 170.0, 172.0, 172.0, 171.0], "value": 171.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 70.0, 72.0, 69.0, 70.0, 70.0, 70.0, 69.0, 66.0, 68.0], "value": 69.4, "unit": "score"}], "name": "kraken", "value": 1445.3000000000002, "alertThreshold": 2.0}]} 06:39:04 INFO - Return code: 0 06:39:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:39:04 INFO - # TBPL SUCCESS # 06:39:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:39:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:39:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:39:04 INFO - 2016-10-25 06:39:04.538 screenresolution[1672:10266] starting screenresolution argv=screenresolution get 06:39:04 INFO - 2016-10-25 06:39:04.542 screenresolution[1672:10266] Display 0: 1600x1200x32@60 06:39:04 INFO - 2016-10-25 06:39:04.549 screenresolution[1673:10269] starting screenresolution argv=screenresolution list 06:39:04 INFO - Available Modes on Display 0 06:39:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:39:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:39:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:39:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:39:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:39:04 INFO - Intel Iris: 06:39:04 INFO - Chipset Model: Intel Iris 06:39:04 INFO - Type: GPU 06:39:04 INFO - Bus: Built-In 06:39:04 INFO - VRAM (Dynamic, Max): 1536 MB 06:39:04 INFO - Vendor: Intel (0x8086) 06:39:04 INFO - Device ID: 0x0a2e 06:39:04 INFO - Revision ID: 0x0009 06:39:04 INFO - Displays: 06:39:04 INFO - Display: 06:39:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:39:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:39:04 INFO - Display Serial Number: 1600x1200 60 06:39:04 INFO - Main Display: Yes 06:39:04 INFO - Mirror: Off 06:39:04 INFO - Online: Yes 06:39:04 INFO - Rotation: Supported 06:39:04 INFO - Return code: 0 06:39:04 INFO - Running post-action listener: _resource_record_post_action 06:39:04 INFO - [mozharness: 2016-10-25 13:39:04.787251Z] Finished run-tests step (success) 06:39:04 INFO - Running post-run listener: _resource_record_post_run 06:39:04 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 31492096; Write bytes: 704691712; Read time: 283; Write time: 2085 06:39:04 INFO - TinderboxPrint: CPU usage
53.6% 06:39:04 INFO - TinderboxPrint: I/O read bytes / time
31,492,096 / 283 06:39:04 INFO - TinderboxPrint: I/O write bytes / time
704,691,712 / 2,085 06:39:04 INFO - TinderboxPrint: CPU idle
2,248.8 (46.4%) 06:39:04 INFO - TinderboxPrint: CPU system
969.8 (20.0%) 06:39:04 INFO - TinderboxPrint: CPU user
1,631.1 (33.6%) 06:39:04 INFO - TinderboxPrint: Swap in / out
433,315,840 / 0 06:39:04 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 405144064; Write bytes: 407275008; Read time: 29881; Write time: 871 06:39:04 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 27383808; Write bytes: 295942144; Read time: 254; Write time: 1207 06:39:05 INFO - Running post-run listener: _upload_blobber_files 06:39:05 INFO - Blob upload gear active. 06:39:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:39:05 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 06:39:05 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'] 06:39:05 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 06:39:05 INFO - (blobuploader) - INFO - Open directory for files ... 06:39:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:39:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:39:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:39:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:39:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:39:06 INFO - (blobuploader) - INFO - Done attempting. 06:39:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:39:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:39:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:39:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:39:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:39:07 INFO - (blobuploader) - INFO - Done attempting. 06:39:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:39:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:39:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:39:09 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:39:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:39:09 INFO - (blobuploader) - INFO - Done attempting. 06:39:09 INFO - (blobuploader) - INFO - Iteration through files over. 06:39:09 INFO - Return code: 0 06:39:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:39:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:39:09 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/96583026bea0d92ccc21879c589044eee93b979c7b64ea53c66aae335e1485c908472f91f4fce597f79162ebe2ba90c31626245f027c45db3d73e8c3372ad2f0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/abee3272770060dda12240daaab0b03d7c350cf8324875473e1d67bf63003140eca5e43564386b3ecf76eb2eecdbe42a9ee0537b2708bb81b9ad435123abca69", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/66229ecbfa658b07fa57be47f865200161530bbe9f1a90bb8fc9cadf9a2929beb1d8ff567cf7b7f1fdf71216c27a745e035b2bc403d7e3c701ee604545e16afb"} 06:39:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:39:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:39:09 INFO - Contents: 06:39:09 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/96583026bea0d92ccc21879c589044eee93b979c7b64ea53c66aae335e1485c908472f91f4fce597f79162ebe2ba90c31626245f027c45db3d73e8c3372ad2f0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/abee3272770060dda12240daaab0b03d7c350cf8324875473e1d67bf63003140eca5e43564386b3ecf76eb2eecdbe42a9ee0537b2708bb81b9ad435123abca69", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/66229ecbfa658b07fa57be47f865200161530bbe9f1a90bb8fc9cadf9a2929beb1d8ff567cf7b7f1fdf71216c27a745e035b2bc403d7e3c701ee604545e16afb"} 06:39:09 INFO - Running post-run listener: copy_logs_to_upload_dir 06:39:09 INFO - Copying logs to upload dir... 06:39:09 INFO - mkdir: /builds/slave/test/build/upload/logs 06:39:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.147432 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-10-25 06:39:09.614738) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-25 06:39:09.618610) ========= 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.bqC0yHTOCk/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.gNFkWmQuXp/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/96583026bea0d92ccc21879c589044eee93b979c7b64ea53c66aae335e1485c908472f91f4fce597f79162ebe2ba90c31626245f027c45db3d73e8c3372ad2f0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/abee3272770060dda12240daaab0b03d7c350cf8324875473e1d67bf63003140eca5e43564386b3ecf76eb2eecdbe42a9ee0537b2708bb81b9ad435123abca69", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/66229ecbfa658b07fa57be47f865200161530bbe9f1a90bb8fc9cadf9a2929beb1d8ff567cf7b7f1fdf71216c27a745e035b2bc403d7e3c701ee604545e16afb"} build_url:https://queue.taskcluster.net/v1/task/bJ1MPhptTY-3XRESFarC8Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011041 build_url: 'https://queue.taskcluster.net/v1/task/bJ1MPhptTY-3XRESFarC8Q/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/96583026bea0d92ccc21879c589044eee93b979c7b64ea53c66aae335e1485c908472f91f4fce597f79162ebe2ba90c31626245f027c45db3d73e8c3372ad2f0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/abee3272770060dda12240daaab0b03d7c350cf8324875473e1d67bf63003140eca5e43564386b3ecf76eb2eecdbe42a9ee0537b2708bb81b9ad435123abca69", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/66229ecbfa658b07fa57be47f865200161530bbe9f1a90bb8fc9cadf9a2929beb1d8ff567cf7b7f1fdf71216c27a745e035b2bc403d7e3c701ee604545e16afb"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-25 06:39:09.651192) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-25 06:39:09.651511) ========= 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.bqC0yHTOCk/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.gNFkWmQuXp/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.005093 ========= master_lag: 0.06 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-25 06:39:09.715028) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-25 06:39:09.715395) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-25 06:39:09.715688) ========= ========= Total master_lag: 0.30 =========