builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0072 starttime: 1475186971.7 results: success (0) buildid: 20160929125319 builduid: 1437e7085268480d9fc9a2a1b1b49cb9 revision: 500496dfefccc73d1f87ff912563a8ee1afe8e91 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-29 15:09:31.700777) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-29 15:09:31.701232) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-29 15:09:31.701518) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-29 15:09:31.725928) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 15:09:31.726240) ========= 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.S3kfk0xSwh/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.KKXyX8aZme/Listeners TMPDIR=/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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-09-29 15:09:31-- 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: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.80M=0.001s 2016-09-29 15:09:31 (8.80 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.096783 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 15:09:31.845150) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 15:09:31.845457) ========= 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.S3kfk0xSwh/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.KKXyX8aZme/Listeners TMPDIR=/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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.035828 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 15:09:31.901123) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 15:09:31.901430) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 500496dfefccc73d1f87ff912563a8ee1afe8e91 --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 500496dfefccc73d1f87ff912563a8ee1afe8e91 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.S3kfk0xSwh/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.KKXyX8aZme/Listeners TMPDIR=/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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-09-29 15:09:31,970 truncating revision to first 12 chars 2016-09-29 15:09:31,971 Setting DEBUG logging. 2016-09-29 15:09:31,971 attempt 1/10 2016-09-29 15:09:31,971 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/500496dfefcc?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-29 15:09:32,632 unpacking tar archive at: fx-team-500496dfefcc/testing/mozharness/ program finished with exit code 0 elapsedTime=0.914151 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 15:09:32.837570) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-29 15:09:32.837890) ========= script_repo_revision: 500496dfefccc73d1f87ff912563a8ee1afe8e91 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-29 15:09:32.838273) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-29 15:09:32.838554) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-29 15:09:32.856955) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-09-29 15:09:32.857490) ========= /tools/buildbot/bin/python 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', '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.S3kfk0xSwh/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.KKXyX8aZme/Listeners TMPDIR=/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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 15:09:33 INFO - MultiFileLogger online at 20160929 15:09:33 in /builds/slave/test 15:09:33 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 15:09:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:09:33 INFO - {'append_to_log': False, 15:09:33 INFO - 'base_work_dir': '/builds/slave/test', 15:09:33 INFO - 'blob_upload_branch': 'Fx-Team', 15:09:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:09:33 INFO - 'branch': 'Fx-Team', 15:09:33 INFO - 'buildbot_json_path': 'buildprops.json', 15:09:33 INFO - 'config_files': ('talos/mac_config.py',), 15:09:33 INFO - 'default_actions': ('clobber', 15:09:33 INFO - 'read-buildbot-config', 15:09:33 INFO - 'download-and-extract', 15:09:33 INFO - 'populate-webroot', 15:09:33 INFO - 'create-virtualenv', 15:09:33 INFO - 'install', 15:09:33 INFO - 'run-tests'), 15:09:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:09:33 INFO - 'download_minidump_stackwalk': True, 15:09:33 INFO - 'download_symbols': 'ondemand', 15:09:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:09:33 INFO - 'tooltool.py': '/tools/tooltool.py', 15:09:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:09:33 INFO - '/tools/misc-python/virtualenv.py')}, 15:09:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:09:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:09:33 INFO - 'installer_path': 'installer.exe', 15:09:33 INFO - 'log_level': 'info', 15:09:33 INFO - 'log_name': 'talos', 15:09:33 INFO - 'log_to_console': True, 15:09:33 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:09:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:09:33 INFO - 'opt_config_files': (), 15:09:33 INFO - 'pip_index': False, 15:09:33 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:09:33 INFO - 'cmd': ('bash', 15:09:33 INFO - '-c', 15:09:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:09:33 INFO - 'enabled': True, 15:09:33 INFO - 'halt_on_failure': False, 15:09:33 INFO - 'name': 'check_screen_resolution'},), 15:09:33 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:09:33 INFO - 'cmd': ('bash', 15:09:33 INFO - '-c', 15:09:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:09:33 INFO - 'enabled': True, 15:09:33 INFO - 'halt_on_failure': False, 15:09:33 INFO - 'name': 'check_screen_resolution'},), 15:09:33 INFO - 'run_cmd_checks_enabled': True, 15:09:33 INFO - 'sps_profile': False, 15:09:33 INFO - 'sps_profile_interval': 0, 15:09:33 INFO - 'suite': 'dromaeojs', 15:09:33 INFO - 'system_bits': '32', 15:09:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:09:33 INFO - 'title': 't-yosemite-r7-0072', 15:09:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:09:33 INFO - 'use_talos_json': True, 15:09:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:09:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:09:33 INFO - 'work_dir': 'build'} 15:09:33 INFO - [mozharness: 2016-09-29 22:09:33.060701Z] Running clobber step. 15:09:33 INFO - Running pre-action listener: _resource_record_pre_action 15:09:33 INFO - Running main action method: clobber 15:09:33 INFO - rmtree: /builds/slave/test/build 15:09:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:09:35 INFO - Running post-action listener: _resource_record_post_action 15:09:35 INFO - [mozharness: 2016-09-29 22:09:35.515102Z] Finished clobber step (success) 15:09:35 INFO - [mozharness: 2016-09-29 22:09:35.515223Z] Running read-buildbot-config step. 15:09:35 INFO - Running pre-action listener: _resource_record_pre_action 15:09:35 INFO - Running main action method: read_buildbot_config 15:09:35 INFO - Using buildbot properties: 15:09:35 INFO - { 15:09:35 INFO - "project": "", 15:09:35 INFO - "product": "firefox", 15:09:35 INFO - "script_repo_revision": "production", 15:09:35 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 15:09:35 INFO - "repository": "", 15:09:35 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 15:09:35 INFO - "buildid": "20160929125319", 15:09:35 INFO - "pgo_build": "False", 15:09:35 INFO - "basedir": "/builds/slave/test", 15:09:35 INFO - "buildnumber": 166, 15:09:35 INFO - "slavename": "t-yosemite-r7-0072", 15:09:35 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 15:09:35 INFO - "platform": "yosemite_r7", 15:09:35 INFO - "scheduler": "tests-fx-team-macosx64-talos", 15:09:35 INFO - "branch": "fx-team", 15:09:35 INFO - "repo_path": "integration/fx-team", 15:09:35 INFO - "revision": "500496dfefccc73d1f87ff912563a8ee1afe8e91", 15:09:35 INFO - "stage_platform": "macosx64", 15:09:35 INFO - "builduid": "1437e7085268480d9fc9a2a1b1b49cb9", 15:09:35 INFO - "slavebuilddir": "test" 15:09:35 INFO - } 15:09:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/WtrTeq1cTLS3BnWBvBfGmw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 15:09:35 INFO - Running post-action listener: _resource_record_post_action 15:09:35 INFO - [mozharness: 2016-09-29 22:09:35.518586Z] Finished read-buildbot-config step (success) 15:09:35 INFO - [mozharness: 2016-09-29 22:09:35.518717Z] Running download-and-extract step. 15:09:35 INFO - Running pre-action listener: _resource_record_pre_action 15:09:35 INFO - Running main action method: download_and_extract 15:09:35 INFO - mkdir: /builds/slave/test/build/tests 15:09:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:09:35 INFO - trying https://queue.taskcluster.net/v1/task/WtrTeq1cTLS3BnWBvBfGmw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 15:09:35 INFO - Downloading https://queue.taskcluster.net/v1/task/WtrTeq1cTLS3BnWBvBfGmw/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 15:09:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WtrTeq1cTLS3BnWBvBfGmw/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 15:09:36 INFO - Downloaded 1199 bytes. 15:09:36 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 15:09:36 INFO - Using the following test package requirements: 15:09:36 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 15:09:36 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:09:36 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 15:09:36 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:09:36 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 15:09:36 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:09:36 INFO - u'jsshell-mac.zip'], 15:09:36 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:09:36 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 15:09:36 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 15:09:36 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:09:36 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 15:09:36 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:09:36 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 15:09:36 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:09:36 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 15:09:36 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:09:36 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 15:09:36 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:09:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WtrTeq1cTLS3BnWBvBfGmw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 15:09:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WtrTeq1cTLS3BnWBvBfGmw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:09:36 INFO - Fetch https://queue.taskcluster.net/v1/task/WtrTeq1cTLS3BnWBvBfGmw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 15:09:37 INFO - Http code: 200 15:09:37 INFO - Content-Encoding: None 15:09:37 INFO - Content-Type: application/zip 15:09:37 INFO - via: 1.1 5ed093c37d643652e278a198e0556a40.cloudfront.net (CloudFront) 15:09:37 INFO - x-amz-cf-id: HGadSYW6g_P9VEcXHwOH7q3YGG4yawX-FgRk-FbE2KBvWjXNdVuKAg== 15:09:37 INFO - x-amz-version-id: Nh8Bm4dcL4DdYKKr3Yzf6.aigxld0jWD 15:09:37 INFO - x-cache: Hit from cloudfront 15:09:37 INFO - Expected file size: 16473003 15:09:37 INFO - Obtained file size: 16473003 15:09:44 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 15:09:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WtrTeq1cTLS3BnWBvBfGmw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 15:09:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WtrTeq1cTLS3BnWBvBfGmw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:09:44 INFO - Fetch https://queue.taskcluster.net/v1/task/WtrTeq1cTLS3BnWBvBfGmw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 15:09:44 INFO - Http code: 200 15:09:44 INFO - Content-Encoding: None 15:09:44 INFO - Content-Type: application/zip 15:09:44 INFO - via: 1.1 423912f2b1c5569e2fa5017167c61fa0.cloudfront.net (CloudFront) 15:09:44 INFO - x-amz-cf-id: O6VpJOvBVtjBPwS1HHwF1CyjxdSOJWc7wTXPIjDMhsKMBmR30VJYpw== 15:09:44 INFO - x-amz-version-id: Nh8Bm4dcL4DdYKKr3Yzf6.aigxld0jWD 15:09:44 INFO - x-cache: Hit from cloudfront 15:09:45 INFO - Expected file size: 16473003 15:09:45 INFO - Obtained file size: 16473003 15:09:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WtrTeq1cTLS3BnWBvBfGmw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 15:09:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WtrTeq1cTLS3BnWBvBfGmw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 15:09:51 INFO - Fetch https://queue.taskcluster.net/v1/task/WtrTeq1cTLS3BnWBvBfGmw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 15:09:52 INFO - Http code: 200 15:09:52 INFO - Content-Encoding: None 15:09:52 INFO - Content-Type: application/zip 15:09:52 INFO - via: 1.1 9baadab4e4d95afe7af7bcba342b29c6.cloudfront.net (CloudFront) 15:09:52 INFO - x-amz-cf-id: 8Ig4byj6PxJ9JAerXsRSrzzVYMJya5SPmWNvEJLvIFpdOaU2VO2TXQ== 15:09:52 INFO - x-amz-version-id: w3TcvulNfqCdXhYte5cmz7tIsHz6iaaS 15:09:52 INFO - x-cache: Hit from cloudfront 15:09:52 INFO - Expected file size: 14414067 15:09:52 INFO - Obtained file size: 14414067 15:09:52 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')]} 15:09:52 INFO - trying https://queue.taskcluster.net/v1/task/WtrTeq1cTLS3BnWBvBfGmw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 15:09:52 INFO - Downloading https://queue.taskcluster.net/v1/task/WtrTeq1cTLS3BnWBvBfGmw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 15:09:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WtrTeq1cTLS3BnWBvBfGmw/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 15:09:55 INFO - Downloaded 111789792 bytes. 15:09:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WtrTeq1cTLS3BnWBvBfGmw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 15:09:55 INFO - mkdir: /builds/slave/test/properties 15:09:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:09:55 INFO - Writing to file /builds/slave/test/properties/build_url 15:09:55 INFO - Contents: 15:09:55 INFO - build_url:https://queue.taskcluster.net/v1/task/WtrTeq1cTLS3BnWBvBfGmw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 15:09:56 INFO - Running post-action listener: _resource_record_post_action 15:09:56 INFO - Running post-action listener: set_extra_try_arguments 15:09:56 INFO - [mozharness: 2016-09-29 22:09:56.326268Z] Finished download-and-extract step (success) 15:09:56 INFO - [mozharness: 2016-09-29 22:09:56.326420Z] Running populate-webroot step. 15:09:56 INFO - Running pre-action listener: _resource_record_pre_action 15:09:56 INFO - Running main action method: populate_webroot 15:09:56 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:09:56 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:09:56 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:09:56 INFO - '--tppagecycles', 15:09:56 INFO - '7'], 15:09:56 INFO - 'tests': ['tsvgm']}}, 15:09:56 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:09:56 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:09:56 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:09:56 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:09:56 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:09:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:09:56 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:09:56 INFO - 'talos_options': ['--disable-e10s'], 15:09:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:09:56 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:09:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:09:56 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:09:56 INFO - 'talos_options': ['--disable-e10s'], 15:09:56 INFO - 'tests': ['damp', 'tps']}, 15:09:56 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:09:56 INFO - 'tests': ['damp', 'tps']}, 15:09:56 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:09:56 INFO - 'tests': ['dromaeo_dom']}, 15:09:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:09:56 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:09:56 INFO - 'tests': ['basic_compositor_video']}, 15:09:56 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:09:56 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:09:56 INFO - 'tests': ['a11yr', 15:09:56 INFO - 'ts_paint', 15:09:56 INFO - 'tpaint', 15:09:56 INFO - 'sessionrestore', 15:09:56 INFO - 'sessionrestore_no_auto_restore', 15:09:56 INFO - 'tabpaint']}, 15:09:56 INFO - 'other-e10s': {'tests': ['a11yr', 15:09:56 INFO - 'ts_paint', 15:09:56 INFO - 'tpaint', 15:09:56 INFO - 'sessionrestore', 15:09:56 INFO - 'sessionrestore_no_auto_restore', 15:09:56 INFO - 'tabpaint']}, 15:09:56 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:09:56 INFO - 'ts_paint', 15:09:56 INFO - 'tpaint', 15:09:56 INFO - 'sessionrestore', 15:09:56 INFO - 'sessionrestore_no_auto_restore', 15:09:56 INFO - 'tabpaint']}, 15:09:56 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:09:56 INFO - 'ts_paint', 15:09:56 INFO - 'tpaint', 15:09:56 INFO - 'sessionrestore', 15:09:56 INFO - 'sessionrestore_no_auto_restore', 15:09:56 INFO - 'tabpaint']}, 15:09:56 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:09:56 INFO - 'tests': ['a11yr', 15:09:56 INFO - 'ts_paint', 15:09:56 INFO - 'tpaint', 15:09:56 INFO - 'sessionrestore', 15:09:56 INFO - 'sessionrestore_no_auto_restore', 15:09:56 INFO - 'tabpaint']}, 15:09:56 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:09:56 INFO - 'tests': ['a11yr', 15:09:56 INFO - 'ts_paint', 15:09:56 INFO - 'tpaint', 15:09:56 INFO - 'sessionrestore', 15:09:56 INFO - 'sessionrestore_no_auto_restore', 15:09:56 INFO - 'tabpaint']}, 15:09:56 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:09:56 INFO - 'tests': ['tsvgx', 15:09:56 INFO - 'tsvgr_opacity', 15:09:56 INFO - 'tart', 15:09:56 INFO - 'tscrollx', 15:09:56 INFO - 'cart']}, 15:09:56 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:09:56 INFO - 'tsvgr_opacity', 15:09:56 INFO - 'tart', 15:09:56 INFO - 'tscrollx', 15:09:56 INFO - 'cart']}, 15:09:56 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:09:56 INFO - 'talos_options': ['--disable-e10s'], 15:09:56 INFO - 'tests': ['tp5o']}, 15:09:56 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:09:56 INFO - 'tests': ['tp5o']}, 15:09:56 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:09:56 INFO - 'talos_options': ['--disable-e10s', 15:09:56 INFO - '--xperf_path', 15:09:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:09:56 INFO - 'tests': ['tp5n']}, 15:09:56 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:09:56 INFO - 'talos_options': ['--xperf_path', 15:09:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:09:56 INFO - 'tests': ['tp5n']}}, 15:09:56 INFO - 'talos.zip': {'path': '', 15:09:56 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:09:56 INFO - Running post-action listener: _resource_record_post_action 15:09:56 INFO - [mozharness: 2016-09-29 22:09:56.345164Z] Finished populate-webroot step (success) 15:09:56 INFO - [mozharness: 2016-09-29 22:09:56.345271Z] Running create-virtualenv step. 15:09:56 INFO - Running pre-action listener: _resource_record_pre_action 15:09:56 INFO - Running main action method: create_virtualenv 15:09:56 INFO - Creating virtualenv /builds/slave/test/build/venv 15:09:56 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 15:09:56 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:09:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:09:56 INFO - Using real prefix '/tools/python27' 15:09:56 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:09:57 INFO - Installing distribute.............................................................................................................................................................................................done. 15:10:00 INFO - Installing pip.................done. 15:10:00 INFO - Return code: 0 15:10:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:10:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:10:00 INFO - Reading from file tmpfile_stdout 15:10:00 INFO - Output received: 15:10:00 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) 15:10:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:10:00 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')]} 15:10:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:10:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:10:00 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')]} 15:10:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:10:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:10:00 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 0x1046c5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10463b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0c857f540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044d9d30>, '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 0x1042ffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1044d1b20>, '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.S3kfk0xSwh/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.KKXyX8aZme/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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 15:10:00 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 15:10:00 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 15:10:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.S3kfk0xSwh/Render', 15:10:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:10:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:10:00 INFO - 'HOME': '/Users/cltbld', 15:10:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:10:00 INFO - 'LOGNAME': 'cltbld', 15:10:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:10:00 INFO - 'MOZ_NO_REMOTE': '1', 15:10:00 INFO - 'NO_EM_RESTART': '1', 15:10:00 INFO - 'PAGER': '/bin/cat', 15:10:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:10:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:10:00 INFO - 'PWD': '/builds/slave/test', 15:10:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:10:00 INFO - 'SHELL': '/bin/bash', 15:10:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KKXyX8aZme/Listeners', 15:10:00 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 15:10:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:10:00 INFO - 'USER': 'cltbld', 15:10:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:10:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:10:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:10:00 INFO - 'XPC_FLAGS': '0x0', 15:10:00 INFO - 'XPC_SERVICE_NAME': '0', 15:10:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:10:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:10:00 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 15:10:00 INFO - Cleaning up... 15:10:00 INFO - Return code: 0 15:10:00 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:10:00 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')]} 15:10:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:10:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:10:00 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')]} 15:10:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:10:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:10:00 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 0x1046c5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10463b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0c857f540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044d9d30>, '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 0x1042ffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1044d1b20>, '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.S3kfk0xSwh/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.KKXyX8aZme/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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 15:10:00 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 15:10:00 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 15:10:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.S3kfk0xSwh/Render', 15:10:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:10:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:10:00 INFO - 'HOME': '/Users/cltbld', 15:10:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:10:00 INFO - 'LOGNAME': 'cltbld', 15:10:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:10:00 INFO - 'MOZ_NO_REMOTE': '1', 15:10:00 INFO - 'NO_EM_RESTART': '1', 15:10:00 INFO - 'PAGER': '/bin/cat', 15:10:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:10:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:10:00 INFO - 'PWD': '/builds/slave/test', 15:10:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:10:00 INFO - 'SHELL': '/bin/bash', 15:10:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KKXyX8aZme/Listeners', 15:10:00 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 15:10:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:10:00 INFO - 'USER': 'cltbld', 15:10:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:10:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:10:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:10:00 INFO - 'XPC_FLAGS': '0x0', 15:10:00 INFO - 'XPC_SERVICE_NAME': '0', 15:10:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:10:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:10:00 INFO - Downloading/unpacking psutil>=3.1.1 15:10:00 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 15:10:00 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 15:10:00 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 15:10:00 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 15:10:00 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 15:10:00 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 15:10:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:10:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:10:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:10:04 INFO - Installing collected packages: psutil 15:10:04 INFO - Running setup.py install for psutil 15:10:04 INFO - building 'psutil._psutil_osx' extension 15:10:04 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 15:10:04 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 15:10:04 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 15:10:04 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 15:10:05 INFO - building 'psutil._psutil_posix' extension 15:10:05 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 15:10:05 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:10:05 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:10:05 INFO - ^ 15:10:05 INFO - 1 warning generated. 15:10:05 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 15:10:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:10:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:10:05 INFO - Successfully installed psutil 15:10:05 INFO - Cleaning up... 15:10:05 INFO - Return code: 0 15:10:05 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:10:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:10:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:10:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:10:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:10:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:10:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:10:05 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 0x1046c5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10463b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0c857f540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044d9d30>, '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 0x1042ffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1044d1b20>, '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.S3kfk0xSwh/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.KKXyX8aZme/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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 15:10:05 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 15:10:05 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 15:10:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.S3kfk0xSwh/Render', 15:10:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:10:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:10:05 INFO - 'HOME': '/Users/cltbld', 15:10:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:10:05 INFO - 'LOGNAME': 'cltbld', 15:10:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:10:05 INFO - 'MOZ_NO_REMOTE': '1', 15:10:05 INFO - 'NO_EM_RESTART': '1', 15:10:05 INFO - 'PAGER': '/bin/cat', 15:10:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:10:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:10:05 INFO - 'PWD': '/builds/slave/test', 15:10:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:10:05 INFO - 'SHELL': '/bin/bash', 15:10:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KKXyX8aZme/Listeners', 15:10:05 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 15:10:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:10:05 INFO - 'USER': 'cltbld', 15:10:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:10:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:10:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:10:05 INFO - 'XPC_FLAGS': '0x0', 15:10:05 INFO - 'XPC_SERVICE_NAME': '0', 15:10:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:10:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:10:05 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:10:05 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 15:10:05 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 15:10:05 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 15:10:05 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 15:10:05 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 15:10:05 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 15:10:08 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:10:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:10:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:10:08 INFO - Installing collected packages: mozsystemmonitor 15:10:08 INFO - Running setup.py install for mozsystemmonitor 15:10:08 INFO - Successfully installed mozsystemmonitor 15:10:08 INFO - Cleaning up... 15:10:08 INFO - Return code: 0 15:10:08 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:10:08 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')]} 15:10:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:10:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:10:08 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')]} 15:10:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:10:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:10:08 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 0x1046c5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10463b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0c857f540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044d9d30>, '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 0x1042ffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1044d1b20>, '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.S3kfk0xSwh/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.KKXyX8aZme/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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 15:10:08 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 15:10:08 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 15:10:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.S3kfk0xSwh/Render', 15:10:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:10:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:10:08 INFO - 'HOME': '/Users/cltbld', 15:10:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:10:08 INFO - 'LOGNAME': 'cltbld', 15:10:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:10:08 INFO - 'MOZ_NO_REMOTE': '1', 15:10:08 INFO - 'NO_EM_RESTART': '1', 15:10:08 INFO - 'PAGER': '/bin/cat', 15:10:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:10:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:10:08 INFO - 'PWD': '/builds/slave/test', 15:10:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:10:08 INFO - 'SHELL': '/bin/bash', 15:10:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KKXyX8aZme/Listeners', 15:10:08 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 15:10:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:10:08 INFO - 'USER': 'cltbld', 15:10:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:10:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:10:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:10:08 INFO - 'XPC_FLAGS': '0x0', 15:10:08 INFO - 'XPC_SERVICE_NAME': '0', 15:10:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:10:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:10:08 INFO - Downloading/unpacking jsonschema==2.5.1 15:10:08 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 15:10:08 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 15:10:08 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 15:10:08 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 15:10:08 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 15:10:08 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 15:10:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:10:11 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:10:11 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 15:10:11 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 15:10:11 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 15:10:11 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 15:10:11 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 15:10:11 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 15:10:11 INFO - Downloading functools32-3.2.3-2.tar.gz 15:10:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:10:11 INFO - warning: no files found matching '*.txt' 15:10:11 INFO - no previously-included directories found matching 'build' 15:10:11 INFO - no previously-included directories found matching 'dist' 15:10:11 INFO - no previously-included directories found matching '.git*' 15:10:11 INFO - Installing collected packages: jsonschema, functools32 15:10:11 INFO - Running setup.py install for functools32 15:10:11 INFO - warning: no files found matching '*.txt' 15:10:11 INFO - no previously-included directories found matching 'build' 15:10:11 INFO - no previously-included directories found matching 'dist' 15:10:11 INFO - no previously-included directories found matching '.git*' 15:10:11 INFO - Successfully installed jsonschema functools32 15:10:11 INFO - Cleaning up... 15:10:11 INFO - Return code: 0 15:10:11 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:10:11 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')]} 15:10:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:10:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:10:11 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')]} 15:10:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:10:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:10:11 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 0x1046c5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10463b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0c857f540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044d9d30>, '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 0x1042ffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1044d1b20>, '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.S3kfk0xSwh/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.KKXyX8aZme/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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 15:10:11 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 15:10:11 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 15:10:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.S3kfk0xSwh/Render', 15:10:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:10:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:10:11 INFO - 'HOME': '/Users/cltbld', 15:10:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:10:11 INFO - 'LOGNAME': 'cltbld', 15:10:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:10:11 INFO - 'MOZ_NO_REMOTE': '1', 15:10:11 INFO - 'NO_EM_RESTART': '1', 15:10:11 INFO - 'PAGER': '/bin/cat', 15:10:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:10:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:10:12 INFO - 'PWD': '/builds/slave/test', 15:10:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:10:12 INFO - 'SHELL': '/bin/bash', 15:10:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KKXyX8aZme/Listeners', 15:10:12 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 15:10:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:10:12 INFO - 'USER': 'cltbld', 15:10:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:10:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:10:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:10:12 INFO - 'XPC_FLAGS': '0x0', 15:10:12 INFO - 'XPC_SERVICE_NAME': '0', 15:10:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:10:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:10:12 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:10:12 INFO - Cleaning up... 15:10:12 INFO - Return code: 0 15:10:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:10:12 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')]} 15:10:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:10:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:10:12 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')]} 15:10:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:10:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:10:12 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 0x1046c5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10463b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0c857f540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044d9d30>, '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 0x1042ffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1044d1b20>, '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.S3kfk0xSwh/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.KKXyX8aZme/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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 15:10:12 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 15:10:12 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 15:10:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.S3kfk0xSwh/Render', 15:10:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:10:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:10:12 INFO - 'HOME': '/Users/cltbld', 15:10:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:10:12 INFO - 'LOGNAME': 'cltbld', 15:10:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:10:12 INFO - 'MOZ_NO_REMOTE': '1', 15:10:12 INFO - 'NO_EM_RESTART': '1', 15:10:12 INFO - 'PAGER': '/bin/cat', 15:10:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:10:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:10:12 INFO - 'PWD': '/builds/slave/test', 15:10:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:10:12 INFO - 'SHELL': '/bin/bash', 15:10:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KKXyX8aZme/Listeners', 15:10:12 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 15:10:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:10:12 INFO - 'USER': 'cltbld', 15:10:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:10:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:10:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:10:12 INFO - 'XPC_FLAGS': '0x0', 15:10:12 INFO - 'XPC_SERVICE_NAME': '0', 15:10:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:10:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:10:12 INFO - Downloading/unpacking blobuploader==1.2.4 15:10:12 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 15:10:12 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 15:10:12 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 15:10:12 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 15:10:12 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 15:10:12 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 15:10:15 INFO - Downloading blobuploader-1.2.4.tar.gz 15:10:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:10:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:10:15 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 15:10:15 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 15:10:15 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 15:10:15 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 15:10:15 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 15:10:15 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 15:10:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:10:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:10:15 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 15:10:15 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 15:10:15 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 15:10:15 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 15:10:15 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 15:10:15 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 15:10:16 INFO - Downloading docopt-0.6.1.tar.gz 15:10:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:10:16 INFO - Installing collected packages: blobuploader, requests, docopt 15:10:16 INFO - Running setup.py install for blobuploader 15:10:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:10:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:10:16 INFO - Running setup.py install for requests 15:10:17 INFO - Running setup.py install for docopt 15:10:17 INFO - Successfully installed blobuploader requests docopt 15:10:17 INFO - Cleaning up... 15:10:17 INFO - Return code: 0 15:10:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:10:17 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')]} 15:10:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:10:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:10:17 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')]} 15:10:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:10:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:10:17 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 0x1046c5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10463b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0c857f540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044d9d30>, '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 0x1042ffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1044d1b20>, '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.S3kfk0xSwh/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.KKXyX8aZme/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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 15:10:17 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 15:10:17 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 15:10:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.S3kfk0xSwh/Render', 15:10:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:10:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:10:17 INFO - 'HOME': '/Users/cltbld', 15:10:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:10:17 INFO - 'LOGNAME': 'cltbld', 15:10:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:10:17 INFO - 'MOZ_NO_REMOTE': '1', 15:10:17 INFO - 'NO_EM_RESTART': '1', 15:10:17 INFO - 'PAGER': '/bin/cat', 15:10:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:10:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:10:17 INFO - 'PWD': '/builds/slave/test', 15:10:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:10:17 INFO - 'SHELL': '/bin/bash', 15:10:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KKXyX8aZme/Listeners', 15:10:17 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 15:10:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:10:17 INFO - 'USER': 'cltbld', 15:10:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:10:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:10:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:10:17 INFO - 'XPC_FLAGS': '0x0', 15:10:17 INFO - 'XPC_SERVICE_NAME': '0', 15:10:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:10:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:10:17 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-xIuvMy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:10:17 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-4yEeTX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:10:17 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-tzH3WM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:10:17 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-kjbaKu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:10:17 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-fRDGX8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:10:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:10:18 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-1Hr2Zg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:10:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:10:18 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-BanAvp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:10:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:10:18 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-xThdYb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:10:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:10:18 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-UrF1dq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:10:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:10:18 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-WntPHz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:10:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:10:18 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-5w8FSx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:10:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:10:18 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-s3udc5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:10:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:10:18 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-sqS2RU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:10:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:10:18 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-TQcvDG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:10:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:10:19 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-cmMYQ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:10:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:10:19 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-JLAAD1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:10:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:10:19 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-GFl74X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:10:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:10:19 INFO - Running setup.py install for manifestparser 15:10:19 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:10:19 INFO - Running setup.py install for mozcrash 15:10:19 INFO - Running setup.py install for mozdebug 15:10:19 INFO - Running setup.py install for mozdevice 15:10:19 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:10:19 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:10:19 INFO - Running setup.py install for mozfile 15:10:20 INFO - Running setup.py install for mozhttpd 15:10:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:10:20 INFO - Running setup.py install for mozinfo 15:10:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:10:20 INFO - Running setup.py install for mozInstall 15:10:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:10:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:10:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:10:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:10:20 INFO - Running setup.py install for mozleak 15:10:20 INFO - Running setup.py install for mozlog 15:10:20 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:10:20 INFO - Running setup.py install for moznetwork 15:10:20 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:10:20 INFO - Running setup.py install for mozprocess 15:10:21 INFO - Running setup.py install for mozprofile 15:10:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:10:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:10:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:10:21 INFO - Running setup.py install for mozrunner 15:10:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:10:21 INFO - Running setup.py install for mozscreenshot 15:10:21 INFO - Running setup.py install for moztest 15:10:21 INFO - Running setup.py install for mozversion 15:10:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:10:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:10:21 INFO - Cleaning up... 15:10:21 INFO - Return code: 0 15:10:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:10: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')]} 15:10:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:10:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:10: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')]} 15:10:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:10:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:10:21 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 0x1046c5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10463b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0c857f540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044d9d30>, '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 0x1042ffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1044d1b20>, '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.S3kfk0xSwh/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.KKXyX8aZme/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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 15:10:21 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 15:10:21 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 15:10:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.S3kfk0xSwh/Render', 15:10:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:10:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:10:21 INFO - 'HOME': '/Users/cltbld', 15:10:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:10:21 INFO - 'LOGNAME': 'cltbld', 15:10:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:10:21 INFO - 'MOZ_NO_REMOTE': '1', 15:10:21 INFO - 'NO_EM_RESTART': '1', 15:10:21 INFO - 'PAGER': '/bin/cat', 15:10:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:10:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:10:21 INFO - 'PWD': '/builds/slave/test', 15:10:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:10:21 INFO - 'SHELL': '/bin/bash', 15:10:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KKXyX8aZme/Listeners', 15:10:21 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 15:10:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:10:21 INFO - 'USER': 'cltbld', 15:10:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:10:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:10:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:10:21 INFO - 'XPC_FLAGS': '0x0', 15:10:21 INFO - 'XPC_SERVICE_NAME': '0', 15:10:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:10:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:10:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:10:22 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-XzwvTH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:10:22 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)) 15:10:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:10:22 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-gRASyU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:10:22 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)) 15:10:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:10:22 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-ybuHb5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:10:22 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)) 15:10:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:10:22 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-A_mlyN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:10:22 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)) 15:10:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:10:22 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-FoMsge-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:10:22 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)) 15:10:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:10:22 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-Azkk0_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:10:22 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)) 15:10:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:10:22 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-aNValj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:10:22 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)) 15:10:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:10:22 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-TcuhgM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:10:23 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)) 15:10:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:10:23 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-dEbJHL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:10:23 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)) 15:10:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:10:23 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-gfq9c5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:10:23 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)) 15:10:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:10:23 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-J6vPP1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:10:23 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)) 15:10:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:10:23 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-9ffgwg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:10:23 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)) 15:10:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:10:23 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-NjykUh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:10:23 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)) 15:10:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:10:23 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-nP_K3Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:10:23 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)) 15:10:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:10:23 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-R84ftn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:10:23 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)) 15:10:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:10:23 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-XkEISm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:10:23 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)) 15:10:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:10:23 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-1yda_P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:10:24 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)) 15:10:24 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)) 15:10:24 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)) 15:10:24 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)) 15:10:24 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)) 15:10:24 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)) 15:10:24 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:10:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:10:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:10:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:10:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:10:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:10:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:10:26 INFO - Downloading blessings-1.6.tar.gz 15:10:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:10:26 INFO - Installing collected packages: blessings 15:10:26 INFO - Running setup.py install for blessings 15:10:26 INFO - Successfully installed blessings 15:10:26 INFO - Cleaning up... 15:10:26 INFO - Return code: 0 15:10:26 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:10:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:10:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:10:27 INFO - Reading from file tmpfile_stdout 15:10:27 INFO - Current package versions: 15:10:27 INFO - blessings == 1.6 15:10:27 INFO - blobuploader == 1.2.4 15:10:27 INFO - docopt == 0.6.1 15:10:27 INFO - functools32 == 3.2.3-2 15:10:27 INFO - jsonschema == 2.5.1 15:10:27 INFO - manifestparser == 1.1 15:10:27 INFO - mozInstall == 1.12 15:10:27 INFO - mozcrash == 0.17 15:10:27 INFO - mozdebug == 0.1 15:10:27 INFO - mozdevice == 0.48 15:10:27 INFO - mozfile == 1.2 15:10:27 INFO - mozhttpd == 0.7 15:10:27 INFO - mozinfo == 0.9 15:10:27 INFO - mozleak == 0.1 15:10:27 INFO - mozlog == 3.4 15:10:27 INFO - moznetwork == 0.27 15:10:27 INFO - mozprocess == 0.23 15:10:27 INFO - mozprofile == 0.28 15:10:27 INFO - mozrunner == 6.12 15:10:27 INFO - mozscreenshot == 0.1 15:10:27 INFO - mozsystemmonitor == 0.3 15:10:27 INFO - moztest == 0.7 15:10:27 INFO - mozversion == 1.4 15:10:27 INFO - psutil == 3.1.1 15:10:27 INFO - requests == 1.2.3 15:10:27 INFO - wsgiref == 0.1.2 15:10:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:10: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')]} 15:10:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:10:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:10: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')]} 15:10:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:10:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:10:27 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 0x1046c5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10463b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0c857f540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044d9d30>, '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 0x1042ffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1044d1b20>, '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.S3kfk0xSwh/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.KKXyX8aZme/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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 15:10:27 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 15:10:27 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 15:10:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.S3kfk0xSwh/Render', 15:10:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:10:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:10:27 INFO - 'HOME': '/Users/cltbld', 15:10:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:10:27 INFO - 'LOGNAME': 'cltbld', 15:10:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:10:27 INFO - 'MOZ_NO_REMOTE': '1', 15:10:27 INFO - 'NO_EM_RESTART': '1', 15:10:27 INFO - 'PAGER': '/bin/cat', 15:10:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:10:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:10:27 INFO - 'PWD': '/builds/slave/test', 15:10:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:10:27 INFO - 'SHELL': '/bin/bash', 15:10:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KKXyX8aZme/Listeners', 15:10:27 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 15:10:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:10:27 INFO - 'USER': 'cltbld', 15:10:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:10:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:10:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:10:27 INFO - 'XPC_FLAGS': '0x0', 15:10:27 INFO - 'XPC_SERVICE_NAME': '0', 15:10:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:10:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:10:27 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)) 15:10:27 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)) 15:10:27 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)) 15:10:27 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)) 15:10:27 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)) 15:10:27 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)) 15:10:27 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)) 15:10:27 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)) 15:10:27 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)) 15:10:27 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)) 15:10:27 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)) 15:10:27 INFO - Cleaning up... 15:10:27 INFO - Return code: 0 15:10:27 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:10: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')]} 15:10:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:10:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:10: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')]} 15:10:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:10:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:10: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1046c5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10463b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0c857f540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044d9d30>, '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 0x1042ffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1044d1b20>, '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.S3kfk0xSwh/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.KKXyX8aZme/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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 15:10: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', 'jsonschema'] in /builds/slave/test/build 15:10: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 jsonschema 15:10:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.S3kfk0xSwh/Render', 15:10:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:10:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:10:27 INFO - 'HOME': '/Users/cltbld', 15:10:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:10:27 INFO - 'LOGNAME': 'cltbld', 15:10:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:10:27 INFO - 'MOZ_NO_REMOTE': '1', 15:10:27 INFO - 'NO_EM_RESTART': '1', 15:10:27 INFO - 'PAGER': '/bin/cat', 15:10:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:10:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:10:27 INFO - 'PWD': '/builds/slave/test', 15:10:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:10:27 INFO - 'SHELL': '/bin/bash', 15:10:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KKXyX8aZme/Listeners', 15:10:27 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 15:10:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:10:27 INFO - 'USER': 'cltbld', 15:10:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:10:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:10:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:10:27 INFO - 'XPC_FLAGS': '0x0', 15:10:27 INFO - 'XPC_SERVICE_NAME': '0', 15:10:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:10:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:10:27 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:10:27 INFO - Cleaning up... 15:10:27 INFO - Return code: 0 15:10:27 INFO - Running post-action listener: _resource_record_post_action 15:10:27 INFO - Running post-action listener: _start_resource_monitoring 15:10:27 INFO - Starting resource monitoring. 15:10:27 INFO - [mozharness: 2016-09-29 22:10:27.750438Z] Finished create-virtualenv step (success) 15:10:27 INFO - [mozharness: 2016-09-29 22:10:27.751486Z] Running install step. 15:10:27 INFO - Running pre-action listener: _resource_record_pre_action 15:10:27 INFO - Running main action method: install 15:10:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:10:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:10:28 INFO - Reading from file tmpfile_stdout 15:10:28 INFO - Detecting whether we're running mozinstall >=1.0... 15:10:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:10:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:10:28 INFO - Reading from file tmpfile_stdout 15:10:28 INFO - Output received: 15:10:28 INFO - Usage: mozinstall [options] installer 15:10:28 INFO - Options: 15:10:28 INFO - -h, --help show this help message and exit 15:10:28 INFO - -d DEST, --destination=DEST 15:10:28 INFO - Directory to install application into. [default: 15:10:28 INFO - "/builds/slave/test"] 15:10:28 INFO - --app=APP Application being installed. [default: firefox] 15:10:28 INFO - mkdir: /builds/slave/test/build/application 15:10:28 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'] 15:10:28 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 15:11:02 INFO - Reading from file tmpfile_stdout 15:11:02 INFO - Output received: 15:11:02 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:11:02 INFO - Running post-action listener: _resource_record_post_action 15:11:02 INFO - [mozharness: 2016-09-29 22:11:02.434376Z] Finished install step (success) 15:11:02 INFO - [mozharness: 2016-09-29 22:11:02.434515Z] Running run-tests step. 15:11:02 INFO - Running pre-action listener: _resource_record_pre_action 15:11:02 INFO - Running main action method: run_tests 15:11:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:11:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:11:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:11:02 INFO - 2016-09-29 15:11:02.449 screenresolution[1632:7441] starting screenresolution argv=screenresolution get 15:11:02 INFO - 2016-09-29 15:11:02.451 screenresolution[1632:7441] Display 0: 1600x1200x32@60 15:11:02 INFO - 2016-09-29 15:11:02.457 screenresolution[1633:7443] starting screenresolution argv=screenresolution list 15:11:02 INFO - Available Modes on Display 0 15:11:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:11:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:11:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:11:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:11:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:11:02 INFO - Intel Iris: 15:11:02 INFO - Chipset Model: Intel Iris 15:11:02 INFO - Type: GPU 15:11:02 INFO - Bus: Built-In 15:11:02 INFO - VRAM (Dynamic, Max): 1536 MB 15:11:02 INFO - Vendor: Intel (0x8086) 15:11:02 INFO - Device ID: 0x0a2e 15:11:02 INFO - Revision ID: 0x0009 15:11:02 INFO - Displays: 15:11:02 INFO - Display: 15:11:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:11:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:11:02 INFO - Display Serial Number: 1600x1200 60 15:11:02 INFO - Main Display: Yes 15:11:02 INFO - Mirror: Off 15:11:02 INFO - Online: Yes 15:11:02 INFO - Rotation: Supported 15:11:02 INFO - Return code: 0 15:11:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:11:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:11:02 INFO - Python 2.7.3 15:11:02 INFO - Return code: 0 15:11:02 INFO - grabbing minidump binary from tooltool 15:11:02 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')]} 15:11:02 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 0x1044d9d30>, '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 0x1042ffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1044d1b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:11:02 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 15:11:02 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 15:11:02 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 15:11:02 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:11:02 INFO - Return code: 0 15:11:02 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:11:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:11:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:11:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:11:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:11:02 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/WtrTeq1cTLS3BnWBvBfGmw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0072', '--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 15:11:02 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/WtrTeq1cTLS3BnWBvBfGmw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0072 --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 15:11:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.S3kfk0xSwh/Render', 15:11:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:11:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:11:02 INFO - 'HOME': '/Users/cltbld', 15:11:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:11:02 INFO - 'LOGNAME': 'cltbld', 15:11:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:11:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:11:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:11:02 INFO - 'MOZ_NO_REMOTE': '1', 15:11:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:11:02 INFO - 'NO_EM_RESTART': '1', 15:11:02 INFO - 'PAGER': '/bin/cat', 15:11:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:11:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:11:02 INFO - 'PWD': '/builds/slave/test', 15:11:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:11:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:11:02 INFO - 'SHELL': '/bin/bash', 15:11:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KKXyX8aZme/Listeners', 15:11:02 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 15:11:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:11:02 INFO - 'USER': 'cltbld', 15:11:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:11:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:11:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:11:02 INFO - 'XPC_FLAGS': '0x0', 15:11:02 INFO - 'XPC_SERVICE_NAME': '0', 15:11:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:11:02 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/WtrTeq1cTLS3BnWBvBfGmw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0072', '--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 15:11:03 INFO - mozversion application_buildid: 20160929125319 15:11:03 INFO - mozversion application_changeset: 500496dfefccc73d1f87ff912563a8ee1afe8e91 15:11:03 INFO - mozversion application_display_name: Nightly 15:11:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:11:03 INFO - mozversion application_name: Firefox 15:11:03 INFO - mozversion application_remotingname: firefox 15:11:03 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 15:11:03 INFO - mozversion application_vendor: Mozilla 15:11:03 INFO - mozversion application_version: 52.0a1 15:11:03 INFO - mozversion platform_buildid: 20160929125319 15:11:03 INFO - mozversion platform_changeset: 500496dfefccc73d1f87ff912563a8ee1afe8e91 15:11:03 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 15:11:03 INFO - mozversion platform_version: 52.0a1 15:11:03 INFO - using testdate: 1475187063 15:11:03 INFO - actual date: 1475187063 15:11:03 INFO - starting webserver on 'localhost:49278' 15:11:03 INFO - SUITE-START | Running 2 tests 15:11:03 INFO - TEST-START | dromaeo_css 15:11:03 INFO - operating with platform_type : mac_ 15:11:03 INFO - Initialising browser for dromaeo_css test... 15:11:03 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/tmpacBNaE/profile http://localhost:49278/getInfo.html) 15:11:05 INFO - PROCESS | 1643 | __metrics Screen width/height:1600/1200 15:11:05 INFO - PROCESS | 1643 | colorDepth:24 15:11:05 INFO - PROCESS | 1643 | Browser inner width/height: 1024/689 15:11:05 INFO - PROCESS | 1643 | __metrics 15:11:05 INFO - PROCESS | 1643 | 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" 15:11:05 INFO - PROCESS | 1643 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:11:06 INFO - TEST-INFO | 1643: exit 0 15:11:06 INFO - Browser initialized. 15:11:06 INFO - Running cycle 1/1 for dromaeo_css test... 15:11:06 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/tmpacBNaE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:11:18 INFO - PROCESS | 1645 | RSS: Main: 236097536 15:11:18 INFO - PROCESS | 1645 | 15:12:10 INFO - PROCESS | 1645 | 1475187130133 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] 15:14:20 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49278/tests/dromaeo/cssquery-ext.html) 15:14:23 INFO - PROCESS | 1645 | RSS: Main: 447336448 15:14:23 INFO - PROCESS | 1645 | 15:17:26 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-ext.html (next: http://localhost:49278/tests/dromaeo/cssquery-jquery.html) 15:17:27 INFO - PROCESS | 1645 | RSS: Main: 375197696 15:17:27 INFO - PROCESS | 1645 | 15:20:29 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49278/tests/dromaeo/cssquery-mootools.html) 15:20:30 INFO - PROCESS | 1645 | RSS: Main: 362164224 15:20:30 INFO - PROCESS | 1645 | 15:23:32 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49278/tests/dromaeo/cssquery-prototype.html) 15:23:32 INFO - PROCESS | 1645 | RSS: Main: 340393984 15:23:32 INFO - PROCESS | 1645 | 15:26:36 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49278/tests/dromaeo/cssquery-yui.html) 15:26:36 INFO - PROCESS | 1645 | RSS: Main: 335204352 15:26:36 INFO - PROCESS | 1645 | 15:29:40 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-yui.html (next: http://localhost:49278/tests/dromaeo/cssquery-dojo.html) 15:29:40 INFO - PROCESS | 1645 | RSS: Main: 505831424 15:29:40 INFO - PROCESS | 1645 | 15:29:40 INFO - PROCESS | 1645 | __start_tp_report 15:29:40 INFO - PROCESS | 1645 | _x_x_mozilla_page_load 15:29:40 INFO - PROCESS | 1645 | _x_x_mozilla_page_load_details 15:29:40 INFO - PROCESS | 1645 | |i|pagename|runs| 15:29:40 INFO - PROCESS | 1645 | |0;dojo.html;3314.2045908981845;3319.07066021514;3327.767056306058;3361.613414457338;3391.7286617070636;5611.466910643492;5642.322921249451;5685.061964775807;5708.800191993281;5737.827865164047;5662.546996240301;5666.206731057655;5745.540356771458;5799.3620701722775;5852.092925596528;5153.330067091273;5194.091034069042;5220.530152286294;5231.686098834082;5261.473852614727;5194.065068287707;5307.681539107647;5307.840764777044;5327.147656374981;5394.865128371783;5295.68225906443;5321.467853214686;5336.466353364672;5359.222912677659;5382.407935127152;5123.87190320245;5169.844904652867;5170.508801663836;5176;5248.921266181011;4308.181445525372;5183.740812959375;5282.577393808486;5285.339332583427;5291.1534154535475;5692;5699.202111704365;5710.086386178233;5727.914081288784;5729.083346664555;6103.084537319393;6117.3402608778015;6245.906311405332;6248.781292654735;6250.624962502265;4576.290674945389;6206.317305096436;6258.405451482102;6281.654509002003;6346.587471814363;4816.686915350561;5750.166225897222;5800.564957628152;5800.564957628195;5843.8539036524;6327.303996560375;6344.080108384258;6360.395762402564;6372.203474565679;6386.9361306386345;5876.941230587725;5952.7321270542925;5995.100734889801;6014.969925150346;6034.366391528914;6154.19995400604;6180.505559555225;6182.474489668339;6185.752569897154;6187.84530386744;6092.817215483543;6103.755849765959;6107.249501910372;6110.052941794029;6118.908216376758;6040.909386359208;6049.304330002018;6100.389961003864;6110.236220472441;6132.570720049643;4895.951040489549;4914.8034078637165;4918.163912134945;4919.114559379346;4945.67853089548;4356.869293921188;4404.339349097661;4466.2853943369555;4473.843415480443;4475.7985890635;5717.342505611908;5723.427657234243;5728.598998070095;5733.397993210736;5738.540916726652;6186.072089186568;6247.219097612799;6275.215598050244;6349.873002539924;6354.745810167541;5766.933117373215;5767.307923049261;5776.104703770922;5793.478586927196;5851.502622277154;5300.946990530122;5386.245925570423;5402.594805389612;5408.242846001563;5420.24116623673;6301.905471418024;6310;6357.14178585885;6361.01404282337;6377.64922929234;6296.433321001132;6307.526935479766;6318.8736225274315;6321.936780632135;6322.905156422749;6334.208223972004;6350.650714210763;6355.904661430175;6373.490120790233;6385.521085918667;5775.5272405538;6045.365236650088;6127.877442451215;6142.416470435302;6167;6180.629162250099;6241.095041219089;6265.122882796503;6289.622622642656;6292.182016337847;6061.454469097803;6111.908321375094;6112.99135642614;6115.7247923844425;6120.510359171345;4584.426946631671;5909.5863289569315;5970.910436343545;6032.065029920369;6096.177016102769;5852.502537284378;5857.824265272048;5869.6771677558145;5871.2073870027;5877.617954832922;6040.637561746309;6043.244594425697;6059.6364218147055;6060.181875446759;6063.090536419396;5831;5866.413358664099;5884.882302353843;5889.528837692888;5897.528197744256 15:29:40 INFO - PROCESS | 1645 | |1;ext.html;522.3209827224608;525.4377815737162;528.725062967257;535.1437699680514;554.2379228560729;31306.373872522563;32846;41520.16959660809;41859;43941.78029109858;42743.3588496172;43197.13605727883;43207.56792432075;43314.566854331446;43391;8712.085231050743;9721.562529686164;9890.604375824958;9898.50507474627;9905.108540231378;8818.162274583905;11902.34537100459;12209.145359824815;12226.694332641666;12344.93827530861;12138.968187704057;12174.63476095714;12186.451609677586;12189.51241950321;12198.512059517609;8817.603207855598;9049.864252036159;12189.451474683661;12252.264864108181;12282.447289871978;11643.476043578059;12037.157398982075;12057.93971030148;12163.695907602292;12167.2699638022;40682.38976415356;41068.1786364274;41082;41170.176596468205;41188.79405602983;3268.4391037925984;3290.19390249388;3377.4933759936207;3427.365937301607;3668.7248456365614;30670;31013.224669383446;31244.218894527592;31422;31691.524627130613;3230.644629090798;3247.951280730791;3283.8029718216985;3363.764536482447;3370.376480351143;409987;410996;412561;413339;417573;33117.66882331146;57186;57513.424865751644;57655.71172144113;57685;34330.656693433244;35342;35530.82234588862;35573;35595.93212203638;14546.781798273034;14637.487687931085;14641.48754793577;14654.413823447156;14669.486567970283;11234.438278086063;11477.36874471912;11486.712832179262;12378;12477.064220183342;7080.610566418896;7229.7347964106075;7564.735234266885;7731.381489480828;7815.765527034198;3189.489681650925;3233.224026233734;3243.8053716777067;3265.248992731685;3292.1440425489545;719.5754504842168;784.7724159993652;858.5621333120154;932.4871320773548;989.9950500247453;23868.52262954731;28300;30099.247518812204;30930.69069309323;31074;765.9540427574252;977.941323520591;988.2143695761887;1038.6987773545738;1054.2409465185056;14020.158790472604;15306.464273750365;15370;15711.21443927799;15719.528414147593;659413;664211;666387;666886;666966;56263.156052659244;56746.71626641841;56796;56805;56808.43191568114;1138.3909608359488;1216.7262365967586;1264.37413480326;1343.3954720375675;1378.4141739760362;29602;30526.847365763027;30849.845750771103;31198.68801311958;31276;48942;49613.25580116229;49707;49764.75117624389;49788;556875;560876;561842;562324;564032;26530.336741581614;27350.726492735615;27444.725552745014;28030.43939121247;28070.71929280681;46452.53547464482;46573.602791916295;47069.764651177895;47309;48015;38834.41748373833;39071.609283907936;39077.6092239074;39774.80112599534;40690.79654601708;38415.42376864293;38707;38816.80591597024;39687.603123969544;39957.60042399539;38202.617973819906;38537.80731096327;38805.611943881326;38847.6115238844;38980.6101938977;30733;32079.03762887117;32427.027189184362;34335.65664343325;34487.82756086203;22401.43996400038;25310.367240819127;25318.36704082339;25644;25781.742182577935 15:29:40 INFO - PROCESS | 1645 | |2;jquery.html;339.3061189866723;343.2534238032279;366.2181243046096;371.4817829128366;395.0656696911803;15017.32422041008;15229.314680839361;16012.359505619761;20331.49171270719;20816.375508734764;19675.5081122972;20208.797912020913;20871.37385878426;20984.790152098474;21215.36353909385;5825.563082768788;5976.89427455922;6088.117223002662;6092.11664308675;6100.450959413652;4769.809207631691;6132.41742034506;6179;6180.474659653913;6182.752689892399;5837.562182836305;5978.8206353809455;5987.55093367997;6077.209962704842;6125.050617154348;5989.970050149765;6046.848828779272;6066.241719785027;6069.848253793691;6078.574499785017;5136.563392111675;5268.6575372600655;5277.47225277473;5406.648567843077;5469.15228139639;12807.487700491969;13037.739245215138;13071.08502404832;14870.851291487055;24899.377515562075;2173.652215645489;2244.2145249162827;2413.963790543143;2727.5635898256182;2932.19364674714;8082.757517274491;9718.757031074208;10187.083162515335;11546.307221566733;15301.234938253043;1476.372541669786;1808.7106063029853;1819.4996375996707;1840.291487777202;1841.558026073746;111167.44416277947;116488.60381056202;119144.40427797892;119872;120599;11362.147838912115;11406.885931140583;11594.130440217017;11600;12057.397130143634;14258.786118208236;14268;14283.64290892736;14291.356888940023;14302.642433939234;8249.546274954815;8416.705415310435;8439.451435656785;8447.408681392366;8561.01548321951;8355.997280326279;8364.53995030267;8494.660213591511;8535.701250456204;8577.05652378238;3879.359905615598;4340.414044104005;4462.9776851116185;4527.207738645724;4608.1474927137915;1603.2684700523407;1825.5892424204449;1878.811676466638;1927.739755133067;1944.1737261663736;689.2829377714107;740.8444226712343;826.6982551368707;886.2953951608487;989.6536212325628;19447.805521944883;21517.54587499633;21783.891080544498;21856.016479258473;22006.009729562207;768.769369189241;841.3984001438878;888.9288856891433;920.9677661281823;925.2413021322451;10970.835437468444;11234.213605047731;11357.829632555518;11370.488328025258;11456.312621242585;551570;591160;594326;596174;599660;120763.99999999824;120885.39557302333;132996.20481056385;136998.3523890343;143735.84396234064;952.6998995316453;982.3958265666564;1013.1084645511902;1013.9391636501833;1015.355249416611;19426.028698565297;20081.196752130334;20090.899545502765;20098.085777562465;20181.6972745406;50441.24338134858;50690;51591;54753;55648.261554227036;71057.34139878853;76035;77470.61264693641;78125.60937195506;78782.81825772733;7302.817429564303;7983.20167983197;8131;8195.549244791355;8268.669253230044;11417.771644566896;13023.804642930174;13054.869451305365;13451;13518.459261629425;10300.845487317845;10786;11322.60370887015;11698.76602467929;11774.587889423829;11237.213395062266;11336.433178341214;11340;11728.648140555797;11761.294322340687;9030.593623287099;11436.428178591204;11545.365004924473;11921.880781192078;12002.879971200176;6863;7430.4798664093;7513.474056816189;8150.388720846079;8295.709650162215;5502.367227768697;8424.705135320479;8781.780455488664;10870.333235474116;11351.602693905674 15:29:40 INFO - PROCESS | 1645 | |3;mootools.html;1507.4121092773817;1520.8859335549835;1656.205021589637;1719.3122750899638;1856.8236017578324;8397.790055248623;8902.421342612726;8902.554872256385;8903.91096089039;8915.554222288893;7776.572288524129;8082.595870206495;8087.029556453219;8431.578421078937;8693.73918782436;2343.8828058597087;2375.4180225844693;2380.773826486481;2380.9166679166233;2391.701037370329;2332.2070496031347;2342.61346877765;2376.7147942246957;2379.2148590965026;2384.0344660412475;1912.598354345589;2253.143805353963;2276.681264622954;2283.1095872609694;2288.8512246703913;2262.28737947546;2343.039353864916;2347.354477518679;2352.9647055294186;2361.6693662887124;2282.9384336283542;2286.16554957441;2286.8399212055165;2288.2448791898632;2290;8826.735197944072;8850.867236991451;8910.50992195429;8933.508657023862;8936;5168.431472538018;5323.201519772027;5323.574114070865;5323.973380133075;5327.920081198785;3158.542011408356;3206.246532064963;3341.3317336532546;3344.7658663893535;3351.9329613407594;1038.6468600675807;1041.7968496143255;1042.8175069362908;1055.7518983038979;1060.4061725433705;9822.852657210147;11524.308541487453;11694.181407301492;11705.239159454693;11707.414629268587;4577.359169716243;4630.791614377361;4773;4814.494478079821;4831.661783675109;10005.699829005142;10014.599416023277;10023.348482348623;10024.899751002542;10033.347832390871;5285.656432331886;5316.202569614588;5326.9467305327225;5329.067413202674;5352.9732351338;4381.38660587518;4508.323751437245;4541.977290113528;4769.594584460359;4861.854144375674;3794.9810250948567;3831.7892515911335;3908.785016824045;3951.5258169019903;3953.802309884494;1107.4241394474989;1267.4423253768311;1279.8016307472358;1283.1146508908823;1296.293520031585;810.3031393002013;811.5942028985507;889.4840992224495;928.3037721708719;942.9009953954874;5696.572757043238;5903.645781253139;5926.644401335933;5967.224260846062;6125.111858780541;805.8428606421692;917.4724533393326;938.7559234599054;939.403478790959;963.6627180486773;3147.5593416921647;3844.8654297099465;3900.8244628991765;3968.146848427602;4033.3748269018347;10347.896521034843;10811.972862578043;10831.916808319105;10844.566217351217;10853.837192442119;10753.78492430147;10810.567577296977;10814.891851081546;10822.512986915608;10847.837282440769;7733.8453230935065;8171.877421838677;8191.262786349257;8339.416240863202;8344.541050242173;5712.80005199816;5943.940560594338;5959.702014899324;5978.491828194652;6034.24571928509;9209.401388909699;9329.72010839676;9899.247088310543;9919.40483570988;9974.55114519863;9770.75573110678;10030.849537257092;10103.292769506357;10170.847437288594;10319.174466042849;7026.437884969292;7512.586807725409;7625.961870190614;8155.755327340189;8310.044344900414;8302.086770455366;8460.450070745383;8463.873041904499;8466.830663386821;8488.320934325116;5530.6128570999645;7329.816754581178;7448.925510744823;7555.962220188864;7564.054493188351;7077.150741911004;7262;7300.853982920418;7347;7364.926350736424;6837.316268373322;6885.621290829053;6968.930310696828;6982.162140543167;7004.824879378057;4906.2640603909695;4965.45380008206;4966.8758281043265;4989.201727723692;5062.139436295912;4256.957430425656;4870.951290487192;4877.097736918681;4923.433805112458;5034.0686972910125 15:29:40 INFO - PROCESS | 1645 | |4;prototype.html;291.35755658772393;296.8085584797805;305.4029372576612;308.89497570825915;309.78409741763573;4805.933448617093;5026.170681837492;5353.06321393756;5372.797153167202;5441.891162176754;5054.342935418391;5207.7916883324715;5252.264682944391;5288.709120998344;5419.645088727818;3489.464635560358;3914.451976723261;4021.0751527148773;4050.5949405059555;4063.2482990646677;3462.6537346265422;3802.923941521182;4047.4535937648334;4060.330045542482;4110;3631.255592603523;3984.7011474139413;4012.2577323195155;4039.515258169024;4067.2272268268935;3629.221681376133;3680.300742858875;3782.602826703184;4048.3725022621543;4062.2687916175078;2897.739203471677;2898.7970842041063;3500.8819664103107;3512.877049303262;3542.787432754043;5129.589632829365;5163.948360516422;5216.26972223889;5230.764615592308;5248.475152484759;4202.6847986400835;4514.796834142471;4553.795079221443;4577.610903073243;4589.839355622537;4722.527747225285;5572.5263352615075;5597.524210442077;5658.915890839502;5689.431056894319;3779.7305330888857;3789;3797.6138709371157;3800.847966081381;3811.2377524495214;5645.137104755421;5808.941910580882;5822.3886491918165;5866.76532938682;5888.322842873039;3789.8587298176444;3830.559485659129;3838.2899163654256;3845.1925095729653;3849.653531182207;3546.361654902091;3841.635044670752;3904.921901561953;3911.119998000427;3922.607739226112;4181.811818468176;4407.801648925742;4561.236979491932;4609.9627583794045;4612;4668.97665511677;5098.872528186751;5308.336457942757;5616.185653080281;5635.633683810539;1198.083466148397;1236.6891095438864;1246.875312468746;1308.8678293276437;1324.4966912573161;758.779953813399;830.759079866844;855.7218903856148;863.5449267983468;906.2795077913075;4441.955580444219;5260.447652996457;5261.421243663194;5270.496514155745;5357.866053348697;5803.65178089316;5805.912911306334;5826.271716035496;5829.502298620879;5848.005839007379;4691.62467002639;5192.962117161503;5240.3973543042275;5286.577073834084;5295.4907851262205;2761.715802152009;2799.510085734988;2998.0063257933666;3015.6676872056064;3027.6820933802073;5147.819826306061;5152.5877929766275;5163;5618.550515958796;5793.899159159831;5313.0967735485065;5799.9130013049835;5801.419858014249;5817.418258174149;5872.911906321408;4692.5307469253485;5219.956008798255;5388.676679399248;5798.275215598051;5870.798450581032;5189.6107791915;5354.491323324278;5449.891002179855;5537.975474537224;5627.352854421631;5492.073167685125;5590.329160500766;5599.748011339418;5628.720368250865;5704.885902282014;5515.834524964257;5816.47651711348;5860.9450298946595;5863.648181108976;5961.225040744675;3543.6279190685636;3621.6559426854406;3822.6177382261553;3839.117003089361;3849.6150384962402;4586.495485496659;5768.798092066758;5784.739686714024;5798.376454592721;5839.445252700986;5274.815381461631;5596.916046259391;5610.4670056344585;5614.691191984317;5651.88696226065;5630.88738225225;5664.631798933056;5695.433755717211;5704.828855134353;5741.913871292018;4623.329617205419;5331.826998060421;5510.972445137749;5605.68266457374;5607.158926161109;4591.163534586192;5315.787368505216;5614.466625670555;5619.325680918316;5794.420557944172;5529.6645506901705;5623.409541998031;5635.33757541518;5645.572403866377;5654.6889921054735 15:29:40 INFO - PROCESS | 1645 | |5;yui.html;691.2741621297638;759.9012128423308;768.3123604374082;796.5340275938576;855.6876739989906;15104.395824167033;15422.151781652005;15848.92075539622;15874.285657145412;16443.013419194856;14510.709785804278;14876;14884.479043233488;14897.851021489781;14922.776158357634;667.706209267923;686.7802303262957;826.0376661189707;848.868425394065;851.9914800851974;696.1472196559225;833.3083540661237;852.1180578101666;862.4782006885838;864.2308345572445;899.244634507014;900.3157600223844;900.5182227508284;901.179926267097;903.3405613901856;699.6641612026256;894.2130924786212;896.7668406214399;897.3404547657467;898.5911410308294;527.3513578298714;638.651934695592;656.9967150164266;660.3561527510686;664.445188267797;15394.538163855103;15445;15502.844971550252;15514.146721930289;15541.689166216613;13673.726525469534;13795.664898753535;13889.097208681504;14076;14292.339222403478;12314.692132696755;12408.56570020045;12471.376431178496;12548.435320410603;12869.806952895715;3764.3976963685955;3803.9239215215816;3896.649301562873;3954.3475326571083;3971.384435412516;111500.88499115067;114927.42536287349;117687.41156294249;117708.05729856736;117817.99999999914;11027.669169924915;11147.275427097213;11174.60888868902;11180.329180249211;11333.26333788301;22461.88769056177;22510.437239069153;22522;22552.66171007436;22561.66157507639;7803.375729941591;8083.555404452694;8090.757277281691;8168.9183108169345;8178.550179740171;4267.637250833714;4461.464624245112;4468.910621787546;4498.460184777848;4671.27595222741;3495.143689795965;3563.376409128444;3627;3632.945505817415;3634.436662317345;649.6621756686496;652.7356420649644;652.9444997175293;653.1965682210907;673.6631684157921;569.7236840132532;598.6767145741251;605.9515238780887;607.5443417436923;640.5836206465741;8606.13588374115;8970.686025989058;9458.763530911645;9527.428354298765;9806.627072209896;607.6263098194579;652.9738810447624;663.7676813115468;686.4714170089103;688.7933619914006;3923.7836270756156;4175.415441838145;4604.700694454849;4657.254839225708;4690.413698287714;145851.5414845838;163624.36375636328;166076;168228;168621;132561;140785.18429631554;142473;143370.28314858358;143652.28173859062;121743.39128304302;123896;124397.37801310935;124911.75088249183;125363;7157.355837974607;8655;8890.244329232088;8902.73291801247;9155.176034156957;61818.38181618126;62478;62822.685886570274;65334.34665653283;66103.7370328709;92172.53913730389;93860.53069734608;95553.13340599793;99944;100118.4994075025;11455.427228638368;11555.306681598795;11828.467718952495;11963;12350.938245308716;11360.92082204867;12554.309512976874;12827.679308017001;13130.868691312964;13184.274864882524;12047.51809927593;12159.331236782062;12173.634790955932;12644.810327844905;12670.049746269251;10097.646582369582;11888.702782430508;12047.939760301142;12534.435950382072;12554.937225313815;11718.179727419;12134.453949572468;12397.442115105023;12463.937680311541;12506.93746531298;9578.233741300819;9864;10123.949380253052;10245.334053286513;10451.738706532398;8525.95737021311;8880.955595221982;9384.859227111463;10293.845592316271;10312.432816195182 15:29:40 INFO - PROCESS | 1645 | __end_tp_report 15:29:40 INFO - PROCESS | 1645 | __start_cc_report 15:29:40 INFO - PROCESS | 1645 | _x_x_mozilla_cycle_collect,2195 15:29:40 INFO - PROCESS | 1645 | __end_cc_report 15:29:40 INFO - PROCESS | 1645 | __startTimestamp1475188180637__endTimestamp 15:29:40 INFO - PROCESS | 1645 | 15:29:40 INFO - PROCESS | 1645 | ------- Summary: start ------- 15:29:40 INFO - PROCESS | 1645 | Number of tests: 6 15:29:40 INFO - PROCESS | 1645 | 15:29:40 INFO - PROCESS | 1645 | [#0] dojo.html Cycles:165 Average:5711.89 Median:5869.68 stddev:651.35 (11.1%) stddev-sans-first:625.60 15:29:40 INFO - PROCESS | 1645 | Values: 3314.2 3319.1 3327.8 3361.6 3391.7 5611.5 5642.3 5685.1 5708.8 5737.8 5662.5 5666.2 5745.5 5799.4 5852.1 5153.3 5194.1 5220.5 5231.7 5261.5 5194.1 5307.7 5307.8 5327.1 5394.9 5295.7 5321.5 5336.5 5359.2 5382.4 5123.9 5169.8 5170.5 5176.0 5248.9 4308.2 5183.7 5282.6 5285.3 5291.2 5692.0 5699.2 5710.1 5727.9 5729.1 6103.1 6117.3 6245.9 6248.8 6250.6 4576.3 6206.3 6258.4 6281.7 6346.6 4816.7 5750.2 5800.6 5800.6 5843.9 6327.3 6344.1 6360.4 6372.2 6386.9 5876.9 5952.7 5995.1 6015.0 6034.4 6154.2 6180.5 6182.5 6185.8 6187.8 6092.8 6103.8 6107.2 6110.1 6118.9 6040.9 6049.3 6100.4 6110.2 6132.6 4896.0 4914.8 4918.2 4919.1 4945.7 4356.9 4404.3 4466.3 4473.8 4475.8 5717.3 5723.4 5728.6 5733.4 5738.5 6186.1 6247.2 6275.2 6349.9 6354.7 5766.9 5767.3 5776.1 5793.5 5851.5 5300.9 5386.2 5402.6 5408.2 5420.2 6301.9 6310.0 6357.1 6361.0 6377.6 6296.4 6307.5 6318.9 6321.9 6322.9 6334.2 6350.7 6355.9 6373.5 6385.5 5775.5 6045.4 6127.9 6142.4 6167.0 6180.6 6241.1 6265.1 6289.6 6292.2 6061.5 6111.9 6113.0 6115.7 6120.5 4584.4 5909.6 5970.9 6032.1 6096.2 5852.5 5857.8 5869.7 5871.2 5877.6 6040.6 6043.2 6059.6 6060.2 6063.1 5831.0 5866.4 5884.9 5889.5 5897.5 15:29:40 INFO - PROCESS | 1645 | 15:29:40 INFO - PROCESS | 1645 | [#1] ext.html Cycles:180 Average:67077.18 Median:19632.33 stddev:148881.86 (758.4%) stddev-sans-first:149215.65 15:29:40 INFO - PROCESS | 1645 | Values: 522.3 525.4 528.7 535.1 554.2 31306.4 32846.0 41520.2 41859.0 43941.8 42743.4 43197.1 43207.6 43314.6 43391.0 8712.1 9721.6 9890.6 9898.5 9905.1 8818.2 11902.3 12209.1 12226.7 12344.9 12139.0 12174.6 12186.5 12189.5 12198.5 8817.6 9049.9 12189.5 12252.3 12282.4 11643.5 12037.2 12057.9 12163.7 12167.3 40682.4 41068.2 41082.0 41170.2 41188.8 3268.4 3290.2 3377.5 3427.4 3668.7 30670.0 31013.2 31244.2 31422.0 31691.5 3230.6 3248.0 3283.8 3363.8 3370.4 409987.0 410996.0 412561.0 413339.0 417573.0 33117.7 57186.0 57513.4 57655.7 57685.0 34330.7 35342.0 35530.8 35573.0 35595.9 14546.8 14637.5 14641.5 14654.4 14669.5 11234.4 11477.4 11486.7 12378.0 12477.1 7080.6 7229.7 7564.7 7731.4 7815.8 3189.5 3233.2 3243.8 3265.2 3292.1 719.6 784.8 858.6 932.5 990.0 23868.5 28300.0 30099.2 30930.7 31074.0 766.0 977.9 988.2 1038.7 1054.2 14020.2 15306.5 15370.0 15711.2 15719.5 659413.0 664211.0 666387.0 666886.0 666966.0 56263.2 56746.7 56796.0 56805.0 56808.4 1138.4 1216.7 1264.4 1343.4 1378.4 29602.0 30526.8 30849.8 31198.7 31276.0 48942.0 49613.3 49707.0 49764.8 49788.0 556875.0 560876.0 561842.0 562324.0 564032.0 26530.3 27350.7 27444.7 28030.4 28070.7 46452.5 46573.6 47069.8 47309.0 48015.0 38834.4 39071.6 39077.6 39774.8 40690.8 38415.4 38707.0 38816.8 39687.6 39957.6 38202.6 38537.8 38805.6 38847.6 38980.6 30733.0 32079.0 32427.0 34335.7 34487.8 22401.4 25310.4 25318.4 25644.0 25781.7 15:29:40 INFO - PROCESS | 1645 | 15:29:40 INFO - PROCESS | 1645 | [#2] jquery.html Cycles:180 Average:34396.74 Median:2829.88 stddev:98171.39 (3469.1%) stddev-sans-first:98413.48 15:29:40 INFO - PROCESS | 1645 | Values: 339.3 343.3 366.2 371.5 395.1 15017.3 15229.3 16012.4 20331.5 20816.4 19675.5 20208.8 20871.4 20984.8 21215.4 5825.6 5976.9 6088.1 6092.1 6100.5 4769.8 6132.4 6179.0 6180.5 6182.8 5837.6 5978.8 5987.6 6077.2 6125.1 5990.0 6046.8 6066.2 6069.8 6078.6 5136.6 5268.7 5277.5 5406.6 5469.2 12807.5 13037.7 13071.1 14870.9 24899.4 2173.7 2244.2 2414.0 2727.6 2932.2 8082.8 9718.8 10187.1 11546.3 15301.2 1476.4 1808.7 1819.5 1840.3 1841.6 111167.4 116488.6 119144.4 119872.0 120599.0 11362.1 11406.9 11594.1 11600.0 12057.4 14258.8 14268.0 14283.6 14291.4 14302.6 8249.5 8416.7 8439.5 8447.4 8561.0 8356.0 8364.5 8494.7 8535.7 8577.1 3879.4 4340.4 4463.0 4527.2 4608.1 1603.3 1825.6 1878.8 1927.7 1944.2 689.3 740.8 826.7 886.3 989.7 19447.8 21517.5 21783.9 21856.0 22006.0 768.8 841.4 888.9 921.0 925.2 10970.8 11234.2 11357.8 11370.5 11456.3 551570.0 591160.0 594326.0 596174.0 599660.0 120764.0 120885.4 132996.2 136998.4 143735.8 952.7 982.4 1013.1 1013.9 1015.4 19426.0 20081.2 20090.9 20098.1 20181.7 50441.2 50690.0 51591.0 54753.0 55648.3 71057.3 76035.0 77470.6 78125.6 78782.8 7302.8 7983.2 8131.0 8195.5 8268.7 11417.8 13023.8 13054.9 13451.0 13518.5 10300.8 10786.0 11322.6 11698.8 11774.6 11237.2 11336.4 11340.0 11728.6 11761.3 9030.6 11436.4 11545.4 11921.9 12002.9 6863.0 7430.5 7513.5 8150.4 8295.7 5502.4 8424.7 8781.8 10870.3 11351.6 15:29:40 INFO - PROCESS | 1645 | 15:29:40 INFO - PROCESS | 1645 | [#3] mootools.html Cycles:180 Average:5665.11 Median:4891.68 stddev:3198.32 (65.4%) stddev-sans-first:3192.03 15:29:40 INFO - PROCESS | 1645 | Values: 1507.4 1520.9 1656.2 1719.3 1856.8 8397.8 8902.4 8902.6 8903.9 8915.6 7776.6 8082.6 8087.0 8431.6 8693.7 2343.9 2375.4 2380.8 2380.9 2391.7 2332.2 2342.6 2376.7 2379.2 2384.0 1912.6 2253.1 2276.7 2283.1 2288.9 2262.3 2343.0 2347.4 2353.0 2361.7 2282.9 2286.2 2286.8 2288.2 2290.0 8826.7 8850.9 8910.5 8933.5 8936.0 5168.4 5323.2 5323.6 5324.0 5327.9 3158.5 3206.2 3341.3 3344.8 3351.9 1038.6 1041.8 1042.8 1055.8 1060.4 9822.9 11524.3 11694.2 11705.2 11707.4 4577.4 4630.8 4773.0 4814.5 4831.7 10005.7 10014.6 10023.3 10024.9 10033.3 5285.7 5316.2 5326.9 5329.1 5353.0 4381.4 4508.3 4542.0 4769.6 4861.9 3795.0 3831.8 3908.8 3951.5 3953.8 1107.4 1267.4 1279.8 1283.1 1296.3 810.3 811.6 889.5 928.3 942.9 5696.6 5903.6 5926.6 5967.2 6125.1 805.8 917.5 938.8 939.4 963.7 3147.6 3844.9 3900.8 3968.1 4033.4 10347.9 10812.0 10831.9 10844.6 10853.8 10753.8 10810.6 10814.9 10822.5 10847.8 7733.8 8171.9 8191.3 8339.4 8344.5 5712.8 5943.9 5959.7 5978.5 6034.2 9209.4 9329.7 9899.2 9919.4 9974.6 9770.8 10030.8 10103.3 10170.8 10319.2 7026.4 7512.6 7626.0 8155.8 8310.0 8302.1 8460.5 8463.9 8466.8 8488.3 5530.6 7329.8 7448.9 7556.0 7564.1 7077.2 7262.0 7300.9 7347.0 7364.9 6837.3 6885.6 6968.9 6982.2 7004.8 4906.3 4965.5 4966.9 4989.2 5062.1 4257.0 4871.0 4877.1 4923.4 5034.1 15:29:40 INFO - PROCESS | 1645 | 15:29:40 INFO - PROCESS | 1645 | [#4] prototype.html Cycles:180 Average:4529.11 Median:5218.11 stddev:1409.82 (27.0%) stddev-sans-first:1377.43 15:29:40 INFO - PROCESS | 1645 | Values: 291.4 296.8 305.4 308.9 309.8 4805.9 5026.2 5353.1 5372.8 5441.9 5054.3 5207.8 5252.3 5288.7 5419.6 3489.5 3914.5 4021.1 4050.6 4063.2 3462.7 3802.9 4047.5 4060.3 4110.0 3631.3 3984.7 4012.3 4039.5 4067.2 3629.2 3680.3 3782.6 4048.4 4062.3 2897.7 2898.8 3500.9 3512.9 3542.8 5129.6 5163.9 5216.3 5230.8 5248.5 4202.7 4514.8 4553.8 4577.6 4589.8 4722.5 5572.5 5597.5 5658.9 5689.4 3779.7 3789.0 3797.6 3800.8 3811.2 5645.1 5808.9 5822.4 5866.8 5888.3 3789.9 3830.6 3838.3 3845.2 3849.7 3546.4 3841.6 3904.9 3911.1 3922.6 4181.8 4407.8 4561.2 4610.0 4612.0 4669.0 5098.9 5308.3 5616.2 5635.6 1198.1 1236.7 1246.9 1308.9 1324.5 758.8 830.8 855.7 863.5 906.3 4442.0 5260.4 5261.4 5270.5 5357.9 5803.7 5805.9 5826.3 5829.5 5848.0 4691.6 5193.0 5240.4 5286.6 5295.5 2761.7 2799.5 2998.0 3015.7 3027.7 5147.8 5152.6 5163.0 5618.6 5793.9 5313.1 5799.9 5801.4 5817.4 5872.9 4692.5 5220.0 5388.7 5798.3 5870.8 5189.6 5354.5 5449.9 5538.0 5627.4 5492.1 5590.3 5599.7 5628.7 5704.9 5515.8 5816.5 5860.9 5863.6 5961.2 3543.6 3621.7 3822.6 3839.1 3849.6 4586.5 5768.8 5784.7 5798.4 5839.4 5274.8 5596.9 5610.5 5614.7 5651.9 5630.9 5664.6 5695.4 5704.8 5741.9 4623.3 5331.8 5511.0 5605.7 5607.2 4591.2 5315.8 5614.5 5619.3 5794.4 5529.7 5623.4 5635.3 5645.6 5654.7 15:29:40 INFO - PROCESS | 1645 | 15:29:40 INFO - PROCESS | 1645 | [#5] yui.html Cycles:180 Average:26071.84 Median:3699.42 stddev:43225.49 (1168.4%) stddev-sans-first:43304.75 15:29:40 INFO - PROCESS | 1645 | Values: 691.3 759.9 768.3 796.5 855.7 15104.4 15422.2 15848.9 15874.3 16443.0 14510.7 14876.0 14884.5 14897.9 14922.8 667.7 686.8 826.0 848.9 852.0 696.1 833.3 852.1 862.5 864.2 899.2 900.3 900.5 901.2 903.3 699.7 894.2 896.8 897.3 898.6 527.4 638.7 657.0 660.4 664.4 15394.5 15445.0 15502.8 15514.1 15541.7 13673.7 13795.7 13889.1 14076.0 14292.3 12314.7 12408.6 12471.4 12548.4 12869.8 3764.4 3803.9 3896.6 3954.3 3971.4 111500.9 114927.4 117687.4 117708.1 117818.0 11027.7 11147.3 11174.6 11180.3 11333.3 22461.9 22510.4 22522.0 22552.7 22561.7 7803.4 8083.6 8090.8 8168.9 8178.6 4267.6 4461.5 4468.9 4498.5 4671.3 3495.1 3563.4 3627.0 3632.9 3634.4 649.7 652.7 652.9 653.2 673.7 569.7 598.7 606.0 607.5 640.6 8606.1 8970.7 9458.8 9527.4 9806.6 607.6 653.0 663.8 686.5 688.8 3923.8 4175.4 4604.7 4657.3 4690.4 145851.5 163624.4 166076.0 168228.0 168621.0 132561.0 140785.2 142473.0 143370.3 143652.3 121743.4 123896.0 124397.4 124911.8 125363.0 7157.4 8655.0 8890.2 8902.7 9155.2 61818.4 62478.0 62822.7 65334.3 66103.7 92172.5 93860.5 95553.1 99944.0 100118.5 11455.4 11555.3 11828.5 11963.0 12350.9 11360.9 12554.3 12827.7 13130.9 13184.3 12047.5 12159.3 12173.6 12644.8 12670.0 10097.6 11888.7 12047.9 12534.4 12554.9 11718.2 12134.5 12397.4 12463.9 12506.9 9578.2 9864.0 10123.9 10245.3 10451.7 8526.0 8881.0 9384.9 10293.8 10312.4 15:29:40 INFO - PROCESS | 1645 | -------- Summary: end -------- 15:29:40 INFO - PROCESS | 1645 | 15:29:41 INFO - TEST-INFO | 1645: exit 0 15:29:41 INFO - TEST-OK | dromaeo_css | took 1118235ms 15:29:41 INFO - TEST-START | kraken 15:29:41 INFO - operating with platform_type : mac_ 15:29:41 INFO - Initialising browser for kraken test... 15:29:41 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/tmpkHB8cX/profile http://localhost:49278/getInfo.html) 15:29:43 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 15:29:43 INFO - PROCESS | 1654 | colorDepth:24 15:29:43 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 15:29:43 INFO - PROCESS | 1654 | __metrics 15:29:43 INFO - PROCESS | 1654 | 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" 15:29:43 INFO - PROCESS | 1654 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:29:44 INFO - TEST-INFO | 1654: exit 0 15:29:44 INFO - Browser initialized. 15:29:44 INFO - Running cycle 1/1 for kraken test... 15:29:44 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/tmpkHB8cX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:29:56 INFO - PROCESS | 1655 | RSS: Main: 236273664 15:29:56 INFO - PROCESS | 1655 | 15:29:59 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection) 15:30:00 INFO - PROCESS | 1655 | RSS: Main: 294572032 15:30:00 INFO - PROCESS | 1655 | 15:30:02 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-dft) 15:30:03 INFO - PROCESS | 1655 | RSS: Main: 293470208 15:30:03 INFO - PROCESS | 1655 | 15:30:07 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-fft) 15:30:07 INFO - PROCESS | 1655 | RSS: Main: 385855488 15:30:07 INFO - PROCESS | 1655 | 15:30:09 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator) 15:30:10 INFO - PROCESS | 1655 | RSS: Main: 285863936 15:30:10 INFO - PROCESS | 1655 | 15:30:12 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:30:13 INFO - PROCESS | 1655 | RSS: Main: 290750464 15:30:13 INFO - PROCESS | 1655 | 15:30:15 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom) 15:30:16 INFO - PROCESS | 1655 | RSS: Main: 329363456 15:30:16 INFO - PROCESS | 1655 | 15:30:19 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate) 15:30:20 INFO - PROCESS | 1655 | RSS: Main: 328753152 15:30:20 INFO - PROCESS | 1655 | 15:30:22 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial) 15:30:23 INFO - PROCESS | 1655 | RSS: Main: 326221824 15:30:23 INFO - PROCESS | 1655 | 15:30:24 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:30:25 INFO - PROCESS | 1655 | RSS: Main: 304865280 15:30:25 INFO - PROCESS | 1655 | 15:30:27 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:30:28 INFO - PROCESS | 1655 | RSS: Main: 306307072 15:30:28 INFO - PROCESS | 1655 | 15:30:30 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:30:30 INFO - PROCESS | 1655 | RSS: Main: 304574464 15:30:30 INFO - PROCESS | 1655 | 15:30:33 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:30:34 INFO - PROCESS | 1655 | RSS: Main: 312791040 15:30:34 INFO - PROCESS | 1655 | 15:30:37 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:30:37 INFO - PROCESS | 1655 | RSS: Main: 314667008 15:30:37 INFO - PROCESS | 1655 | 15:30:39 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49278/tests/kraken/driver.html?testName=ai-astar) 15:30:39 INFO - PROCESS | 1655 | RSS: Main: 358297600 15:30:39 INFO - PROCESS | 1655 | 15:30:39 INFO - PROCESS | 1655 | __start_tp_report 15:30:39 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 15:30:39 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 15:30:39 INFO - PROCESS | 1655 | |i|pagename|runs| 15:30:39 INFO - PROCESS | 1655 | |0;ai-astar;115;114;114;113;115;118;161;169;114;114 15:30:39 INFO - PROCESS | 1655 | |1;audio-beat-detection;145;133;102;185;143;141;108;111;142;131 15:30:39 INFO - PROCESS | 1655 | |2;audio-dft;186;184;184;183;185;196;262;181;184;183 15:30:39 INFO - PROCESS | 1655 | |3;audio-fft;80;59;59;88;64;60;59;57;81;59 15:30:39 INFO - PROCESS | 1655 | |4;audio-oscillator;83;94;103;70;68;69;92;67;69;89 15:30:39 INFO - PROCESS | 1655 | |5;imaging-gaussian-blur;90;128;126;86;88;85;90;87;88;87 15:30:39 INFO - PROCESS | 1655 | |6;imaging-darkroom;94;94;94;94;93;95;96;94;101;127 15:30:39 INFO - PROCESS | 1655 | |7;imaging-desaturate;79;85;86;85;84;85;83;86;86;86 15:30:39 INFO - PROCESS | 1655 | |8;json-parse-financial;56;56;54;54;54;55;55;54;55;55 15:30:39 INFO - PROCESS | 1655 | |9;json-stringify-tinderbox;63;70;72;76;74;68;69;69;70;70 15:30:39 INFO - PROCESS | 1655 | |10;stanford-crypto-aes;84;84;85;84;86;86;82;87;87;86 15:30:39 INFO - PROCESS | 1655 | |11;stanford-crypto-ccm;140;138;138;141;134;139;138;140;167;154 15:30:39 INFO - PROCESS | 1655 | |12;stanford-crypto-pbkdf2;176;170;168;168;171;165;168;170;170;168 15:30:39 INFO - PROCESS | 1655 | |13;stanford-crypto-sha256-iterative;66;67;66;68;67;67;65;67;66;67 15:30:39 INFO - PROCESS | 1655 | __end_tp_report 15:30:39 INFO - PROCESS | 1655 | __start_cc_report 15:30:39 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,2013 15:30:39 INFO - PROCESS | 1655 | __end_cc_report 15:30:39 INFO - PROCESS | 1655 | __startTimestamp1475188239893__endTimestamp 15:30:39 INFO - PROCESS | 1655 | 15:30:39 INFO - PROCESS | 1655 | ------- Summary: start ------- 15:30:39 INFO - PROCESS | 1655 | Number of tests: 14 15:30:39 INFO - PROCESS | 1655 | 15:30:39 INFO - PROCESS | 1655 | [#0] ai-astar Cycles:10 Average:124.70 Median:115.00 stddev:21.36 (18.6%) stddev-sans-first:22.37 15:30:39 INFO - PROCESS | 1655 | Values: 115.0 114.0 114.0 113.0 115.0 118.0 161.0 169.0 114.0 114.0 15:30:39 INFO - PROCESS | 1655 | 15:30:39 INFO - PROCESS | 1655 | [#1] audio-beat-detection Cycles:10 Average:134.10 Median:141.50 stddev:23.93 (16.9%) stddev-sans-first:25.06 15:30:39 INFO - PROCESS | 1655 | Values: 145.0 133.0 102.0 185.0 143.0 141.0 108.0 111.0 142.0 131.0 15:30:39 INFO - PROCESS | 1655 | 15:30:39 INFO - PROCESS | 1655 | [#2] audio-dft Cycles:10 Average:192.80 Median:184.50 stddev:24.65 (13.4%) stddev-sans-first:26.02 15:30:39 INFO - PROCESS | 1655 | Values: 186.0 184.0 184.0 183.0 185.0 196.0 262.0 181.0 184.0 183.0 15:30:39 INFO - PROCESS | 1655 | 15:30:39 INFO - PROCESS | 1655 | [#3] audio-fft Cycles:10 Average:66.60 Median:62.00 stddev:11.64 (18.8%) stddev-sans-first:11.29 15:30:39 INFO - PROCESS | 1655 | Values: 80.0 59.0 59.0 88.0 64.0 60.0 59.0 57.0 81.0 59.0 15:30:39 INFO - PROCESS | 1655 | 15:30:39 INFO - PROCESS | 1655 | [#4] audio-oscillator Cycles:10 Average:80.40 Median:76.50 stddev:13.38 (17.5%) stddev-sans-first:14.16 15:30:39 INFO - PROCESS | 1655 | Values: 83.0 94.0 103.0 70.0 68.0 69.0 92.0 67.0 69.0 89.0 15:30:39 INFO - PROCESS | 1655 | 15:30:39 INFO - PROCESS | 1655 | [#5] imaging-gaussian-blur Cycles:10 Average:95.50 Median:87.50 stddev:16.68 (19.1%) stddev-sans-first:17.57 15:30:39 INFO - PROCESS | 1655 | Values: 90.0 128.0 126.0 86.0 88.0 85.0 90.0 87.0 88.0 87.0 15:30:39 INFO - PROCESS | 1655 | 15:30:39 INFO - PROCESS | 1655 | [#6] imaging-darkroom Cycles:10 Average:98.20 Median:94.00 stddev:10.37 (11.0%) stddev-sans-first:10.89 15:30:39 INFO - PROCESS | 1655 | Values: 94.0 94.0 94.0 94.0 93.0 95.0 96.0 94.0 101.0 127.0 15:30:39 INFO - PROCESS | 1655 | 15:30:39 INFO - PROCESS | 1655 | [#7] imaging-desaturate Cycles:10 Average:84.50 Median:85.50 stddev:2.17 (2.5%) stddev-sans-first:1.05 15:30:39 INFO - PROCESS | 1655 | Values: 79.0 85.0 86.0 85.0 84.0 85.0 83.0 86.0 86.0 86.0 15:30:39 INFO - PROCESS | 1655 | 15:30:39 INFO - PROCESS | 1655 | [#8] json-parse-financial Cycles:10 Average:54.80 Median:55.00 stddev:0.79 (1.4%) stddev-sans-first:0.71 15:30:39 INFO - PROCESS | 1655 | Values: 56.0 56.0 54.0 54.0 54.0 55.0 55.0 54.0 55.0 55.0 15:30:39 INFO - PROCESS | 1655 | 15:30:39 INFO - PROCESS | 1655 | [#9] json-stringify-tinderbox Cycles:10 Average:70.10 Median:70.00 stddev:3.51 (5.0%) stddev-sans-first:2.62 15:30:39 INFO - PROCESS | 1655 | Values: 63.0 70.0 72.0 76.0 74.0 68.0 69.0 69.0 70.0 70.0 15:30:39 INFO - PROCESS | 1655 | 15:30:39 INFO - PROCESS | 1655 | [#10] stanford-crypto-aes Cycles:10 Average:85.10 Median:86.00 stddev:1.60 (1.9%) stddev-sans-first:1.64 15:30:39 INFO - PROCESS | 1655 | Values: 84.0 84.0 85.0 84.0 86.0 86.0 82.0 87.0 87.0 86.0 15:30:39 INFO - PROCESS | 1655 | 15:30:39 INFO - PROCESS | 1655 | [#11] stanford-crypto-ccm Cycles:10 Average:142.90 Median:140.00 stddev:9.95 (7.1%) stddev-sans-first:10.50 15:30:39 INFO - PROCESS | 1655 | Values: 140.0 138.0 138.0 141.0 134.0 139.0 138.0 140.0 167.0 154.0 15:30:39 INFO - PROCESS | 1655 | 15:30:39 INFO - PROCESS | 1655 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.40 Median:170.00 stddev:2.88 (1.7%) stddev-sans-first:1.80 15:30:39 INFO - PROCESS | 1655 | Values: 176.0 170.0 168.0 168.0 171.0 165.0 168.0 170.0 170.0 168.0 15:30:39 INFO - PROCESS | 1655 | 15:30:39 INFO - PROCESS | 1655 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.60 Median:67.00 stddev:0.84 (1.3%) stddev-sans-first:0.87 15:30:39 INFO - PROCESS | 1655 | Values: 66.0 67.0 66.0 68.0 67.0 67.0 65.0 67.0 66.0 67.0 15:30:39 INFO - PROCESS | 1655 | -------- Summary: end -------- 15:30:39 INFO - PROCESS | 1655 | 15:30:40 INFO - TEST-INFO | 1655: exit 0 15:30:40 INFO - TEST-OK | kraken | took 59494ms 15:30:40 INFO - SUITE-END | took 1177s 15:30:41 INFO - Completed test suite (00:19:38) 15:30:41 INFO - javascript benchmark 15:30:41 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3314.2045908981845, 3319.07066021514, 3327.767056306058, 3361.613414457338, 3391.7286617070636, 5611.466910643492, 5642.322921249451, 5685.061964775807, 5708.800191993281, 5737.827865164047, 5662.546996240301, 5666.206731057655, 5745.540356771458, 5799.3620701722775, 5852.092925596528, 5153.330067091273, 5194.091034069042, 5220.530152286294, 5231.686098834082, 5261.473852614727, 5194.065068287707, 5307.681539107647, 5307.840764777044, 5327.147656374981, 5394.865128371783, 5295.68225906443, 5321.467853214686, 5336.466353364672, 5359.222912677659, 5382.407935127152, 5123.87190320245, 5169.844904652867, 5170.508801663836, 5176.0, 5248.921266181011, 4308.181445525372, 5183.740812959375, 5282.577393808486, 5285.339332583427, 5291.1534154535475, 5692.0, 5699.202111704365, 5710.086386178233, 5727.914081288784, 5729.083346664555, 6103.084537319393, 6117.3402608778015, 6245.906311405332, 6248.781292654735, 6250.624962502265, 4576.290674945389, 6206.317305096436, 6258.405451482102, 6281.654509002003, 6346.587471814363, 4816.686915350561, 5750.166225897222, 5800.564957628152, 5800.564957628195, 5843.8539036524, 6327.303996560375, 6344.080108384258, 6360.395762402564, 6372.203474565679, 6386.9361306386345, 5876.941230587725, 5952.7321270542925, 5995.100734889801, 6014.969925150346, 6034.366391528914, 6154.19995400604, 6180.505559555225, 6182.474489668339, 6185.752569897154, 6187.84530386744, 6092.817215483543, 6103.755849765959, 6107.249501910372, 6110.052941794029, 6118.908216376758, 6040.909386359208, 6049.304330002018, 6100.389961003864, 6110.236220472441, 6132.570720049643, 4895.951040489549, 4914.8034078637165, 4918.163912134945, 4919.114559379346, 4945.67853089548, 4356.869293921188, 4404.339349097661, 4466.2853943369555, 4473.843415480443, 4475.7985890635, 5717.342505611908, 5723.427657234243, 5728.598998070095, 5733.397993210736, 5738.540916726652, 6186.072089186568, 6247.219097612799, 6275.215598050244, 6349.873002539924, 6354.745810167541, 5766.933117373215, 5767.307923049261, 5776.104703770922, 5793.478586927196, 5851.502622277154, 5300.946990530122, 5386.245925570423, 5402.594805389612, 5408.242846001563, 5420.24116623673, 6301.905471418024, 6310.0, 6357.14178585885, 6361.01404282337, 6377.64922929234, 6296.433321001132, 6307.526935479766, 6318.8736225274315, 6321.936780632135, 6322.905156422749, 6334.208223972004, 6350.650714210763, 6355.904661430175, 6373.490120790233, 6385.521085918667, 5775.5272405538, 6045.365236650088, 6127.877442451215, 6142.416470435302, 6167.0, 6180.629162250099, 6241.095041219089, 6265.122882796503, 6289.622622642656, 6292.182016337847, 6061.454469097803, 6111.908321375094, 6112.99135642614, 6115.7247923844425, 6120.510359171345, 4584.426946631671, 5909.5863289569315, 5970.910436343545, 6032.065029920369, 6096.177016102769, 5852.502537284378, 5857.824265272048, 5869.6771677558145, 5871.2073870027, 5877.617954832922, 6040.637561746309, 6043.244594425697, 6059.6364218147055, 6060.181875446759, 6063.090536419396, 5831.0, 5866.413358664099, 5884.882302353843, 5889.528837692888, 5897.528197744256], "value": 5671.491554793793, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [522.3209827224608, 525.4377815737162, 528.725062967257, 535.1437699680514, 554.2379228560729, 31306.373872522563, 32846.0, 41520.16959660809, 41859.0, 43941.78029109858, 42743.3588496172, 43197.13605727883, 43207.56792432075, 43314.566854331446, 43391.0, 8712.085231050743, 9721.562529686164, 9890.604375824958, 9898.50507474627, 9905.108540231378, 8818.162274583905, 11902.34537100459, 12209.145359824815, 12226.694332641666, 12344.93827530861, 12138.968187704057, 12174.63476095714, 12186.451609677586, 12189.51241950321, 12198.512059517609, 8817.603207855598, 9049.864252036159, 12189.451474683661, 12252.264864108181, 12282.447289871978, 11643.476043578059, 12037.157398982075, 12057.93971030148, 12163.695907602292, 12167.2699638022, 40682.38976415356, 41068.1786364274, 41082.0, 41170.176596468205, 41188.79405602983, 3268.4391037925984, 3290.19390249388, 3377.4933759936207, 3427.365937301607, 3668.7248456365614, 30670.0, 31013.224669383446, 31244.218894527592, 31422.0, 31691.524627130613, 3230.644629090798, 3247.951280730791, 3283.8029718216985, 3363.764536482447, 3370.376480351143, 409987.0, 410996.0, 412561.0, 413339.0, 417573.0, 33117.66882331146, 57186.0, 57513.424865751644, 57655.71172144113, 57685.0, 34330.656693433244, 35342.0, 35530.82234588862, 35573.0, 35595.93212203638, 14546.781798273034, 14637.487687931085, 14641.48754793577, 14654.413823447156, 14669.486567970283, 11234.438278086063, 11477.36874471912, 11486.712832179262, 12378.0, 12477.064220183342, 7080.610566418896, 7229.7347964106075, 7564.735234266885, 7731.381489480828, 7815.765527034198, 3189.489681650925, 3233.224026233734, 3243.8053716777067, 3265.248992731685, 3292.1440425489545, 719.5754504842168, 784.7724159993652, 858.5621333120154, 932.4871320773548, 989.9950500247453, 23868.52262954731, 28300.0, 30099.247518812204, 30930.69069309323, 31074.0, 765.9540427574252, 977.941323520591, 988.2143695761887, 1038.6987773545738, 1054.2409465185056, 14020.158790472604, 15306.464273750365, 15370.0, 15711.21443927799, 15719.528414147593, 659413.0, 664211.0, 666387.0, 666886.0, 666966.0, 56263.156052659244, 56746.71626641841, 56796.0, 56805.0, 56808.43191568114, 1138.3909608359488, 1216.7262365967586, 1264.37413480326, 1343.3954720375675, 1378.4141739760362, 29602.0, 30526.847365763027, 30849.845750771103, 31198.68801311958, 31276.0, 48942.0, 49613.25580116229, 49707.0, 49764.75117624389, 49788.0, 556875.0, 560876.0, 561842.0, 562324.0, 564032.0, 26530.336741581614, 27350.726492735615, 27444.725552745014, 28030.43939121247, 28070.71929280681, 46452.53547464482, 46573.602791916295, 47069.764651177895, 47309.0, 48015.0, 38834.41748373833, 39071.609283907936, 39077.6092239074, 39774.80112599534, 40690.79654601708, 38415.42376864293, 38707.0, 38816.80591597024, 39687.603123969544, 39957.60042399539, 38202.617973819906, 38537.80731096327, 38805.611943881326, 38847.6115238844, 38980.6101938977, 30733.0, 32079.03762887117, 32427.027189184362, 34335.65664343325, 34487.82756086203, 22401.43996400038, 25310.367240819127, 25318.36704082339, 25644.0, 25781.742182577935], "value": 18754.54293255395, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [339.3061189866723, 343.2534238032279, 366.2181243046096, 371.4817829128366, 395.0656696911803, 15017.32422041008, 15229.314680839361, 16012.359505619761, 20331.49171270719, 20816.375508734764, 19675.5081122972, 20208.797912020913, 20871.37385878426, 20984.790152098474, 21215.36353909385, 5825.563082768788, 5976.89427455922, 6088.117223002662, 6092.11664308675, 6100.450959413652, 4769.809207631691, 6132.41742034506, 6179.0, 6180.474659653913, 6182.752689892399, 5837.562182836305, 5978.8206353809455, 5987.55093367997, 6077.209962704842, 6125.050617154348, 5989.970050149765, 6046.848828779272, 6066.241719785027, 6069.848253793691, 6078.574499785017, 5136.563392111675, 5268.6575372600655, 5277.47225277473, 5406.648567843077, 5469.15228139639, 12807.487700491969, 13037.739245215138, 13071.08502404832, 14870.851291487055, 24899.377515562075, 2173.652215645489, 2244.2145249162827, 2413.963790543143, 2727.5635898256182, 2932.19364674714, 8082.757517274491, 9718.757031074208, 10187.083162515335, 11546.307221566733, 15301.234938253043, 1476.372541669786, 1808.7106063029853, 1819.4996375996707, 1840.291487777202, 1841.558026073746, 111167.44416277947, 116488.60381056202, 119144.40427797892, 119872.0, 120599.0, 11362.147838912115, 11406.885931140583, 11594.130440217017, 11600.0, 12057.397130143634, 14258.786118208236, 14268.0, 14283.64290892736, 14291.356888940023, 14302.642433939234, 8249.546274954815, 8416.705415310435, 8439.451435656785, 8447.408681392366, 8561.01548321951, 8355.997280326279, 8364.53995030267, 8494.660213591511, 8535.701250456204, 8577.05652378238, 3879.359905615598, 4340.414044104005, 4462.9776851116185, 4527.207738645724, 4608.1474927137915, 1603.2684700523407, 1825.5892424204449, 1878.811676466638, 1927.739755133067, 1944.1737261663736, 689.2829377714107, 740.8444226712343, 826.6982551368707, 886.2953951608487, 989.6536212325628, 19447.805521944883, 21517.54587499633, 21783.891080544498, 21856.016479258473, 22006.009729562207, 768.769369189241, 841.3984001438878, 888.9288856891433, 920.9677661281823, 925.2413021322451, 10970.835437468444, 11234.213605047731, 11357.829632555518, 11370.488328025258, 11456.312621242585, 551570.0, 591160.0, 594326.0, 596174.0, 599660.0, 120763.99999999824, 120885.39557302333, 132996.20481056385, 136998.3523890343, 143735.84396234064, 952.6998995316453, 982.3958265666564, 1013.1084645511902, 1013.9391636501833, 1015.355249416611, 19426.028698565297, 20081.196752130334, 20090.899545502765, 20098.085777562465, 20181.6972745406, 50441.24338134858, 50690.0, 51591.0, 54753.0, 55648.261554227036, 71057.34139878853, 76035.0, 77470.61264693641, 78125.60937195506, 78782.81825772733, 7302.817429564303, 7983.20167983197, 8131.0, 8195.549244791355, 8268.669253230044, 11417.771644566896, 13023.804642930174, 13054.869451305365, 13451.0, 13518.459261629425, 10300.845487317845, 10786.0, 11322.60370887015, 11698.76602467929, 11774.587889423829, 11237.213395062266, 11336.433178341214, 11340.0, 11728.648140555797, 11761.294322340687, 9030.593623287099, 11436.428178591204, 11545.365004924473, 11921.880781192078, 12002.879971200176, 6863.0, 7430.4798664093, 7513.474056816189, 8150.388720846079, 8295.709650162215, 5502.367227768697, 8424.705135320479, 8781.780455488664, 10870.333235474116, 11351.602693905674], "value": 9327.256933563405, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1507.4121092773817, 1520.8859335549835, 1656.205021589637, 1719.3122750899638, 1856.8236017578324, 8397.790055248623, 8902.421342612726, 8902.554872256385, 8903.91096089039, 8915.554222288893, 7776.572288524129, 8082.595870206495, 8087.029556453219, 8431.578421078937, 8693.73918782436, 2343.8828058597087, 2375.4180225844693, 2380.773826486481, 2380.9166679166233, 2391.701037370329, 2332.2070496031347, 2342.61346877765, 2376.7147942246957, 2379.2148590965026, 2384.0344660412475, 1912.598354345589, 2253.143805353963, 2276.681264622954, 2283.1095872609694, 2288.8512246703913, 2262.28737947546, 2343.039353864916, 2347.354477518679, 2352.9647055294186, 2361.6693662887124, 2282.9384336283542, 2286.16554957441, 2286.8399212055165, 2288.2448791898632, 2290.0, 8826.735197944072, 8850.867236991451, 8910.50992195429, 8933.508657023862, 8936.0, 5168.431472538018, 5323.201519772027, 5323.574114070865, 5323.973380133075, 5327.920081198785, 3158.542011408356, 3206.246532064963, 3341.3317336532546, 3344.7658663893535, 3351.9329613407594, 1038.6468600675807, 1041.7968496143255, 1042.8175069362908, 1055.7518983038979, 1060.4061725433705, 9822.852657210147, 11524.308541487453, 11694.181407301492, 11705.239159454693, 11707.414629268587, 4577.359169716243, 4630.791614377361, 4773.0, 4814.494478079821, 4831.661783675109, 10005.699829005142, 10014.599416023277, 10023.348482348623, 10024.899751002542, 10033.347832390871, 5285.656432331886, 5316.202569614588, 5326.9467305327225, 5329.067413202674, 5352.9732351338, 4381.38660587518, 4508.323751437245, 4541.977290113528, 4769.594584460359, 4861.854144375674, 3794.9810250948567, 3831.7892515911335, 3908.785016824045, 3951.5258169019903, 3953.802309884494, 1107.4241394474989, 1267.4423253768311, 1279.8016307472358, 1283.1146508908823, 1296.293520031585, 810.3031393002013, 811.5942028985507, 889.4840992224495, 928.3037721708719, 942.9009953954874, 5696.572757043238, 5903.645781253139, 5926.644401335933, 5967.224260846062, 6125.111858780541, 805.8428606421692, 917.4724533393326, 938.7559234599054, 939.403478790959, 963.6627180486773, 3147.5593416921647, 3844.8654297099465, 3900.8244628991765, 3968.146848427602, 4033.3748269018347, 10347.896521034843, 10811.972862578043, 10831.916808319105, 10844.566217351217, 10853.837192442119, 10753.78492430147, 10810.567577296977, 10814.891851081546, 10822.512986915608, 10847.837282440769, 7733.8453230935065, 8171.877421838677, 8191.262786349257, 8339.416240863202, 8344.541050242173, 5712.80005199816, 5943.940560594338, 5959.702014899324, 5978.491828194652, 6034.24571928509, 9209.401388909699, 9329.72010839676, 9899.247088310543, 9919.40483570988, 9974.55114519863, 9770.75573110678, 10030.849537257092, 10103.292769506357, 10170.847437288594, 10319.174466042849, 7026.437884969292, 7512.586807725409, 7625.961870190614, 8155.755327340189, 8310.044344900414, 8302.086770455366, 8460.450070745383, 8463.873041904499, 8466.830663386821, 8488.320934325116, 5530.6128570999645, 7329.816754581178, 7448.925510744823, 7555.962220188864, 7564.054493188351, 7077.150741911004, 7262.0, 7300.853982920418, 7347.0, 7364.926350736424, 6837.316268373322, 6885.621290829053, 6968.930310696828, 6982.162140543167, 7004.824879378057, 4906.2640603909695, 4965.45380008206, 4966.8758281043265, 4989.201727723692, 5062.139436295912, 4256.957430425656, 4870.951290487192, 4877.097736918681, 4923.433805112458, 5034.0686972910125], "value": 4537.710903229968, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [291.35755658772393, 296.8085584797805, 305.4029372576612, 308.89497570825915, 309.78409741763573, 4805.933448617093, 5026.170681837492, 5353.06321393756, 5372.797153167202, 5441.891162176754, 5054.342935418391, 5207.7916883324715, 5252.264682944391, 5288.709120998344, 5419.645088727818, 3489.464635560358, 3914.451976723261, 4021.0751527148773, 4050.5949405059555, 4063.2482990646677, 3462.6537346265422, 3802.923941521182, 4047.4535937648334, 4060.330045542482, 4110.0, 3631.255592603523, 3984.7011474139413, 4012.2577323195155, 4039.515258169024, 4067.2272268268935, 3629.221681376133, 3680.300742858875, 3782.602826703184, 4048.3725022621543, 4062.2687916175078, 2897.739203471677, 2898.7970842041063, 3500.8819664103107, 3512.877049303262, 3542.787432754043, 5129.589632829365, 5163.948360516422, 5216.26972223889, 5230.764615592308, 5248.475152484759, 4202.6847986400835, 4514.796834142471, 4553.795079221443, 4577.610903073243, 4589.839355622537, 4722.527747225285, 5572.5263352615075, 5597.524210442077, 5658.915890839502, 5689.431056894319, 3779.7305330888857, 3789.0, 3797.6138709371157, 3800.847966081381, 3811.2377524495214, 5645.137104755421, 5808.941910580882, 5822.3886491918165, 5866.76532938682, 5888.322842873039, 3789.8587298176444, 3830.559485659129, 3838.2899163654256, 3845.1925095729653, 3849.653531182207, 3546.361654902091, 3841.635044670752, 3904.921901561953, 3911.119998000427, 3922.607739226112, 4181.811818468176, 4407.801648925742, 4561.236979491932, 4609.9627583794045, 4612.0, 4668.97665511677, 5098.872528186751, 5308.336457942757, 5616.185653080281, 5635.633683810539, 1198.083466148397, 1236.6891095438864, 1246.875312468746, 1308.8678293276437, 1324.4966912573161, 758.779953813399, 830.759079866844, 855.7218903856148, 863.5449267983468, 906.2795077913075, 4441.955580444219, 5260.447652996457, 5261.421243663194, 5270.496514155745, 5357.866053348697, 5803.65178089316, 5805.912911306334, 5826.271716035496, 5829.502298620879, 5848.005839007379, 4691.62467002639, 5192.962117161503, 5240.3973543042275, 5286.577073834084, 5295.4907851262205, 2761.715802152009, 2799.510085734988, 2998.0063257933666, 3015.6676872056064, 3027.6820933802073, 5147.819826306061, 5152.5877929766275, 5163.0, 5618.550515958796, 5793.899159159831, 5313.0967735485065, 5799.9130013049835, 5801.419858014249, 5817.418258174149, 5872.911906321408, 4692.5307469253485, 5219.956008798255, 5388.676679399248, 5798.275215598051, 5870.798450581032, 5189.6107791915, 5354.491323324278, 5449.891002179855, 5537.975474537224, 5627.352854421631, 5492.073167685125, 5590.329160500766, 5599.748011339418, 5628.720368250865, 5704.885902282014, 5515.834524964257, 5816.47651711348, 5860.9450298946595, 5863.648181108976, 5961.225040744675, 3543.6279190685636, 3621.6559426854406, 3822.6177382261553, 3839.117003089361, 3849.6150384962402, 4586.495485496659, 5768.798092066758, 5784.739686714024, 5798.376454592721, 5839.445252700986, 5274.815381461631, 5596.916046259391, 5610.4670056344585, 5614.691191984317, 5651.88696226065, 5630.88738225225, 5664.631798933056, 5695.433755717211, 5704.828855134353, 5741.913871292018, 4623.329617205419, 5331.826998060421, 5510.972445137749, 5605.68266457374, 5607.158926161109, 4591.163534586192, 5315.787368505216, 5614.466625670555, 5619.325680918316, 5794.420557944172, 5529.6645506901705, 5623.409541998031, 5635.33757541518, 5645.572403866377, 5654.6889921054735], "value": 4071.3226407392654, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [691.2741621297638, 759.9012128423308, 768.3123604374082, 796.5340275938576, 855.6876739989906, 15104.395824167033, 15422.151781652005, 15848.92075539622, 15874.285657145412, 16443.013419194856, 14510.709785804278, 14876.0, 14884.479043233488, 14897.851021489781, 14922.776158357634, 667.706209267923, 686.7802303262957, 826.0376661189707, 848.868425394065, 851.9914800851974, 696.1472196559225, 833.3083540661237, 852.1180578101666, 862.4782006885838, 864.2308345572445, 899.244634507014, 900.3157600223844, 900.5182227508284, 901.179926267097, 903.3405613901856, 699.6641612026256, 894.2130924786212, 896.7668406214399, 897.3404547657467, 898.5911410308294, 527.3513578298714, 638.651934695592, 656.9967150164266, 660.3561527510686, 664.445188267797, 15394.538163855103, 15445.0, 15502.844971550252, 15514.146721930289, 15541.689166216613, 13673.726525469534, 13795.664898753535, 13889.097208681504, 14076.0, 14292.339222403478, 12314.692132696755, 12408.56570020045, 12471.376431178496, 12548.435320410603, 12869.806952895715, 3764.3976963685955, 3803.9239215215816, 3896.649301562873, 3954.3475326571083, 3971.384435412516, 111500.88499115067, 114927.42536287349, 117687.41156294249, 117708.05729856736, 117817.99999999914, 11027.669169924915, 11147.275427097213, 11174.60888868902, 11180.329180249211, 11333.26333788301, 22461.88769056177, 22510.437239069153, 22522.0, 22552.66171007436, 22561.66157507639, 7803.375729941591, 8083.555404452694, 8090.757277281691, 8168.9183108169345, 8178.550179740171, 4267.637250833714, 4461.464624245112, 4468.910621787546, 4498.460184777848, 4671.27595222741, 3495.143689795965, 3563.376409128444, 3627.0, 3632.945505817415, 3634.436662317345, 649.6621756686496, 652.7356420649644, 652.9444997175293, 653.1965682210907, 673.6631684157921, 569.7236840132532, 598.6767145741251, 605.9515238780887, 607.5443417436923, 640.5836206465741, 8606.13588374115, 8970.686025989058, 9458.763530911645, 9527.428354298765, 9806.627072209896, 607.6263098194579, 652.9738810447624, 663.7676813115468, 686.4714170089103, 688.7933619914006, 3923.7836270756156, 4175.415441838145, 4604.700694454849, 4657.254839225708, 4690.413698287714, 145851.5414845838, 163624.36375636328, 166076.0, 168228.0, 168621.0, 132561.0, 140785.18429631554, 142473.0, 143370.28314858358, 143652.28173859062, 121743.39128304302, 123896.0, 124397.37801310935, 124911.75088249183, 125363.0, 7157.355837974607, 8655.0, 8890.244329232088, 8902.73291801247, 9155.176034156957, 61818.38181618126, 62478.0, 62822.685886570274, 65334.34665653283, 66103.7370328709, 92172.53913730389, 93860.53069734608, 95553.13340599793, 99944.0, 100118.4994075025, 11455.427228638368, 11555.306681598795, 11828.467718952495, 11963.0, 12350.938245308716, 11360.92082204867, 12554.309512976874, 12827.679308017001, 13130.868691312964, 13184.274864882524, 12047.51809927593, 12159.331236782062, 12173.634790955932, 12644.810327844905, 12670.049746269251, 10097.646582369582, 11888.702782430508, 12047.939760301142, 12534.435950382072, 12554.937225313815, 11718.179727419, 12134.453949572468, 12397.442115105023, 12463.937680311541, 12506.93746531298, 9578.233741300819, 9864.0, 10123.949380253052, 10245.334053286513, 10451.738706532398, 8525.95737021311, 8880.955595221982, 9384.859227111463, 10293.845592316271, 10312.432816195182], "value": 7721.674394510227, "unit": "score"}], "name": "dromaeo_css", "value": 7219.064384407189, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 114.0, 113.0, 115.0, 118.0, 161.0, 169.0, 114.0, 114.0], "value": 124.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 133.0, 102.0, 185.0, 143.0, 141.0, 108.0, 111.0, 142.0, 131.0], "value": 134.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 184.0, 184.0, 183.0, 185.0, 196.0, 262.0, 181.0, 184.0, 183.0], "value": 192.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 59.0, 59.0, 88.0, 64.0, 60.0, 59.0, 57.0, 81.0, 59.0], "value": 66.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 94.0, 103.0, 70.0, 68.0, 69.0, 92.0, 67.0, 69.0, 89.0], "value": 80.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 128.0, 126.0, 86.0, 88.0, 85.0, 90.0, 87.0, 88.0, 87.0], "value": 95.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 93.0, 95.0, 96.0, 94.0, 101.0, 127.0], "value": 98.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 85.0, 86.0, 85.0, 84.0, 85.0, 83.0, 86.0, 86.0, 86.0], "value": 84.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 54.0, 54.0, 54.0, 55.0, 55.0, 54.0, 55.0, 55.0], "value": 54.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 70.0, 72.0, 76.0, 74.0, 68.0, 69.0, 69.0, 70.0, 70.0], "value": 70.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 85.0, 84.0, 86.0, 86.0, 82.0, 87.0, 87.0, 86.0], "value": 85.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 138.0, 138.0, 141.0, 134.0, 139.0, 138.0, 140.0, 167.0, 154.0], "value": 142.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 170.0, 168.0, 168.0, 171.0, 165.0, 168.0, 170.0, 170.0, 168.0], "value": 169.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 66.0, 68.0, 67.0, 67.0, 65.0, 67.0, 66.0, 67.0], "value": 66.6, "unit": "score"}], "name": "kraken", "value": 1465.7, "alertThreshold": 2.0}]} 15:30:41 INFO - Return code: 0 15:30:41 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:30:41 INFO - # TBPL SUCCESS # 15:30:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:30:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:30:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:30:41 INFO - 2016-09-29 15:30:41.206 screenresolution[1660:9714] starting screenresolution argv=screenresolution get 15:30:41 INFO - 2016-09-29 15:30:41.209 screenresolution[1660:9714] Display 0: 1600x1200x32@60 15:30:41 INFO - 2016-09-29 15:30:41.215 screenresolution[1661:9717] starting screenresolution argv=screenresolution list 15:30:41 INFO - Available Modes on Display 0 15:30:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:30:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:30:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:30:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:30:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:30:41 INFO - Intel Iris: 15:30:41 INFO - Chipset Model: Intel Iris 15:30:41 INFO - Type: GPU 15:30:41 INFO - Bus: Built-In 15:30:41 INFO - VRAM (Dynamic, Max): 1536 MB 15:30:41 INFO - Vendor: Intel (0x8086) 15:30:41 INFO - Device ID: 0x0a2e 15:30:41 INFO - Revision ID: 0x0009 15:30:41 INFO - Displays: 15:30:41 INFO - Display: 15:30:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:30:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:30:41 INFO - Display Serial Number: 1600x1200 60 15:30:41 INFO - Main Display: Yes 15:30:41 INFO - Mirror: Off 15:30:41 INFO - Online: Yes 15:30:41 INFO - Rotation: Supported 15:30:41 INFO - Return code: 0 15:30:41 INFO - Running post-action listener: _resource_record_post_action 15:30:41 INFO - [mozharness: 2016-09-29 22:30:41.452387Z] Finished run-tests step (success) 15:30:41 INFO - Running post-run listener: _resource_record_post_run 15:30:41 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 38413312; Write bytes: 706426368; Read time: 372; Write time: 1841 15:30:41 INFO - TinderboxPrint: CPU usage
53.5% 15:30:41 INFO - TinderboxPrint: I/O read bytes / time
38,413,312 / 372 15:30:41 INFO - TinderboxPrint: I/O write bytes / time
706,426,368 / 1,841 15:30:41 INFO - TinderboxPrint: CPU idle
2,256.2 (46.5%) 15:30:41 INFO - TinderboxPrint: CPU system
969.3 (20.0%) 15:30:41 INFO - TinderboxPrint: CPU user
1,628.3 (33.5%) 15:30:41 INFO - TinderboxPrint: Swap in / out
445,333,504 / 0 15:30:41 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 393611776; Write bytes: 388270080; Read time: 29309; Write time: 704 15:30:41 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 37180416; Write bytes: 295996928; Read time: 355; Write time: 1091 15:30:41 INFO - Running post-run listener: _upload_blobber_files 15:30:41 INFO - Blob upload gear active. 15:30:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:30:41 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 15:30:41 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'] 15:30:41 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 15:30:42 INFO - (blobuploader) - INFO - Open directory for files ... 15:30:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:30:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:30:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:30:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:30:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:30:43 INFO - (blobuploader) - INFO - Done attempting. 15:30:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:30:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:30:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:30:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:30:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:30:44 INFO - (blobuploader) - INFO - Done attempting. 15:30:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:30:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:30:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:30:46 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:30:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:30:46 INFO - (blobuploader) - INFO - Done attempting. 15:30:46 INFO - (blobuploader) - INFO - Iteration through files over. 15:30:46 INFO - Return code: 0 15:30:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:30:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:30:46 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ce4c1a0826bd5a84b0539d95509883d67bdd07b248a54f21c92353564337cb31c5493db71d570457872a7f8f3d52326e3215248bfe9a13354079ac89c454eff0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/87abf6aac5f407910b1704406acaf33e9245a7173d5ebf169cb8756d09a90590987bc965f5719423dc2068de1d0c0947ad513cfeb6680977ad77af1edb5742cf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e03e1d5746bb406776b1cbc18b991b7eb7f42ed988216a0b96779f06aae758e1bff7f2b04c42517b79f6c03980468e78cb44fe71aa6dac353b59fa3bb5be9056"} 15:30:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:30:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:30:46 INFO - Contents: 15:30:46 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ce4c1a0826bd5a84b0539d95509883d67bdd07b248a54f21c92353564337cb31c5493db71d570457872a7f8f3d52326e3215248bfe9a13354079ac89c454eff0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/87abf6aac5f407910b1704406acaf33e9245a7173d5ebf169cb8756d09a90590987bc965f5719423dc2068de1d0c0947ad513cfeb6680977ad77af1edb5742cf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e03e1d5746bb406776b1cbc18b991b7eb7f42ed988216a0b96779f06aae758e1bff7f2b04c42517b79f6c03980468e78cb44fe71aa6dac353b59fa3bb5be9056"} 15:30:46 INFO - Running post-run listener: copy_logs_to_upload_dir 15:30:46 INFO - Copying logs to upload dir... 15:30:46 INFO - mkdir: /builds/slave/test/build/upload/logs 15:30:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.923900 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-09-29 15:30:46.818549) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-29 15:30:46.821860) ========= 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.S3kfk0xSwh/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.KKXyX8aZme/Listeners TMPDIR=/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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/ce4c1a0826bd5a84b0539d95509883d67bdd07b248a54f21c92353564337cb31c5493db71d570457872a7f8f3d52326e3215248bfe9a13354079ac89c454eff0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/87abf6aac5f407910b1704406acaf33e9245a7173d5ebf169cb8756d09a90590987bc965f5719423dc2068de1d0c0947ad513cfeb6680977ad77af1edb5742cf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e03e1d5746bb406776b1cbc18b991b7eb7f42ed988216a0b96779f06aae758e1bff7f2b04c42517b79f6c03980468e78cb44fe71aa6dac353b59fa3bb5be9056"} build_url:https://queue.taskcluster.net/v1/task/WtrTeq1cTLS3BnWBvBfGmw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011264 build_url: 'https://queue.taskcluster.net/v1/task/WtrTeq1cTLS3BnWBvBfGmw/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/ce4c1a0826bd5a84b0539d95509883d67bdd07b248a54f21c92353564337cb31c5493db71d570457872a7f8f3d52326e3215248bfe9a13354079ac89c454eff0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/87abf6aac5f407910b1704406acaf33e9245a7173d5ebf169cb8756d09a90590987bc965f5719423dc2068de1d0c0947ad513cfeb6680977ad77af1edb5742cf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e03e1d5746bb406776b1cbc18b991b7eb7f42ed988216a0b96779f06aae758e1bff7f2b04c42517b79f6c03980468e78cb44fe71aa6dac353b59fa3bb5be9056"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-29 15:30:46.854138) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 15:30:46.854425) ========= 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.S3kfk0xSwh/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.KKXyX8aZme/Listeners TMPDIR=/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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.005357 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 15:30:46.902922) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-29 15:30:46.903209) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-29 15:30:46.903535) ========= ========= Total master_lag: 0.17 =========