builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0024 starttime: 1476184251.6 results: success (0) buildid: 20161011015547 builduid: 7bd4d93f037441b49a3b680104981155 revision: cf0fc7add23cf008568653ce03f4c511506458b1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-11 04:10:51.597936) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-11 04:10:51.598436) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-11 04:10:51.598711) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-11 04:10:51.666529) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-11 04:10:51.666969) ========= 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.ggo5OUBHv1/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.Sm1GIn3wKD/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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-11 04:10:51-- 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% 29.4M=0s 2016-10-11 04:10:51 (29.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.196223 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-11 04:10:51.921468) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-11 04:10:51.921788) ========= 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.ggo5OUBHv1/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.Sm1GIn3wKD/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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.029168 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-11 04:10:51.980541) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-11 04:10:51.980855) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev cf0fc7add23cf008568653ce03f4c511506458b1 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev cf0fc7add23cf008568653ce03f4c511506458b1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ggo5OUBHv1/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.Sm1GIn3wKD/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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-11 04:10:52,054 truncating revision to first 12 chars 2016-10-11 04:10:52,054 Setting DEBUG logging. 2016-10-11 04:10:52,054 attempt 1/10 2016-10-11 04:10:52,054 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/cf0fc7add23c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-11 04:10:53,474 unpacking tar archive at: fx-team-cf0fc7add23c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.682019 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-11 04:10:53.684788) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-11 04:10:53.685132) ========= script_repo_revision: cf0fc7add23cf008568653ce03f4c511506458b1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-11 04:10:53.685583) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-11 04:10:53.685856) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-11 04:10:53.703736) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-10-11 04:10:53.704042) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ggo5OUBHv1/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.Sm1GIn3wKD/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 04:10:53 INFO - MultiFileLogger online at 20161011 04:10:53 in /builds/slave/test 04:10:53 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 04:10:53 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:10:53 INFO - {'append_to_log': False, 04:10:53 INFO - 'base_work_dir': '/builds/slave/test', 04:10:53 INFO - 'blob_upload_branch': 'Fx-Team', 04:10:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:10:53 INFO - 'branch': 'Fx-Team', 04:10:53 INFO - 'buildbot_json_path': 'buildprops.json', 04:10:53 INFO - 'config_files': ('talos/mac_config.py',), 04:10:53 INFO - 'default_actions': ('clobber', 04:10:53 INFO - 'read-buildbot-config', 04:10:53 INFO - 'download-and-extract', 04:10:53 INFO - 'populate-webroot', 04:10:53 INFO - 'create-virtualenv', 04:10:53 INFO - 'install', 04:10:53 INFO - 'run-tests'), 04:10:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:10:53 INFO - 'download_minidump_stackwalk': True, 04:10:53 INFO - 'download_symbols': 'ondemand', 04:10:53 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:10:53 INFO - 'tooltool.py': '/tools/tooltool.py', 04:10:53 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:10:53 INFO - '/tools/misc-python/virtualenv.py')}, 04:10:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:10:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:10:53 INFO - 'installer_path': 'installer.exe', 04:10:53 INFO - 'log_level': 'info', 04:10:53 INFO - 'log_name': 'talos', 04:10:53 INFO - 'log_to_console': True, 04:10:53 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:10:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:10:53 INFO - 'opt_config_files': (), 04:10:53 INFO - 'pip_index': False, 04:10:53 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:10:53 INFO - 'cmd': ('bash', 04:10:53 INFO - '-c', 04:10:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:10:53 INFO - 'enabled': True, 04:10:53 INFO - 'halt_on_failure': False, 04:10:53 INFO - 'name': 'check_screen_resolution'},), 04:10:53 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:10:53 INFO - 'cmd': ('bash', 04:10:53 INFO - '-c', 04:10:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:10:53 INFO - 'enabled': True, 04:10:53 INFO - 'halt_on_failure': False, 04:10:53 INFO - 'name': 'check_screen_resolution'},), 04:10:53 INFO - 'run_cmd_checks_enabled': True, 04:10:53 INFO - 'sps_profile': False, 04:10:53 INFO - 'sps_profile_interval': 0, 04:10:53 INFO - 'suite': 'dromaeojs', 04:10:53 INFO - 'system_bits': '32', 04:10:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:10:53 INFO - 'title': 't-yosemite-r7-0024', 04:10:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:10:53 INFO - 'use_talos_json': True, 04:10:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:10:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:10:53 INFO - 'work_dir': 'build'} 04:10:53 INFO - [mozharness: 2016-10-11 11:10:53.921179Z] Running clobber step. 04:10:53 INFO - Running pre-action listener: _resource_record_pre_action 04:10:53 INFO - Running main action method: clobber 04:10:53 INFO - rmtree: /builds/slave/test/build 04:10:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:10:56 INFO - Running post-action listener: _resource_record_post_action 04:10:56 INFO - [mozharness: 2016-10-11 11:10:56.170786Z] Finished clobber step (success) 04:10:56 INFO - [mozharness: 2016-10-11 11:10:56.170902Z] Running read-buildbot-config step. 04:10:56 INFO - Running pre-action listener: _resource_record_pre_action 04:10:56 INFO - Running main action method: read_buildbot_config 04:10:56 INFO - Using buildbot properties: 04:10:56 INFO - { 04:10:56 INFO - "project": "", 04:10:56 INFO - "product": "firefox", 04:10:56 INFO - "script_repo_revision": "production", 04:10:56 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 04:10:56 INFO - "repository": "", 04:10:56 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 04:10:56 INFO - "buildid": "20161011015547", 04:10:56 INFO - "pgo_build": "False", 04:10:56 INFO - "basedir": "/builds/slave/test", 04:10:56 INFO - "buildnumber": 5, 04:10:56 INFO - "slavename": "t-yosemite-r7-0024", 04:10:56 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 04:10:56 INFO - "platform": "yosemite_r7", 04:10:56 INFO - "scheduler": "tests-fx-team-macosx64-talos", 04:10:56 INFO - "branch": "fx-team", 04:10:56 INFO - "repo_path": "integration/fx-team", 04:10:56 INFO - "revision": "cf0fc7add23cf008568653ce03f4c511506458b1", 04:10:56 INFO - "stage_platform": "macosx64", 04:10:56 INFO - "builduid": "7bd4d93f037441b49a3b680104981155", 04:10:56 INFO - "slavebuilddir": "test" 04:10:56 INFO - } 04:10:56 INFO - Found installer url https://queue.taskcluster.net/v1/task/KNpU4yC9T4Ww7kmvYeOvsQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 04:10:56 INFO - Running post-action listener: _resource_record_post_action 04:10:56 INFO - [mozharness: 2016-10-11 11:10:56.173983Z] Finished read-buildbot-config step (success) 04:10:56 INFO - [mozharness: 2016-10-11 11:10:56.174119Z] Running download-and-extract step. 04:10:56 INFO - Running pre-action listener: _resource_record_pre_action 04:10:56 INFO - Running main action method: download_and_extract 04:10:56 INFO - mkdir: /builds/slave/test/build/tests 04:10: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')]} 04:10:56 INFO - trying https://queue.taskcluster.net/v1/task/KNpU4yC9T4Ww7kmvYeOvsQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 04:10:56 INFO - Downloading https://queue.taskcluster.net/v1/task/KNpU4yC9T4Ww7kmvYeOvsQ/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 04:10:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KNpU4yC9T4Ww7kmvYeOvsQ/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 04:10:57 INFO - Downloaded 1199 bytes. 04:10:57 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 04:10:57 INFO - Using the following test package requirements: 04:10:57 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 04:10:57 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:10:57 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 04:10:57 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:10:57 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 04:10:57 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:10:57 INFO - u'jsshell-mac.zip'], 04:10:57 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:10:57 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 04:10:57 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 04:10:57 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:10:57 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 04:10:57 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:10:57 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 04:10:57 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:10:57 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 04:10:57 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:10:57 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 04:10:57 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:10:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KNpU4yC9T4Ww7kmvYeOvsQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 04:10:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KNpU4yC9T4Ww7kmvYeOvsQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:10:57 INFO - Fetch https://queue.taskcluster.net/v1/task/KNpU4yC9T4Ww7kmvYeOvsQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 04:10:57 INFO - Http code: 200 04:10:57 INFO - via: 1.1 9552af4ceb2e0dd55964a80d1e549fd5.cloudfront.net (CloudFront) 04:10:57 INFO - x-amz-cf-id: v6Kl919jw9BQ57mYaZlrqGC_Q4mE5CqX988cScpAC6uK9L7gT6STBg== 04:10:57 INFO - x-amz-version-id: .fccuKXJIP0y6.ANvxeBzI95BqddCJ3y 04:10:58 INFO - Expected file size: 16508421 04:10:58 INFO - Obtained file size: 16508421 04:11:04 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 04:11:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KNpU4yC9T4Ww7kmvYeOvsQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 04:11:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KNpU4yC9T4Ww7kmvYeOvsQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:11:04 INFO - Fetch https://queue.taskcluster.net/v1/task/KNpU4yC9T4Ww7kmvYeOvsQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 04:11:05 INFO - Http code: 200 04:11:05 INFO - via: 1.1 a75342fa94ff4a9431476624fe74f02e.cloudfront.net (CloudFront) 04:11:05 INFO - x-amz-cf-id: eTUe8UiECNq-b5Ovhl7sxx7yR6v0yDdhr7usMKKraaCdIujegx4eKw== 04:11:05 INFO - x-amz-version-id: .fccuKXJIP0y6.ANvxeBzI95BqddCJ3y 04:11:05 INFO - Expected file size: 16508421 04:11:05 INFO - Obtained file size: 16508421 04:11:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KNpU4yC9T4Ww7kmvYeOvsQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 04:11:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KNpU4yC9T4Ww7kmvYeOvsQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:11:11 INFO - Fetch https://queue.taskcluster.net/v1/task/KNpU4yC9T4Ww7kmvYeOvsQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 04:11:12 INFO - Http code: 200 04:11:12 INFO - via: 1.1 6cd88b9fd84ad5daa3b3867fb8c5c91a.cloudfront.net (CloudFront) 04:11:12 INFO - x-amz-cf-id: DQcZMHOGfwAPo2RFeQoTVSD4NPa9i32KqJRc-7ulIVv1zuqSKYNsHw== 04:11:12 INFO - x-amz-version-id: f4fZLzDlWQ8rK7qfbqeBne4Utf7uGbj3 04:11:13 INFO - Expected file size: 14409006 04:11:13 INFO - Obtained file size: 14409006 04:11: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')]} 04:11:13 INFO - trying https://queue.taskcluster.net/v1/task/KNpU4yC9T4Ww7kmvYeOvsQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 04:11:13 INFO - Downloading https://queue.taskcluster.net/v1/task/KNpU4yC9T4Ww7kmvYeOvsQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 04:11:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KNpU4yC9T4Ww7kmvYeOvsQ/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 04:11:16 INFO - Downloaded 111686864 bytes. 04:11:16 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KNpU4yC9T4Ww7kmvYeOvsQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 04:11:16 INFO - mkdir: /builds/slave/test/properties 04:11:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:11:16 INFO - Writing to file /builds/slave/test/properties/build_url 04:11:16 INFO - Contents: 04:11:16 INFO - build_url:https://queue.taskcluster.net/v1/task/KNpU4yC9T4Ww7kmvYeOvsQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 04:11:17 INFO - Running post-action listener: _resource_record_post_action 04:11:17 INFO - Running post-action listener: set_extra_try_arguments 04:11:17 INFO - [mozharness: 2016-10-11 11:11:17.486587Z] Finished download-and-extract step (success) 04:11:17 INFO - [mozharness: 2016-10-11 11:11:17.486711Z] Running populate-webroot step. 04:11:17 INFO - Running pre-action listener: _resource_record_pre_action 04:11:17 INFO - Running main action method: populate_webroot 04:11:17 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:11:17 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:11:17 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:11:17 INFO - '--tppagecycles', 04:11:17 INFO - '7'], 04:11:17 INFO - 'tests': ['tsvgm']}}, 04:11:17 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:11:17 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:11:17 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:11:17 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:11:17 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:11:17 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:11:17 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:11:17 INFO - 'talos_options': ['--disable-e10s'], 04:11:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:11:17 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:11:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:11:17 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:11:17 INFO - 'talos_options': ['--disable-e10s'], 04:11:17 INFO - 'tests': ['damp', 'tps']}, 04:11:17 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:11:17 INFO - 'tests': ['damp', 'tps']}, 04:11:17 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:11:17 INFO - 'tests': ['dromaeo_dom']}, 04:11:17 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:11:17 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:11:17 INFO - 'tests': ['basic_compositor_video']}, 04:11:17 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:11:17 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:11:17 INFO - 'tests': ['a11yr', 04:11:17 INFO - 'ts_paint', 04:11:17 INFO - 'tpaint', 04:11:17 INFO - 'sessionrestore', 04:11:17 INFO - 'sessionrestore_no_auto_restore', 04:11:17 INFO - 'tabpaint']}, 04:11:17 INFO - 'other-e10s': {'tests': ['a11yr', 04:11:17 INFO - 'ts_paint', 04:11:17 INFO - 'tpaint', 04:11:17 INFO - 'sessionrestore', 04:11:17 INFO - 'sessionrestore_no_auto_restore', 04:11:17 INFO - 'tabpaint']}, 04:11:17 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:11:17 INFO - 'ts_paint', 04:11:17 INFO - 'tpaint', 04:11:17 INFO - 'sessionrestore', 04:11:17 INFO - 'sessionrestore_no_auto_restore', 04:11:17 INFO - 'tabpaint']}, 04:11:17 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:11:17 INFO - 'ts_paint', 04:11:17 INFO - 'tpaint', 04:11:17 INFO - 'sessionrestore', 04:11:17 INFO - 'sessionrestore_no_auto_restore', 04:11:17 INFO - 'tabpaint']}, 04:11:17 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:11:17 INFO - 'tests': ['a11yr', 04:11:17 INFO - 'ts_paint', 04:11:17 INFO - 'tpaint', 04:11:17 INFO - 'sessionrestore', 04:11:17 INFO - 'sessionrestore_no_auto_restore', 04:11:17 INFO - 'tabpaint']}, 04:11:17 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:11:17 INFO - 'tests': ['a11yr', 04:11:17 INFO - 'ts_paint', 04:11:17 INFO - 'tpaint', 04:11:17 INFO - 'sessionrestore', 04:11:17 INFO - 'sessionrestore_no_auto_restore', 04:11:17 INFO - 'tabpaint']}, 04:11:17 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:11:17 INFO - 'tests': ['tsvgx', 04:11:17 INFO - 'tsvgr_opacity', 04:11:17 INFO - 'tart', 04:11:17 INFO - 'tscrollx', 04:11:17 INFO - 'cart']}, 04:11:17 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:11:17 INFO - 'tsvgr_opacity', 04:11:17 INFO - 'tart', 04:11:17 INFO - 'tscrollx', 04:11:17 INFO - 'cart']}, 04:11:17 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:11:17 INFO - 'talos_options': ['--disable-e10s'], 04:11:17 INFO - 'tests': ['tp5o']}, 04:11:17 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:11:17 INFO - 'tests': ['tp5o']}, 04:11:17 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:11:17 INFO - 'talos_options': ['--disable-e10s', 04:11:17 INFO - '--xperf_path', 04:11:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:11:17 INFO - 'tests': ['tp5n']}, 04:11:17 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:11:17 INFO - 'talos_options': ['--xperf_path', 04:11:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:11:17 INFO - 'tests': ['tp5n']}}, 04:11:17 INFO - 'talos.zip': {'path': '', 04:11:17 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:11:17 INFO - Running post-action listener: _resource_record_post_action 04:11:17 INFO - [mozharness: 2016-10-11 11:11:17.505493Z] Finished populate-webroot step (success) 04:11:17 INFO - [mozharness: 2016-10-11 11:11:17.505623Z] Running create-virtualenv step. 04:11:17 INFO - Running pre-action listener: _resource_record_pre_action 04:11:17 INFO - Running main action method: create_virtualenv 04:11:17 INFO - Creating virtualenv /builds/slave/test/build/venv 04:11:17 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 04:11:17 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:11:17 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:11:17 INFO - Using real prefix '/tools/python27' 04:11:17 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:11:18 INFO - Installing distribute.............................................................................................................................................................................................done. 04:11:21 INFO - Installing pip.................done. 04:11:21 INFO - Return code: 0 04:11:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:11:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:11:21 INFO - Reading from file tmpfile_stdout 04:11:21 INFO - Output received: 04:11:21 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) 04:11:21 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:11:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:11:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:11:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:11:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:11:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:11:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:11:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108fe0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f56570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91f04d1310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108df4d30>, '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 0x108c1adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108decb20>, '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.ggo5OUBHv1/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.Sm1GIn3wKD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 04:11:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:11:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:11:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ggo5OUBHv1/Render', 04:11:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:11:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:11:21 INFO - 'HOME': '/Users/cltbld', 04:11:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:11:21 INFO - 'LOGNAME': 'cltbld', 04:11:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:11:21 INFO - 'MOZ_NO_REMOTE': '1', 04:11:21 INFO - 'NO_EM_RESTART': '1', 04:11:21 INFO - 'PAGER': '/bin/cat', 04:11:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:11:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:11:21 INFO - 'PWD': '/builds/slave/test', 04:11:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:11:21 INFO - 'SHELL': '/bin/bash', 04:11:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Sm1GIn3wKD/Listeners', 04:11:21 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 04:11:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:11:21 INFO - 'USER': 'cltbld', 04:11:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:11:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:11:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:11:21 INFO - 'XPC_FLAGS': '0x0', 04:11:21 INFO - 'XPC_SERVICE_NAME': '0', 04:11:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:11:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:11:21 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 04:11:21 INFO - Cleaning up... 04:11:21 INFO - Return code: 0 04:11:21 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:11:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:11:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:11:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:11:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:11:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:11:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:11:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x108fe0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f56570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91f04d1310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108df4d30>, '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 0x108c1adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108decb20>, '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.ggo5OUBHv1/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.Sm1GIn3wKD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 04:11:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:11:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:11:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ggo5OUBHv1/Render', 04:11:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:11:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:11:21 INFO - 'HOME': '/Users/cltbld', 04:11:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:11:21 INFO - 'LOGNAME': 'cltbld', 04:11:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:11:21 INFO - 'MOZ_NO_REMOTE': '1', 04:11:21 INFO - 'NO_EM_RESTART': '1', 04:11:21 INFO - 'PAGER': '/bin/cat', 04:11:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:11:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:11:21 INFO - 'PWD': '/builds/slave/test', 04:11:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:11:21 INFO - 'SHELL': '/bin/bash', 04:11:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Sm1GIn3wKD/Listeners', 04:11:21 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 04:11:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:11:21 INFO - 'USER': 'cltbld', 04:11:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:11:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:11:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:11:21 INFO - 'XPC_FLAGS': '0x0', 04:11:21 INFO - 'XPC_SERVICE_NAME': '0', 04:11:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:11:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:11:22 INFO - Downloading/unpacking psutil>=3.1.1 04:11:22 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 04:11:22 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 04:11:22 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 04:11:22 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 04:11:22 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 04:11:22 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 04:11:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:11:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:11:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:11:25 INFO - Installing collected packages: psutil 04:11:25 INFO - Running setup.py install for psutil 04:11:26 INFO - building 'psutil._psutil_osx' extension 04:11:26 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 04:11:26 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 04:11:26 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 04:11:26 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 04:11:26 INFO - building 'psutil._psutil_posix' extension 04:11:26 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 04:11:26 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:11:26 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:11:26 INFO - ^ 04:11:26 INFO - 1 warning generated. 04:11:26 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 04:11:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:11:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:11:27 INFO - Successfully installed psutil 04:11:27 INFO - Cleaning up... 04:11:27 INFO - Return code: 0 04:11:27 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:11:27 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')]} 04:11:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:11:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:11:27 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')]} 04:11:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:11:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:11:27 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 0x108fe0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f56570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91f04d1310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108df4d30>, '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 0x108c1adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108decb20>, '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.ggo5OUBHv1/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.Sm1GIn3wKD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 04:11:27 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 04:11:27 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 04:11:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ggo5OUBHv1/Render', 04:11:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:11:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:11:27 INFO - 'HOME': '/Users/cltbld', 04:11:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:11:27 INFO - 'LOGNAME': 'cltbld', 04:11:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:11:27 INFO - 'MOZ_NO_REMOTE': '1', 04:11:27 INFO - 'NO_EM_RESTART': '1', 04:11:27 INFO - 'PAGER': '/bin/cat', 04:11:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:11:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:11:27 INFO - 'PWD': '/builds/slave/test', 04:11:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:11:27 INFO - 'SHELL': '/bin/bash', 04:11:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Sm1GIn3wKD/Listeners', 04:11:27 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 04:11:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:11:27 INFO - 'USER': 'cltbld', 04:11:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:11:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:11:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:11:27 INFO - 'XPC_FLAGS': '0x0', 04:11:27 INFO - 'XPC_SERVICE_NAME': '0', 04:11:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:11:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:11:27 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:11:27 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 04:11:27 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 04:11:27 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 04:11:27 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 04:11:27 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 04:11:27 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 04:11:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:11:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:11:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:11:30 INFO - Installing collected packages: mozsystemmonitor 04:11:30 INFO - Running setup.py install for mozsystemmonitor 04:11:30 INFO - Successfully installed mozsystemmonitor 04:11:30 INFO - Cleaning up... 04:11:30 INFO - Return code: 0 04:11:30 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:11:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:11:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:11:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:11:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:11:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:11:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:11:30 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 0x108fe0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f56570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91f04d1310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108df4d30>, '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 0x108c1adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108decb20>, '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.ggo5OUBHv1/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.Sm1GIn3wKD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 04:11:30 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 04:11:30 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 04:11:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ggo5OUBHv1/Render', 04:11:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:11:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:11:30 INFO - 'HOME': '/Users/cltbld', 04:11:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:11:30 INFO - 'LOGNAME': 'cltbld', 04:11:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:11:30 INFO - 'MOZ_NO_REMOTE': '1', 04:11:30 INFO - 'NO_EM_RESTART': '1', 04:11:30 INFO - 'PAGER': '/bin/cat', 04:11:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:11:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:11:30 INFO - 'PWD': '/builds/slave/test', 04:11:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:11:30 INFO - 'SHELL': '/bin/bash', 04:11:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Sm1GIn3wKD/Listeners', 04:11:30 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 04:11:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:11:30 INFO - 'USER': 'cltbld', 04:11:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:11:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:11:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:11:30 INFO - 'XPC_FLAGS': '0x0', 04:11:30 INFO - 'XPC_SERVICE_NAME': '0', 04:11:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:11:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:11:30 INFO - Downloading/unpacking jsonschema==2.5.1 04:11:30 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 04:11:30 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 04:11:30 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 04:11:30 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 04:11:30 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 04:11:30 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 04:11:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:11:33 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:11:33 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 04:11:33 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 04:11:33 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 04:11:33 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 04:11:33 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 04:11:33 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 04:11:33 INFO - Downloading functools32-3.2.3-2.tar.gz 04:11:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:11:33 INFO - warning: no files found matching '*.txt' 04:11:33 INFO - no previously-included directories found matching 'build' 04:11:33 INFO - no previously-included directories found matching 'dist' 04:11:33 INFO - no previously-included directories found matching '.git*' 04:11:33 INFO - Installing collected packages: jsonschema, functools32 04:11:33 INFO - Running setup.py install for functools32 04:11:33 INFO - warning: no files found matching '*.txt' 04:11:33 INFO - no previously-included directories found matching 'build' 04:11:33 INFO - no previously-included directories found matching 'dist' 04:11:33 INFO - no previously-included directories found matching '.git*' 04:11:33 INFO - Successfully installed jsonschema functools32 04:11:33 INFO - Cleaning up... 04:11:33 INFO - Return code: 0 04:11:33 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:11:33 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')]} 04:11:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:11:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:11:33 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')]} 04:11:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:11:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:11:33 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 0x108fe0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f56570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91f04d1310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108df4d30>, '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 0x108c1adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108decb20>, '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.ggo5OUBHv1/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.Sm1GIn3wKD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 04:11:33 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 04:11:33 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 04:11:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ggo5OUBHv1/Render', 04:11:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:11:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:11:33 INFO - 'HOME': '/Users/cltbld', 04:11:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:11:33 INFO - 'LOGNAME': 'cltbld', 04:11:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:11:33 INFO - 'MOZ_NO_REMOTE': '1', 04:11:33 INFO - 'NO_EM_RESTART': '1', 04:11:33 INFO - 'PAGER': '/bin/cat', 04:11:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:11:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:11:33 INFO - 'PWD': '/builds/slave/test', 04:11:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:11:33 INFO - 'SHELL': '/bin/bash', 04:11:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Sm1GIn3wKD/Listeners', 04:11:33 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 04:11:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:11:33 INFO - 'USER': 'cltbld', 04:11:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:11:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:11:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:11:33 INFO - 'XPC_FLAGS': '0x0', 04:11:33 INFO - 'XPC_SERVICE_NAME': '0', 04:11:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:11:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:11:34 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:11:34 INFO - Cleaning up... 04:11:34 INFO - Return code: 0 04:11:34 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:11:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:11:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:11:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:11:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:11:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:11:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:11:34 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 0x108fe0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f56570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91f04d1310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108df4d30>, '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 0x108c1adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108decb20>, '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.ggo5OUBHv1/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.Sm1GIn3wKD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 04:11:34 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 04:11:34 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 04:11:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ggo5OUBHv1/Render', 04:11:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:11:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:11:34 INFO - 'HOME': '/Users/cltbld', 04:11:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:11:34 INFO - 'LOGNAME': 'cltbld', 04:11:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:11:34 INFO - 'MOZ_NO_REMOTE': '1', 04:11:34 INFO - 'NO_EM_RESTART': '1', 04:11:34 INFO - 'PAGER': '/bin/cat', 04:11:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:11:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:11:34 INFO - 'PWD': '/builds/slave/test', 04:11:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:11:34 INFO - 'SHELL': '/bin/bash', 04:11:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Sm1GIn3wKD/Listeners', 04:11:34 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 04:11:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:11:34 INFO - 'USER': 'cltbld', 04:11:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:11:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:11:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:11:34 INFO - 'XPC_FLAGS': '0x0', 04:11:34 INFO - 'XPC_SERVICE_NAME': '0', 04:11:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:11:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:11:34 INFO - Downloading/unpacking blobuploader==1.2.4 04:11: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 04:11: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 04:11: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 04:11: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 04:11: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 04:11: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 04:11:36 INFO - Downloading blobuploader-1.2.4.tar.gz 04:11:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:11:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:11: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 04:11: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 04:11: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 04:11: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 04:11: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 04:11: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 04:11:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:11:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:11:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:11:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:11:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:11:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:11:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:11:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:11:38 INFO - Downloading docopt-0.6.1.tar.gz 04:11:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:11:38 INFO - Installing collected packages: blobuploader, requests, docopt 04:11:38 INFO - Running setup.py install for blobuploader 04:11:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:11:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:11:38 INFO - Running setup.py install for requests 04:11:38 INFO - Running setup.py install for docopt 04:11:38 INFO - Successfully installed blobuploader requests docopt 04:11:38 INFO - Cleaning up... 04:11:38 INFO - Return code: 0 04:11:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:11:38 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')]} 04:11:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:11:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:11:38 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')]} 04:11:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:11:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:11:38 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 0x108fe0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f56570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91f04d1310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108df4d30>, '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 0x108c1adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108decb20>, '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.ggo5OUBHv1/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.Sm1GIn3wKD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 04:11:38 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 04:11:38 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 04:11:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ggo5OUBHv1/Render', 04:11:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:11:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:11:38 INFO - 'HOME': '/Users/cltbld', 04:11:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:11:38 INFO - 'LOGNAME': 'cltbld', 04:11:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:11:38 INFO - 'MOZ_NO_REMOTE': '1', 04:11:38 INFO - 'NO_EM_RESTART': '1', 04:11:38 INFO - 'PAGER': '/bin/cat', 04:11:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:11:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:11:38 INFO - 'PWD': '/builds/slave/test', 04:11:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:11:38 INFO - 'SHELL': '/bin/bash', 04:11:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Sm1GIn3wKD/Listeners', 04:11:38 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 04:11:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:11:38 INFO - 'USER': 'cltbld', 04:11:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:11:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:11:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:11:38 INFO - 'XPC_FLAGS': '0x0', 04:11:38 INFO - 'XPC_SERVICE_NAME': '0', 04:11:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:11:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:11:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:11:39 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-XXwDtX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:11:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:11:39 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-z0lqsW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:11:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:11:39 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-vmR6dM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:11:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:11:39 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-VodzWT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:11:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:11:39 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-g3ZEkO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:11:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:11:39 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-0vm65c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:11:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:11:39 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-ubowHr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:11:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:11:39 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-1Laznb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:11:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:11:40 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-6dmD4h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:11:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:11:40 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-CujREK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:11:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:11:40 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-kdRtpY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:11:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:11:40 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-3Plv6j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:11:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:11:40 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-Yreqj3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:11:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:11:40 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-lKU4wJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:11:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:11:40 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-iEkGEE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:11:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:11:40 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-QOVi1c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:11:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:11:40 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-St6uCH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:11:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:11:40 INFO - Running setup.py install for manifestparser 04:11:41 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:11:41 INFO - Running setup.py install for mozcrash 04:11:41 INFO - Running setup.py install for mozdebug 04:11:41 INFO - Running setup.py install for mozdevice 04:11:41 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:11:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:11:41 INFO - Running setup.py install for mozfile 04:11:41 INFO - Running setup.py install for mozhttpd 04:11:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:11:41 INFO - Running setup.py install for mozinfo 04:11:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:11:41 INFO - Running setup.py install for mozInstall 04:11:42 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:11:42 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:11:42 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:11:42 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:11:42 INFO - Running setup.py install for mozleak 04:11:42 INFO - Running setup.py install for mozlog 04:11:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:11:42 INFO - Running setup.py install for moznetwork 04:11:42 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:11:42 INFO - Running setup.py install for mozprocess 04:11:42 INFO - Running setup.py install for mozprofile 04:11:42 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:11:42 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:11:42 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:11:42 INFO - Running setup.py install for mozrunner 04:11:43 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:11:43 INFO - Running setup.py install for mozscreenshot 04:11:43 INFO - Running setup.py install for moztest 04:11:43 INFO - Running setup.py install for mozversion 04:11:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:11:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:11:43 INFO - Cleaning up... 04:11:43 INFO - Return code: 0 04:11:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:11:43 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')]} 04:11:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:11:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:11:43 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')]} 04:11:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:11:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:11:43 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 0x108fe0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f56570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91f04d1310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108df4d30>, '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 0x108c1adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108decb20>, '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.ggo5OUBHv1/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.Sm1GIn3wKD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 04:11:43 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 04:11:43 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 04:11:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ggo5OUBHv1/Render', 04:11:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:11:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:11:43 INFO - 'HOME': '/Users/cltbld', 04:11:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:11:43 INFO - 'LOGNAME': 'cltbld', 04:11:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:11:43 INFO - 'MOZ_NO_REMOTE': '1', 04:11:43 INFO - 'NO_EM_RESTART': '1', 04:11:43 INFO - 'PAGER': '/bin/cat', 04:11:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:11:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:11:43 INFO - 'PWD': '/builds/slave/test', 04:11:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:11:43 INFO - 'SHELL': '/bin/bash', 04:11:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Sm1GIn3wKD/Listeners', 04:11:43 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 04:11:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:11:43 INFO - 'USER': 'cltbld', 04:11:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:11:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:11:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:11:43 INFO - 'XPC_FLAGS': '0x0', 04:11:43 INFO - 'XPC_SERVICE_NAME': '0', 04:11:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:11:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:11:43 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-HFfEkm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:11:43 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)) 04:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:11:43 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-2Mqtsy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:11:44 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)) 04:11:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:11:44 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-hIuRTK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:11:44 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)) 04:11:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:11:44 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-baL_lH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:11:44 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)) 04:11:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:11:44 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-51ohU9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:11:44 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)) 04:11:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:11:44 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-WEvEt1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:11:44 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)) 04:11:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:11:44 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-I0eVbe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:11:44 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)) 04:11:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:11:44 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-sodAOO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:11:44 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)) 04:11:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:11:44 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-KMemG7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:11:44 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)) 04:11:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:11:44 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-FXToCo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:11:44 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)) 04:11:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:11:44 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-jOlbyM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:11:44 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)) 04:11:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:11:45 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-fnkbV1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:11:45 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)) 04:11:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:11:45 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-CcFsPS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:11:45 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)) 04:11:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:11:45 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-Q1mQPr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:11:45 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)) 04:11:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:11:45 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-3tEm88-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:11:45 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)) 04:11:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:11:45 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-1jPmXn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:11:45 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)) 04:11:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:11:45 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-qbsM5F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:11:45 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)) 04:11:45 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)) 04:11:45 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)) 04:11:45 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)) 04:11:45 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)) 04:11:45 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)) 04:11:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:11:45 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 04:11:45 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 04:11:45 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 04:11:45 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 04:11:45 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 04:11:45 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 04:11:48 INFO - Downloading blessings-1.6.tar.gz 04:11:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:11:48 INFO - Installing collected packages: blessings 04:11:48 INFO - Running setup.py install for blessings 04:11:48 INFO - Successfully installed blessings 04:11:48 INFO - Cleaning up... 04:11:48 INFO - Return code: 0 04:11:48 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:11:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:11:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:11:48 INFO - Reading from file tmpfile_stdout 04:11:48 INFO - Current package versions: 04:11:48 INFO - blessings == 1.6 04:11:48 INFO - blobuploader == 1.2.4 04:11:48 INFO - docopt == 0.6.1 04:11:48 INFO - functools32 == 3.2.3-2 04:11:48 INFO - jsonschema == 2.5.1 04:11:48 INFO - manifestparser == 1.1 04:11:48 INFO - mozInstall == 1.12 04:11:48 INFO - mozcrash == 0.17 04:11:48 INFO - mozdebug == 0.1 04:11:48 INFO - mozdevice == 0.48 04:11:48 INFO - mozfile == 1.2 04:11:48 INFO - mozhttpd == 0.7 04:11:48 INFO - mozinfo == 0.9 04:11:48 INFO - mozleak == 0.1 04:11:48 INFO - mozlog == 3.4 04:11:48 INFO - moznetwork == 0.27 04:11:48 INFO - mozprocess == 0.23 04:11:48 INFO - mozprofile == 0.28 04:11:48 INFO - mozrunner == 6.12 04:11:48 INFO - mozscreenshot == 0.1 04:11:48 INFO - mozsystemmonitor == 0.3 04:11:48 INFO - moztest == 0.7 04:11:48 INFO - mozversion == 1.4 04:11:48 INFO - psutil == 3.1.1 04:11:48 INFO - requests == 1.2.3 04:11:48 INFO - wsgiref == 0.1.2 04:11:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:11:48 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')]} 04:11:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:11:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:11:48 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')]} 04:11:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:11:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:11:48 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 0x108fe0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f56570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91f04d1310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108df4d30>, '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 0x108c1adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108decb20>, '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.ggo5OUBHv1/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.Sm1GIn3wKD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 04:11:48 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 04:11:48 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 04:11:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ggo5OUBHv1/Render', 04:11:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:11:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:11:48 INFO - 'HOME': '/Users/cltbld', 04:11:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:11:48 INFO - 'LOGNAME': 'cltbld', 04:11:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:11:48 INFO - 'MOZ_NO_REMOTE': '1', 04:11:48 INFO - 'NO_EM_RESTART': '1', 04:11:48 INFO - 'PAGER': '/bin/cat', 04:11:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:11:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:11:48 INFO - 'PWD': '/builds/slave/test', 04:11:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:11:48 INFO - 'SHELL': '/bin/bash', 04:11:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Sm1GIn3wKD/Listeners', 04:11:48 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 04:11:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:11:48 INFO - 'USER': 'cltbld', 04:11:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:11:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:11:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:11:48 INFO - 'XPC_FLAGS': '0x0', 04:11:48 INFO - 'XPC_SERVICE_NAME': '0', 04:11:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:11:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:11:49 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)) 04:11:49 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)) 04:11:49 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)) 04:11:49 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)) 04:11:49 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)) 04:11:49 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)) 04:11:49 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)) 04:11:49 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)) 04:11:49 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)) 04:11:49 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)) 04:11:49 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)) 04:11:49 INFO - Cleaning up... 04:11:49 INFO - Return code: 0 04:11:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:11:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:11:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:11:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:11:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:11:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:11:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:11:49 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 0x108fe0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f56570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91f04d1310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108df4d30>, '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 0x108c1adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108decb20>, '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.ggo5OUBHv1/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.Sm1GIn3wKD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 04:11:49 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 04:11:49 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 04:11:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ggo5OUBHv1/Render', 04:11:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:11:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:11:49 INFO - 'HOME': '/Users/cltbld', 04:11:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:11:49 INFO - 'LOGNAME': 'cltbld', 04:11:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:11:49 INFO - 'MOZ_NO_REMOTE': '1', 04:11:49 INFO - 'NO_EM_RESTART': '1', 04:11:49 INFO - 'PAGER': '/bin/cat', 04:11:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:11:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:11:49 INFO - 'PWD': '/builds/slave/test', 04:11:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:11:49 INFO - 'SHELL': '/bin/bash', 04:11:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Sm1GIn3wKD/Listeners', 04:11:49 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 04:11:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:11:49 INFO - 'USER': 'cltbld', 04:11:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:11:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:11:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:11:49 INFO - 'XPC_FLAGS': '0x0', 04:11:49 INFO - 'XPC_SERVICE_NAME': '0', 04:11:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:11:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:11:49 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:11:49 INFO - Cleaning up... 04:11:49 INFO - Return code: 0 04:11:49 INFO - Running post-action listener: _resource_record_post_action 04:11:49 INFO - Running post-action listener: _start_resource_monitoring 04:11:49 INFO - Starting resource monitoring. 04:11:49 INFO - [mozharness: 2016-10-11 11:11:49.355172Z] Finished create-virtualenv step (success) 04:11:49 INFO - [mozharness: 2016-10-11 11:11:49.356241Z] Running install step. 04:11:49 INFO - Running pre-action listener: _resource_record_pre_action 04:11:49 INFO - Running main action method: install 04:11:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:11:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:11:49 INFO - Reading from file tmpfile_stdout 04:11:49 INFO - Detecting whether we're running mozinstall >=1.0... 04:11:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:11:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:11:49 INFO - Reading from file tmpfile_stdout 04:11:49 INFO - Output received: 04:11:49 INFO - Usage: mozinstall [options] installer 04:11:49 INFO - Options: 04:11:49 INFO - -h, --help show this help message and exit 04:11:49 INFO - -d DEST, --destination=DEST 04:11:49 INFO - Directory to install application into. [default: 04:11:49 INFO - "/builds/slave/test"] 04:11:49 INFO - --app=APP Application being installed. [default: firefox] 04:11:49 INFO - mkdir: /builds/slave/test/build/application 04:11:49 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'] 04:11:49 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 04:12:24 INFO - Reading from file tmpfile_stdout 04:12:24 INFO - Output received: 04:12:24 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:12:24 INFO - Running post-action listener: _resource_record_post_action 04:12:24 INFO - [mozharness: 2016-10-11 11:12:24.199257Z] Finished install step (success) 04:12:24 INFO - [mozharness: 2016-10-11 11:12:24.199383Z] Running run-tests step. 04:12:24 INFO - Running pre-action listener: _resource_record_pre_action 04:12:24 INFO - Running main action method: run_tests 04:12:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:12:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:12:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:12:24 INFO - 2016-10-11 04:12:24.213 screenresolution[1639:8326] starting screenresolution argv=screenresolution get 04:12:24 INFO - 2016-10-11 04:12:24.216 screenresolution[1639:8326] Display 0: 1600x1200x32@60 04:12:24 INFO - 2016-10-11 04:12:24.221 screenresolution[1640:8328] starting screenresolution argv=screenresolution list 04:12:24 INFO - Available Modes on Display 0 04:12:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:12:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:12:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:12:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:12:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:12:24 INFO - Intel Iris: 04:12:24 INFO - Chipset Model: Intel Iris 04:12:24 INFO - Type: GPU 04:12:24 INFO - Bus: Built-In 04:12:24 INFO - VRAM (Dynamic, Max): 1536 MB 04:12:24 INFO - Vendor: Intel (0x8086) 04:12:24 INFO - Device ID: 0x0a2e 04:12:24 INFO - Revision ID: 0x0009 04:12:24 INFO - Displays: 04:12:24 INFO - Display: 04:12:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:12:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:12:24 INFO - Display Serial Number: 1600x1200 60 04:12:24 INFO - Main Display: Yes 04:12:24 INFO - Mirror: Off 04:12:24 INFO - Online: Yes 04:12:24 INFO - Rotation: Supported 04:12:24 INFO - Return code: 0 04:12:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:12:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:12:24 INFO - Python 2.7.3 04:12:24 INFO - Return code: 0 04:12:24 INFO - grabbing minidump binary from tooltool 04:12:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:12:24 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 0x108df4d30>, '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 0x108c1adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108decb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:12:24 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 04:12:24 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 04:12:24 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 04:12:24 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:12:24 INFO - Return code: 0 04:12:24 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:12:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:12:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:12:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:12:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:12:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KNpU4yC9T4Ww7kmvYeOvsQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0024', '--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 04:12:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/KNpU4yC9T4Ww7kmvYeOvsQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0024 --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 04:12:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ggo5OUBHv1/Render', 04:12:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:12:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:12:24 INFO - 'HOME': '/Users/cltbld', 04:12:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:12:24 INFO - 'LOGNAME': 'cltbld', 04:12:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:12:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:12:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:12:24 INFO - 'MOZ_NO_REMOTE': '1', 04:12:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:12:24 INFO - 'NO_EM_RESTART': '1', 04:12:24 INFO - 'PAGER': '/bin/cat', 04:12:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:12:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:12:24 INFO - 'PWD': '/builds/slave/test', 04:12:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:12:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:12:24 INFO - 'SHELL': '/bin/bash', 04:12:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Sm1GIn3wKD/Listeners', 04:12:24 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 04:12:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:12:24 INFO - 'USER': 'cltbld', 04:12:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:12:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:12:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:12:24 INFO - 'XPC_FLAGS': '0x0', 04:12:24 INFO - 'XPC_SERVICE_NAME': '0', 04:12:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:12:24 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KNpU4yC9T4Ww7kmvYeOvsQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0024', '--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 04:12:24 INFO - mozversion application_buildid: 20161011015547 04:12:24 INFO - mozversion application_changeset: cf0fc7add23cf008568653ce03f4c511506458b1 04:12:24 INFO - mozversion application_display_name: Nightly 04:12:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:12:24 INFO - mozversion application_name: Firefox 04:12:24 INFO - mozversion application_remotingname: firefox 04:12:24 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 04:12:24 INFO - mozversion application_vendor: Mozilla 04:12:24 INFO - mozversion application_version: 52.0a1 04:12:24 INFO - mozversion platform_buildid: 20161011015547 04:12:24 INFO - mozversion platform_changeset: cf0fc7add23cf008568653ce03f4c511506458b1 04:12:24 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 04:12:24 INFO - mozversion platform_version: 52.0a1 04:12:24 INFO - using testdate: 1476184344 04:12:24 INFO - actual date: 1476184344 04:12:24 INFO - starting webserver on 'localhost:49266' 04:12:24 INFO - SUITE-START | Running 2 tests 04:12:24 INFO - TEST-START | dromaeo_css 04:12:24 INFO - operating with platform_type : mac_ 04:12:24 INFO - Initialising browser for dromaeo_css test... 04:12:24 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/tmpz4v0zx/profile http://localhost:49266/getInfo.html) 04:12:27 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 04:12:27 INFO - PROCESS | 1650 | colorDepth:24 04:12:27 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 04:12:27 INFO - PROCESS | 1650 | __metrics 04:12:27 INFO - PROCESS | 1650 | 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" 04:12:27 INFO - PROCESS | 1650 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:12:28 INFO - TEST-INFO | 1650: exit 0 04:12:28 INFO - Browser initialized. 04:12:28 INFO - Running cycle 1/1 for dromaeo_css test... 04:12:28 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/tmpz4v0zx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:12:40 INFO - PROCESS | 1653 | RSS: Main: 234598400 04:12:40 INFO - PROCESS | 1653 | 04:13:31 INFO - PROCESS | 1653 | 1476184411393 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] 04:15:44 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49266/tests/dromaeo/cssquery-ext.html) 04:15:46 INFO - PROCESS | 1653 | RSS: Main: 409960448 04:15:46 INFO - PROCESS | 1653 | 04:18:48 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-ext.html (next: http://localhost:49266/tests/dromaeo/cssquery-jquery.html) 04:18:49 INFO - PROCESS | 1653 | RSS: Main: 373329920 04:18:49 INFO - PROCESS | 1653 | 04:21:51 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49266/tests/dromaeo/cssquery-mootools.html) 04:21:52 INFO - PROCESS | 1653 | RSS: Main: 336793600 04:21:52 INFO - PROCESS | 1653 | 04:24:54 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49266/tests/dromaeo/cssquery-prototype.html) 04:24:55 INFO - PROCESS | 1653 | RSS: Main: 345104384 04:24:55 INFO - PROCESS | 1653 | 04:27:58 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49266/tests/dromaeo/cssquery-yui.html) 04:27:59 INFO - PROCESS | 1653 | RSS: Main: 331145216 04:27:59 INFO - PROCESS | 1653 | 04:31:03 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-yui.html (next: http://localhost:49266/tests/dromaeo/cssquery-dojo.html) 04:31:03 INFO - PROCESS | 1653 | RSS: Main: 380633088 04:31:03 INFO - PROCESS | 1653 | 04:31:03 INFO - PROCESS | 1653 | __start_tp_report 04:31:03 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 04:31:03 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 04:31:03 INFO - PROCESS | 1653 | |i|pagename|runs| 04:31:03 INFO - PROCESS | 1653 | |0;dojo.html;3312.0891754767435;3328.7503437242212;3331.516930045142;3370.1743072947133;3380.5605271314726;5537.556995440365;5657.519110875575;5700.945325114846;5736.340320863102;5736.684482353474;5640.633358831673;5668.603197776147;5787.363390027093;5798.942010579893;5830.329512106109;5072.9746351268195;5162.225666150089;5181.1710126379785;5237.6071794615555;5238.05714971305;5189.403218629868;5189.662671926331;5193.740312984354;5247.658902171365;5271.65734227274;5153.304303918961;5160.845174644766;5181.9740901295445;5196.298499702549;5217.791288348461;5125.794968201268;5149.974250128763;5192.506711862367;5196.740162991835;5251.842444726664;5131.615128865313;5152.098382782998;5161.483851614846;5182.326297581328;5256.894862102737;5755.079187330049;5758.74085666146;5770.480656740871;5795.536357091465;5798.536117110621;6103.0858187454;6110.63336199825;6126.456603435028;6169.198004259418;6224.06639004153;6098.786542471036;6167.259928808572;6210.937890621081;6211.596246243979;6220.906686399708;5760.2223699800825;5768.971155144198;5770.596058275881;5779;5810.564207684441;6109.246413565088;6116.911372994184;6148.1699970504305;6180.567360284737;6191.876162476815;6125.206275768898;6126.287952498583;6130.846728831815;6132.080177995925;6139.004823432402;6180.6909654517995;6180.907286390708;6230.844228894223;6238.719257633417;6245.156903818017;6034.939650603438;6042.305134909454;6142.416470435391;6185.783497577653;6203.069539569011;4805.591524720368;5346.91979620306;6120.720577099361;6135.815925522242;6229.283632382243;4903.877403064952;4905.166121759309;4917.434495033117;4923.1075004348;4957.801687932514;4370.541093185233;4376.14665140301;4420.425344705232;4425.380446737459;4480.641548676098;5623.325200975909;5655.179998900218;5700.344460387026;5719.656820590778;5797.594168408255;6171.413715697091;6180.47465965398;6183.721732522047;6185.257769067741;6193.84515387119;5708.086706127;5734.39788822176;5747.195392645033;5750;5766.769329226868;5273.1299335609965;5302.337207849019;5364.91952620711;5388.057090009311;5405.297311349499;6223.782167624111;6227.283862355796;6237.251529816451;6243.125962365181;6253.530985176039;6120.812855101315;6170.352113028247;6176.567640265318;6178.752849885954;6184.721687524162;5601.383847776659;6140.631562106288;6289.9056514153235;6295.276043255084;6301.936980630318;6146.108814222002;6182.103594978793;6185.103160041679;6198.504119670506;6203.410675985774;5499.615026948075;6103.389661033861;6144.771045790949;6205.758848230463;6277.309495955533;5557.416471270459;6020.698965051817;6078.939210607838;6110.862540392742;6142.600730952652;6098.695065246809;6100.786472473442;6111.694415279308;6112.755489780537;6125.142480052886;5789.594728368974;5907.497862681548;5927.407259274038;5930;5935.554833387427;6008.399160083956;6053.606515576474;6081.300650425081;6096.664683442275;6097.5731698780655;5792.652440853562;5969.7313620888035;5972.5520585955355;5977.880442391215;6014.1580178775885 04:31:03 INFO - PROCESS | 1653 | |1;ext.html;454.8635409377187;499.7326430359758;513.0790231534395;520.4145336496441;535.472559528864;30875.691243087564;40468.19063618726;41789.79105104478;41824.745257642244;41977.37033944485;43009.35485967705;43291.35062974058;43298;43371.56628433715;43446.348304775456;9746.4152150871;9927.702168934908;9942.204623630128;9948.751281217956;9949.552270147824;9790.804183916318;9892.604295828158;9893.554790034466;9911.306208565404;9916.801663966753;9858.014198580157;9924.851127233062;9933.552990115462;9939.50302484873;9954.352967057155;9826.213902887788;9891.35706179096;9891.505424728735;9900.356476829054;9901.108900199017;9621.230301575857;9703.12671859529;9705.320627556073;9706.660266890696;9713.854292185622;40758.18483630341;41089;41092.58907410917;41111.58888411138;41141.79429102865;3273.541704161419;3432.845521951518;3496.125968507873;3709.3879509880835;3725.4784330193793;30564.694353056406;31874.521882171786;31933.84033079843;31967.840160799045;31975.880844170344;2856.9714302856914;3188.8883889064005;3192.2179066128947;3286.342731453719;3303.7687361884678;454845;478866.26802391966;479083;481205.9999999965;489547;58964;59864.40135598675;59895;60038.099428508605;60053.399466005656;63772;63859.36140638534;63885.680571596844;63969.72060558856;63987.36012639907;15969.68060638804;16219.675606487805;16229.513114606581;16234.431794887123;16250.918745406198;12577.93711031439;12645.367731613382;12657.240565566095;12659;12667.683307917192;16727.996320220584;17304.65390692179;17313.39403120885;17323.826761732475;17351.392701255394;6036.788712395045;6435.517336199804;6662.300458451889;6732.528722989442;6739.629320387426;875.9737207883774;1010.434156872154;1029.8197815382277;1073.313079629038;1109.3565731875492;25304.74695253024;29903.102906912827;29950.55074173889;30392.696073039428;30845.383092338026;829.8962629671291;1047.4448542272607;1054.8765564673597;1083.8753543342616;1136.8237923121928;14574.927125364306;15127.243637818065;15155;15317.791240302882;15332.616684582974;594079;597868;598261;598727;599193;57733.13400298999;57894;57937.4206257932;58061.12908306379;58154.4184558149;1224.1981502116128;1263.6019653809021;1339.4642143142626;1371.245814801875;1380.827396575428;25022.499550008535;30227;31112.755489780156;31260.687393125776;32031.67968320287;50103.74948125236;50248.236347001264;50495.24257136219;50585.49414505808;50598.470076495294;493738;496477;496619.03380967176;496873;496967;24857.005719771005;24918.501629966937;26086.956521738917;27713.307167320978;27722.306942326602;46950.53049469461;47740.522594775;48019.51980480151;48454.03091938212;48568;32668.50997234996;35934.460983085795;38878.805605971786;39738.801305993285;40088.59911400849;38565.4215186778;39025;39124.6087539121;39965.200695986496;40578.39132413075;37456.625433746405;37866.810665947596;38323.616763832004;38454.37013190872;38722.838314850596;33320.00039998804;34341.828290858386;35161.68215422966;35244;35532.82233588816;19988.90005549963;25179;25287.620685690097;25875.87062064753;25948.09181678632 04:31:03 INFO - PROCESS | 1653 | |2;jquery.html;319.2720597038751;342.29487256252105;367.2856294507183;370.79606216580873;401.15756910487977;14994.325255363508;15129.546113616596;15949.441769538054;20011.399658010283;20653.690194647053;19528;20033.398998030047;20454.69317960228;20545.38363849083;20882.89558552209;5800.53595712342;6052.243469566304;6062.333143354227;6064.605800622968;6079.392060793907;4837.298591704201;6055.697215139247;6101.694915254242;6124.938750612481;6135.693215339237;5755;6077.939220607804;6080.179175811275;6087.634741915499;6088.178095957055;5971.522278217754;6007.849803754941;6017.308009578911;6053.576249662526;6089.117078023706;4971.229459433794;5154.664946778485;5234.869128271824;5254.21186821976;5267.078261304257;22360.552788944293;23703.288901332817;25641.35896602581;25816.87091564549;26104.34739131518;2281.885905704708;2288.736795268532;2340.1926335414255;2392.569337519246;2416.5287768885073;11528.827067593993;15320.693586128215;15380.846191538054;15387.923060384739;16079.276432560446;1806.9277228910828;1839.4481655503296;1840.5214644192472;1944.9788860848028;1956.6869300911785;91946.31876599;121726.99999999911;123120;123418;123577;9337.486438245825;9452;10828.891711082946;10864.34813911152;11056.135300467768;13458.125221860548;13483.056186067077;13490.730185396433;13499.392527336293;13509.527166549124;8219.794505137299;8445.451045682012;8483.066862645104;8633.69782057625;8660.177283158217;7195.604241766753;8556.529390883561;8574.22831945128;8582.227599516074;8738.86891696625;6308.801327747713;6443.5167362448;6471.644059576674;6479.157709497735;6582.308857569887;1672.1973452742752;1805.8374746272898;1824.2976454065015;1844.8339649431616;1866.887986720801;720.4236610711409;829.7261903571807;912.6030176872948;931.6599441204047;964.628617982082;16122.516324510283;16838.747418788727;20195.596088078157;21829.781702183092;21838.804776894827;947.5877993072897;963.7735132243918;1040.105509262034;1048.821700310949;1086.1093902999464;10782.73043173927;11596.304221746723;11618.244814087057;11640.592579259855;11657.650270491898;228705;229204.85397572906;229609;231455;555239.9144178076;66575.66712166408;74917.62541187259;94111;113285.30072048926;121239;979.6816034788579;1012.063840947121;1026.7279171019686;1028.7993841200894;1029.8300780371305;18592.721109183643;19417.70873436928;19478;19520.31678891232;19582.216711331956;54544.90910181695;56595;57807.4219257802;58238.379272931976;58730.88883778265;83676;83709.16290837257;83957.58021209855;84189;84847.1515284864;6851.828704282233;7803.219678032378;7920.168382320004;7922.168172342052;8137.226963438464;11169.553217871195;12363.93818030904;12755.234685918504;12930.676733081373;12968.740625187633;10625.096866766411;11062.889371106186;11076.280041797258;11098.167637427065;11148;9627.663031793854;10771.6229931952;11194.328340299608;11398.20212585111;11430.085593152695;8648.481091134552;11247.493862776031;11439.256448330832;11770.76458470843;11943.984761295387;6481.902771458337;8028.872348827448;8266;8404.663813447394;8416.537090460084;10519.368837869752;11235.943820280847;11287.379194144401;11525.711857203636;11674.94162529182 04:31:03 INFO - PROCESS | 1653 | |3;mootools.html;1524.8246451658058;1533.677927635197;1753.6755700195465;1864.4220291709562;1908.1127275816743;8441.831163376737;8957.149070838268;8957.731268061953;8962.686305979292;8986.730398088068;7821.726239581616;8135.023797144336;8476.91523084769;8749.606267717953;8752.474851508898;2343.7890589846866;2367.0886708617136;2375.406148462884;2377.4413012941945;2379.1791831817995;2324.1865347128537;2337.55586438577;2369.6208606622945;2370.5065808540594;2370.727366352866;2345.2377977157407;2350.435895385104;2360.2683168217823;2364.7280562735245;2368.7394386617457;2318.547883162784;2333.5449587330477;2341.356127065054;2348.847324923883;2355.62310030396;2332.9066837326486;2333.614953532665;2334.3463830127594;2336.661184128309;2348.9647655285185;7538.698452061911;8816.603252853636;8821.42660727057;8829.690960816404;8907.73276801697;4494.797734101973;5344.679319240858;5349.786008559653;5350.143976963706;5351.464853514656;3431.811250381228;3586.605473397924;3656.8537258509627;3669.5963444021136;3671.5777685565968;1044.7074819050601;1083.3391631105019;1085.0776839686202;1099;1103.9503222355013;11594.304341739438;11705.648830535098;11712.824307635392;11720.648380548511;11728.88271117295;4178.206140833293;4456.108778244364;4569.794359253841;4752.382190315237;4759.976200118977;10010.349327293849;10025.398476091457;10032.197424206046;10045.246606504541;10051.547680354402;5319;5324.148136298174;5327.280817089721;5330.013947419739;5334.199870019528;3826.4069069294305;4345.152695224401;4505.43682039745;4828.251620998751;4872.439669437989;3795.7912314822397;3827.349350610403;3918.9804050979565;3961.3067713150085;3961.58403367648;1269.7587458382877;1270.8729127087402;1274.3691872523161;1283.5956673647959;1290.8450985881755;744.9106107267054;845.4673555659895;902.6885724425063;902.8510295801251;935.7473482159779;5286.577073834161;5560.761638919398;5733.827985160452;5941.55438342126;6137.60105593135;837.24647816964;909.3180114913814;912.4662072687552;940.2008292951074;962.9951850240705;3679.3377192105145;3893.980530097388;3931.724779265424;3968.920621587552;4031.899202519902;10475;10815.188860835311;10840.13278937683;10853;10891.945540272249;10046.74883127913;10587;10608.416537090534;10689.30519516229;10813.459327033617;7993.880091798627;8212.548309843016;8225.218604232468;8238.052623948202;8239.670413183405;5834.970825145847;5883.264591926009;5887.7056147193325;6051.606645568024;6227;9804.068613481708;9900.25748068912;9924.900750992398;10009.299349045497;10083.798324033332;8301.46040507365;9745;10217.846732298873;10291.94854025725;10357.792844143225;6646.8005959821285;7040.190378106378;7168.53404528704;7543.811404714706;7695.23047695226;8349.791255218668;8450.112738162405;8534.78663033429;8535.658573657232;8537.74386768373;7449.478536502393;7556.131044929683;7558.508696934681;7620.885686714814;7702.576358300347;7325.194228634952;7383.778486645409;7430.4798664093;7454.4781865268915;7556.508826926232;6854.246032936273;7015.228324884157;7016.7544135954995;7020.087388639444;7024.227334993045;4905.288733133747;5002.974985125051;5074.3657042869645;5147.687339728345;5148.562372198405;4962.3052772611145;5011.09800235961;5139.6145289102715;5153.742312884266;5259.631825772312 04:31:03 INFO - PROCESS | 1653 | |4;prototype.html;300.1999670877113;300.7473722073459;308.4032397311202;313.70668425022603;315.846814295067;4427.424434823476;4659.951510910044;4973.602111831054;5360.65155764875;5375.569954403648;4033.838646454138;4644.465886423062;4651.837040739815;5037.999999999991;5315.3887302960175;3757.910206040245;3869.7036492774955;4063.9796801016028;4075.7394084732246;4108.4864391951005;3511.2801875615564;3751.399776035821;4070.61329173728;4080.6735461163184;4144.937825932613;3885.805709714503;4017.268257584841;4038.3336749436307;4068.430419741224;4092.2224777292367;3077.753779697619;3573.6069032406413;3624.4563315502887;4099.9545115995525;4114.650254728337;2962.851857407121;3541.468779683068;3552.5914519830294;3564.308946745191;3575.6781889630056;3919.8432062717457;3929.6070392960473;5167.948320516822;5185.351831021122;5305;4079.6741059155597;4212.726172798758;4322.610965013164;4435.778211089465;4611.262198048296;4633.582977532038;5264.921026184572;5538.556915446755;5565.686592890543;5604.907043126592;3805.771653700787;3808.923821523582;3826.2921359548573;3838.2323535292776;3855.1325951660933;5565.526930210937;5737.454941780524;5757.510611597977;5846.532277417797;5892.734826932799;3597.244578638463;3872.96127038726;3884.3590807516443;3890.5720370759195;3910.9413358799643;3913.686905047646;3932.488776459042;3947.684185265172;3979.2041591681777;3984.4222587724616;3827.5598306195143;4254.787385595152;4394.4408986784965;4638.443386793607;4661.440627124699;5522.530584900329;5552.750126244328;5604.747786349624;5611.8877622448135;5656.727236371784;1326.104879206551;1339.216558313378;1339.7253563019754;1345.822405395279;1352.1684164238923;827.8054657155564;847.8261956298944;850.6814437621662;860.3418384935529;887.3389324952951;4905.6811307265625;5234.9476505235225;5295.947040529545;5323.6273460857365;5330.333708286465;4430.62339701129;5401.730593310569;5688;5720.628159169641;5739.684317362585;3926.9410958835642;4565.406497155349;5151.6136289778415;5204.8959020819375;5246.930545630848;2844.563495434792;2975.9702402976127;2976.6009975311567;2987.9243472349935;2989.6113505244616;5397.514223719805;5465.043617366945;5555;5788.160716696056;5832.88334233313;4596.701214421052;5094.917574867226;5430.212619170198;5682.7442765075675;5707.63133299676;4874.512548745097;5148.382194136728;5366.604682782427;5770.971145144247;5813.418658134153;5246.029484545219;5630.7466164023535;5631.1271752878365;5655.4910058094965;5742.885142297214;4969.577585905094;5650.29371328584;5681.460261275172;5690.743916523849;5693.9430605693415;5934.169216309634;5997.0404735242155;5998.430149135826;6050.788222412194;6070.54470914692;3008.9849550752106;3698.2603479304785;3847.8460861566346;3902.6487616114687;3927.155661532784;4752.691075080109;4818.590419814355;5107.591392688651;5478.767277362562;5701.258836351248;5268.736563171903;5396.325459317585;5644.915326270027;5734.455226753452;5772.451617096369;4963.280324353021;5310.867228319323;5447.782088716407;5624.8875022498505;5655.547556195576;5171.431142574389;5515.062439385394;5525.336959564878;5542.307211598551;5565.805196818092;4095.32427149517;5484.2596249505805;5489.560835133259;5533.6403133796175;5595.664260144405;4077.653399461079;4566.45202575693;4728.534154412143;5273.551748101454;5473.97662717894 04:31:03 INFO - PROCESS | 1653 | |5;yui.html;708.6138054760156;724.3336130759703;766.6166916541729;799.2407213147511;804.8591496488114;15741.21293935303;15939.203039847991;15953.601159970993;15974.680506389865;16021.038737675734;13193.010524210698;14275.21486318252;14391.06458080224;14417.783733243983;14842.554723358317;672.660306545194;695.1796879681973;697.3061803505519;779.2052106851008;834.3867257565714;851.936104792143;853.8121613245107;855.683397143058;857.5712143928037;858.3347905373323;894.7315805258429;911.7036962987023;911.8541033434652;913.5477938420491;913.6482454255093;905.9456432614064;907.1246247371319;907.2288554728494;907.3829795738895;910.6949172027379;522.9764660590282;535.6759160707754;538.1685296217314;674.2414783368711;676.3439463720183;12370.319632420213;12413.565525206574;14261.429542818274;14833.851661483355;14865.2567371631;13905.374258158407;14388.424463021467;15041.323140458597;15095.924520377437;15156.772648410282;11999.820002699966;12000.099992500598;12005.699857503545;12048.156629035971;12314.26114433128;3844.7693138411505;3891.396833490785;3911.1395492991783;3986.142979259444;4034.495688038995;114679;115979;118249;118411.38269099078;118638;11513.942430287794;11537;11598.362090084958;11681.88318116825;12113.878861211451;24030.63954040656;24151.63772543413;24300.513989720108;24325.270241892773;24602;8050.355971522265;8105.432619716681;8148.592570371457;8234.176582341717;8343.499390036617;4282.914341713148;4336.1299241875895;4456.955430445719;4611.1930412177735;4613.169629466728;3370.882019129319;3545.751797374159;3627.401478755976;3635.2729454109285;3649.0512466758305;561.9775208991676;612.770211170811;667.2293501006345;672.586359388982;691.8028361916829;664.8603793203481;665.381195488191;674.578388507183;681.6898311268361;682.9107573420387;8147.633356498972;8956.369217276928;9617.807643847083;9681.37071090377;9711.417314961125;638.7894939090397;673.195531340044;673.599208470959;677.0791723256367;680.3332733920786;4860.708357498491;5037.4458809530925;5081.822136225214;5196.402413722395;5346.91979620306;94439.52780236055;121754.3912280433;143662;148168.77746833806;162987;96669;115129.4243528777;117154;119154;119423;88938;92556;93336.53331733299;94359.52820235993;116416.41791791156;9360.44428520235;9610.588241174282;9621.374610650286;10131.696049118538;10136.594536218763;44051.77974110109;50360;57288.56778580402;58363.124553132584;58754.824903502544;76294.61852690701;80506;89381.55309223413;90106.54946725225;93068;10791.514381852678;12826.61520154397;13305.66735831612;13319.733605327645;13610.455581776618;12743.681407964876;12949;14705.044456994934;14996.400143994118;15222;13532.729345413234;13864.306784660928;13897.16617002983;14090.580162195884;14127.788083178966;13566.38951247216;13709.794353084511;13899.374528146056;14195.574132776033;14317.355718992461;13293.534726284533;13900.51348202808;13905.096168749;13957.720845583235;13961.301934903011;11182.88817111851;11413.828792567952;11747.412629368668;11826.34955077479;11854.94072529632;8802;11333.433328333715;11453.25553839031;11512.424378781196;11546 04:31:03 INFO - PROCESS | 1653 | __end_tp_report 04:31:03 INFO - PROCESS | 1653 | __start_cc_report 04:31:03 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,1882 04:31:03 INFO - PROCESS | 1653 | __end_cc_report 04:31:03 INFO - PROCESS | 1653 | __startTimestamp1476185463268__endTimestamp 04:31:03 INFO - PROCESS | 1653 | 04:31:03 INFO - PROCESS | 1653 | ------- Summary: start ------- 04:31:03 INFO - PROCESS | 1653 | Number of tests: 6 04:31:03 INFO - PROCESS | 1653 | 04:31:03 INFO - PROCESS | 1653 | [#0] dojo.html Cycles:165 Average:5696.88 Median:5907.50 stddev:625.71 (10.6%) stddev-sans-first:599.01 04:31:03 INFO - PROCESS | 1653 | Values: 3312.1 3328.8 3331.5 3370.2 3380.6 5537.6 5657.5 5700.9 5736.3 5736.7 5640.6 5668.6 5787.4 5798.9 5830.3 5073.0 5162.2 5181.2 5237.6 5238.1 5189.4 5189.7 5193.7 5247.7 5271.7 5153.3 5160.8 5182.0 5196.3 5217.8 5125.8 5150.0 5192.5 5196.7 5251.8 5131.6 5152.1 5161.5 5182.3 5256.9 5755.1 5758.7 5770.5 5795.5 5798.5 6103.1 6110.6 6126.5 6169.2 6224.1 6098.8 6167.3 6210.9 6211.6 6220.9 5760.2 5769.0 5770.6 5779.0 5810.6 6109.2 6116.9 6148.2 6180.6 6191.9 6125.2 6126.3 6130.8 6132.1 6139.0 6180.7 6180.9 6230.8 6238.7 6245.2 6034.9 6042.3 6142.4 6185.8 6203.1 4805.6 5346.9 6120.7 6135.8 6229.3 4903.9 4905.2 4917.4 4923.1 4957.8 4370.5 4376.1 4420.4 4425.4 4480.6 5623.3 5655.2 5700.3 5719.7 5797.6 6171.4 6180.5 6183.7 6185.3 6193.8 5708.1 5734.4 5747.2 5750.0 5766.8 5273.1 5302.3 5364.9 5388.1 5405.3 6223.8 6227.3 6237.3 6243.1 6253.5 6120.8 6170.4 6176.6 6178.8 6184.7 5601.4 6140.6 6289.9 6295.3 6301.9 6146.1 6182.1 6185.1 6198.5 6203.4 5499.6 6103.4 6144.8 6205.8 6277.3 5557.4 6020.7 6078.9 6110.9 6142.6 6098.7 6100.8 6111.7 6112.8 6125.1 5789.6 5907.5 5927.4 5930.0 5935.6 6008.4 6053.6 6081.3 6096.7 6097.6 5792.7 5969.7 5972.6 5977.9 6014.2 04:31:03 INFO - PROCESS | 1653 | 04:31:03 INFO - PROCESS | 1653 | [#1] ext.html Cycles:180 Average:66472.91 Median:40923.59 stddev:140211.29 (342.6%) stddev-sans-first:140517.00 04:31:03 INFO - PROCESS | 1653 | Values: 454.9 499.7 513.1 520.4 535.5 30875.7 40468.2 41789.8 41824.7 41977.4 43009.4 43291.4 43298.0 43371.6 43446.3 9746.4 9927.7 9942.2 9948.8 9949.6 9790.8 9892.6 9893.6 9911.3 9916.8 9858.0 9924.9 9933.6 9939.5 9954.4 9826.2 9891.4 9891.5 9900.4 9901.1 9621.2 9703.1 9705.3 9706.7 9713.9 40758.2 41089.0 41092.6 41111.6 41141.8 3273.5 3432.8 3496.1 3709.4 3725.5 30564.7 31874.5 31933.8 31967.8 31975.9 2857.0 3188.9 3192.2 3286.3 3303.8 454845.0 478866.3 479083.0 481206.0 489547.0 58964.0 59864.4 59895.0 60038.1 60053.4 63772.0 63859.4 63885.7 63969.7 63987.4 15969.7 16219.7 16229.5 16234.4 16250.9 12577.9 12645.4 12657.2 12659.0 12667.7 16728.0 17304.7 17313.4 17323.8 17351.4 6036.8 6435.5 6662.3 6732.5 6739.6 876.0 1010.4 1029.8 1073.3 1109.4 25304.7 29903.1 29950.6 30392.7 30845.4 829.9 1047.4 1054.9 1083.9 1136.8 14574.9 15127.2 15155.0 15317.8 15332.6 594079.0 597868.0 598261.0 598727.0 599193.0 57733.1 57894.0 57937.4 58061.1 58154.4 1224.2 1263.6 1339.5 1371.2 1380.8 25022.5 30227.0 31112.8 31260.7 32031.7 50103.7 50248.2 50495.2 50585.5 50598.5 493738.0 496477.0 496619.0 496873.0 496967.0 24857.0 24918.5 26087.0 27713.3 27722.3 46950.5 47740.5 48019.5 48454.0 48568.0 32668.5 35934.5 38878.8 39738.8 40088.6 38565.4 39025.0 39124.6 39965.2 40578.4 37456.6 37866.8 38323.6 38454.4 38722.8 33320.0 34341.8 35161.7 35244.0 35532.8 19988.9 25179.0 25287.6 25875.9 25948.1 04:31:03 INFO - PROCESS | 1653 | 04:31:03 INFO - PROCESS | 1653 | [#2] jquery.html Cycles:180 Average:25883.43 Median:116897.60 stddev:57178.61 (48.9%) stddev-sans-first:57306.80 04:31:03 INFO - PROCESS | 1653 | Values: 319.3 342.3 367.3 370.8 401.2 14994.3 15129.5 15949.4 20011.4 20653.7 19528.0 20033.4 20454.7 20545.4 20882.9 5800.5 6052.2 6062.3 6064.6 6079.4 4837.3 6055.7 6101.7 6124.9 6135.7 5755.0 6077.9 6080.2 6087.6 6088.2 5971.5 6007.8 6017.3 6053.6 6089.1 4971.2 5154.7 5234.9 5254.2 5267.1 22360.6 23703.3 25641.4 25816.9 26104.3 2281.9 2288.7 2340.2 2392.6 2416.5 11528.8 15320.7 15380.8 15387.9 16079.3 1806.9 1839.4 1840.5 1945.0 1956.7 91946.3 121727.0 123120.0 123418.0 123577.0 9337.5 9452.0 10828.9 10864.3 11056.1 13458.1 13483.1 13490.7 13499.4 13509.5 8219.8 8445.5 8483.1 8633.7 8660.2 7195.6 8556.5 8574.2 8582.2 8738.9 6308.8 6443.5 6471.6 6479.2 6582.3 1672.2 1805.8 1824.3 1844.8 1866.9 720.4 829.7 912.6 931.7 964.6 16122.5 16838.7 20195.6 21829.8 21838.8 947.6 963.8 1040.1 1048.8 1086.1 10782.7 11596.3 11618.2 11640.6 11657.7 228705.0 229204.9 229609.0 231455.0 555239.9 66575.7 74917.6 94111.0 113285.3 121239.0 979.7 1012.1 1026.7 1028.8 1029.8 18592.7 19417.7 19478.0 19520.3 19582.2 54544.9 56595.0 57807.4 58238.4 58730.9 83676.0 83709.2 83957.6 84189.0 84847.2 6851.8 7803.2 7920.2 7922.2 8137.2 11169.6 12363.9 12755.2 12930.7 12968.7 10625.1 11062.9 11076.3 11098.2 11148.0 9627.7 10771.6 11194.3 11398.2 11430.1 8648.5 11247.5 11439.3 11770.8 11944.0 6481.9 8028.9 8266.0 8404.7 8416.5 10519.4 11235.9 11287.4 11525.7 11674.9 04:31:03 INFO - PROCESS | 1653 | 04:31:03 INFO - PROCESS | 1653 | [#3] mootools.html Cycles:180 Average:5691.39 Median:4888.86 stddev:3190.53 (65.3%) stddev-sans-first:3184.11 04:31:03 INFO - PROCESS | 1653 | Values: 1524.8 1533.7 1753.7 1864.4 1908.1 8441.8 8957.1 8957.7 8962.7 8986.7 7821.7 8135.0 8476.9 8749.6 8752.5 2343.8 2367.1 2375.4 2377.4 2379.2 2324.2 2337.6 2369.6 2370.5 2370.7 2345.2 2350.4 2360.3 2364.7 2368.7 2318.5 2333.5 2341.4 2348.8 2355.6 2332.9 2333.6 2334.3 2336.7 2349.0 7538.7 8816.6 8821.4 8829.7 8907.7 4494.8 5344.7 5349.8 5350.1 5351.5 3431.8 3586.6 3656.9 3669.6 3671.6 1044.7 1083.3 1085.1 1099.0 1104.0 11594.3 11705.6 11712.8 11720.6 11728.9 4178.2 4456.1 4569.8 4752.4 4760.0 10010.3 10025.4 10032.2 10045.2 10051.5 5319.0 5324.1 5327.3 5330.0 5334.2 3826.4 4345.2 4505.4 4828.3 4872.4 3795.8 3827.3 3919.0 3961.3 3961.6 1269.8 1270.9 1274.4 1283.6 1290.8 744.9 845.5 902.7 902.9 935.7 5286.6 5560.8 5733.8 5941.6 6137.6 837.2 909.3 912.5 940.2 963.0 3679.3 3894.0 3931.7 3968.9 4031.9 10475.0 10815.2 10840.1 10853.0 10891.9 10046.7 10587.0 10608.4 10689.3 10813.5 7993.9 8212.5 8225.2 8238.1 8239.7 5835.0 5883.3 5887.7 6051.6 6227.0 9804.1 9900.3 9924.9 10009.3 10083.8 8301.5 9745.0 10217.8 10291.9 10357.8 6646.8 7040.2 7168.5 7543.8 7695.2 8349.8 8450.1 8534.8 8535.7 8537.7 7449.5 7556.1 7558.5 7620.9 7702.6 7325.2 7383.8 7430.5 7454.5 7556.5 6854.2 7015.2 7016.8 7020.1 7024.2 4905.3 5003.0 5074.4 5147.7 5148.6 4962.3 5011.1 5139.6 5153.7 5259.6 04:31:03 INFO - PROCESS | 1653 | 04:31:03 INFO - PROCESS | 1653 | [#4] prototype.html Cycles:180 Average:4446.57 Median:4966.43 stddev:1370.85 (27.6%) stddev-sans-first:1338.91 04:31:03 INFO - PROCESS | 1653 | Values: 300.2 300.7 308.4 313.7 315.8 4427.4 4660.0 4973.6 5360.7 5375.6 4033.8 4644.5 4651.8 5038.0 5315.4 3757.9 3869.7 4064.0 4075.7 4108.5 3511.3 3751.4 4070.6 4080.7 4144.9 3885.8 4017.3 4038.3 4068.4 4092.2 3077.8 3573.6 3624.5 4100.0 4114.7 2962.9 3541.5 3552.6 3564.3 3575.7 3919.8 3929.6 5167.9 5185.4 5305.0 4079.7 4212.7 4322.6 4435.8 4611.3 4633.6 5264.9 5538.6 5565.7 5604.9 3805.8 3808.9 3826.3 3838.2 3855.1 5565.5 5737.5 5757.5 5846.5 5892.7 3597.2 3873.0 3884.4 3890.6 3910.9 3913.7 3932.5 3947.7 3979.2 3984.4 3827.6 4254.8 4394.4 4638.4 4661.4 5522.5 5552.8 5604.7 5611.9 5656.7 1326.1 1339.2 1339.7 1345.8 1352.2 827.8 847.8 850.7 860.3 887.3 4905.7 5234.9 5295.9 5323.6 5330.3 4430.6 5401.7 5688.0 5720.6 5739.7 3926.9 4565.4 5151.6 5204.9 5246.9 2844.6 2976.0 2976.6 2987.9 2989.6 5397.5 5465.0 5555.0 5788.2 5832.9 4596.7 5094.9 5430.2 5682.7 5707.6 4874.5 5148.4 5366.6 5771.0 5813.4 5246.0 5630.7 5631.1 5655.5 5742.9 4969.6 5650.3 5681.5 5690.7 5693.9 5934.2 5997.0 5998.4 6050.8 6070.5 3009.0 3698.3 3847.8 3902.6 3927.2 4752.7 4818.6 5107.6 5478.8 5701.3 5268.7 5396.3 5644.9 5734.5 5772.5 4963.3 5310.9 5447.8 5624.9 5655.5 5171.4 5515.1 5525.3 5542.3 5565.8 4095.3 5484.3 5489.6 5533.6 5595.7 4077.7 4566.5 4728.5 5273.6 5474.0 04:31:03 INFO - PROCESS | 1653 | 04:31:03 INFO - PROCESS | 1653 | [#5] yui.html Cycles:180 Average:23621.29 Median:3868.08 stddev:36736.41 (949.7%) stddev-sans-first:36799.18 04:31:03 INFO - PROCESS | 1653 | Values: 708.6 724.3 766.6 799.2 804.9 15741.2 15939.2 15953.6 15974.7 16021.0 13193.0 14275.2 14391.1 14417.8 14842.6 672.7 695.2 697.3 779.2 834.4 851.9 853.8 855.7 857.6 858.3 894.7 911.7 911.9 913.5 913.6 905.9 907.1 907.2 907.4 910.7 523.0 535.7 538.2 674.2 676.3 12370.3 12413.6 14261.4 14833.9 14865.3 13905.4 14388.4 15041.3 15095.9 15156.8 11999.8 12000.1 12005.7 12048.2 12314.3 3844.8 3891.4 3911.1 3986.1 4034.5 114679.0 115979.0 118249.0 118411.4 118638.0 11513.9 11537.0 11598.4 11681.9 12113.9 24030.6 24151.6 24300.5 24325.3 24602.0 8050.4 8105.4 8148.6 8234.2 8343.5 4282.9 4336.1 4457.0 4611.2 4613.2 3370.9 3545.8 3627.4 3635.3 3649.1 562.0 612.8 667.2 672.6 691.8 664.9 665.4 674.6 681.7 682.9 8147.6 8956.4 9617.8 9681.4 9711.4 638.8 673.2 673.6 677.1 680.3 4860.7 5037.4 5081.8 5196.4 5346.9 94439.5 121754.4 143662.0 148168.8 162987.0 96669.0 115129.4 117154.0 119154.0 119423.0 88938.0 92556.0 93336.5 94359.5 116416.4 9360.4 9610.6 9621.4 10131.7 10136.6 44051.8 50360.0 57288.6 58363.1 58754.8 76294.6 80506.0 89381.6 90106.5 93068.0 10791.5 12826.6 13305.7 13319.7 13610.5 12743.7 12949.0 14705.0 14996.4 15222.0 13532.7 13864.3 13897.2 14090.6 14127.8 13566.4 13709.8 13899.4 14195.6 14317.4 13293.5 13900.5 13905.1 13957.7 13961.3 11182.9 11413.8 11747.4 11826.3 11854.9 8802.0 11333.4 11453.3 11512.4 11546.0 04:31:03 INFO - PROCESS | 1653 | -------- Summary: end -------- 04:31:03 INFO - PROCESS | 1653 | 04:31:03 INFO - TEST-INFO | 1653: exit 0 04:31:03 INFO - TEST-OK | dromaeo_css | took 1119071ms 04:31:03 INFO - TEST-START | kraken 04:31:03 INFO - operating with platform_type : mac_ 04:31:03 INFO - Initialising browser for kraken test... 04:31:03 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/tmpPaAfv9/profile http://localhost:49266/getInfo.html) 04:31:06 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 04:31:06 INFO - PROCESS | 1662 | colorDepth:24 04:31:06 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 04:31:06 INFO - PROCESS | 1662 | __metrics 04:31:06 INFO - PROCESS | 1662 | 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" 04:31:06 INFO - PROCESS | 1662 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:31:06 INFO - TEST-INFO | 1662: exit 0 04:31:06 INFO - Browser initialized. 04:31:06 INFO - Running cycle 1/1 for kraken test... 04:31:06 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/tmpPaAfv9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:31:18 INFO - PROCESS | 1663 | RSS: Main: 234598400 04:31:18 INFO - PROCESS | 1663 | 04:31:21 INFO - PROCESS | 1663 | 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) 04:31:22 INFO - PROCESS | 1663 | RSS: Main: 293031936 04:31:22 INFO - PROCESS | 1663 | 04:31:25 INFO - PROCESS | 1663 | 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) 04:31:25 INFO - PROCESS | 1663 | RSS: Main: 293064704 04:31:25 INFO - PROCESS | 1663 | 04:31:29 INFO - PROCESS | 1663 | 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) 04:31:29 INFO - PROCESS | 1663 | RSS: Main: 382988288 04:31:29 INFO - PROCESS | 1663 | 04:31:31 INFO - PROCESS | 1663 | 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) 04:31:32 INFO - PROCESS | 1663 | RSS: Main: 286281728 04:31:32 INFO - PROCESS | 1663 | 04:31:34 INFO - PROCESS | 1663 | 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) 04:31:35 INFO - PROCESS | 1663 | RSS: Main: 288952320 04:31:35 INFO - PROCESS | 1663 | 04:31:37 INFO - PROCESS | 1663 | 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) 04:31:38 INFO - PROCESS | 1663 | RSS: Main: 326213632 04:31:38 INFO - PROCESS | 1663 | 04:31:41 INFO - PROCESS | 1663 | 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) 04:31:42 INFO - PROCESS | 1663 | RSS: Main: 326508544 04:31:42 INFO - PROCESS | 1663 | 04:31:44 INFO - PROCESS | 1663 | 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) 04:31:45 INFO - PROCESS | 1663 | RSS: Main: 327409664 04:31:45 INFO - PROCESS | 1663 | 04:31:46 INFO - PROCESS | 1663 | 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) 04:31:47 INFO - PROCESS | 1663 | RSS: Main: 301379584 04:31:47 INFO - PROCESS | 1663 | 04:31:49 INFO - PROCESS | 1663 | 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) 04:31:50 INFO - PROCESS | 1663 | RSS: Main: 303460352 04:31:50 INFO - PROCESS | 1663 | 04:31:52 INFO - PROCESS | 1663 | 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) 04:31:52 INFO - PROCESS | 1663 | RSS: Main: 308297728 04:31:52 INFO - PROCESS | 1663 | 04:31:55 INFO - PROCESS | 1663 | 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) 04:31:56 INFO - PROCESS | 1663 | RSS: Main: 321667072 04:31:56 INFO - PROCESS | 1663 | 04:31:59 INFO - PROCESS | 1663 | 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) 04:32:00 INFO - PROCESS | 1663 | RSS: Main: 321040384 04:32:00 INFO - PROCESS | 1663 | 04:32:02 INFO - PROCESS | 1663 | 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) 04:32:02 INFO - PROCESS | 1663 | RSS: Main: 365191168 04:32:02 INFO - PROCESS | 1663 | 04:32:02 INFO - PROCESS | 1663 | __start_tp_report 04:32:02 INFO - PROCESS | 1663 | _x_x_mozilla_page_load 04:32:02 INFO - PROCESS | 1663 | _x_x_mozilla_page_load_details 04:32:02 INFO - PROCESS | 1663 | |i|pagename|runs| 04:32:02 INFO - PROCESS | 1663 | |0;ai-astar;112;114;113;113;112;111;112;113;112;113 04:32:02 INFO - PROCESS | 1663 | |1;audio-beat-detection;175;139;114;152;113;112;114;113;145;118 04:32:02 INFO - PROCESS | 1663 | |2;audio-dft;187;181;183;187;181;183;179;182;183;182 04:32:02 INFO - PROCESS | 1663 | |3;audio-fft;82;59;59;87;62;57;58;58;84;81 04:32:02 INFO - PROCESS | 1663 | |4;audio-oscillator;91;70;95;70;67;68;109;97;100;104 04:32:02 INFO - PROCESS | 1663 | |5;imaging-gaussian-blur;89;85;87;85;88;85;88;86;90;85 04:32:02 INFO - PROCESS | 1663 | |6;imaging-darkroom;95;96;96;97;96;96;95;97;95;96 04:32:02 INFO - PROCESS | 1663 | |7;imaging-desaturate;86;85;87;84;85;83;85;84;85;83 04:32:02 INFO - PROCESS | 1663 | |8;json-parse-financial;54;54;52;53;55;54;55;53;54;55 04:32:02 INFO - PROCESS | 1663 | |9;json-stringify-tinderbox;64;72;67;71;72;75;70;69;69;71 04:32:02 INFO - PROCESS | 1663 | |10;stanford-crypto-aes;83;90;87;86;87;93;89;87;86;86 04:32:02 INFO - PROCESS | 1663 | |11;stanford-crypto-ccm;147;159;153;163;158;145;146;146;186;159 04:32:02 INFO - PROCESS | 1663 | |12;stanford-crypto-pbkdf2;172;173;172;179;239;186;170;174;173;176 04:32:02 INFO - PROCESS | 1663 | |13;stanford-crypto-sha256-iterative;69;69;70;69;68;69;70;68;72;73 04:32:02 INFO - PROCESS | 1663 | __end_tp_report 04:32:02 INFO - PROCESS | 1663 | __start_cc_report 04:32:02 INFO - PROCESS | 1663 | _x_x_mozilla_cycle_collect,1969 04:32:02 INFO - PROCESS | 1663 | __end_cc_report 04:32:02 INFO - PROCESS | 1663 | __startTimestamp1476185522395__endTimestamp 04:32:02 INFO - PROCESS | 1663 | 04:32:02 INFO - PROCESS | 1663 | ------- Summary: start ------- 04:32:02 INFO - PROCESS | 1663 | Number of tests: 14 04:32:02 INFO - PROCESS | 1663 | 04:32:02 INFO - PROCESS | 1663 | [#0] ai-astar Cycles:10 Average:112.50 Median:113.00 stddev:0.85 (0.8%) stddev-sans-first:0.88 04:32:02 INFO - PROCESS | 1663 | Values: 112.0 114.0 113.0 113.0 112.0 111.0 112.0 113.0 112.0 113.0 04:32:02 INFO - PROCESS | 1663 | 04:32:02 INFO - PROCESS | 1663 | [#1] audio-beat-detection Cycles:10 Average:129.50 Median:128.50 stddev:22.04 (17.1%) stddev-sans-first:16.09 04:32:02 INFO - PROCESS | 1663 | Values: 175.0 139.0 114.0 152.0 113.0 112.0 114.0 113.0 145.0 118.0 04:32:02 INFO - PROCESS | 1663 | 04:32:02 INFO - PROCESS | 1663 | [#2] audio-dft Cycles:10 Average:182.80 Median:183.00 stddev:2.53 (1.4%) stddev-sans-first:2.18 04:32:02 INFO - PROCESS | 1663 | Values: 187.0 181.0 183.0 187.0 181.0 183.0 179.0 182.0 183.0 182.0 04:32:02 INFO - PROCESS | 1663 | 04:32:02 INFO - PROCESS | 1663 | [#3] audio-fft Cycles:10 Average:68.70 Median:71.50 stddev:12.89 (18.0%) stddev-sans-first:12.75 04:32:02 INFO - PROCESS | 1663 | Values: 82.0 59.0 59.0 87.0 62.0 57.0 58.0 58.0 84.0 81.0 04:32:02 INFO - PROCESS | 1663 | 04:32:02 INFO - PROCESS | 1663 | [#4] audio-oscillator Cycles:10 Average:87.10 Median:70.00 stddev:16.54 (23.6%) stddev-sans-first:17.48 04:32:02 INFO - PROCESS | 1663 | Values: 91.0 70.0 95.0 70.0 67.0 68.0 109.0 97.0 100.0 104.0 04:32:02 INFO - PROCESS | 1663 | 04:32:02 INFO - PROCESS | 1663 | [#5] imaging-gaussian-blur Cycles:10 Average:86.80 Median:87.50 stddev:1.87 (2.1%) stddev-sans-first:1.81 04:32:02 INFO - PROCESS | 1663 | Values: 89.0 85.0 87.0 85.0 88.0 85.0 88.0 86.0 90.0 85.0 04:32:02 INFO - PROCESS | 1663 | 04:32:02 INFO - PROCESS | 1663 | [#6] imaging-darkroom Cycles:10 Average:95.90 Median:96.00 stddev:0.74 (0.8%) stddev-sans-first:0.71 04:32:02 INFO - PROCESS | 1663 | Values: 95.0 96.0 96.0 97.0 96.0 96.0 95.0 97.0 95.0 96.0 04:32:02 INFO - PROCESS | 1663 | 04:32:02 INFO - PROCESS | 1663 | [#7] imaging-desaturate Cycles:10 Average:84.70 Median:85.00 stddev:1.25 (1.5%) stddev-sans-first:1.24 04:32:02 INFO - PROCESS | 1663 | Values: 86.0 85.0 87.0 84.0 85.0 83.0 85.0 84.0 85.0 83.0 04:32:02 INFO - PROCESS | 1663 | 04:32:02 INFO - PROCESS | 1663 | [#8] json-parse-financial Cycles:10 Average:53.90 Median:54.00 stddev:0.99 (1.8%) stddev-sans-first:1.05 04:32:02 INFO - PROCESS | 1663 | Values: 54.0 54.0 52.0 53.0 55.0 54.0 55.0 53.0 54.0 55.0 04:32:02 INFO - PROCESS | 1663 | 04:32:02 INFO - PROCESS | 1663 | [#9] json-stringify-tinderbox Cycles:10 Average:70.00 Median:71.00 stddev:3.02 (4.3%) stddev-sans-first:2.29 04:32:02 INFO - PROCESS | 1663 | Values: 64.0 72.0 67.0 71.0 72.0 75.0 70.0 69.0 69.0 71.0 04:32:02 INFO - PROCESS | 1663 | 04:32:02 INFO - PROCESS | 1663 | [#10] stanford-crypto-aes Cycles:10 Average:87.40 Median:87.00 stddev:2.72 (3.1%) stddev-sans-first:2.37 04:32:02 INFO - PROCESS | 1663 | Values: 83.0 90.0 87.0 86.0 87.0 93.0 89.0 87.0 86.0 86.0 04:32:02 INFO - PROCESS | 1663 | 04:32:02 INFO - PROCESS | 1663 | [#11] stanford-crypto-ccm Cycles:10 Average:156.20 Median:158.50 stddev:12.39 (7.8%) stddev-sans-first:12.69 04:32:02 INFO - PROCESS | 1663 | Values: 147.0 159.0 153.0 163.0 158.0 145.0 146.0 146.0 186.0 159.0 04:32:02 INFO - PROCESS | 1663 | 04:32:02 INFO - PROCESS | 1663 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.40 Median:175.00 stddev:20.75 (11.9%) stddev-sans-first:21.73 04:32:02 INFO - PROCESS | 1663 | Values: 172.0 173.0 172.0 179.0 239.0 186.0 170.0 174.0 173.0 176.0 04:32:02 INFO - PROCESS | 1663 | 04:32:02 INFO - PROCESS | 1663 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.70 Median:69.50 stddev:1.64 (2.4%) stddev-sans-first:1.72 04:32:02 INFO - PROCESS | 1663 | Values: 69.0 69.0 70.0 69.0 68.0 69.0 70.0 68.0 72.0 73.0 04:32:02 INFO - PROCESS | 1663 | -------- Summary: end -------- 04:32:02 INFO - PROCESS | 1663 | 04:32:03 INFO - TEST-INFO | 1663: exit 0 04:32:03 INFO - TEST-OK | kraken | took 59450ms 04:32:03 INFO - SUITE-END | took 1178s 04:32:03 INFO - Completed test suite (00:19:38) 04:32:03 INFO - javascript benchmark 04:32:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3312.0891754767435, 3328.7503437242212, 3331.516930045142, 3370.1743072947133, 3380.5605271314726, 5537.556995440365, 5657.519110875575, 5700.945325114846, 5736.340320863102, 5736.684482353474, 5640.633358831673, 5668.603197776147, 5787.363390027093, 5798.942010579893, 5830.329512106109, 5072.9746351268195, 5162.225666150089, 5181.1710126379785, 5237.6071794615555, 5238.05714971305, 5189.403218629868, 5189.662671926331, 5193.740312984354, 5247.658902171365, 5271.65734227274, 5153.304303918961, 5160.845174644766, 5181.9740901295445, 5196.298499702549, 5217.791288348461, 5125.794968201268, 5149.974250128763, 5192.506711862367, 5196.740162991835, 5251.842444726664, 5131.615128865313, 5152.098382782998, 5161.483851614846, 5182.326297581328, 5256.894862102737, 5755.079187330049, 5758.74085666146, 5770.480656740871, 5795.536357091465, 5798.536117110621, 6103.0858187454, 6110.63336199825, 6126.456603435028, 6169.198004259418, 6224.06639004153, 6098.786542471036, 6167.259928808572, 6210.937890621081, 6211.596246243979, 6220.906686399708, 5760.2223699800825, 5768.971155144198, 5770.596058275881, 5779.0, 5810.564207684441, 6109.246413565088, 6116.911372994184, 6148.1699970504305, 6180.567360284737, 6191.876162476815, 6125.206275768898, 6126.287952498583, 6130.846728831815, 6132.080177995925, 6139.004823432402, 6180.6909654517995, 6180.907286390708, 6230.844228894223, 6238.719257633417, 6245.156903818017, 6034.939650603438, 6042.305134909454, 6142.416470435391, 6185.783497577653, 6203.069539569011, 4805.591524720368, 5346.91979620306, 6120.720577099361, 6135.815925522242, 6229.283632382243, 4903.877403064952, 4905.166121759309, 4917.434495033117, 4923.1075004348, 4957.801687932514, 4370.541093185233, 4376.14665140301, 4420.425344705232, 4425.380446737459, 4480.641548676098, 5623.325200975909, 5655.179998900218, 5700.344460387026, 5719.656820590778, 5797.594168408255, 6171.413715697091, 6180.47465965398, 6183.721732522047, 6185.257769067741, 6193.84515387119, 5708.086706127, 5734.39788822176, 5747.195392645033, 5750.0, 5766.769329226868, 5273.1299335609965, 5302.337207849019, 5364.91952620711, 5388.057090009311, 5405.297311349499, 6223.782167624111, 6227.283862355796, 6237.251529816451, 6243.125962365181, 6253.530985176039, 6120.812855101315, 6170.352113028247, 6176.567640265318, 6178.752849885954, 6184.721687524162, 5601.383847776659, 6140.631562106288, 6289.9056514153235, 6295.276043255084, 6301.936980630318, 6146.108814222002, 6182.103594978793, 6185.103160041679, 6198.504119670506, 6203.410675985774, 5499.615026948075, 6103.389661033861, 6144.771045790949, 6205.758848230463, 6277.309495955533, 5557.416471270459, 6020.698965051817, 6078.939210607838, 6110.862540392742, 6142.600730952652, 6098.695065246809, 6100.786472473442, 6111.694415279308, 6112.755489780537, 6125.142480052886, 5789.594728368974, 5907.497862681548, 5927.407259274038, 5930.0, 5935.554833387427, 6008.399160083956, 6053.606515576474, 6081.300650425081, 6096.664683442275, 6097.5731698780655, 5792.652440853562, 5969.7313620888035, 5972.5520585955355, 5977.880442391215, 6014.1580178775885], "value": 5657.640497936649, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [454.8635409377187, 499.7326430359758, 513.0790231534395, 520.4145336496441, 535.472559528864, 30875.691243087564, 40468.19063618726, 41789.79105104478, 41824.745257642244, 41977.37033944485, 43009.35485967705, 43291.35062974058, 43298.0, 43371.56628433715, 43446.348304775456, 9746.4152150871, 9927.702168934908, 9942.204623630128, 9948.751281217956, 9949.552270147824, 9790.804183916318, 9892.604295828158, 9893.554790034466, 9911.306208565404, 9916.801663966753, 9858.014198580157, 9924.851127233062, 9933.552990115462, 9939.50302484873, 9954.352967057155, 9826.213902887788, 9891.35706179096, 9891.505424728735, 9900.356476829054, 9901.108900199017, 9621.230301575857, 9703.12671859529, 9705.320627556073, 9706.660266890696, 9713.854292185622, 40758.18483630341, 41089.0, 41092.58907410917, 41111.58888411138, 41141.79429102865, 3273.541704161419, 3432.845521951518, 3496.125968507873, 3709.3879509880835, 3725.4784330193793, 30564.694353056406, 31874.521882171786, 31933.84033079843, 31967.840160799045, 31975.880844170344, 2856.9714302856914, 3188.8883889064005, 3192.2179066128947, 3286.342731453719, 3303.7687361884678, 454845.0, 478866.26802391966, 479083.0, 481205.9999999965, 489547.0, 58964.0, 59864.40135598675, 59895.0, 60038.099428508605, 60053.399466005656, 63772.0, 63859.36140638534, 63885.680571596844, 63969.72060558856, 63987.36012639907, 15969.68060638804, 16219.675606487805, 16229.513114606581, 16234.431794887123, 16250.918745406198, 12577.93711031439, 12645.367731613382, 12657.240565566095, 12659.0, 12667.683307917192, 16727.996320220584, 17304.65390692179, 17313.39403120885, 17323.826761732475, 17351.392701255394, 6036.788712395045, 6435.517336199804, 6662.300458451889, 6732.528722989442, 6739.629320387426, 875.9737207883774, 1010.434156872154, 1029.8197815382277, 1073.313079629038, 1109.3565731875492, 25304.74695253024, 29903.102906912827, 29950.55074173889, 30392.696073039428, 30845.383092338026, 829.8962629671291, 1047.4448542272607, 1054.8765564673597, 1083.8753543342616, 1136.8237923121928, 14574.927125364306, 15127.243637818065, 15155.0, 15317.791240302882, 15332.616684582974, 594079.0, 597868.0, 598261.0, 598727.0, 599193.0, 57733.13400298999, 57894.0, 57937.4206257932, 58061.12908306379, 58154.4184558149, 1224.1981502116128, 1263.6019653809021, 1339.4642143142626, 1371.245814801875, 1380.827396575428, 25022.499550008535, 30227.0, 31112.755489780156, 31260.687393125776, 32031.67968320287, 50103.74948125236, 50248.236347001264, 50495.24257136219, 50585.49414505808, 50598.470076495294, 493738.0, 496477.0, 496619.03380967176, 496873.0, 496967.0, 24857.005719771005, 24918.501629966937, 26086.956521738917, 27713.307167320978, 27722.306942326602, 46950.53049469461, 47740.522594775, 48019.51980480151, 48454.03091938212, 48568.0, 32668.50997234996, 35934.460983085795, 38878.805605971786, 39738.801305993285, 40088.59911400849, 38565.4215186778, 39025.0, 39124.6087539121, 39965.200695986496, 40578.39132413075, 37456.625433746405, 37866.810665947596, 38323.616763832004, 38454.37013190872, 38722.838314850596, 33320.00039998804, 34341.828290858386, 35161.68215422966, 35244.0, 35532.82233588816, 19988.90005549963, 25179.0, 25287.620685690097, 25875.87062064753, 25948.09181678632], "value": 19785.139105014787, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [319.2720597038751, 342.29487256252105, 367.2856294507183, 370.79606216580873, 401.15756910487977, 14994.325255363508, 15129.546113616596, 15949.441769538054, 20011.399658010283, 20653.690194647053, 19528.0, 20033.398998030047, 20454.69317960228, 20545.38363849083, 20882.89558552209, 5800.53595712342, 6052.243469566304, 6062.333143354227, 6064.605800622968, 6079.392060793907, 4837.298591704201, 6055.697215139247, 6101.694915254242, 6124.938750612481, 6135.693215339237, 5755.0, 6077.939220607804, 6080.179175811275, 6087.634741915499, 6088.178095957055, 5971.522278217754, 6007.849803754941, 6017.308009578911, 6053.576249662526, 6089.117078023706, 4971.229459433794, 5154.664946778485, 5234.869128271824, 5254.21186821976, 5267.078261304257, 22360.552788944293, 23703.288901332817, 25641.35896602581, 25816.87091564549, 26104.34739131518, 2281.885905704708, 2288.736795268532, 2340.1926335414255, 2392.569337519246, 2416.5287768885073, 11528.827067593993, 15320.693586128215, 15380.846191538054, 15387.923060384739, 16079.276432560446, 1806.9277228910828, 1839.4481655503296, 1840.5214644192472, 1944.9788860848028, 1956.6869300911785, 91946.31876599, 121726.99999999911, 123120.0, 123418.0, 123577.0, 9337.486438245825, 9452.0, 10828.891711082946, 10864.34813911152, 11056.135300467768, 13458.125221860548, 13483.056186067077, 13490.730185396433, 13499.392527336293, 13509.527166549124, 8219.794505137299, 8445.451045682012, 8483.066862645104, 8633.69782057625, 8660.177283158217, 7195.604241766753, 8556.529390883561, 8574.22831945128, 8582.227599516074, 8738.86891696625, 6308.801327747713, 6443.5167362448, 6471.644059576674, 6479.157709497735, 6582.308857569887, 1672.1973452742752, 1805.8374746272898, 1824.2976454065015, 1844.8339649431616, 1866.887986720801, 720.4236610711409, 829.7261903571807, 912.6030176872948, 931.6599441204047, 964.628617982082, 16122.516324510283, 16838.747418788727, 20195.596088078157, 21829.781702183092, 21838.804776894827, 947.5877993072897, 963.7735132243918, 1040.105509262034, 1048.821700310949, 1086.1093902999464, 10782.73043173927, 11596.304221746723, 11618.244814087057, 11640.592579259855, 11657.650270491898, 228705.0, 229204.85397572906, 229609.0, 231455.0, 555239.9144178076, 66575.66712166408, 74917.62541187259, 94111.0, 113285.30072048926, 121239.0, 979.6816034788579, 1012.063840947121, 1026.7279171019686, 1028.7993841200894, 1029.8300780371305, 18592.721109183643, 19417.70873436928, 19478.0, 19520.31678891232, 19582.216711331956, 54544.90910181695, 56595.0, 57807.4219257802, 58238.379272931976, 58730.88883778265, 83676.0, 83709.16290837257, 83957.58021209855, 84189.0, 84847.1515284864, 6851.828704282233, 7803.219678032378, 7920.168382320004, 7922.168172342052, 8137.226963438464, 11169.553217871195, 12363.93818030904, 12755.234685918504, 12930.676733081373, 12968.740625187633, 10625.096866766411, 11062.889371106186, 11076.280041797258, 11098.167637427065, 11148.0, 9627.663031793854, 10771.6229931952, 11194.328340299608, 11398.20212585111, 11430.085593152695, 8648.481091134552, 11247.493862776031, 11439.256448330832, 11770.76458470843, 11943.984761295387, 6481.902771458337, 8028.872348827448, 8266.0, 8404.663813447394, 8416.537090460084, 10519.368837869752, 11235.943820280847, 11287.379194144401, 11525.711857203636, 11674.94162529182], "value": 9392.796801212471, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1524.8246451658058, 1533.677927635197, 1753.6755700195465, 1864.4220291709562, 1908.1127275816743, 8441.831163376737, 8957.149070838268, 8957.731268061953, 8962.686305979292, 8986.730398088068, 7821.726239581616, 8135.023797144336, 8476.91523084769, 8749.606267717953, 8752.474851508898, 2343.7890589846866, 2367.0886708617136, 2375.406148462884, 2377.4413012941945, 2379.1791831817995, 2324.1865347128537, 2337.55586438577, 2369.6208606622945, 2370.5065808540594, 2370.727366352866, 2345.2377977157407, 2350.435895385104, 2360.2683168217823, 2364.7280562735245, 2368.7394386617457, 2318.547883162784, 2333.5449587330477, 2341.356127065054, 2348.847324923883, 2355.62310030396, 2332.9066837326486, 2333.614953532665, 2334.3463830127594, 2336.661184128309, 2348.9647655285185, 7538.698452061911, 8816.603252853636, 8821.42660727057, 8829.690960816404, 8907.73276801697, 4494.797734101973, 5344.679319240858, 5349.786008559653, 5350.143976963706, 5351.464853514656, 3431.811250381228, 3586.605473397924, 3656.8537258509627, 3669.5963444021136, 3671.5777685565968, 1044.7074819050601, 1083.3391631105019, 1085.0776839686202, 1099.0, 1103.9503222355013, 11594.304341739438, 11705.648830535098, 11712.824307635392, 11720.648380548511, 11728.88271117295, 4178.206140833293, 4456.108778244364, 4569.794359253841, 4752.382190315237, 4759.976200118977, 10010.349327293849, 10025.398476091457, 10032.197424206046, 10045.246606504541, 10051.547680354402, 5319.0, 5324.148136298174, 5327.280817089721, 5330.013947419739, 5334.199870019528, 3826.4069069294305, 4345.152695224401, 4505.43682039745, 4828.251620998751, 4872.439669437989, 3795.7912314822397, 3827.349350610403, 3918.9804050979565, 3961.3067713150085, 3961.58403367648, 1269.7587458382877, 1270.8729127087402, 1274.3691872523161, 1283.5956673647959, 1290.8450985881755, 744.9106107267054, 845.4673555659895, 902.6885724425063, 902.8510295801251, 935.7473482159779, 5286.577073834161, 5560.761638919398, 5733.827985160452, 5941.55438342126, 6137.60105593135, 837.24647816964, 909.3180114913814, 912.4662072687552, 940.2008292951074, 962.9951850240705, 3679.3377192105145, 3893.980530097388, 3931.724779265424, 3968.920621587552, 4031.899202519902, 10475.0, 10815.188860835311, 10840.13278937683, 10853.0, 10891.945540272249, 10046.74883127913, 10587.0, 10608.416537090534, 10689.30519516229, 10813.459327033617, 7993.880091798627, 8212.548309843016, 8225.218604232468, 8238.052623948202, 8239.670413183405, 5834.970825145847, 5883.264591926009, 5887.7056147193325, 6051.606645568024, 6227.0, 9804.068613481708, 9900.25748068912, 9924.900750992398, 10009.299349045497, 10083.798324033332, 8301.46040507365, 9745.0, 10217.846732298873, 10291.94854025725, 10357.792844143225, 6646.8005959821285, 7040.190378106378, 7168.53404528704, 7543.811404714706, 7695.23047695226, 8349.791255218668, 8450.112738162405, 8534.78663033429, 8535.658573657232, 8537.74386768373, 7449.478536502393, 7556.131044929683, 7558.508696934681, 7620.885686714814, 7702.576358300347, 7325.194228634952, 7383.778486645409, 7430.4798664093, 7454.4781865268915, 7556.508826926232, 6854.246032936273, 7015.228324884157, 7016.7544135954995, 7020.087388639444, 7024.227334993045, 4905.288733133747, 5002.974985125051, 5074.3657042869645, 5147.687339728345, 5148.562372198405, 4962.3052772611145, 5011.09800235961, 5139.6145289102715, 5153.742312884266, 5259.631825772312], "value": 4576.092693391437, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.1999670877113, 300.7473722073459, 308.4032397311202, 313.70668425022603, 315.846814295067, 4427.424434823476, 4659.951510910044, 4973.602111831054, 5360.65155764875, 5375.569954403648, 4033.838646454138, 4644.465886423062, 4651.837040739815, 5037.999999999991, 5315.3887302960175, 3757.910206040245, 3869.7036492774955, 4063.9796801016028, 4075.7394084732246, 4108.4864391951005, 3511.2801875615564, 3751.399776035821, 4070.61329173728, 4080.6735461163184, 4144.937825932613, 3885.805709714503, 4017.268257584841, 4038.3336749436307, 4068.430419741224, 4092.2224777292367, 3077.753779697619, 3573.6069032406413, 3624.4563315502887, 4099.9545115995525, 4114.650254728337, 2962.851857407121, 3541.468779683068, 3552.5914519830294, 3564.308946745191, 3575.6781889630056, 3919.8432062717457, 3929.6070392960473, 5167.948320516822, 5185.351831021122, 5305.0, 4079.6741059155597, 4212.726172798758, 4322.610965013164, 4435.778211089465, 4611.262198048296, 4633.582977532038, 5264.921026184572, 5538.556915446755, 5565.686592890543, 5604.907043126592, 3805.771653700787, 3808.923821523582, 3826.2921359548573, 3838.2323535292776, 3855.1325951660933, 5565.526930210937, 5737.454941780524, 5757.510611597977, 5846.532277417797, 5892.734826932799, 3597.244578638463, 3872.96127038726, 3884.3590807516443, 3890.5720370759195, 3910.9413358799643, 3913.686905047646, 3932.488776459042, 3947.684185265172, 3979.2041591681777, 3984.4222587724616, 3827.5598306195143, 4254.787385595152, 4394.4408986784965, 4638.443386793607, 4661.440627124699, 5522.530584900329, 5552.750126244328, 5604.747786349624, 5611.8877622448135, 5656.727236371784, 1326.104879206551, 1339.216558313378, 1339.7253563019754, 1345.822405395279, 1352.1684164238923, 827.8054657155564, 847.8261956298944, 850.6814437621662, 860.3418384935529, 887.3389324952951, 4905.6811307265625, 5234.9476505235225, 5295.947040529545, 5323.6273460857365, 5330.333708286465, 4430.62339701129, 5401.730593310569, 5688.0, 5720.628159169641, 5739.684317362585, 3926.9410958835642, 4565.406497155349, 5151.6136289778415, 5204.8959020819375, 5246.930545630848, 2844.563495434792, 2975.9702402976127, 2976.6009975311567, 2987.9243472349935, 2989.6113505244616, 5397.514223719805, 5465.043617366945, 5555.0, 5788.160716696056, 5832.88334233313, 4596.701214421052, 5094.917574867226, 5430.212619170198, 5682.7442765075675, 5707.63133299676, 4874.512548745097, 5148.382194136728, 5366.604682782427, 5770.971145144247, 5813.418658134153, 5246.029484545219, 5630.7466164023535, 5631.1271752878365, 5655.4910058094965, 5742.885142297214, 4969.577585905094, 5650.29371328584, 5681.460261275172, 5690.743916523849, 5693.9430605693415, 5934.169216309634, 5997.0404735242155, 5998.430149135826, 6050.788222412194, 6070.54470914692, 3008.9849550752106, 3698.2603479304785, 3847.8460861566346, 3902.6487616114687, 3927.155661532784, 4752.691075080109, 4818.590419814355, 5107.591392688651, 5478.767277362562, 5701.258836351248, 5268.736563171903, 5396.325459317585, 5644.915326270027, 5734.455226753452, 5772.451617096369, 4963.280324353021, 5310.867228319323, 5447.782088716407, 5624.8875022498505, 5655.547556195576, 5171.431142574389, 5515.062439385394, 5525.336959564878, 5542.307211598551, 5565.805196818092, 4095.32427149517, 5484.2596249505805, 5489.560835133259, 5533.6403133796175, 5595.664260144405, 4077.653399461079, 4566.45202575693, 4728.534154412143, 5273.551748101454, 5473.97662717894], "value": 4016.722910899905, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [708.6138054760156, 724.3336130759703, 766.6166916541729, 799.2407213147511, 804.8591496488114, 15741.21293935303, 15939.203039847991, 15953.601159970993, 15974.680506389865, 16021.038737675734, 13193.010524210698, 14275.21486318252, 14391.06458080224, 14417.783733243983, 14842.554723358317, 672.660306545194, 695.1796879681973, 697.3061803505519, 779.2052106851008, 834.3867257565714, 851.936104792143, 853.8121613245107, 855.683397143058, 857.5712143928037, 858.3347905373323, 894.7315805258429, 911.7036962987023, 911.8541033434652, 913.5477938420491, 913.6482454255093, 905.9456432614064, 907.1246247371319, 907.2288554728494, 907.3829795738895, 910.6949172027379, 522.9764660590282, 535.6759160707754, 538.1685296217314, 674.2414783368711, 676.3439463720183, 12370.319632420213, 12413.565525206574, 14261.429542818274, 14833.851661483355, 14865.2567371631, 13905.374258158407, 14388.424463021467, 15041.323140458597, 15095.924520377437, 15156.772648410282, 11999.820002699966, 12000.099992500598, 12005.699857503545, 12048.156629035971, 12314.26114433128, 3844.7693138411505, 3891.396833490785, 3911.1395492991783, 3986.142979259444, 4034.495688038995, 114679.0, 115979.0, 118249.0, 118411.38269099078, 118638.0, 11513.942430287794, 11537.0, 11598.362090084958, 11681.88318116825, 12113.878861211451, 24030.63954040656, 24151.63772543413, 24300.513989720108, 24325.270241892773, 24602.0, 8050.355971522265, 8105.432619716681, 8148.592570371457, 8234.176582341717, 8343.499390036617, 4282.914341713148, 4336.1299241875895, 4456.955430445719, 4611.1930412177735, 4613.169629466728, 3370.882019129319, 3545.751797374159, 3627.401478755976, 3635.2729454109285, 3649.0512466758305, 561.9775208991676, 612.770211170811, 667.2293501006345, 672.586359388982, 691.8028361916829, 664.8603793203481, 665.381195488191, 674.578388507183, 681.6898311268361, 682.9107573420387, 8147.633356498972, 8956.369217276928, 9617.807643847083, 9681.37071090377, 9711.417314961125, 638.7894939090397, 673.195531340044, 673.599208470959, 677.0791723256367, 680.3332733920786, 4860.708357498491, 5037.4458809530925, 5081.822136225214, 5196.402413722395, 5346.91979620306, 94439.52780236055, 121754.3912280433, 143662.0, 148168.77746833806, 162987.0, 96669.0, 115129.4243528777, 117154.0, 119154.0, 119423.0, 88938.0, 92556.0, 93336.53331733299, 94359.52820235993, 116416.41791791156, 9360.44428520235, 9610.588241174282, 9621.374610650286, 10131.696049118538, 10136.594536218763, 44051.77974110109, 50360.0, 57288.56778580402, 58363.124553132584, 58754.824903502544, 76294.61852690701, 80506.0, 89381.55309223413, 90106.54946725225, 93068.0, 10791.514381852678, 12826.61520154397, 13305.66735831612, 13319.733605327645, 13610.455581776618, 12743.681407964876, 12949.0, 14705.044456994934, 14996.400143994118, 15222.0, 13532.729345413234, 13864.306784660928, 13897.16617002983, 14090.580162195884, 14127.788083178966, 13566.38951247216, 13709.794353084511, 13899.374528146056, 14195.574132776033, 14317.355718992461, 13293.534726284533, 13900.51348202808, 13905.096168749, 13957.720845583235, 13961.301934903011, 11182.88817111851, 11413.828792567952, 11747.412629368668, 11826.34955077479, 11854.94072529632, 8802.0, 11333.433328333715, 11453.25553839031, 11512.424378781196, 11546.0], "value": 7787.391751916444, "unit": "score"}], "name": "dromaeo_css", "value": 7293.38418768479, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 114.0, 113.0, 113.0, 112.0, 111.0, 112.0, 113.0, 112.0, 113.0], "value": 112.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 139.0, 114.0, 152.0, 113.0, 112.0, 114.0, 113.0, 145.0, 118.0], "value": 129.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 181.0, 183.0, 187.0, 181.0, 183.0, 179.0, 182.0, 183.0, 182.0], "value": 182.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 59.0, 59.0, 87.0, 62.0, 57.0, 58.0, 58.0, 84.0, 81.0], "value": 68.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 70.0, 95.0, 70.0, 67.0, 68.0, 109.0, 97.0, 100.0, 104.0], "value": 87.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 87.0, 85.0, 88.0, 85.0, 88.0, 86.0, 90.0, 85.0], "value": 86.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 96.0, 97.0, 96.0, 96.0, 95.0, 97.0, 95.0, 96.0], "value": 95.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 87.0, 84.0, 85.0, 83.0, 85.0, 84.0, 85.0, 83.0], "value": 84.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 52.0, 53.0, 55.0, 54.0, 55.0, 53.0, 54.0, 55.0], "value": 53.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 72.0, 67.0, 71.0, 72.0, 75.0, 70.0, 69.0, 69.0, 71.0], "value": 70.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 90.0, 87.0, 86.0, 87.0, 93.0, 89.0, 87.0, 86.0, 86.0], "value": 87.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 159.0, 153.0, 163.0, 158.0, 145.0, 146.0, 146.0, 186.0, 159.0], "value": 156.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 173.0, 172.0, 179.0, 239.0, 186.0, 170.0, 174.0, 173.0, 176.0], "value": 181.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 70.0, 69.0, 68.0, 69.0, 70.0, 68.0, 72.0, 73.0], "value": 69.7, "unit": "score"}], "name": "kraken", "value": 1466.6000000000001, "alertThreshold": 2.0}]} 04:32:03 INFO - Return code: 0 04:32:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:32:03 INFO - # TBPL SUCCESS # 04:32:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:32:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:32:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:32:03 INFO - 2016-10-11 04:32:03.673 screenresolution[1667:10698] starting screenresolution argv=screenresolution get 04:32:03 INFO - 2016-10-11 04:32:03.676 screenresolution[1667:10698] Display 0: 1600x1200x32@60 04:32:03 INFO - 2016-10-11 04:32:03.681 screenresolution[1668:10702] starting screenresolution argv=screenresolution list 04:32:03 INFO - Available Modes on Display 0 04:32:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:32:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:32:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:32:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:32:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:32:03 INFO - Intel Iris: 04:32:03 INFO - Chipset Model: Intel Iris 04:32:03 INFO - Type: GPU 04:32:03 INFO - Bus: Built-In 04:32:03 INFO - VRAM (Dynamic, Max): 1536 MB 04:32:03 INFO - Vendor: Intel (0x8086) 04:32:03 INFO - Device ID: 0x0a2e 04:32:03 INFO - Revision ID: 0x0009 04:32:03 INFO - Displays: 04:32:03 INFO - Display: 04:32:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:32:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:32:03 INFO - Display Serial Number: 1600x1200 60 04:32:03 INFO - Main Display: Yes 04:32:03 INFO - Mirror: Off 04:32:03 INFO - Online: Yes 04:32:03 INFO - Rotation: Supported 04:32:03 INFO - Return code: 0 04:32:03 INFO - Running post-action listener: _resource_record_post_action 04:32:03 INFO - [mozharness: 2016-10-11 11:32:03.922991Z] Finished run-tests step (success) 04:32:03 INFO - Running post-run listener: _resource_record_post_run 04:32:03 INFO - Total resource usage - Wall time: 1214s; CPU: 53.0%; Read bytes: 33336320; Write bytes: 712899072; Read time: 313; Write time: 1980 04:32:03 INFO - TinderboxPrint: CPU usage
53.5% 04:32:03 INFO - TinderboxPrint: I/O read bytes / time
33,336,320 / 313 04:32:03 INFO - TinderboxPrint: I/O write bytes / time
712,899,072 / 1,980 04:32:03 INFO - TinderboxPrint: CPU idle
2,260.0 (46.5%) 04:32:03 INFO - TinderboxPrint: CPU system
972.5 (20.0%) 04:32:03 INFO - TinderboxPrint: CPU user
1,625.2 (33.5%) 04:32:03 INFO - TinderboxPrint: Swap in / out
437,956,608 / 0 04:32:03 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 391897600; Write bytes: 384379904; Read time: 29874; Write time: 674 04:32:04 INFO - run-tests - Wall time: 1180s; CPU: 53.0%; Read bytes: 32406528; Write bytes: 298497536; Read time: 300; Write time: 1158 04:32:04 INFO - Running post-run listener: _upload_blobber_files 04:32:04 INFO - Blob upload gear active. 04:32:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:32:04 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 04:32:04 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:32:04 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:32:04 INFO - (blobuploader) - INFO - Open directory for files ... 04:32:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:32:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:32:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:32:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:32:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:32:05 INFO - (blobuploader) - INFO - Done attempting. 04:32:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:32:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:32:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:32:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:32:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:32:06 INFO - (blobuploader) - INFO - Done attempting. 04:32:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:32:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:32:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:32:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:32:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:32:08 INFO - (blobuploader) - INFO - Done attempting. 04:32:08 INFO - (blobuploader) - INFO - Iteration through files over. 04:32:08 INFO - Return code: 0 04:32:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:32:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:32:08 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0daf5e05834da5c06f1508849f9b66801bc8ce496bf014afe82190922032921ff2e10b7dba84d072ceb0bd9faf6d6eb696d7884f3eb9efe915b8dd9157a096fb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c1f522197fd39126202b63e70ef1154ed847c81d59519402aeb14ac9fbcf41a0d83d8250170852387c1e60bbd6c4a824e542fc23bfe4c6216a8a8fac577f018a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/53a657ae2dcc33258fdadad5d6b1573407d61e4609337414720ce9280c7e3699666646e726b56c921268faf1ec962ae443d211ddab9c50952db55beeca64e086"} 04:32:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:32:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:32:08 INFO - Contents: 04:32:08 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0daf5e05834da5c06f1508849f9b66801bc8ce496bf014afe82190922032921ff2e10b7dba84d072ceb0bd9faf6d6eb696d7884f3eb9efe915b8dd9157a096fb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c1f522197fd39126202b63e70ef1154ed847c81d59519402aeb14ac9fbcf41a0d83d8250170852387c1e60bbd6c4a824e542fc23bfe4c6216a8a8fac577f018a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/53a657ae2dcc33258fdadad5d6b1573407d61e4609337414720ce9280c7e3699666646e726b56c921268faf1ec962ae443d211ddab9c50952db55beeca64e086"} 04:32:08 INFO - Running post-run listener: copy_logs_to_upload_dir 04:32:08 INFO - Copying logs to upload dir... 04:32:08 INFO - mkdir: /builds/slave/test/build/upload/logs 04:32:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.987111 ========= master_lag: 0.08 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-10-11 04:32:08.767157) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-11 04:32:08.771373) ========= 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.ggo5OUBHv1/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.Sm1GIn3wKD/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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/Fx-Team/sha512/0daf5e05834da5c06f1508849f9b66801bc8ce496bf014afe82190922032921ff2e10b7dba84d072ceb0bd9faf6d6eb696d7884f3eb9efe915b8dd9157a096fb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c1f522197fd39126202b63e70ef1154ed847c81d59519402aeb14ac9fbcf41a0d83d8250170852387c1e60bbd6c4a824e542fc23bfe4c6216a8a8fac577f018a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/53a657ae2dcc33258fdadad5d6b1573407d61e4609337414720ce9280c7e3699666646e726b56c921268faf1ec962ae443d211ddab9c50952db55beeca64e086"} build_url:https://queue.taskcluster.net/v1/task/KNpU4yC9T4Ww7kmvYeOvsQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011378 build_url: 'https://queue.taskcluster.net/v1/task/KNpU4yC9T4Ww7kmvYeOvsQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0daf5e05834da5c06f1508849f9b66801bc8ce496bf014afe82190922032921ff2e10b7dba84d072ceb0bd9faf6d6eb696d7884f3eb9efe915b8dd9157a096fb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c1f522197fd39126202b63e70ef1154ed847c81d59519402aeb14ac9fbcf41a0d83d8250170852387c1e60bbd6c4a824e542fc23bfe4c6216a8a8fac577f018a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/53a657ae2dcc33258fdadad5d6b1573407d61e4609337414720ce9280c7e3699666646e726b56c921268faf1ec962ae443d211ddab9c50952db55beeca64e086"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-11 04:32:08.804243) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-11 04:32:08.804559) ========= 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.ggo5OUBHv1/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.Sm1GIn3wKD/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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.005199 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-11 04:32:08.854663) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-11 04:32:08.857001) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-11 04:32:08.857318) ========= ========= Total master_lag: 0.25 =========