builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0297 starttime: 1476394324.21 results: success (0) buildid: 20161013112920 builduid: 74bf32c4402d4a7a97e4ac40d67484f2 revision: f903be214d27ffd90dbb594bd6a91f671dd00eca ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-13 14:32:04.213791) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-13 14:32:04.214235) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-13 14:32:04.214508) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-13 14:32:04.238356) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 14:32:04.238725) ========= 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.QhSsA2eNqg/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.jznVOVHfRU/Listeners TMPDIR=/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-10-13 14:32:04-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.0M=0.001s 2016-10-13 14:32:04 (18.0 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.158024 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 14:32:04.418945) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 14:32:04.419252) ========= 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.QhSsA2eNqg/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.jznVOVHfRU/Listeners TMPDIR=/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/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.026966 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 14:32:04.467656) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 14:32:04.468014) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev f903be214d27ffd90dbb594bd6a91f671dd00eca --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev f903be214d27ffd90dbb594bd6a91f671dd00eca --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.QhSsA2eNqg/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.jznVOVHfRU/Listeners TMPDIR=/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-10-13 14:32:04,548 truncating revision to first 12 chars 2016-10-13 14:32:04,548 Setting DEBUG logging. 2016-10-13 14:32:04,548 attempt 1/10 2016-10-13 14:32:04,548 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/f903be214d27?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-13 14:32:05,176 unpacking tar archive at: autoland-f903be214d27/testing/mozharness/ program finished with exit code 0 elapsedTime=0.888970 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 14:32:05.382113) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-13 14:32:05.382531) ========= script_repo_revision: f903be214d27ffd90dbb594bd6a91f671dd00eca ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-13 14:32:05.383426) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-13 14:32:05.384036) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-13 14:32:05.402667) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-10-13 14:32:05.403009) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.QhSsA2eNqg/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.jznVOVHfRU/Listeners TMPDIR=/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/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 14:32:05 INFO - MultiFileLogger online at 20161013 14:32:05 in /builds/slave/test 14:32:05 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 14:32:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:32:05 INFO - {'append_to_log': False, 14:32:05 INFO - 'base_work_dir': '/builds/slave/test', 14:32:05 INFO - 'blob_upload_branch': 'Autoland', 14:32:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:32:05 INFO - 'branch': 'Autoland', 14:32:05 INFO - 'buildbot_json_path': 'buildprops.json', 14:32:05 INFO - 'config_files': ('talos/mac_config.py',), 14:32:05 INFO - 'default_actions': ('clobber', 14:32:05 INFO - 'read-buildbot-config', 14:32:05 INFO - 'download-and-extract', 14:32:05 INFO - 'populate-webroot', 14:32:05 INFO - 'create-virtualenv', 14:32:05 INFO - 'install', 14:32:05 INFO - 'run-tests'), 14:32:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:32:05 INFO - 'download_minidump_stackwalk': True, 14:32:05 INFO - 'download_symbols': 'ondemand', 14:32:05 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:32:05 INFO - 'tooltool.py': '/tools/tooltool.py', 14:32:05 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:32:05 INFO - '/tools/misc-python/virtualenv.py')}, 14:32:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:32:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:32:05 INFO - 'installer_path': 'installer.exe', 14:32:05 INFO - 'log_level': 'info', 14:32:05 INFO - 'log_name': 'talos', 14:32:05 INFO - 'log_to_console': True, 14:32:05 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:32:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:32:05 INFO - 'opt_config_files': (), 14:32:05 INFO - 'pip_index': False, 14:32:05 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:32:05 INFO - 'cmd': ('bash', 14:32:05 INFO - '-c', 14:32:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:32:05 INFO - 'enabled': True, 14:32:05 INFO - 'halt_on_failure': False, 14:32:05 INFO - 'name': 'check_screen_resolution'},), 14:32:05 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:32:05 INFO - 'cmd': ('bash', 14:32:05 INFO - '-c', 14:32:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:32:05 INFO - 'enabled': True, 14:32:05 INFO - 'halt_on_failure': False, 14:32:05 INFO - 'name': 'check_screen_resolution'},), 14:32:05 INFO - 'run_cmd_checks_enabled': True, 14:32:05 INFO - 'sps_profile': False, 14:32:05 INFO - 'sps_profile_interval': 0, 14:32:05 INFO - 'suite': 'dromaeojs', 14:32:05 INFO - 'system_bits': '32', 14:32:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:32:05 INFO - 'title': 't-yosemite-r7-0297', 14:32:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:32:05 INFO - 'use_talos_json': True, 14:32:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:32:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:32:05 INFO - 'work_dir': 'build'} 14:32:05 INFO - [mozharness: 2016-10-13 21:32:05.605947Z] Running clobber step. 14:32:05 INFO - Running pre-action listener: _resource_record_pre_action 14:32:05 INFO - Running main action method: clobber 14:32:05 INFO - rmtree: /builds/slave/test/build 14:32:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:32:07 INFO - Running post-action listener: _resource_record_post_action 14:32:07 INFO - [mozharness: 2016-10-13 21:32:07.829104Z] Finished clobber step (success) 14:32:07 INFO - [mozharness: 2016-10-13 21:32:07.829267Z] Running read-buildbot-config step. 14:32:07 INFO - Running pre-action listener: _resource_record_pre_action 14:32:07 INFO - Running main action method: read_buildbot_config 14:32:07 INFO - Using buildbot properties: 14:32:07 INFO - { 14:32:07 INFO - "project": "", 14:32:07 INFO - "product": "firefox", 14:32:07 INFO - "script_repo_revision": "production", 14:32:07 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 14:32:07 INFO - "repository": "", 14:32:07 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 14:32:07 INFO - "buildid": "20161013112920", 14:32:07 INFO - "pgo_build": "False", 14:32:07 INFO - "basedir": "/builds/slave/test", 14:32:07 INFO - "buildnumber": 262, 14:32:07 INFO - "slavename": "t-yosemite-r7-0297", 14:32:07 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 14:32:07 INFO - "platform": "yosemite_r7", 14:32:07 INFO - "scheduler": "tests-autoland-macosx64-talos", 14:32:07 INFO - "branch": "autoland", 14:32:07 INFO - "repo_path": "integration/autoland", 14:32:07 INFO - "revision": "f903be214d27ffd90dbb594bd6a91f671dd00eca", 14:32:07 INFO - "stage_platform": "macosx64", 14:32:07 INFO - "builduid": "74bf32c4402d4a7a97e4ac40d67484f2", 14:32:07 INFO - "slavebuilddir": "test" 14:32:07 INFO - } 14:32:07 INFO - Found installer url https://queue.taskcluster.net/v1/task/KRCRqzQnS_-aWRvi8TZKBw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 14:32:07 INFO - Running post-action listener: _resource_record_post_action 14:32:07 INFO - [mozharness: 2016-10-13 21:32:07.832563Z] Finished read-buildbot-config step (success) 14:32:07 INFO - [mozharness: 2016-10-13 21:32:07.832693Z] Running download-and-extract step. 14:32:07 INFO - Running pre-action listener: _resource_record_pre_action 14:32:07 INFO - Running main action method: download_and_extract 14:32:07 INFO - mkdir: /builds/slave/test/build/tests 14:32:07 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')]} 14:32:07 INFO - trying https://queue.taskcluster.net/v1/task/KRCRqzQnS_-aWRvi8TZKBw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 14:32:07 INFO - Downloading https://queue.taskcluster.net/v1/task/KRCRqzQnS_-aWRvi8TZKBw/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 14:32:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KRCRqzQnS_-aWRvi8TZKBw/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 14:32:09 INFO - Downloaded 1199 bytes. 14:32:09 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 14:32:09 INFO - Using the following test package requirements: 14:32:09 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 14:32:09 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 14:32:09 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 14:32:09 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 14:32:09 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 14:32:09 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 14:32:09 INFO - u'jsshell-mac.zip'], 14:32:09 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 14:32:09 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 14:32:09 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 14:32:09 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 14:32:09 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 14:32:09 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 14:32:09 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 14:32:09 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 14:32:09 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 14:32:09 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 14:32:09 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 14:32:09 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 14:32:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KRCRqzQnS_-aWRvi8TZKBw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 14:32:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KRCRqzQnS_-aWRvi8TZKBw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:32:09 INFO - Fetch https://queue.taskcluster.net/v1/task/KRCRqzQnS_-aWRvi8TZKBw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 14:32:09 INFO - Http code: 200 14:32:09 INFO - via: 1.1 bfc29822e21ecc352f71dce83e06ab74.cloudfront.net (CloudFront) 14:32:09 INFO - x-amz-cf-id: 6y2iQS26f_RQUwwHU-8pkwdkfjajza88CCSB_EsmME-hQJ2G6pJEhQ== 14:32:09 INFO - x-amz-version-id: midO2GdJQyVP0L3S09Q6QcpmZLu4Uk7G 14:32:10 INFO - Expected file size: 16515403 14:32:10 INFO - Obtained file size: 16515403 14:32:17 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 14:32:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KRCRqzQnS_-aWRvi8TZKBw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 14:32:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KRCRqzQnS_-aWRvi8TZKBw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:32:17 INFO - Fetch https://queue.taskcluster.net/v1/task/KRCRqzQnS_-aWRvi8TZKBw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 14:32:17 INFO - Http code: 200 14:32:17 INFO - via: 1.1 d8f42fc9558e3e49ebfdf8834baeb756.cloudfront.net (CloudFront) 14:32:17 INFO - x-amz-cf-id: vrlpF5UUIpDiK0KIzyJYR46ygaA_VvKGhjxdVHcWTxV7yyCdUdGBuQ== 14:32:17 INFO - x-amz-version-id: midO2GdJQyVP0L3S09Q6QcpmZLu4Uk7G 14:32:17 INFO - Expected file size: 16515403 14:32:17 INFO - Obtained file size: 16515403 14:32:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KRCRqzQnS_-aWRvi8TZKBw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 14:32:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KRCRqzQnS_-aWRvi8TZKBw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 14:32:24 INFO - Fetch https://queue.taskcluster.net/v1/task/KRCRqzQnS_-aWRvi8TZKBw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 14:32:25 INFO - Http code: 200 14:32:25 INFO - via: 1.1 23172d761378a3865feafcda78328053.cloudfront.net (CloudFront) 14:32:25 INFO - x-amz-cf-id: Lqs2zmkx8n0aqPrea4Q0IoOEX1aZepjoRDS6Z5FCayyMO_s6DFgRnw== 14:32:25 INFO - x-amz-version-id: voE.Gz5cpnxAQHrlTp2lCiPYcF6rS7HL 14:32:25 INFO - Expected file size: 14409006 14:32:25 INFO - Obtained file size: 14409006 14:32:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:32:25 INFO - trying https://queue.taskcluster.net/v1/task/KRCRqzQnS_-aWRvi8TZKBw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 14:32:25 INFO - Downloading https://queue.taskcluster.net/v1/task/KRCRqzQnS_-aWRvi8TZKBw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 14:32:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KRCRqzQnS_-aWRvi8TZKBw/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 14:32:28 INFO - Downloaded 111675191 bytes. 14:32:28 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KRCRqzQnS_-aWRvi8TZKBw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 14:32:28 INFO - mkdir: /builds/slave/test/properties 14:32:28 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:32:28 INFO - Writing to file /builds/slave/test/properties/build_url 14:32:28 INFO - Contents: 14:32:28 INFO - build_url:https://queue.taskcluster.net/v1/task/KRCRqzQnS_-aWRvi8TZKBw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 14:32:29 INFO - Running post-action listener: _resource_record_post_action 14:32:29 INFO - Running post-action listener: set_extra_try_arguments 14:32:29 INFO - [mozharness: 2016-10-13 21:32:29.226546Z] Finished download-and-extract step (success) 14:32:29 INFO - [mozharness: 2016-10-13 21:32:29.226692Z] Running populate-webroot step. 14:32:29 INFO - Running pre-action listener: _resource_record_pre_action 14:32:29 INFO - Running main action method: populate_webroot 14:32:29 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:32:29 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:32:29 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:32:29 INFO - '--tppagecycles', 14:32:29 INFO - '7'], 14:32:29 INFO - 'tests': ['tsvgm']}}, 14:32:29 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:32:29 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:32:29 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:32:29 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:32:29 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:32:29 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:32:29 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:32:29 INFO - 'talos_options': ['--disable-e10s'], 14:32:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:32:29 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:32:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:32:29 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:32:29 INFO - 'talos_options': ['--disable-e10s'], 14:32:29 INFO - 'tests': ['damp', 'tps']}, 14:32:29 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:32:29 INFO - 'tests': ['damp', 'tps']}, 14:32:29 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:32:29 INFO - 'tests': ['dromaeo_dom']}, 14:32:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:32:29 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:32:29 INFO - 'tests': ['basic_compositor_video']}, 14:32:29 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:32:29 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:32:29 INFO - 'tests': ['a11yr', 14:32:29 INFO - 'ts_paint', 14:32:29 INFO - 'tpaint', 14:32:29 INFO - 'sessionrestore', 14:32:29 INFO - 'sessionrestore_no_auto_restore', 14:32:29 INFO - 'tabpaint']}, 14:32:29 INFO - 'other-e10s': {'tests': ['a11yr', 14:32:29 INFO - 'ts_paint', 14:32:29 INFO - 'tpaint', 14:32:29 INFO - 'sessionrestore', 14:32:29 INFO - 'sessionrestore_no_auto_restore', 14:32:29 INFO - 'tabpaint']}, 14:32:29 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:32:29 INFO - 'ts_paint', 14:32:29 INFO - 'tpaint', 14:32:29 INFO - 'sessionrestore', 14:32:29 INFO - 'sessionrestore_no_auto_restore', 14:32:29 INFO - 'tabpaint']}, 14:32:29 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:32:29 INFO - 'ts_paint', 14:32:29 INFO - 'tpaint', 14:32:29 INFO - 'sessionrestore', 14:32:29 INFO - 'sessionrestore_no_auto_restore', 14:32:29 INFO - 'tabpaint']}, 14:32:29 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:32:29 INFO - 'tests': ['a11yr', 14:32:29 INFO - 'ts_paint', 14:32:29 INFO - 'tpaint', 14:32:29 INFO - 'sessionrestore', 14:32:29 INFO - 'sessionrestore_no_auto_restore', 14:32:29 INFO - 'tabpaint']}, 14:32:29 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:32:29 INFO - 'tests': ['a11yr', 14:32:29 INFO - 'ts_paint', 14:32:29 INFO - 'tpaint', 14:32:29 INFO - 'sessionrestore', 14:32:29 INFO - 'sessionrestore_no_auto_restore', 14:32:29 INFO - 'tabpaint']}, 14:32:29 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:32:29 INFO - 'tests': ['tsvgx', 14:32:29 INFO - 'tsvgr_opacity', 14:32:29 INFO - 'tart', 14:32:29 INFO - 'tscrollx', 14:32:29 INFO - 'cart']}, 14:32:29 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:32:29 INFO - 'tsvgr_opacity', 14:32:29 INFO - 'tart', 14:32:29 INFO - 'tscrollx', 14:32:29 INFO - 'cart']}, 14:32:29 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:32:29 INFO - 'talos_options': ['--disable-e10s'], 14:32:29 INFO - 'tests': ['tp5o']}, 14:32:29 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:32:29 INFO - 'tests': ['tp5o']}, 14:32:29 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:32:29 INFO - 'talos_options': ['--disable-e10s', 14:32:29 INFO - '--xperf_path', 14:32:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:32:29 INFO - 'tests': ['tp5n']}, 14:32:29 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:32:29 INFO - 'talos_options': ['--xperf_path', 14:32:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:32:29 INFO - 'tests': ['tp5n']}}, 14:32:29 INFO - 'talos.zip': {'path': '', 14:32:29 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:32:29 INFO - Running post-action listener: _resource_record_post_action 14:32:29 INFO - [mozharness: 2016-10-13 21:32:29.245516Z] Finished populate-webroot step (success) 14:32:29 INFO - [mozharness: 2016-10-13 21:32:29.245599Z] Running create-virtualenv step. 14:32:29 INFO - Running pre-action listener: _resource_record_pre_action 14:32:29 INFO - Running main action method: create_virtualenv 14:32:29 INFO - Creating virtualenv /builds/slave/test/build/venv 14:32:29 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 14:32:29 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:32:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:32:29 INFO - Using real prefix '/tools/python27' 14:32:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:32:30 INFO - Installing distribute.............................................................................................................................................................................................done. 14:32:32 INFO - Installing pip.................done. 14:32:32 INFO - Return code: 0 14:32:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:32:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:32:33 INFO - Reading from file tmpfile_stdout 14:32:33 INFO - Output received: 14:32:33 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) 14:32:33 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:32:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:32:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:32:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:32:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:32:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:32:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:32:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c865490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7db570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0f3d59ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c679d30>, '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 0x10c49fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c671b20>, '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.QhSsA2eNqg/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.jznVOVHfRU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/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 14:32:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 14:32:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:32:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QhSsA2eNqg/Render', 14:32:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:32:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:32:33 INFO - 'HOME': '/Users/cltbld', 14:32:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:32:33 INFO - 'LOGNAME': 'cltbld', 14:32:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:32:33 INFO - 'MOZ_NO_REMOTE': '1', 14:32:33 INFO - 'NO_EM_RESTART': '1', 14:32:33 INFO - 'PAGER': '/bin/cat', 14:32:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:32:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:32:33 INFO - 'PWD': '/builds/slave/test', 14:32:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:32:33 INFO - 'SHELL': '/bin/bash', 14:32:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jznVOVHfRU/Listeners', 14:32:33 INFO - 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', 14:32:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:32:33 INFO - 'USER': 'cltbld', 14:32:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:32:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:32:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:32:33 INFO - 'XPC_FLAGS': '0x0', 14:32:33 INFO - 'XPC_SERVICE_NAME': '0', 14:32:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:32:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:32:33 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 14:32:33 INFO - Cleaning up... 14:32:33 INFO - Return code: 0 14:32:33 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:32:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:32:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:32:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:32:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:32:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:32:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:32:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c865490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7db570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0f3d59ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c679d30>, '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 0x10c49fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c671b20>, '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.QhSsA2eNqg/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.jznVOVHfRU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/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 14:32:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 14:32:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 14:32:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QhSsA2eNqg/Render', 14:32:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:32:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:32:33 INFO - 'HOME': '/Users/cltbld', 14:32:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:32:33 INFO - 'LOGNAME': 'cltbld', 14:32:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:32:33 INFO - 'MOZ_NO_REMOTE': '1', 14:32:33 INFO - 'NO_EM_RESTART': '1', 14:32:33 INFO - 'PAGER': '/bin/cat', 14:32:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:32:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:32:33 INFO - 'PWD': '/builds/slave/test', 14:32:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:32:33 INFO - 'SHELL': '/bin/bash', 14:32:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jznVOVHfRU/Listeners', 14:32:33 INFO - 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', 14:32:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:32:33 INFO - 'USER': 'cltbld', 14:32:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:32:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:32:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:32:33 INFO - 'XPC_FLAGS': '0x0', 14:32:33 INFO - 'XPC_SERVICE_NAME': '0', 14:32:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:32:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:32:33 INFO - Downloading/unpacking psutil>=3.1.1 14:32:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:32:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:32:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:32:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:32:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:32:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:32:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:32:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:32:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:32:37 INFO - Installing collected packages: psutil 14:32:37 INFO - Running setup.py install for psutil 14:32:37 INFO - building 'psutil._psutil_osx' extension 14:32:37 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 14:32:37 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 14:32:37 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 14:32:37 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 14:32:38 INFO - building 'psutil._psutil_posix' extension 14:32:38 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 14:32:38 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:32:38 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:32:38 INFO - ^ 14:32:38 INFO - 1 warning generated. 14:32:38 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 14:32:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:32:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:32:38 INFO - Successfully installed psutil 14:32:38 INFO - Cleaning up... 14:32:38 INFO - Return code: 0 14:32:38 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:32:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:32:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:32:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:32:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:32:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:32:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:32:38 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 0x10c865490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7db570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0f3d59ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c679d30>, '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 0x10c49fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c671b20>, '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.QhSsA2eNqg/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.jznVOVHfRU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/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 14:32:38 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 14:32:38 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 14:32:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QhSsA2eNqg/Render', 14:32:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:32:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:32:38 INFO - 'HOME': '/Users/cltbld', 14:32:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:32:38 INFO - 'LOGNAME': 'cltbld', 14:32:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:32:38 INFO - 'MOZ_NO_REMOTE': '1', 14:32:38 INFO - 'NO_EM_RESTART': '1', 14:32:38 INFO - 'PAGER': '/bin/cat', 14:32:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:32:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:32:38 INFO - 'PWD': '/builds/slave/test', 14:32:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:32:38 INFO - 'SHELL': '/bin/bash', 14:32:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jznVOVHfRU/Listeners', 14:32:38 INFO - 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', 14:32:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:32:38 INFO - 'USER': 'cltbld', 14:32:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:32:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:32:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:32:38 INFO - 'XPC_FLAGS': '0x0', 14:32:38 INFO - 'XPC_SERVICE_NAME': '0', 14:32:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:32:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:32:38 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:32:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:32:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:32:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:32:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:32:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:32:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:32:41 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:32:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:32:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:32:41 INFO - Installing collected packages: mozsystemmonitor 14:32:41 INFO - Running setup.py install for mozsystemmonitor 14:32:41 INFO - Successfully installed mozsystemmonitor 14:32:41 INFO - Cleaning up... 14:32:41 INFO - Return code: 0 14:32:41 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:32:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:32:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:32:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:32:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:32:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:32:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:32:41 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 0x10c865490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7db570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0f3d59ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c679d30>, '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 0x10c49fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c671b20>, '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.QhSsA2eNqg/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.jznVOVHfRU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/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 14:32:41 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 14:32:41 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 14:32:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QhSsA2eNqg/Render', 14:32:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:32:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:32:41 INFO - 'HOME': '/Users/cltbld', 14:32:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:32:41 INFO - 'LOGNAME': 'cltbld', 14:32:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:32:41 INFO - 'MOZ_NO_REMOTE': '1', 14:32:41 INFO - 'NO_EM_RESTART': '1', 14:32:41 INFO - 'PAGER': '/bin/cat', 14:32:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:32:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:32:41 INFO - 'PWD': '/builds/slave/test', 14:32:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:32:41 INFO - 'SHELL': '/bin/bash', 14:32:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jznVOVHfRU/Listeners', 14:32:41 INFO - 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', 14:32:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:32:41 INFO - 'USER': 'cltbld', 14:32:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:32:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:32:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:32:41 INFO - 'XPC_FLAGS': '0x0', 14:32:41 INFO - 'XPC_SERVICE_NAME': '0', 14:32:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:32:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:32:41 INFO - Downloading/unpacking jsonschema==2.5.1 14:32:41 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 14:32:41 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 14:32:41 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 14:32:41 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 14:32:41 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 14:32:41 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 14:32:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:32:44 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:32:44 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 14:32:44 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 14:32:44 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 14:32:44 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 14:32:44 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 14:32:44 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 14:32:44 INFO - Downloading functools32-3.2.3-2.tar.gz 14:32:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:32:44 INFO - warning: no files found matching '*.txt' 14:32:44 INFO - no previously-included directories found matching 'build' 14:32:44 INFO - no previously-included directories found matching 'dist' 14:32:44 INFO - no previously-included directories found matching '.git*' 14:32:44 INFO - Installing collected packages: jsonschema, functools32 14:32:44 INFO - Running setup.py install for functools32 14:32:45 INFO - warning: no files found matching '*.txt' 14:32:45 INFO - no previously-included directories found matching 'build' 14:32:45 INFO - no previously-included directories found matching 'dist' 14:32:45 INFO - no previously-included directories found matching '.git*' 14:32:45 INFO - Successfully installed jsonschema functools32 14:32:45 INFO - Cleaning up... 14:32:45 INFO - Return code: 0 14:32:45 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:32:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:32:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:32:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:32:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:32:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:32:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:32:45 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 0x10c865490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7db570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0f3d59ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c679d30>, '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 0x10c49fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c671b20>, '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.QhSsA2eNqg/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.jznVOVHfRU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/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 14:32:45 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 14:32:45 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 14:32:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QhSsA2eNqg/Render', 14:32:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:32:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:32:45 INFO - 'HOME': '/Users/cltbld', 14:32:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:32:45 INFO - 'LOGNAME': 'cltbld', 14:32:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:32:45 INFO - 'MOZ_NO_REMOTE': '1', 14:32:45 INFO - 'NO_EM_RESTART': '1', 14:32:45 INFO - 'PAGER': '/bin/cat', 14:32:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:32:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:32:45 INFO - 'PWD': '/builds/slave/test', 14:32:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:32:45 INFO - 'SHELL': '/bin/bash', 14:32:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jznVOVHfRU/Listeners', 14:32:45 INFO - 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', 14:32:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:32:45 INFO - 'USER': 'cltbld', 14:32:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:32:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:32:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:32:45 INFO - 'XPC_FLAGS': '0x0', 14:32:45 INFO - 'XPC_SERVICE_NAME': '0', 14:32:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:32:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:32:45 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:32:45 INFO - Cleaning up... 14:32:45 INFO - Return code: 0 14:32:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:32:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:32:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:32:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:32:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:32:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:32:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:32:45 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 0x10c865490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7db570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0f3d59ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c679d30>, '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 0x10c49fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c671b20>, '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.QhSsA2eNqg/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.jznVOVHfRU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/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 14:32:45 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 14:32:45 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 14:32:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QhSsA2eNqg/Render', 14:32:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:32:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:32:45 INFO - 'HOME': '/Users/cltbld', 14:32:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:32:45 INFO - 'LOGNAME': 'cltbld', 14:32:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:32:45 INFO - 'MOZ_NO_REMOTE': '1', 14:32:45 INFO - 'NO_EM_RESTART': '1', 14:32:45 INFO - 'PAGER': '/bin/cat', 14:32:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:32:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:32:45 INFO - 'PWD': '/builds/slave/test', 14:32:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:32:45 INFO - 'SHELL': '/bin/bash', 14:32:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jznVOVHfRU/Listeners', 14:32:45 INFO - 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', 14:32:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:32:45 INFO - 'USER': 'cltbld', 14:32:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:32:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:32:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:32:45 INFO - 'XPC_FLAGS': '0x0', 14:32:45 INFO - 'XPC_SERVICE_NAME': '0', 14:32:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:32:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:32:45 INFO - Downloading/unpacking blobuploader==1.2.4 14:32:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:32:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:32:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:32:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:32:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:32:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:32:48 INFO - Downloading blobuploader-1.2.4.tar.gz 14:32:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:32:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:32:48 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 14:32:48 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 14:32:48 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 14:32:48 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 14:32:48 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 14:32:48 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 14:32:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:32:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:32:49 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 14:32:49 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 14:32:49 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 14:32:49 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 14:32:49 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 14:32:49 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 14:32:49 INFO - Downloading docopt-0.6.1.tar.gz 14:32:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:32:49 INFO - Installing collected packages: blobuploader, requests, docopt 14:32:49 INFO - Running setup.py install for blobuploader 14:32:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:32:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:32:49 INFO - Running setup.py install for requests 14:32:50 INFO - Running setup.py install for docopt 14:32:50 INFO - Successfully installed blobuploader requests docopt 14:32:50 INFO - Cleaning up... 14:32:50 INFO - Return code: 0 14:32:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:32:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:32:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:32:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:32:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:32:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:32:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:32:50 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 0x10c865490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7db570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0f3d59ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c679d30>, '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 0x10c49fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c671b20>, '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.QhSsA2eNqg/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.jznVOVHfRU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/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 14:32:50 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 14:32:50 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 14:32:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QhSsA2eNqg/Render', 14:32:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:32:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:32:50 INFO - 'HOME': '/Users/cltbld', 14:32:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:32:50 INFO - 'LOGNAME': 'cltbld', 14:32:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:32:50 INFO - 'MOZ_NO_REMOTE': '1', 14:32:50 INFO - 'NO_EM_RESTART': '1', 14:32:50 INFO - 'PAGER': '/bin/cat', 14:32:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:32:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:32:50 INFO - 'PWD': '/builds/slave/test', 14:32:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:32:50 INFO - 'SHELL': '/bin/bash', 14:32:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jznVOVHfRU/Listeners', 14:32:50 INFO - 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', 14:32:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:32:50 INFO - 'USER': 'cltbld', 14:32:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:32:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:32:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:32:50 INFO - 'XPC_FLAGS': '0x0', 14:32:50 INFO - 'XPC_SERVICE_NAME': '0', 14:32:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:32:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:32:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:32:50 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-8x6UB_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:32:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:32:50 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-64c9qW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:32:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:32:50 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-Bq867I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:32:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:32:51 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-iRr1IP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:32:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:32:51 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-MLJ2mJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:32:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:32:51 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-ZUnTX5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:32:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:32:51 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-Xc_QoD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:32:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:32:51 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-ZSu6uE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:32:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:32:51 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-xCm9dh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:32:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:32:51 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-r8v9tE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:32:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:32:51 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-nazzrK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:32:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:32:51 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-sk5Qok-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:32:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:32:51 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-YewfWX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:32:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:32:52 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-pZ8R7L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:32:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:32:52 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-WGqs3v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:32:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:32:52 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-1ibCqe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:32:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:32:52 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-RSI1_N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:32:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:32:52 INFO - Running setup.py install for manifestparser 14:32:52 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:32:52 INFO - Running setup.py install for mozcrash 14:32:52 INFO - Running setup.py install for mozdebug 14:32:52 INFO - Running setup.py install for mozdevice 14:32:53 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:32:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:32:53 INFO - Running setup.py install for mozfile 14:32:53 INFO - Running setup.py install for mozhttpd 14:32:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:32:53 INFO - Running setup.py install for mozinfo 14:32:53 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:32:53 INFO - Running setup.py install for mozInstall 14:32:53 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:32:53 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:32:53 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:32:53 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:32:53 INFO - Running setup.py install for mozleak 14:32:53 INFO - Running setup.py install for mozlog 14:32:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:32:54 INFO - Running setup.py install for moznetwork 14:32:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:32:54 INFO - Running setup.py install for mozprocess 14:32:54 INFO - Running setup.py install for mozprofile 14:32:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:32:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:32:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:32:54 INFO - Running setup.py install for mozrunner 14:32:54 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:32:54 INFO - Running setup.py install for mozscreenshot 14:32:54 INFO - Running setup.py install for moztest 14:32:54 INFO - Running setup.py install for mozversion 14:32:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:32:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:32:55 INFO - Cleaning up... 14:32:55 INFO - Return code: 0 14:32:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:32:55 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')]} 14:32:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:32:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:32:55 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')]} 14:32:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:32:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:32:55 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 0x10c865490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7db570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0f3d59ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c679d30>, '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 0x10c49fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c671b20>, '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.QhSsA2eNqg/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.jznVOVHfRU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/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 14:32:55 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 14:32:55 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 14:32:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QhSsA2eNqg/Render', 14:32:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:32:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:32:55 INFO - 'HOME': '/Users/cltbld', 14:32:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:32:55 INFO - 'LOGNAME': 'cltbld', 14:32:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:32:55 INFO - 'MOZ_NO_REMOTE': '1', 14:32:55 INFO - 'NO_EM_RESTART': '1', 14:32:55 INFO - 'PAGER': '/bin/cat', 14:32:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:32:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:32:55 INFO - 'PWD': '/builds/slave/test', 14:32:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:32:55 INFO - 'SHELL': '/bin/bash', 14:32:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jznVOVHfRU/Listeners', 14:32:55 INFO - 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', 14:32:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:32:55 INFO - 'USER': 'cltbld', 14:32:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:32:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:32:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:32:55 INFO - 'XPC_FLAGS': '0x0', 14:32:55 INFO - 'XPC_SERVICE_NAME': '0', 14:32:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:32:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:32:55 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-Yn2rZm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:32:55 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)) 14:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:32:55 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-dvtPSG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:32:55 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)) 14:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:32:55 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-P49Z3R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:32:55 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)) 14:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:32:55 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-7LIKF6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:32:55 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)) 14:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:32:55 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-kHsi80-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:32:55 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)) 14:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:32:55 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-heHdh0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:32:56 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)) 14:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:32:56 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-u4QbmQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:32:56 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)) 14:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:32:56 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-K8J4E7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:32:56 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)) 14:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:32:56 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-U8IEUW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:32:56 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)) 14:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:32:56 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-jvvsFF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:32:56 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)) 14:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:32:56 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-G2KUGp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:32:56 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)) 14:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:32:56 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-AuC5QZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:32:56 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)) 14:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:32:56 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-1udEef-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:32:56 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)) 14:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:32:56 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-HqQJO3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:32:56 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)) 14:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:32:56 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-tdFQ12-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:32:56 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)) 14:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:32:56 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-rerr1f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:32:57 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)) 14:32:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:32:57 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-iX8Doc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:32:57 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)) 14:32:57 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)) 14:32:57 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)) 14:32:57 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)) 14:32:57 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)) 14:32:57 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)) 14:32:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:32:57 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 14:32:57 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 14:32:57 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 14:32:57 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 14:32:57 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 14:32:57 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 14:32:59 INFO - Downloading blessings-1.6.tar.gz 14:32:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:32:59 INFO - Installing collected packages: blessings 14:32:59 INFO - Running setup.py install for blessings 14:32:59 INFO - Successfully installed blessings 14:32:59 INFO - Cleaning up... 14:33:00 INFO - Return code: 0 14:33:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:33:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:33:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:33:00 INFO - Reading from file tmpfile_stdout 14:33:00 INFO - Current package versions: 14:33:00 INFO - blessings == 1.6 14:33:00 INFO - blobuploader == 1.2.4 14:33:00 INFO - docopt == 0.6.1 14:33:00 INFO - functools32 == 3.2.3-2 14:33:00 INFO - jsonschema == 2.5.1 14:33:00 INFO - manifestparser == 1.1 14:33:00 INFO - mozInstall == 1.12 14:33:00 INFO - mozcrash == 0.17 14:33:00 INFO - mozdebug == 0.1 14:33:00 INFO - mozdevice == 0.48 14:33:00 INFO - mozfile == 1.2 14:33:00 INFO - mozhttpd == 0.7 14:33:00 INFO - mozinfo == 0.9 14:33:00 INFO - mozleak == 0.1 14:33:00 INFO - mozlog == 3.4 14:33:00 INFO - moznetwork == 0.27 14:33:00 INFO - mozprocess == 0.23 14:33:00 INFO - mozprofile == 0.28 14:33:00 INFO - mozrunner == 6.12 14:33:00 INFO - mozscreenshot == 0.1 14:33:00 INFO - mozsystemmonitor == 0.3 14:33:00 INFO - moztest == 0.7 14:33:00 INFO - mozversion == 1.4 14:33:00 INFO - psutil == 3.1.1 14:33:00 INFO - requests == 1.2.3 14:33:00 INFO - wsgiref == 0.1.2 14:33:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:33: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')]} 14:33:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:33:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:33: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')]} 14:33:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:33:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:33:00 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 0x10c865490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7db570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0f3d59ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c679d30>, '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 0x10c49fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c671b20>, '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.QhSsA2eNqg/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.jznVOVHfRU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/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 14:33:00 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 14:33:00 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 14:33:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QhSsA2eNqg/Render', 14:33:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:33:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:33:00 INFO - 'HOME': '/Users/cltbld', 14:33:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:33:00 INFO - 'LOGNAME': 'cltbld', 14:33:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:33:00 INFO - 'MOZ_NO_REMOTE': '1', 14:33:00 INFO - 'NO_EM_RESTART': '1', 14:33:00 INFO - 'PAGER': '/bin/cat', 14:33:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:33:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:33:00 INFO - 'PWD': '/builds/slave/test', 14:33:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:33:00 INFO - 'SHELL': '/bin/bash', 14:33:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jznVOVHfRU/Listeners', 14:33:00 INFO - 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', 14:33:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:33:00 INFO - 'USER': 'cltbld', 14:33:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:33:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:33:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:33:00 INFO - 'XPC_FLAGS': '0x0', 14:33:00 INFO - 'XPC_SERVICE_NAME': '0', 14:33:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:33:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:33:00 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)) 14:33:00 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)) 14:33:00 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)) 14:33:00 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)) 14:33:00 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)) 14:33:00 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)) 14:33:00 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)) 14:33:00 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)) 14:33:00 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)) 14:33:00 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)) 14:33:00 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)) 14:33:00 INFO - Cleaning up... 14:33:00 INFO - Return code: 0 14:33:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:33: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')]} 14:33:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:33:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:33: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')]} 14:33:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:33:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:33: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c865490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7db570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0f3d59ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c679d30>, '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 0x10c49fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c671b20>, '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.QhSsA2eNqg/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.jznVOVHfRU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/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 14:33: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', 'jsonschema'] in /builds/slave/test/build 14:33: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 jsonschema 14:33:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QhSsA2eNqg/Render', 14:33:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:33:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:33:00 INFO - 'HOME': '/Users/cltbld', 14:33:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:33:00 INFO - 'LOGNAME': 'cltbld', 14:33:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:33:00 INFO - 'MOZ_NO_REMOTE': '1', 14:33:00 INFO - 'NO_EM_RESTART': '1', 14:33:00 INFO - 'PAGER': '/bin/cat', 14:33:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:33:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:33:00 INFO - 'PWD': '/builds/slave/test', 14:33:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:33:00 INFO - 'SHELL': '/bin/bash', 14:33:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jznVOVHfRU/Listeners', 14:33:00 INFO - 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', 14:33:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:33:00 INFO - 'USER': 'cltbld', 14:33:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:33:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:33:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:33:00 INFO - 'XPC_FLAGS': '0x0', 14:33:00 INFO - 'XPC_SERVICE_NAME': '0', 14:33:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:33:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:33:00 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:33:00 INFO - Cleaning up... 14:33:00 INFO - Return code: 0 14:33:00 INFO - Running post-action listener: _resource_record_post_action 14:33:00 INFO - Running post-action listener: _start_resource_monitoring 14:33:00 INFO - Starting resource monitoring. 14:33:00 INFO - [mozharness: 2016-10-13 21:33:00.855031Z] Finished create-virtualenv step (success) 14:33:00 INFO - [mozharness: 2016-10-13 21:33:00.856015Z] Running install step. 14:33:00 INFO - Running pre-action listener: _resource_record_pre_action 14:33:00 INFO - Running main action method: install 14:33:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:33:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:33:01 INFO - Reading from file tmpfile_stdout 14:33:01 INFO - Detecting whether we're running mozinstall >=1.0... 14:33:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:33:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:33:01 INFO - Reading from file tmpfile_stdout 14:33:01 INFO - Output received: 14:33:01 INFO - Usage: mozinstall [options] installer 14:33:01 INFO - Options: 14:33:01 INFO - -h, --help show this help message and exit 14:33:01 INFO - -d DEST, --destination=DEST 14:33:01 INFO - Directory to install application into. [default: 14:33:01 INFO - "/builds/slave/test"] 14:33:01 INFO - --app=APP Application being installed. [default: firefox] 14:33:01 INFO - mkdir: /builds/slave/test/build/application 14:33:01 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'] 14:33:01 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 14:33:35 INFO - Reading from file tmpfile_stdout 14:33:35 INFO - Output received: 14:33:35 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 14:33:35 INFO - Running post-action listener: _resource_record_post_action 14:33:35 INFO - [mozharness: 2016-10-13 21:33:35.359242Z] Finished install step (success) 14:33:35 INFO - [mozharness: 2016-10-13 21:33:35.359375Z] Running run-tests step. 14:33:35 INFO - Running pre-action listener: _resource_record_pre_action 14:33:35 INFO - Running main action method: run_tests 14:33:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:33:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:33:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:33:35 INFO - 2016-10-13 14:33:35.373 screenresolution[1639:7539] starting screenresolution argv=screenresolution get 14:33:35 INFO - 2016-10-13 14:33:35.376 screenresolution[1639:7539] Display 0: 1600x1200x32@60 14:33:35 INFO - 2016-10-13 14:33:35.382 screenresolution[1640:7541] starting screenresolution argv=screenresolution list 14:33:35 INFO - Available Modes on Display 0 14:33:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:33:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:33:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:33:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:33:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:33:35 INFO - Intel Iris: 14:33:35 INFO - Chipset Model: Intel Iris 14:33:35 INFO - Type: GPU 14:33:35 INFO - Bus: Built-In 14:33:35 INFO - VRAM (Dynamic, Max): 1536 MB 14:33:35 INFO - Vendor: Intel (0x8086) 14:33:35 INFO - Device ID: 0x0a2e 14:33:35 INFO - Revision ID: 0x0009 14:33:35 INFO - Displays: 14:33:35 INFO - Display: 14:33:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:33:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:33:35 INFO - Display Serial Number: 1600x1200 60 14:33:35 INFO - Main Display: Yes 14:33:35 INFO - Mirror: Off 14:33:35 INFO - Online: Yes 14:33:35 INFO - Rotation: Supported 14:33:35 INFO - Return code: 0 14:33:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:33:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:33:35 INFO - Python 2.7.3 14:33:35 INFO - Return code: 0 14:33:35 INFO - grabbing minidump binary from tooltool 14:33: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')]} 14:33:35 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 0x10c679d30>, '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 0x10c49fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c671b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:33:35 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 14:33:35 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 14:33:35 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 14:33:35 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:33:35 INFO - Return code: 0 14:33:35 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:33:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:33:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:33:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:33:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:33:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KRCRqzQnS_-aWRvi8TZKBw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0297', '--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 14:33:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/KRCRqzQnS_-aWRvi8TZKBw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0297 --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 14:33:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QhSsA2eNqg/Render', 14:33:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:33:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:33:35 INFO - 'HOME': '/Users/cltbld', 14:33:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:33:35 INFO - 'LOGNAME': 'cltbld', 14:33:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:33:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:33:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:33:35 INFO - 'MOZ_NO_REMOTE': '1', 14:33:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:33:35 INFO - 'NO_EM_RESTART': '1', 14:33:35 INFO - 'PAGER': '/bin/cat', 14:33:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:33:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:33:35 INFO - 'PWD': '/builds/slave/test', 14:33:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:33:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:33:35 INFO - 'SHELL': '/bin/bash', 14:33:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jznVOVHfRU/Listeners', 14:33:35 INFO - 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', 14:33:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:33:35 INFO - 'USER': 'cltbld', 14:33:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:33:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:33:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:33:35 INFO - 'XPC_FLAGS': '0x0', 14:33:35 INFO - 'XPC_SERVICE_NAME': '0', 14:33:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:33:35 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KRCRqzQnS_-aWRvi8TZKBw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0297', '--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 14:33:35 INFO - mozversion application_buildid: 20161013112920 14:33:35 INFO - mozversion application_changeset: f903be214d27ffd90dbb594bd6a91f671dd00eca 14:33:35 INFO - mozversion application_display_name: Nightly 14:33:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:33:35 INFO - mozversion application_name: Firefox 14:33:35 INFO - mozversion application_remotingname: firefox 14:33:35 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 14:33:35 INFO - mozversion application_vendor: Mozilla 14:33:35 INFO - mozversion application_version: 52.0a1 14:33:35 INFO - mozversion platform_buildid: 20161013112920 14:33:35 INFO - mozversion platform_changeset: f903be214d27ffd90dbb594bd6a91f671dd00eca 14:33:35 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 14:33:35 INFO - mozversion platform_version: 52.0a1 14:33:35 INFO - using testdate: 1476394415 14:33:35 INFO - actual date: 1476394415 14:33:35 INFO - starting webserver on 'localhost:49267' 14:33:35 INFO - SUITE-START | Running 2 tests 14:33:35 INFO - TEST-START | dromaeo_css 14:33:35 INFO - operating with platform_type : mac_ 14:33:35 INFO - Initialising browser for dromaeo_css test... 14:33:35 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/tmphBB0zo/profile http://localhost:49267/getInfo.html) 14:33:38 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 14:33:38 INFO - PROCESS | 1650 | colorDepth:24 14:33:38 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 14:33:38 INFO - PROCESS | 1650 | __metrics 14:33:38 INFO - PROCESS | 1650 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:33:38 INFO - PROCESS | 1650 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:33:39 INFO - TEST-INFO | 1650: exit 0 14:33:39 INFO - Browser initialized. 14:33:39 INFO - Running cycle 1/1 for dromaeo_css test... 14:33:39 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/tmphBB0zo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:33:51 INFO - PROCESS | 1652 | RSS: Main: 235417600 14:33:51 INFO - PROCESS | 1652 | 14:34:42 INFO - PROCESS | 1652 | 1476394482685 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] 14:36:56 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49267/tests/dromaeo/cssquery-ext.html) 14:36:57 INFO - PROCESS | 1652 | RSS: Main: 379518976 14:36:57 INFO - PROCESS | 1652 | 14:40:00 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-ext.html (next: http://localhost:49267/tests/dromaeo/cssquery-jquery.html) 14:40:01 INFO - PROCESS | 1652 | RSS: Main: 373133312 14:40:01 INFO - PROCESS | 1652 | 14:43:03 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49267/tests/dromaeo/cssquery-mootools.html) 14:43:04 INFO - PROCESS | 1652 | RSS: Main: 366399488 14:43:04 INFO - PROCESS | 1652 | 14:46:06 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49267/tests/dromaeo/cssquery-prototype.html) 14:46:07 INFO - PROCESS | 1652 | RSS: Main: 351318016 14:46:07 INFO - PROCESS | 1652 | 14:49:10 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49267/tests/dromaeo/cssquery-yui.html) 14:49:11 INFO - PROCESS | 1652 | RSS: Main: 335519744 14:49:11 INFO - PROCESS | 1652 | 14:52:14 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-yui.html (next: http://localhost:49267/tests/dromaeo/cssquery-dojo.html) 14:52:14 INFO - PROCESS | 1652 | RSS: Main: 416559104 14:52:14 INFO - PROCESS | 1652 | 14:52:14 INFO - PROCESS | 1652 | __start_tp_report 14:52:14 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 14:52:14 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 14:52:14 INFO - PROCESS | 1652 | |i|pagename|runs| 14:52:14 INFO - PROCESS | 1652 | |0;dojo.html;3246.074868662431;3301.7523685723568;3315.6021277446694;3337.2824842658824;3378.662133786622;5517.503424691777;5554.055810512213;5619.77520899164;5627.831165065044;5635.520980716634;5547.306586676666;5588.944110558893;5595.804146854861;5601.383847776741;5613;5054.696718196901;5082.263071854579;5087.313212716273;5126.512981266792;5192.350956130484;5127.564157046656;5199.844004679847;5204.193350030751;5206.3231779868565;5206.479352064801;5076.111680455924;5139.897202055957;5144.511271429227;5176.378834539861;5185.403678576974;5189.792408303682;5210.374755029421;5221.634485586011;5221.791128354861;5266.8683282917855;5022.648414610979;5028.673136246132;5131.897362052775;5137.563307118862;5165.896682066375;5422.457754224585;5495.477929596682;5508.6970216638065;5609.607327487037;5642.012647786621;6029.8191054268445;6043.153958445821;6099.329073801879;6110.816675499742;6122.602030868024;6097.878042439126;6115.847103822395;6183.6598987055695;6194.5044396448175;6194.721237544321;4987.850364488999;5579.911917176193;5642.943570564283;5652.095664693711;5698.458646428583;6289.0252010938375;6293.118963345135;6293.244810622663;6301.873962520724;6304.117423560705;6012.849678757979;6048.425399587032;6054.51563874889;6062.060380641008;6071.2714474263375;6031;6034.818955431344;6092.482139018233;6111.622125543816;6114.358196333954;6054.545909056838;6074.605150665193;6078.483328917003;6083.300420451616;6089.056196289582;6087.756489740449;6092.055731361646;6106.145139680449;6114.205153330038;6115.449609535163;4196.223698615766;4822.734749588806;4833.275008748646;4873.366462359871;4877.097736918681;4365.061511774984;4368.08270263242;4392.846250381222;4433.113377324548;4473.060657262012;5683.403242659544;5690.374058853523;5695.601307908407;5699.145128230715;5701.429857014348;6049.576529642883;6123.203983482119;6131.478824299896;6234.407731265523;6252.906206406908;5634.183043458757;5641.830745077655;5643.689597072118;5665.631733937364;5667.801626943037;5296.841094767163;5305.734713264398;5306.8407947761625;5308.442613525602;5312.76092575835;6171.13604095418;6234.657093859882;6298.937010629835;6320.873582528324;6335.651539165298;6167.907481387872;6216.098665693539;6228.56400051992;6233.594816336924;6236.064590311489;5037.92443113361;6049.334573196857;6132.9386706134155;6246.9062964054665;6275.748970041147;5888.381719919346;5966.134910438049;5974.701264936823;6110.7861224856915;6139.631622102688;5154.664946778447;6113.172264937315;6131.294901086432;6155.076738489262;6241.032639940817;5149.098907691214;6058.182145410313;6065.029595264737;6066.9696651518225;6077.4226448487325;6041.6375017499095;6046.818595442144;6075.908861367171;6081.726322315418;6085.726142323695;5738.081906894877;5798.768049277982;5808.5353171747;5845.210896528914;5856.59003869725;6073.939260607337;6081.087836824512;6083.239595050619;6100.8779824404155;6141.201643786279;5208.739563021758;5871.647701137946;5897.5576831736935;5922.466977972003;5971.522278217819 14:52:14 INFO - PROCESS | 1652 | |1;ext.html;497.12505989458543;499.93513815572834;520.2040877457491;522.4305506997372;557.5483858074957;29721.959731409406;38772.41841372382;40595.79702101493;40766;42733.35899961503;41166;41423;41462;41649.95875103124;41901.79049104758;9339.766505837357;9530.99924507927;9547.570359333811;9568.760780980461;9604.567794449267;9317.767055823624;9404.717858464222;9421.340506164572;9424.246060315158;9424.52877356133;9393;9426.010268921767;9464.716058518256;9464.95267523665;9468.24254059677;9365.391249568791;9410.952945235298;9429.434233945985;9440.433573985583;9452.338336316461;9258.398204116711;9361.48511831849;9365.157135857737;9369.953150234205;9370.625174992992;39787.00532486682;39972;40043.7997810012;40086.79956600198;40170.59829401698;3155.3373791503814;3290.19390249388;3432.2105915639295;3668.394714872043;3803.7147213959065;29349.26626834325;30082.84958575215;30219.395612087854;30285.545716814264;31061.22346941322;3164.746820254374;3196.9840150799328;3234.644189139193;3323.6842499962463;3360.2775403288165;425370;432005;433363.99999999686;434283;441316;30296.394072118434;31133.377332453223;31243.062708118792;58407.12389314164;59064.70467647634;33251.3349733004;33347.3330533388;33413;33421.83289083539;33466.330673386394;13355.198688078746;13539;13557.186568805902;13559.932200338935;13591.252481113634;11162.051225645911;11235.325880447199;11236.157288203254;11236.775264494829;11275.943620281847;6659.833504162338;7610.847783044308;7684.1173912390705;7692.499987500794;7709.727894897279;3204.7086333271823;3264.9836750816094;3267;3267.281198136406;3299.736021118305;903.2683526343683;995.6117114325419;1001.249063202598;1006.627547807316;1059.7138772531373;26245.73754262433;27323.72676273212;29364.412711745645;29640.703592964226;29890.850545747133;1041.1358572384902;1041.5885725138528;1050.9001644843727;1067.1942683274233;1077.4612693653173;15012.099274043592;15059.322330495153;15148.848511514743;15399.23003849803;15478.845211547741;651807;658244;658254;658942;658969;55930.60173495614;56594;56807.43192568104;56935.43064569301;57010;1000.7790495595362;1123.528118190362;1239.4546399584153;1349.0286993364762;1363.4205462678322;29490.26274343073;29803;30523.29770476499;30542.694573054872;31291.74833006654;50613.98772024465;51476.742616288175;51699;51708.741456292475;51903;583349;588337;591080.0445997743;591517;591975;22723.431914202276;23217.76782232156;25654.61518077268;26028.73971260263;26036;43015;45652.771736141105;45914.540854591025;48191.75904120457;48427.5157248423;37211;37606.811965939996;37969.62030379771;38695.419568705925;38744.2251154981;29910.252243693212;37487;37970;38249;38581;35486.645133548336;35789.28421431505;35799.64200357963;35858.46212306766;36328.81835590805;32315.51526727148;33485.33029339448;33542.83228583939;33560;33758.83120584382;24294.392640184138;24460.75539244656;24561.63157552674;25116.748832511443;25149.119780807585 14:52:14 INFO - PROCESS | 1652 | |2;jquery.html;317.8140787639231;342.8799920027989;355.51294726225075;364.287817317145;394.2528907719871;14993.40026398944;15371.385144594216;16766.664666706674;20150.496237594067;20175;18523;19909;19953.201871925106;19957;20033.499162520944;5651.9152212716735;5956.791512297071;5969.522438204955;5973.924693555158;5974.49216816569;4173.853915112972;4392.626626736731;6251.687415629223;6498.220213574354;6589.538732288742;6293.842653933642;6519.543631945766;6601.603903765766;6611.074449577063;6620;6485.286618471988;6521.891278482669;6532.5753826001155;6539.705713242892;6547.639879806608;5674.063779476382;6119.256012036592;6795.422389096932;7261.60061196634;7280.8179795505;27113.1866044019;27339;27459.588106178424;28237.435251295065;28473;2221.0671517962496;2410.361254267603;2456.078970386105;2460.7785299323145;2469.5307891500556;15493.535193944199;16341.140300447374;16784.496465106065;16952.98282103078;17615.207315670825;1869.4204796512988;1940.5148712821795;2003.7695664998566;2057.4260690571723;2081.6044951459326;94464;100522;101620.49189754004;111457.44271278672;112400.43799781031;19769.58640509809;19896.329546079298;20397.286094986604;20750.377488675364;20837.79162208389;33315.500267496005;33392.66607333944;33421.83289083539;33488.83255583755;33508.664913351044;8377.036640786388;9667.226621870233;9879.407235565888;9986.750331241778;10007.599696012077;6759.932400676029;6772.52592318533;8018.759437216893;8077.919220807834;8217.753467395987;9057.094290571022;9454.621815127455;9537.856932146024;9540.570674319672;9558.808823823623;2709.3362126278794;3574.7140228781636;3646.5624125105155;3666.2484190740834;3674.3018826423427;368.46204541369366;381.1310212715014;397.594453657268;770.7340967366362;926.9582868770922;11484.310941343547;11625.360605166798;11694.824577631342;11707.88292117068;11905.285682859056;403.1734943366146;416.8228502886322;420.5248069681302;430.8621241202785;833.8999320081629;5738.942610573924;5749.68376739275;5939.2575928009;5964.582479226413;6167.229096362955;600166;606106;618377;631900;632325;76095;128768;130849;131571;136576;435.94550681164856;441.85418811792033;448.5985043386151;448.9730616162975;449.4965638484918;9992.65025724096;10340.27618066728;11118.110551155727;11256.21206515536;11283.943580282046;37506.62493375031;54137.187942181685;55974.1603875934;56014.71992640174;56089;62283.06575401282;80021;81214;81905.36189276061;82288.58855705684;5461.890762184655;5545.417731138173;7896.815477678394;7985.921900543584;8448.873266900879;11716.765664686489;12436.751264974831;12506.562270320495;12572.811407829073;13037.56586775435;9213.493257870614;10968.341899486057;11194.272372295774;11266.493007814504;11337.03635191018;10060.39637621745;10735.94632026835;10775.946120269662;11156.609518666808;11249.606263780728;10985.670429887115;11217.55129794799;11407.030402415556;11502.88497115018;11659.591914282959;7940.404469664682;8173.713920013009;8232.382571307056;8275;8422.157784221774;8263;9387.906120938977;10366.896331036593;11013.83479247796;11309.321440713255 14:52:14 INFO - PROCESS | 1652 | |3;mootools.html;1480.1119328402956;1483.3473271760427;1668.382698401591;1789.7046987247104;1848.1036697201846;8209.138040505743;8712.041675415707;8716.346274029442;8722.563871806407;8746.86879696805;7724.922750772491;7946.16565260648;8314.459560128587;8560.443571167869;8578.185072418126;2295.391721193885;2332.9968113711097;2333.1017558240114;2336.6611841283;2338.2985104468676;2308.319045881469;2321.5589038082794;2349.8120150387927;2350.059976009601;2360.3862995621175;2316.8030717389042;2337.5441788851094;2342.121704360865;2355.140373763581;2358.445765245166;2300.309907027885;2309.1109922679807;2316.548273086749;2318.594246006942;2322.9651555226687;2301.7813307735737;2303.332033710222;2305.1816605105114;2306.261996161229;2310.4570425949887;8685.913140868573;8745.693900713444;8755.912440875638;8763.605637746317;8766.430182038148;4963.138822941397;5064.113780088506;5145.614078944094;5150.768215430277;5159;3386.6951974322183;3467.0465621954163;3595.4247320428867;3600.207954250061;3609.169890925076;1012.8025035118155;1042.3381152967845;1047.9109275711573;1053.1364281289345;1068.551208492435;11375.829362559485;11506.654800356002;11507.07943364529;11517.193796434169;11521.769564608745;4459.687821852439;4517.232070548015;4697.342372067913;4715.198416269242;4747.287906813936;9724.367916085432;9728.124468797841;9738.707838764849;9758.853617195748;9773.12041916231;5078.390593128848;5138.100832354323;5142.048720986555;5144.15121504955;5166.302549155891;4216.894577635522;4220.85227017053;4371.584699453547;4680.836170734076;4693.647976401783;3565.823278318149;3655.3785856404475;3732.432378401078;3772.981135094362;3840.1759164397245;1022.9079382855579;1197.2098415046028;1232.827404163418;1241.1250068701543;1243.154654834703;722.1803253307534;825.1830687619214;884.2395539835736;887.4409122252939;925.6760133953062;5594.552435805126;5641.351244606841;5873.85459835328;5885.61743486672;6043.244594425697;914.3371055984358;921.6543796076471;942.783159873233;942.8632848236967;966.3284017607696;3741.1956429367815;3811.3330167220624;3884.3979183226643;3893.124047089439;3907.3552863777727;10255.33340332876;10702.143828493701;10714.892851071545;10721.249512534203;10721.30311529748;9927.851082233772;10493.790124197474;10494.790104198026;10512.421816799997;10538.894611053945;7954.403419743427;8133.75598732039;8158.551279679675;8162.020557533134;8189;5747.770089196385;5785.218995435731;5796.246487956707;5973.581849270683;6144.818589400698;9634.13292803651;9960.402375857471;9976.850347244652;9988.700338989842;9996.600135994478;9856.408615482807;10165.339252948535;10181.74545636365;10194.745131371776;10201.285909986229;6802.6598670065305;7070;7094.716211351488;7317.817054573678;7446.776596702108;7916.881246781188;8105.797355065934;8252.958735206286;8345.123762005145;8370.497770133812;7304.452166087671;7402.7409040683315;7413.406927445683;7432.070991126109;7572.167061623107;5883.264591926009;7152.821179470555;7231.4214862811905;7296.598687072262;7311.341979221896;6428.325025872216;6617;6730.6779161189315;6790.287019862843;6816.488763342868;4884.511548845087;4930.408350997904;4975.278584605139;4990.725510096834;5061.569766569883;4786.617070634411;4788.377510923558;4890.144224760724;4961.479044700399;4966.279889416087 14:52:14 INFO - PROCESS | 1652 | |4;prototype.html;289.7291032884253;291.2311497646216;297.2316561688037;307.24724425158365;308.6913086913087;4169.583041695836;4855.25210924068;5317.521423071923;5396.298481197444;5433.320834895638;4726.550977657125;5153.330067091282;5217.2695822584865;5230.450802665722;5263.736703191235;3552.3960926642535;3911.8044097795137;4032.9395059074136;4068.4304197412384;4115.629593336599;3347.8493467793887;3547.964520354802;3828.464015037897;3960.4059391091278;4088.8568900088503;4007.2185923744883;4014.7390419622775;4016.5581786003518;4023.2154729827694;4048.271311163989;3706.814659267026;3832.9616703832885;4000.639942405183;4034.3545032794614;4063.6545893599077;2917.6498820141505;3448.499967504698;3482.9129271768406;3498.475228715713;3511.2977404518942;4440.666949978765;4889.486603906575;4987.750612469362;5161.593348390044;5248.448912864133;3678.503402040717;4595.655325850575;4602.516735742765;4602.861914142581;4618.769061546909;4380.912381752347;5043.621728370387;5317;5540.199435183581;5645.491905728504;3582.3372676055014;3797.259534390795;3824.808759562011;3826.111285979096;3844.480995065658;5322.988632159877;5724.513416359614;5941.970290148565;5947.650677982173;5961.135635332896;3917.5690674025836;3918.686505079587;3925.4700615417123;3927.9410808837893;3932.5674175840636;3811.7712937223855;3934.488516492838;3961.900952476211;3966.087799806003;3990.3016972029777;4550.704204226715;4568.7156421789105;4595.425571803525;4601.562851529099;4602.838900638461;5218.851852592424;5555.472230138131;5700.3444603871085;5760.3951585083805;5773.566982476331;1249.6750844780422;1278.5844600504688;1284.9335051906894;1310.7181955879532;1332.5336132353598;753.1865585168006;850.6984313999591;897.1790811407488;898.595631965618;905.2215095018279;4541.227991241497;5247.685138891678;5273.578113750891;5307.230451584499;5316.601254905898;5119.232115182678;5537.151679582651;5716;5742.817729262853;5755.510781583613;4616.929741721718;4996.3504744383;4998.700077995332;5233.528982391603;5275.340582427197;2121.2363549122306;2204.8787316697417;2517.483915797294;2547.274026902324;2556.297018319977;4271.914561708749;4634.467036290802;4666.766661666903;5009.474005229471;5043.083628221326;4322.1041901925155;4827.758612069382;4875.658703890765;4902.965269413225;4917.688686317425;4439.622632076245;4753.952460475351;4965.020987407599;4993.400791904995;5004.42449118356;4089.5297040840683;4155.10665206982;4189.350650649154;4452.866414007585;4831.396075490564;4507.121111383249;4605.144810217686;4755.453122890912;4762.976185119052;4797;5109.6678715883345;5536.944630553804;5618.073017951992;5619.129034999582;6028.909566356596;3852.4221366795205;3904.9414258785573;3923.47033150521;3935.988968439089;3944.092858642471;5526.392096869261;5614.354349249888;5789.334226563999;5907.366969313755;5919.260092488439;5649.7740090397565;5650.519705824887;5656.943430565641;5678.574106942078;5687.67271146384;4793.856184314476;5646.802361917313;5661.603687741818;5719.2279042328755;5743.712814359349;4966.900661986812;5189.532942035235;5493.972530137324;5580.469855363734;5587.636803607753;5258.474152584711;5313.495217954288;5413.891722165614;5654.208410822571;5658.462446067617;4363.629091527256;5120.6159538035545;5295.4581800735095;5581.748821303132;5583.609147359646 14:52:14 INFO - PROCESS | 1652 | |5;yui.html;672.5090683800826;696.04989189756;716.6345163966375;736.7421402509126;798.8841617965398;13628.863711362883;14390;14423.350949207284;14432.350544225495;14450.988430809848;12807;12924.870751292485;12929.870701292984;12930.870691293085;12989.285589292609;690.9689063992107;716.706150478304;717.3651318583065;823.6211342782327;841.1504380575601;856.6016802186982;857.2584714222185;863.2791618998112;864.2653744317342;864.9308055355557;841.5119230846125;844.9704260350888;888.3781353052857;909.6361455417853;910.9590068446936;711.4130842055273;743.5241445474901;904.231403307187;905.3255324783013;905.8731777551181;652.7845810882445;675.5440077947366;678.5080816408114;678.5182520410515;687.9759208427731;13910.443582256697;13940.51208207718;13943.651408714763;13952.2326272055;13981.930090349584;13544;13899.861001389958;13931.58205253844;13981.930090349482;14092.084014539023;12596.874031259753;12607.369631518388;12674.74650506994;12760.106792524526;12885.484580616763;4439.66702497314;4499.910001799978;4595.517470665565;4616.792244349012;4726.621870250372;111299.44350278277;111942.44028779805;114248.42875785651;115156.42421787838;116596.41701791437;9422.434653920787;10693.41186234749;10812.243142980073;10885.455727213608;11119.110471162288;20708.482287942923;20771.27300544474;21571.67642485364;21581.67627485589;21584.892075539523;7651.387888968869;7695.268949449794;7778.416618753616;7808.7657370278985;7916.643751031217;4227.196832601796;4242.342436922282;4256.765877876746;4332.696711230184;4358.8038538265855;3370.427027405347;3388.0852169913987;3411.2836304375865;3473.2532505511435;3475.6176820549717;639;647.2621211818531;653.1509038250256;655.7311502284041;656.983575410609;493.82469223425886;563.4618938913328;584.9444302791228;590.094205394717;613.4969325153331;8719.60761765722;8818.77953051166;8883.511406872554;9424.481653509123;9664.71005869825;462.8195003948463;621.6262060845492;626.4330780643525;643.8937575300117;692.5671455340412;4036.7981600920425;4188.602082802129;4427.291633338715;4648.837790552362;4658.045100754337;123767.38116309542;145205;147038;148959;151311;124084;132268;133139.33430332787;134688.3265583666;140675.59324406626;105586;109711;110941.44529277463;111928.99999999837;113055.43472282586;9526.082136483043;9549.952250238704;9579.9042009579;9594.42433453995;9700.029997000525;53386.73306633442;55421;62946.13173353331;63373;65914.01128983164;68701.65649171722;92808.07191927994;93101.5344923271;94672;94931.52534237561;12193.085518585964;12508.812367814307;12781.424835882593;12795.552155674506;12819.679508012374;13896.86103138996;14281.571552853431;14609.926950365605;14712.632184195481;15137.848621514084;11153.88846111561;13495.987800914774;13529.356103051448;13623.18260904349;13850.792238116235;13357.398917048562;13391.464341426234;13637.181769093886;13678;13809.86190138126;13054.412551435018;13326.733465330833;13646.727065458834;13785.035047546575;13791.724165516835;9670.613175473183;11263.211575189658;11366.43167841588;11373.374464404536;11522.42387880586;10284.794304114026;11384.48769805344;11476.254043487146;11541;11547.653570392566 14:52:14 INFO - PROCESS | 1652 | __end_tp_report 14:52:14 INFO - PROCESS | 1652 | __start_cc_report 14:52:14 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,1357 14:52:14 INFO - PROCESS | 1652 | __end_cc_report 14:52:14 INFO - PROCESS | 1652 | __startTimestamp1476395534898__endTimestamp 14:52:14 INFO - PROCESS | 1652 | 14:52:14 INFO - PROCESS | 1652 | ------- Summary: start ------- 14:52:14 INFO - PROCESS | 1652 | Number of tests: 6 14:52:14 INFO - PROCESS | 1652 | 14:52:14 INFO - PROCESS | 1652 | [#0] dojo.html Cycles:165 Average:5640.12 Median:5845.21 stddev:638.90 (10.9%) stddev-sans-first:612.63 14:52:14 INFO - PROCESS | 1652 | Values: 3246.1 3301.8 3315.6 3337.3 3378.7 5517.5 5554.1 5619.8 5627.8 5635.5 5547.3 5588.9 5595.8 5601.4 5613.0 5054.7 5082.3 5087.3 5126.5 5192.4 5127.6 5199.8 5204.2 5206.3 5206.5 5076.1 5139.9 5144.5 5176.4 5185.4 5189.8 5210.4 5221.6 5221.8 5266.9 5022.6 5028.7 5131.9 5137.6 5165.9 5422.5 5495.5 5508.7 5609.6 5642.0 6029.8 6043.2 6099.3 6110.8 6122.6 6097.9 6115.8 6183.7 6194.5 6194.7 4987.9 5579.9 5642.9 5652.1 5698.5 6289.0 6293.1 6293.2 6301.9 6304.1 6012.8 6048.4 6054.5 6062.1 6071.3 6031.0 6034.8 6092.5 6111.6 6114.4 6054.5 6074.6 6078.5 6083.3 6089.1 6087.8 6092.1 6106.1 6114.2 6115.4 4196.2 4822.7 4833.3 4873.4 4877.1 4365.1 4368.1 4392.8 4433.1 4473.1 5683.4 5690.4 5695.6 5699.1 5701.4 6049.6 6123.2 6131.5 6234.4 6252.9 5634.2 5641.8 5643.7 5665.6 5667.8 5296.8 5305.7 5306.8 5308.4 5312.8 6171.1 6234.7 6298.9 6320.9 6335.7 6167.9 6216.1 6228.6 6233.6 6236.1 5037.9 6049.3 6132.9 6246.9 6275.7 5888.4 5966.1 5974.7 6110.8 6139.6 5154.7 6113.2 6131.3 6155.1 6241.0 5149.1 6058.2 6065.0 6067.0 6077.4 6041.6 6046.8 6075.9 6081.7 6085.7 5738.1 5798.8 5808.5 5845.2 5856.6 6073.9 6081.1 6083.2 6100.9 6141.2 5208.7 5871.6 5897.6 5922.5 5971.5 14:52:14 INFO - PROCESS | 1652 | 14:52:14 INFO - PROCESS | 1652 | [#1] ext.html Cycles:180 Average:67224.33 Median:37969.81 stddev:152291.09 (401.1%) stddev-sans-first:152635.90 14:52:14 INFO - PROCESS | 1652 | Values: 497.1 499.9 520.2 522.4 557.5 29722.0 38772.4 40595.8 40766.0 42733.4 41166.0 41423.0 41462.0 41650.0 41901.8 9339.8 9531.0 9547.6 9568.8 9604.6 9317.8 9404.7 9421.3 9424.2 9424.5 9393.0 9426.0 9464.7 9465.0 9468.2 9365.4 9411.0 9429.4 9440.4 9452.3 9258.4 9361.5 9365.2 9370.0 9370.6 39787.0 39972.0 40043.8 40086.8 40170.6 3155.3 3290.2 3432.2 3668.4 3803.7 29349.3 30082.8 30219.4 30285.5 31061.2 3164.7 3197.0 3234.6 3323.7 3360.3 425370.0 432005.0 433364.0 434283.0 441316.0 30296.4 31133.4 31243.1 58407.1 59064.7 33251.3 33347.3 33413.0 33421.8 33466.3 13355.2 13539.0 13557.2 13559.9 13591.3 11162.1 11235.3 11236.2 11236.8 11275.9 6659.8 7610.8 7684.1 7692.5 7709.7 3204.7 3265.0 3267.0 3267.3 3299.7 903.3 995.6 1001.2 1006.6 1059.7 26245.7 27323.7 29364.4 29640.7 29890.9 1041.1 1041.6 1050.9 1067.2 1077.5 15012.1 15059.3 15148.8 15399.2 15478.8 651807.0 658244.0 658254.0 658942.0 658969.0 55930.6 56594.0 56807.4 56935.4 57010.0 1000.8 1123.5 1239.5 1349.0 1363.4 29490.3 29803.0 30523.3 30542.7 31291.7 50614.0 51476.7 51699.0 51708.7 51903.0 583349.0 588337.0 591080.0 591517.0 591975.0 22723.4 23217.8 25654.6 26028.7 26036.0 43015.0 45652.8 45914.5 48191.8 48427.5 37211.0 37606.8 37969.6 38695.4 38744.2 29910.3 37487.0 37970.0 38249.0 38581.0 35486.6 35789.3 35799.6 35858.5 36328.8 32315.5 33485.3 33542.8 33560.0 33758.8 24294.4 24460.8 24561.6 25116.7 25149.1 14:52:14 INFO - PROCESS | 1652 | 14:52:14 INFO - PROCESS | 1652 | [#2] jquery.html Cycles:180 Average:35389.38 Median:18943.88 stddev:102522.50 (541.2%) stddev-sans-first:102776.29 14:52:14 INFO - PROCESS | 1652 | Values: 317.8 342.9 355.5 364.3 394.3 14993.4 15371.4 16766.7 20150.5 20175.0 18523.0 19909.0 19953.2 19957.0 20033.5 5651.9 5956.8 5969.5 5973.9 5974.5 4173.9 4392.6 6251.7 6498.2 6589.5 6293.8 6519.5 6601.6 6611.1 6620.0 6485.3 6521.9 6532.6 6539.7 6547.6 5674.1 6119.3 6795.4 7261.6 7280.8 27113.2 27339.0 27459.6 28237.4 28473.0 2221.1 2410.4 2456.1 2460.8 2469.5 15493.5 16341.1 16784.5 16953.0 17615.2 1869.4 1940.5 2003.8 2057.4 2081.6 94464.0 100522.0 101620.5 111457.4 112400.4 19769.6 19896.3 20397.3 20750.4 20837.8 33315.5 33392.7 33421.8 33488.8 33508.7 8377.0 9667.2 9879.4 9986.8 10007.6 6759.9 6772.5 8018.8 8077.9 8217.8 9057.1 9454.6 9537.9 9540.6 9558.8 2709.3 3574.7 3646.6 3666.2 3674.3 368.5 381.1 397.6 770.7 927.0 11484.3 11625.4 11694.8 11707.9 11905.3 403.2 416.8 420.5 430.9 833.9 5738.9 5749.7 5939.3 5964.6 6167.2 600166.0 606106.0 618377.0 631900.0 632325.0 76095.0 128768.0 130849.0 131571.0 136576.0 435.9 441.9 448.6 449.0 449.5 9992.7 10340.3 11118.1 11256.2 11283.9 37506.6 54137.2 55974.2 56014.7 56089.0 62283.1 80021.0 81214.0 81905.4 82288.6 5461.9 5545.4 7896.8 7985.9 8448.9 11716.8 12436.8 12506.6 12572.8 13037.6 9213.5 10968.3 11194.3 11266.5 11337.0 10060.4 10735.9 10775.9 11156.6 11249.6 10985.7 11217.6 11407.0 11502.9 11659.6 7940.4 8173.7 8232.4 8275.0 8422.2 8263.0 9387.9 10366.9 11013.8 11309.3 14:52:14 INFO - PROCESS | 1652 | 14:52:14 INFO - PROCESS | 1652 | [#3] mootools.html Cycles:180 Average:5601.09 Median:4970.78 stddev:3154.08 (63.5%) stddev-sans-first:3147.72 14:52:14 INFO - PROCESS | 1652 | Values: 1480.1 1483.3 1668.4 1789.7 1848.1 8209.1 8712.0 8716.3 8722.6 8746.9 7724.9 7946.2 8314.5 8560.4 8578.2 2295.4 2333.0 2333.1 2336.7 2338.3 2308.3 2321.6 2349.8 2350.1 2360.4 2316.8 2337.5 2342.1 2355.1 2358.4 2300.3 2309.1 2316.5 2318.6 2323.0 2301.8 2303.3 2305.2 2306.3 2310.5 8685.9 8745.7 8755.9 8763.6 8766.4 4963.1 5064.1 5145.6 5150.8 5159.0 3386.7 3467.0 3595.4 3600.2 3609.2 1012.8 1042.3 1047.9 1053.1 1068.6 11375.8 11506.7 11507.1 11517.2 11521.8 4459.7 4517.2 4697.3 4715.2 4747.3 9724.4 9728.1 9738.7 9758.9 9773.1 5078.4 5138.1 5142.0 5144.2 5166.3 4216.9 4220.9 4371.6 4680.8 4693.6 3565.8 3655.4 3732.4 3773.0 3840.2 1022.9 1197.2 1232.8 1241.1 1243.2 722.2 825.2 884.2 887.4 925.7 5594.6 5641.4 5873.9 5885.6 6043.2 914.3 921.7 942.8 942.9 966.3 3741.2 3811.3 3884.4 3893.1 3907.4 10255.3 10702.1 10714.9 10721.2 10721.3 9927.9 10493.8 10494.8 10512.4 10538.9 7954.4 8133.8 8158.6 8162.0 8189.0 5747.8 5785.2 5796.2 5973.6 6144.8 9634.1 9960.4 9976.9 9988.7 9996.6 9856.4 10165.3 10181.7 10194.7 10201.3 6802.7 7070.0 7094.7 7317.8 7446.8 7916.9 8105.8 8253.0 8345.1 8370.5 7304.5 7402.7 7413.4 7432.1 7572.2 5883.3 7152.8 7231.4 7296.6 7311.3 6428.3 6617.0 6730.7 6790.3 6816.5 4884.5 4930.4 4975.3 4990.7 5061.6 4786.6 4788.4 4890.1 4961.5 4966.3 14:52:14 INFO - PROCESS | 1652 | 14:52:14 INFO - PROCESS | 1652 | [#4] prototype.html Cycles:180 Average:4380.01 Median:4778.42 stddev:1347.16 (28.2%) stddev-sans-first:1315.50 14:52:14 INFO - PROCESS | 1652 | Values: 289.7 291.2 297.2 307.2 308.7 4169.6 4855.3 5317.5 5396.3 5433.3 4726.6 5153.3 5217.3 5230.5 5263.7 3552.4 3911.8 4032.9 4068.4 4115.6 3347.8 3548.0 3828.5 3960.4 4088.9 4007.2 4014.7 4016.6 4023.2 4048.3 3706.8 3833.0 4000.6 4034.4 4063.7 2917.6 3448.5 3482.9 3498.5 3511.3 4440.7 4889.5 4987.8 5161.6 5248.4 3678.5 4595.7 4602.5 4602.9 4618.8 4380.9 5043.6 5317.0 5540.2 5645.5 3582.3 3797.3 3824.8 3826.1 3844.5 5323.0 5724.5 5942.0 5947.7 5961.1 3917.6 3918.7 3925.5 3927.9 3932.6 3811.8 3934.5 3961.9 3966.1 3990.3 4550.7 4568.7 4595.4 4601.6 4602.8 5218.9 5555.5 5700.3 5760.4 5773.6 1249.7 1278.6 1284.9 1310.7 1332.5 753.2 850.7 897.2 898.6 905.2 4541.2 5247.7 5273.6 5307.2 5316.6 5119.2 5537.2 5716.0 5742.8 5755.5 4616.9 4996.4 4998.7 5233.5 5275.3 2121.2 2204.9 2517.5 2547.3 2556.3 4271.9 4634.5 4666.8 5009.5 5043.1 4322.1 4827.8 4875.7 4903.0 4917.7 4439.6 4754.0 4965.0 4993.4 5004.4 4089.5 4155.1 4189.4 4452.9 4831.4 4507.1 4605.1 4755.5 4763.0 4797.0 5109.7 5536.9 5618.1 5619.1 6028.9 3852.4 3904.9 3923.5 3936.0 3944.1 5526.4 5614.4 5789.3 5907.4 5919.3 5649.8 5650.5 5656.9 5678.6 5687.7 4793.9 5646.8 5661.6 5719.2 5743.7 4966.9 5189.5 5494.0 5580.5 5587.6 5258.5 5313.5 5413.9 5654.2 5658.5 4363.6 5120.6 5295.5 5581.7 5583.6 14:52:14 INFO - PROCESS | 1652 | 14:52:14 INFO - PROCESS | 1652 | [#5] yui.html Cycles:180 Average:24674.48 Median:3442.27 stddev:39598.98 (1150.4%) stddev-sans-first:39669.06 14:52:14 INFO - PROCESS | 1652 | Values: 672.5 696.0 716.6 736.7 798.9 13628.9 14390.0 14423.4 14432.4 14451.0 12807.0 12924.9 12929.9 12930.9 12989.3 691.0 716.7 717.4 823.6 841.2 856.6 857.3 863.3 864.3 864.9 841.5 845.0 888.4 909.6 911.0 711.4 743.5 904.2 905.3 905.9 652.8 675.5 678.5 678.5 688.0 13910.4 13940.5 13943.7 13952.2 13981.9 13544.0 13899.9 13931.6 13981.9 14092.1 12596.9 12607.4 12674.7 12760.1 12885.5 4439.7 4499.9 4595.5 4616.8 4726.6 111299.4 111942.4 114248.4 115156.4 116596.4 9422.4 10693.4 10812.2 10885.5 11119.1 20708.5 20771.3 21571.7 21581.7 21584.9 7651.4 7695.3 7778.4 7808.8 7916.6 4227.2 4242.3 4256.8 4332.7 4358.8 3370.4 3388.1 3411.3 3473.3 3475.6 639.0 647.3 653.2 655.7 657.0 493.8 563.5 584.9 590.1 613.5 8719.6 8818.8 8883.5 9424.5 9664.7 462.8 621.6 626.4 643.9 692.6 4036.8 4188.6 4427.3 4648.8 4658.0 123767.4 145205.0 147038.0 148959.0 151311.0 124084.0 132268.0 133139.3 134688.3 140675.6 105586.0 109711.0 110941.4 111929.0 113055.4 9526.1 9550.0 9579.9 9594.4 9700.0 53386.7 55421.0 62946.1 63373.0 65914.0 68701.7 92808.1 93101.5 94672.0 94931.5 12193.1 12508.8 12781.4 12795.6 12819.7 13896.9 14281.6 14609.9 14712.6 15137.8 11153.9 13496.0 13529.4 13623.2 13850.8 13357.4 13391.5 13637.2 13678.0 13809.9 13054.4 13326.7 13646.7 13785.0 13791.7 9670.6 11263.2 11366.4 11373.4 11522.4 10284.8 11384.5 11476.3 11541.0 11547.7 14:52:14 INFO - PROCESS | 1652 | -------- Summary: end -------- 14:52:14 INFO - PROCESS | 1652 | 14:52:15 INFO - TEST-INFO | 1652: exit 0 14:52:15 INFO - TEST-OK | dromaeo_css | took 1119585ms 14:52:15 INFO - TEST-START | kraken 14:52:15 INFO - operating with platform_type : mac_ 14:52:15 INFO - Initialising browser for kraken test... 14:52:15 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/tmp6I7gcf/profile http://localhost:49267/getInfo.html) 14:52:17 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 14:52:17 INFO - PROCESS | 1661 | colorDepth:24 14:52:17 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 14:52:17 INFO - PROCESS | 1661 | __metrics 14:52:17 INFO - PROCESS | 1661 | 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" 14:52:17 INFO - PROCESS | 1661 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:52:18 INFO - TEST-INFO | 1661: exit 0 14:52:18 INFO - Browser initialized. 14:52:18 INFO - Running cycle 1/1 for kraken test... 14:52:18 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/tmp6I7gcf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:52:30 INFO - PROCESS | 1662 | RSS: Main: 234713088 14:52:30 INFO - PROCESS | 1662 | 14:52:33 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection) 14:52:34 INFO - PROCESS | 1662 | RSS: Main: 294105088 14:52:34 INFO - PROCESS | 1662 | 14:52:37 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-dft) 14:52:37 INFO - PROCESS | 1662 | RSS: Main: 295276544 14:52:37 INFO - PROCESS | 1662 | 14:52:41 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-fft) 14:52:42 INFO - PROCESS | 1662 | RSS: Main: 384344064 14:52:42 INFO - PROCESS | 1662 | 14:52:44 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator) 14:52:44 INFO - PROCESS | 1662 | RSS: Main: 287776768 14:52:44 INFO - PROCESS | 1662 | 14:52:46 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:52:47 INFO - PROCESS | 1662 | RSS: Main: 289370112 14:52:47 INFO - PROCESS | 1662 | 14:52:50 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom) 14:52:50 INFO - PROCESS | 1662 | RSS: Main: 327479296 14:52:50 INFO - PROCESS | 1662 | 14:52:53 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate) 14:52:54 INFO - PROCESS | 1662 | RSS: Main: 328245248 14:52:54 INFO - PROCESS | 1662 | 14:52:57 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial) 14:52:57 INFO - PROCESS | 1662 | RSS: Main: 325681152 14:52:57 INFO - PROCESS | 1662 | 14:52:59 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:52:59 INFO - PROCESS | 1662 | RSS: Main: 300666880 14:52:59 INFO - PROCESS | 1662 | 14:53:01 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:53:02 INFO - PROCESS | 1662 | RSS: Main: 305070080 14:53:02 INFO - PROCESS | 1662 | 14:53:04 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:53:05 INFO - PROCESS | 1662 | RSS: Main: 310890496 14:53:05 INFO - PROCESS | 1662 | 14:53:08 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:53:09 INFO - PROCESS | 1662 | RSS: Main: 320487424 14:53:09 INFO - PROCESS | 1662 | 14:53:12 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:53:12 INFO - PROCESS | 1662 | RSS: Main: 320217088 14:53:12 INFO - PROCESS | 1662 | 14:53:14 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49267/tests/kraken/driver.html?testName=ai-astar) 14:53:14 INFO - PROCESS | 1662 | RSS: Main: 364707840 14:53:14 INFO - PROCESS | 1662 | 14:53:14 INFO - PROCESS | 1662 | __start_tp_report 14:53:14 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 14:53:14 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 14:53:14 INFO - PROCESS | 1662 | |i|pagename|runs| 14:53:14 INFO - PROCESS | 1662 | |0;ai-astar;113;114;114;112;112;113;112;113;113;113 14:53:14 INFO - PROCESS | 1662 | |1;audio-beat-detection;142;116;111;150;113;113;117;136;175;126 14:53:14 INFO - PROCESS | 1662 | |2;audio-dft;183;183;184;185;184;193;241;263;183;186 14:53:14 INFO - PROCESS | 1662 | |3;audio-fft;78;58;58;87;78;81;80;79;82;58 14:53:14 INFO - PROCESS | 1662 | |4;audio-oscillator;91;71;92;70;68;68;92;67;69;90 14:53:14 INFO - PROCESS | 1662 | |5;imaging-gaussian-blur;89;87;88;86;89;86;89;85;89;86 14:53:14 INFO - PROCESS | 1662 | |6;imaging-darkroom;100;136;101;144;159;97;97;98;97;96 14:53:14 INFO - PROCESS | 1662 | |7;imaging-desaturate;84;86;89;89;88;89;84;91;87;90 14:53:14 INFO - PROCESS | 1662 | |8;json-parse-financial;57;53;52;53;56;53;53;54;54;55 14:53:14 INFO - PROCESS | 1662 | |9;json-stringify-tinderbox;65;73;69;77;71;71;71;72;70;70 14:53:14 INFO - PROCESS | 1662 | |10;stanford-crypto-aes;88;88;90;87;86;85;86;84;85;87 14:53:14 INFO - PROCESS | 1662 | |11;stanford-crypto-ccm;151;153;154;150;142;144;144;159;204;136 14:53:14 INFO - PROCESS | 1662 | |12;stanford-crypto-pbkdf2;224;174;172;171;177;227;176;171;170;196 14:53:14 INFO - PROCESS | 1662 | |13;stanford-crypto-sha256-iterative;78;75;85;85;85;68;69;67;67;68 14:53:14 INFO - PROCESS | 1662 | __end_tp_report 14:53:14 INFO - PROCESS | 1662 | __start_cc_report 14:53:14 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,2073 14:53:14 INFO - PROCESS | 1662 | __end_cc_report 14:53:14 INFO - PROCESS | 1662 | __startTimestamp1476395594882__endTimestamp 14:53:14 INFO - PROCESS | 1662 | 14:53:14 INFO - PROCESS | 1662 | ------- Summary: start ------- 14:53:14 INFO - PROCESS | 1662 | Number of tests: 14 14:53:14 INFO - PROCESS | 1662 | 14:53:14 INFO - PROCESS | 1662 | [#0] ai-astar Cycles:10 Average:112.90 Median:113.00 stddev:0.74 (0.7%) stddev-sans-first:0.78 14:53:14 INFO - PROCESS | 1662 | Values: 113.0 114.0 114.0 112.0 112.0 113.0 112.0 113.0 113.0 113.0 14:53:14 INFO - PROCESS | 1662 | 14:53:14 INFO - PROCESS | 1662 | [#1] audio-beat-detection Cycles:10 Average:129.90 Median:131.00 stddev:20.88 (15.9%) stddev-sans-first:21.69 14:53:14 INFO - PROCESS | 1662 | Values: 142.0 116.0 111.0 150.0 113.0 113.0 117.0 136.0 175.0 126.0 14:53:14 INFO - PROCESS | 1662 | 14:53:14 INFO - PROCESS | 1662 | [#2] audio-dft Cycles:10 Average:198.50 Median:185.50 stddev:28.82 (15.5%) stddev-sans-first:30.02 14:53:14 INFO - PROCESS | 1662 | Values: 183.0 183.0 184.0 185.0 184.0 193.0 241.0 263.0 183.0 186.0 14:53:14 INFO - PROCESS | 1662 | 14:53:14 INFO - PROCESS | 1662 | [#3] audio-fft Cycles:10 Average:73.90 Median:79.50 stddev:11.27 (14.2%) stddev-sans-first:11.85 14:53:14 INFO - PROCESS | 1662 | Values: 78.0 58.0 58.0 87.0 78.0 81.0 80.0 79.0 82.0 58.0 14:53:14 INFO - PROCESS | 1662 | 14:53:14 INFO - PROCESS | 1662 | [#4] audio-oscillator Cycles:10 Average:77.80 Median:80.50 stddev:11.64 (14.5%) stddev-sans-first:11.32 14:53:14 INFO - PROCESS | 1662 | Values: 91.0 71.0 92.0 70.0 68.0 68.0 92.0 67.0 69.0 90.0 14:53:14 INFO - PROCESS | 1662 | 14:53:14 INFO - PROCESS | 1662 | [#5] imaging-gaussian-blur Cycles:10 Average:87.40 Median:88.50 stddev:1.58 (1.8%) stddev-sans-first:1.56 14:53:14 INFO - PROCESS | 1662 | Values: 89.0 87.0 88.0 86.0 89.0 86.0 89.0 85.0 89.0 86.0 14:53:14 INFO - PROCESS | 1662 | 14:53:14 INFO - PROCESS | 1662 | [#6] imaging-darkroom Cycles:10 Average:112.50 Median:96.50 stddev:24.03 (24.9%) stddev-sans-first:25.06 14:53:14 INFO - PROCESS | 1662 | Values: 100.0 136.0 101.0 144.0 159.0 97.0 97.0 98.0 97.0 96.0 14:53:14 INFO - PROCESS | 1662 | 14:53:14 INFO - PROCESS | 1662 | [#7] imaging-desaturate Cycles:10 Average:87.70 Median:89.00 stddev:2.41 (2.7%) stddev-sans-first:2.15 14:53:14 INFO - PROCESS | 1662 | Values: 84.0 86.0 89.0 89.0 88.0 89.0 84.0 91.0 87.0 90.0 14:53:14 INFO - PROCESS | 1662 | 14:53:14 INFO - PROCESS | 1662 | [#8] json-parse-financial Cycles:10 Average:54.00 Median:54.00 stddev:1.56 (2.9%) stddev-sans-first:1.22 14:53:14 INFO - PROCESS | 1662 | Values: 57.0 53.0 52.0 53.0 56.0 53.0 53.0 54.0 54.0 55.0 14:53:14 INFO - PROCESS | 1662 | 14:53:14 INFO - PROCESS | 1662 | [#9] json-stringify-tinderbox Cycles:10 Average:70.90 Median:71.00 stddev:3.03 (4.3%) stddev-sans-first:2.35 14:53:14 INFO - PROCESS | 1662 | Values: 65.0 73.0 69.0 77.0 71.0 71.0 71.0 72.0 70.0 70.0 14:53:14 INFO - PROCESS | 1662 | 14:53:14 INFO - PROCESS | 1662 | [#10] stanford-crypto-aes Cycles:10 Average:86.60 Median:87.00 stddev:1.78 (2.0%) stddev-sans-first:1.81 14:53:14 INFO - PROCESS | 1662 | Values: 88.0 88.0 90.0 87.0 86.0 85.0 86.0 84.0 85.0 87.0 14:53:14 INFO - PROCESS | 1662 | 14:53:14 INFO - PROCESS | 1662 | [#11] stanford-crypto-ccm Cycles:10 Average:153.70 Median:152.00 stddev:18.91 (12.4%) stddev-sans-first:20.03 14:53:14 INFO - PROCESS | 1662 | Values: 151.0 153.0 154.0 150.0 142.0 144.0 144.0 159.0 204.0 136.0 14:53:14 INFO - PROCESS | 1662 | 14:53:14 INFO - PROCESS | 1662 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.80 Median:176.50 stddev:22.24 (12.6%) stddev-sans-first:18.81 14:53:14 INFO - PROCESS | 1662 | Values: 224.0 174.0 172.0 171.0 177.0 227.0 176.0 171.0 170.0 196.0 14:53:14 INFO - PROCESS | 1662 | 14:53:14 INFO - PROCESS | 1662 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.70 Median:76.50 stddev:7.96 (10.4%) stddev-sans-first:8.35 14:53:14 INFO - PROCESS | 1662 | Values: 78.0 75.0 85.0 85.0 85.0 68.0 69.0 67.0 67.0 68.0 14:53:14 INFO - PROCESS | 1662 | -------- Summary: end -------- 14:53:14 INFO - PROCESS | 1662 | 14:53:15 INFO - TEST-INFO | 1662: exit 0 14:53:15 INFO - TEST-OK | kraken | took 60199ms 14:53:15 INFO - SUITE-END | took 1179s 14:53:16 INFO - Completed test suite (00:19:40) 14:53:16 INFO - javascript benchmark 14:53:16 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3246.074868662431, 3301.7523685723568, 3315.6021277446694, 3337.2824842658824, 3378.662133786622, 5517.503424691777, 5554.055810512213, 5619.77520899164, 5627.831165065044, 5635.520980716634, 5547.306586676666, 5588.944110558893, 5595.804146854861, 5601.383847776741, 5613.0, 5054.696718196901, 5082.263071854579, 5087.313212716273, 5126.512981266792, 5192.350956130484, 5127.564157046656, 5199.844004679847, 5204.193350030751, 5206.3231779868565, 5206.479352064801, 5076.111680455924, 5139.897202055957, 5144.511271429227, 5176.378834539861, 5185.403678576974, 5189.792408303682, 5210.374755029421, 5221.634485586011, 5221.791128354861, 5266.8683282917855, 5022.648414610979, 5028.673136246132, 5131.897362052775, 5137.563307118862, 5165.896682066375, 5422.457754224585, 5495.477929596682, 5508.6970216638065, 5609.607327487037, 5642.012647786621, 6029.8191054268445, 6043.153958445821, 6099.329073801879, 6110.816675499742, 6122.602030868024, 6097.878042439126, 6115.847103822395, 6183.6598987055695, 6194.5044396448175, 6194.721237544321, 4987.850364488999, 5579.911917176193, 5642.943570564283, 5652.095664693711, 5698.458646428583, 6289.0252010938375, 6293.118963345135, 6293.244810622663, 6301.873962520724, 6304.117423560705, 6012.849678757979, 6048.425399587032, 6054.51563874889, 6062.060380641008, 6071.2714474263375, 6031.0, 6034.818955431344, 6092.482139018233, 6111.622125543816, 6114.358196333954, 6054.545909056838, 6074.605150665193, 6078.483328917003, 6083.300420451616, 6089.056196289582, 6087.756489740449, 6092.055731361646, 6106.145139680449, 6114.205153330038, 6115.449609535163, 4196.223698615766, 4822.734749588806, 4833.275008748646, 4873.366462359871, 4877.097736918681, 4365.061511774984, 4368.08270263242, 4392.846250381222, 4433.113377324548, 4473.060657262012, 5683.403242659544, 5690.374058853523, 5695.601307908407, 5699.145128230715, 5701.429857014348, 6049.576529642883, 6123.203983482119, 6131.478824299896, 6234.407731265523, 6252.906206406908, 5634.183043458757, 5641.830745077655, 5643.689597072118, 5665.631733937364, 5667.801626943037, 5296.841094767163, 5305.734713264398, 5306.8407947761625, 5308.442613525602, 5312.76092575835, 6171.13604095418, 6234.657093859882, 6298.937010629835, 6320.873582528324, 6335.651539165298, 6167.907481387872, 6216.098665693539, 6228.56400051992, 6233.594816336924, 6236.064590311489, 5037.92443113361, 6049.334573196857, 6132.9386706134155, 6246.9062964054665, 6275.748970041147, 5888.381719919346, 5966.134910438049, 5974.701264936823, 6110.7861224856915, 6139.631622102688, 5154.664946778447, 6113.172264937315, 6131.294901086432, 6155.076738489262, 6241.032639940817, 5149.098907691214, 6058.182145410313, 6065.029595264737, 6066.9696651518225, 6077.4226448487325, 6041.6375017499095, 6046.818595442144, 6075.908861367171, 6081.726322315418, 6085.726142323695, 5738.081906894877, 5798.768049277982, 5808.5353171747, 5845.210896528914, 5856.59003869725, 6073.939260607337, 6081.087836824512, 6083.239595050619, 6100.8779824404155, 6141.201643786279, 5208.739563021758, 5871.647701137946, 5897.5576831736935, 5922.466977972003, 5971.522278217819], "value": 5599.860851628815, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [497.12505989458543, 499.93513815572834, 520.2040877457491, 522.4305506997372, 557.5483858074957, 29721.959731409406, 38772.41841372382, 40595.79702101493, 40766.0, 42733.35899961503, 41166.0, 41423.0, 41462.0, 41649.95875103124, 41901.79049104758, 9339.766505837357, 9530.99924507927, 9547.570359333811, 9568.760780980461, 9604.567794449267, 9317.767055823624, 9404.717858464222, 9421.340506164572, 9424.246060315158, 9424.52877356133, 9393.0, 9426.010268921767, 9464.716058518256, 9464.95267523665, 9468.24254059677, 9365.391249568791, 9410.952945235298, 9429.434233945985, 9440.433573985583, 9452.338336316461, 9258.398204116711, 9361.48511831849, 9365.157135857737, 9369.953150234205, 9370.625174992992, 39787.00532486682, 39972.0, 40043.7997810012, 40086.79956600198, 40170.59829401698, 3155.3373791503814, 3290.19390249388, 3432.2105915639295, 3668.394714872043, 3803.7147213959065, 29349.26626834325, 30082.84958575215, 30219.395612087854, 30285.545716814264, 31061.22346941322, 3164.746820254374, 3196.9840150799328, 3234.644189139193, 3323.6842499962463, 3360.2775403288165, 425370.0, 432005.0, 433363.99999999686, 434283.0, 441316.0, 30296.394072118434, 31133.377332453223, 31243.062708118792, 58407.12389314164, 59064.70467647634, 33251.3349733004, 33347.3330533388, 33413.0, 33421.83289083539, 33466.330673386394, 13355.198688078746, 13539.0, 13557.186568805902, 13559.932200338935, 13591.252481113634, 11162.051225645911, 11235.325880447199, 11236.157288203254, 11236.775264494829, 11275.943620281847, 6659.833504162338, 7610.847783044308, 7684.1173912390705, 7692.499987500794, 7709.727894897279, 3204.7086333271823, 3264.9836750816094, 3267.0, 3267.281198136406, 3299.736021118305, 903.2683526343683, 995.6117114325419, 1001.249063202598, 1006.627547807316, 1059.7138772531373, 26245.73754262433, 27323.72676273212, 29364.412711745645, 29640.703592964226, 29890.850545747133, 1041.1358572384902, 1041.5885725138528, 1050.9001644843727, 1067.1942683274233, 1077.4612693653173, 15012.099274043592, 15059.322330495153, 15148.848511514743, 15399.23003849803, 15478.845211547741, 651807.0, 658244.0, 658254.0, 658942.0, 658969.0, 55930.60173495614, 56594.0, 56807.43192568104, 56935.43064569301, 57010.0, 1000.7790495595362, 1123.528118190362, 1239.4546399584153, 1349.0286993364762, 1363.4205462678322, 29490.26274343073, 29803.0, 30523.29770476499, 30542.694573054872, 31291.74833006654, 50613.98772024465, 51476.742616288175, 51699.0, 51708.741456292475, 51903.0, 583349.0, 588337.0, 591080.0445997743, 591517.0, 591975.0, 22723.431914202276, 23217.76782232156, 25654.61518077268, 26028.73971260263, 26036.0, 43015.0, 45652.771736141105, 45914.540854591025, 48191.75904120457, 48427.5157248423, 37211.0, 37606.811965939996, 37969.62030379771, 38695.419568705925, 38744.2251154981, 29910.252243693212, 37487.0, 37970.0, 38249.0, 38581.0, 35486.645133548336, 35789.28421431505, 35799.64200357963, 35858.46212306766, 36328.81835590805, 32315.51526727148, 33485.33029339448, 33542.83228583939, 33560.0, 33758.83120584382, 24294.392640184138, 24460.75539244656, 24561.63157552674, 25116.748832511443, 25149.119780807585], "value": 18043.783678033535, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [317.8140787639231, 342.8799920027989, 355.51294726225075, 364.287817317145, 394.2528907719871, 14993.40026398944, 15371.385144594216, 16766.664666706674, 20150.496237594067, 20175.0, 18523.0, 19909.0, 19953.201871925106, 19957.0, 20033.499162520944, 5651.9152212716735, 5956.791512297071, 5969.522438204955, 5973.924693555158, 5974.49216816569, 4173.853915112972, 4392.626626736731, 6251.687415629223, 6498.220213574354, 6589.538732288742, 6293.842653933642, 6519.543631945766, 6601.603903765766, 6611.074449577063, 6620.0, 6485.286618471988, 6521.891278482669, 6532.5753826001155, 6539.705713242892, 6547.639879806608, 5674.063779476382, 6119.256012036592, 6795.422389096932, 7261.60061196634, 7280.8179795505, 27113.1866044019, 27339.0, 27459.588106178424, 28237.435251295065, 28473.0, 2221.0671517962496, 2410.361254267603, 2456.078970386105, 2460.7785299323145, 2469.5307891500556, 15493.535193944199, 16341.140300447374, 16784.496465106065, 16952.98282103078, 17615.207315670825, 1869.4204796512988, 1940.5148712821795, 2003.7695664998566, 2057.4260690571723, 2081.6044951459326, 94464.0, 100522.0, 101620.49189754004, 111457.44271278672, 112400.43799781031, 19769.58640509809, 19896.329546079298, 20397.286094986604, 20750.377488675364, 20837.79162208389, 33315.500267496005, 33392.66607333944, 33421.83289083539, 33488.83255583755, 33508.664913351044, 8377.036640786388, 9667.226621870233, 9879.407235565888, 9986.750331241778, 10007.599696012077, 6759.932400676029, 6772.52592318533, 8018.759437216893, 8077.919220807834, 8217.753467395987, 9057.094290571022, 9454.621815127455, 9537.856932146024, 9540.570674319672, 9558.808823823623, 2709.3362126278794, 3574.7140228781636, 3646.5624125105155, 3666.2484190740834, 3674.3018826423427, 368.46204541369366, 381.1310212715014, 397.594453657268, 770.7340967366362, 926.9582868770922, 11484.310941343547, 11625.360605166798, 11694.824577631342, 11707.88292117068, 11905.285682859056, 403.1734943366146, 416.8228502886322, 420.5248069681302, 430.8621241202785, 833.8999320081629, 5738.942610573924, 5749.68376739275, 5939.2575928009, 5964.582479226413, 6167.229096362955, 600166.0, 606106.0, 618377.0, 631900.0, 632325.0, 76095.0, 128768.0, 130849.0, 131571.0, 136576.0, 435.94550681164856, 441.85418811792033, 448.5985043386151, 448.9730616162975, 449.4965638484918, 9992.65025724096, 10340.27618066728, 11118.110551155727, 11256.21206515536, 11283.943580282046, 37506.62493375031, 54137.187942181685, 55974.1603875934, 56014.71992640174, 56089.0, 62283.06575401282, 80021.0, 81214.0, 81905.36189276061, 82288.58855705684, 5461.890762184655, 5545.417731138173, 7896.815477678394, 7985.921900543584, 8448.873266900879, 11716.765664686489, 12436.751264974831, 12506.562270320495, 12572.811407829073, 13037.56586775435, 9213.493257870614, 10968.341899486057, 11194.272372295774, 11266.493007814504, 11337.03635191018, 10060.39637621745, 10735.94632026835, 10775.946120269662, 11156.609518666808, 11249.606263780728, 10985.670429887115, 11217.55129794799, 11407.030402415556, 11502.88497115018, 11659.591914282959, 7940.404469664682, 8173.713920013009, 8232.382571307056, 8275.0, 8422.157784221774, 8263.0, 9387.906120938977, 10366.896331036593, 11013.83479247796, 11309.321440713255], "value": 9418.668724342791, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1480.1119328402956, 1483.3473271760427, 1668.382698401591, 1789.7046987247104, 1848.1036697201846, 8209.138040505743, 8712.041675415707, 8716.346274029442, 8722.563871806407, 8746.86879696805, 7724.922750772491, 7946.16565260648, 8314.459560128587, 8560.443571167869, 8578.185072418126, 2295.391721193885, 2332.9968113711097, 2333.1017558240114, 2336.6611841283, 2338.2985104468676, 2308.319045881469, 2321.5589038082794, 2349.8120150387927, 2350.059976009601, 2360.3862995621175, 2316.8030717389042, 2337.5441788851094, 2342.121704360865, 2355.140373763581, 2358.445765245166, 2300.309907027885, 2309.1109922679807, 2316.548273086749, 2318.594246006942, 2322.9651555226687, 2301.7813307735737, 2303.332033710222, 2305.1816605105114, 2306.261996161229, 2310.4570425949887, 8685.913140868573, 8745.693900713444, 8755.912440875638, 8763.605637746317, 8766.430182038148, 4963.138822941397, 5064.113780088506, 5145.614078944094, 5150.768215430277, 5159.0, 3386.6951974322183, 3467.0465621954163, 3595.4247320428867, 3600.207954250061, 3609.169890925076, 1012.8025035118155, 1042.3381152967845, 1047.9109275711573, 1053.1364281289345, 1068.551208492435, 11375.829362559485, 11506.654800356002, 11507.07943364529, 11517.193796434169, 11521.769564608745, 4459.687821852439, 4517.232070548015, 4697.342372067913, 4715.198416269242, 4747.287906813936, 9724.367916085432, 9728.124468797841, 9738.707838764849, 9758.853617195748, 9773.12041916231, 5078.390593128848, 5138.100832354323, 5142.048720986555, 5144.15121504955, 5166.302549155891, 4216.894577635522, 4220.85227017053, 4371.584699453547, 4680.836170734076, 4693.647976401783, 3565.823278318149, 3655.3785856404475, 3732.432378401078, 3772.981135094362, 3840.1759164397245, 1022.9079382855579, 1197.2098415046028, 1232.827404163418, 1241.1250068701543, 1243.154654834703, 722.1803253307534, 825.1830687619214, 884.2395539835736, 887.4409122252939, 925.6760133953062, 5594.552435805126, 5641.351244606841, 5873.85459835328, 5885.61743486672, 6043.244594425697, 914.3371055984358, 921.6543796076471, 942.783159873233, 942.8632848236967, 966.3284017607696, 3741.1956429367815, 3811.3330167220624, 3884.3979183226643, 3893.124047089439, 3907.3552863777727, 10255.33340332876, 10702.143828493701, 10714.892851071545, 10721.249512534203, 10721.30311529748, 9927.851082233772, 10493.790124197474, 10494.790104198026, 10512.421816799997, 10538.894611053945, 7954.403419743427, 8133.75598732039, 8158.551279679675, 8162.020557533134, 8189.0, 5747.770089196385, 5785.218995435731, 5796.246487956707, 5973.581849270683, 6144.818589400698, 9634.13292803651, 9960.402375857471, 9976.850347244652, 9988.700338989842, 9996.600135994478, 9856.408615482807, 10165.339252948535, 10181.74545636365, 10194.745131371776, 10201.285909986229, 6802.6598670065305, 7070.0, 7094.716211351488, 7317.817054573678, 7446.776596702108, 7916.881246781188, 8105.797355065934, 8252.958735206286, 8345.123762005145, 8370.497770133812, 7304.452166087671, 7402.7409040683315, 7413.406927445683, 7432.070991126109, 7572.167061623107, 5883.264591926009, 7152.821179470555, 7231.4214862811905, 7296.598687072262, 7311.341979221896, 6428.325025872216, 6617.0, 6730.6779161189315, 6790.287019862843, 6816.488763342868, 4884.511548845087, 4930.408350997904, 4975.278584605139, 4990.725510096834, 5061.569766569883, 4786.617070634411, 4788.377510923558, 4890.144224760724, 4961.479044700399, 4966.279889416087], "value": 4495.529465149342, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [289.7291032884253, 291.2311497646216, 297.2316561688037, 307.24724425158365, 308.6913086913087, 4169.583041695836, 4855.25210924068, 5317.521423071923, 5396.298481197444, 5433.320834895638, 4726.550977657125, 5153.330067091282, 5217.2695822584865, 5230.450802665722, 5263.736703191235, 3552.3960926642535, 3911.8044097795137, 4032.9395059074136, 4068.4304197412384, 4115.629593336599, 3347.8493467793887, 3547.964520354802, 3828.464015037897, 3960.4059391091278, 4088.8568900088503, 4007.2185923744883, 4014.7390419622775, 4016.5581786003518, 4023.2154729827694, 4048.271311163989, 3706.814659267026, 3832.9616703832885, 4000.639942405183, 4034.3545032794614, 4063.6545893599077, 2917.6498820141505, 3448.499967504698, 3482.9129271768406, 3498.475228715713, 3511.2977404518942, 4440.666949978765, 4889.486603906575, 4987.750612469362, 5161.593348390044, 5248.448912864133, 3678.503402040717, 4595.655325850575, 4602.516735742765, 4602.861914142581, 4618.769061546909, 4380.912381752347, 5043.621728370387, 5317.0, 5540.199435183581, 5645.491905728504, 3582.3372676055014, 3797.259534390795, 3824.808759562011, 3826.111285979096, 3844.480995065658, 5322.988632159877, 5724.513416359614, 5941.970290148565, 5947.650677982173, 5961.135635332896, 3917.5690674025836, 3918.686505079587, 3925.4700615417123, 3927.9410808837893, 3932.5674175840636, 3811.7712937223855, 3934.488516492838, 3961.900952476211, 3966.087799806003, 3990.3016972029777, 4550.704204226715, 4568.7156421789105, 4595.425571803525, 4601.562851529099, 4602.838900638461, 5218.851852592424, 5555.472230138131, 5700.3444603871085, 5760.3951585083805, 5773.566982476331, 1249.6750844780422, 1278.5844600504688, 1284.9335051906894, 1310.7181955879532, 1332.5336132353598, 753.1865585168006, 850.6984313999591, 897.1790811407488, 898.595631965618, 905.2215095018279, 4541.227991241497, 5247.685138891678, 5273.578113750891, 5307.230451584499, 5316.601254905898, 5119.232115182678, 5537.151679582651, 5716.0, 5742.817729262853, 5755.510781583613, 4616.929741721718, 4996.3504744383, 4998.700077995332, 5233.528982391603, 5275.340582427197, 2121.2363549122306, 2204.8787316697417, 2517.483915797294, 2547.274026902324, 2556.297018319977, 4271.914561708749, 4634.467036290802, 4666.766661666903, 5009.474005229471, 5043.083628221326, 4322.1041901925155, 4827.758612069382, 4875.658703890765, 4902.965269413225, 4917.688686317425, 4439.622632076245, 4753.952460475351, 4965.020987407599, 4993.400791904995, 5004.42449118356, 4089.5297040840683, 4155.10665206982, 4189.350650649154, 4452.866414007585, 4831.396075490564, 4507.121111383249, 4605.144810217686, 4755.453122890912, 4762.976185119052, 4797.0, 5109.6678715883345, 5536.944630553804, 5618.073017951992, 5619.129034999582, 6028.909566356596, 3852.4221366795205, 3904.9414258785573, 3923.47033150521, 3935.988968439089, 3944.092858642471, 5526.392096869261, 5614.354349249888, 5789.334226563999, 5907.366969313755, 5919.260092488439, 5649.7740090397565, 5650.519705824887, 5656.943430565641, 5678.574106942078, 5687.67271146384, 4793.856184314476, 5646.802361917313, 5661.603687741818, 5719.2279042328755, 5743.712814359349, 4966.900661986812, 5189.532942035235, 5493.972530137324, 5580.469855363734, 5587.636803607753, 5258.474152584711, 5313.495217954288, 5413.891722165614, 5654.208410822571, 5658.462446067617, 4363.629091527256, 5120.6159538035545, 5295.4581800735095, 5581.748821303132, 5583.609147359646], "value": 3950.2442760474423, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [672.5090683800826, 696.04989189756, 716.6345163966375, 736.7421402509126, 798.8841617965398, 13628.863711362883, 14390.0, 14423.350949207284, 14432.350544225495, 14450.988430809848, 12807.0, 12924.870751292485, 12929.870701292984, 12930.870691293085, 12989.285589292609, 690.9689063992107, 716.706150478304, 717.3651318583065, 823.6211342782327, 841.1504380575601, 856.6016802186982, 857.2584714222185, 863.2791618998112, 864.2653744317342, 864.9308055355557, 841.5119230846125, 844.9704260350888, 888.3781353052857, 909.6361455417853, 910.9590068446936, 711.4130842055273, 743.5241445474901, 904.231403307187, 905.3255324783013, 905.8731777551181, 652.7845810882445, 675.5440077947366, 678.5080816408114, 678.5182520410515, 687.9759208427731, 13910.443582256697, 13940.51208207718, 13943.651408714763, 13952.2326272055, 13981.930090349584, 13544.0, 13899.861001389958, 13931.58205253844, 13981.930090349482, 14092.084014539023, 12596.874031259753, 12607.369631518388, 12674.74650506994, 12760.106792524526, 12885.484580616763, 4439.66702497314, 4499.910001799978, 4595.517470665565, 4616.792244349012, 4726.621870250372, 111299.44350278277, 111942.44028779805, 114248.42875785651, 115156.42421787838, 116596.41701791437, 9422.434653920787, 10693.41186234749, 10812.243142980073, 10885.455727213608, 11119.110471162288, 20708.482287942923, 20771.27300544474, 21571.67642485364, 21581.67627485589, 21584.892075539523, 7651.387888968869, 7695.268949449794, 7778.416618753616, 7808.7657370278985, 7916.643751031217, 4227.196832601796, 4242.342436922282, 4256.765877876746, 4332.696711230184, 4358.8038538265855, 3370.427027405347, 3388.0852169913987, 3411.2836304375865, 3473.2532505511435, 3475.6176820549717, 639.0, 647.2621211818531, 653.1509038250256, 655.7311502284041, 656.983575410609, 493.82469223425886, 563.4618938913328, 584.9444302791228, 590.094205394717, 613.4969325153331, 8719.60761765722, 8818.77953051166, 8883.511406872554, 9424.481653509123, 9664.71005869825, 462.8195003948463, 621.6262060845492, 626.4330780643525, 643.8937575300117, 692.5671455340412, 4036.7981600920425, 4188.602082802129, 4427.291633338715, 4648.837790552362, 4658.045100754337, 123767.38116309542, 145205.0, 147038.0, 148959.0, 151311.0, 124084.0, 132268.0, 133139.33430332787, 134688.3265583666, 140675.59324406626, 105586.0, 109711.0, 110941.44529277463, 111928.99999999837, 113055.43472282586, 9526.082136483043, 9549.952250238704, 9579.9042009579, 9594.42433453995, 9700.029997000525, 53386.73306633442, 55421.0, 62946.13173353331, 63373.0, 65914.01128983164, 68701.65649171722, 92808.07191927994, 93101.5344923271, 94672.0, 94931.52534237561, 12193.085518585964, 12508.812367814307, 12781.424835882593, 12795.552155674506, 12819.679508012374, 13896.86103138996, 14281.571552853431, 14609.926950365605, 14712.632184195481, 15137.848621514084, 11153.88846111561, 13495.987800914774, 13529.356103051448, 13623.18260904349, 13850.792238116235, 13357.398917048562, 13391.464341426234, 13637.181769093886, 13678.0, 13809.86190138126, 13054.412551435018, 13326.733465330833, 13646.727065458834, 13785.035047546575, 13791.724165516835, 9670.613175473183, 11263.211575189658, 11366.43167841588, 11373.374464404536, 11522.42387880586, 10284.794304114026, 11384.48769805344, 11476.254043487146, 11541.0, 11547.653570392566], "value": 7684.5348858215075, "unit": "score"}], "name": "dromaeo_css", "value": 7116.3980072266, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 114.0, 112.0, 112.0, 113.0, 112.0, 113.0, 113.0, 113.0], "value": 112.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 116.0, 111.0, 150.0, 113.0, 113.0, 117.0, 136.0, 175.0, 126.0], "value": 129.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 183.0, 184.0, 185.0, 184.0, 193.0, 241.0, 263.0, 183.0, 186.0], "value": 198.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 58.0, 58.0, 87.0, 78.0, 81.0, 80.0, 79.0, 82.0, 58.0], "value": 73.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 71.0, 92.0, 70.0, 68.0, 68.0, 92.0, 67.0, 69.0, 90.0], "value": 77.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 88.0, 86.0, 89.0, 86.0, 89.0, 85.0, 89.0, 86.0], "value": 87.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 136.0, 101.0, 144.0, 159.0, 97.0, 97.0, 98.0, 97.0, 96.0], "value": 112.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 89.0, 89.0, 88.0, 89.0, 84.0, 91.0, 87.0, 90.0], "value": 87.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 53.0, 52.0, 53.0, 56.0, 53.0, 53.0, 54.0, 54.0, 55.0], "value": 54.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 73.0, 69.0, 77.0, 71.0, 71.0, 71.0, 72.0, 70.0, 70.0], "value": 70.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 90.0, 87.0, 86.0, 85.0, 86.0, 84.0, 85.0, 87.0], "value": 86.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 153.0, 154.0, 150.0, 142.0, 144.0, 144.0, 159.0, 204.0, 136.0], "value": 153.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [224.0, 174.0, 172.0, 171.0, 177.0, 227.0, 176.0, 171.0, 170.0, 196.0], "value": 185.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 75.0, 85.0, 85.0, 85.0, 68.0, 69.0, 67.0, 67.0, 68.0], "value": 74.7, "unit": "score"}], "name": "kraken", "value": 1506.3, "alertThreshold": 2.0}]} 14:53:16 INFO - Return code: 0 14:53:16 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:53:16 INFO - # TBPL SUCCESS # 14:53:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:53:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:53:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:53:16 INFO - 2016-10-13 14:53:16.215 screenresolution[1666:10073] starting screenresolution argv=screenresolution get 14:53:16 INFO - 2016-10-13 14:53:16.217 screenresolution[1666:10073] Display 0: 1600x1200x32@60 14:53:16 INFO - 2016-10-13 14:53:16.223 screenresolution[1667:10076] starting screenresolution argv=screenresolution list 14:53:16 INFO - Available Modes on Display 0 14:53:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:53:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:53:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:53:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:53:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:53:16 INFO - Intel Iris: 14:53:16 INFO - Chipset Model: Intel Iris 14:53:16 INFO - Type: GPU 14:53:16 INFO - Bus: Built-In 14:53:16 INFO - VRAM (Dynamic, Max): 1536 MB 14:53:16 INFO - Vendor: Intel (0x8086) 14:53:16 INFO - Device ID: 0x0a2e 14:53:16 INFO - Revision ID: 0x0009 14:53:16 INFO - Displays: 14:53:16 INFO - Display: 14:53:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:53:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:53:16 INFO - Display Serial Number: 1600x1200 60 14:53:16 INFO - Main Display: Yes 14:53:16 INFO - Mirror: Off 14:53:16 INFO - Online: Yes 14:53:16 INFO - Rotation: Supported 14:53:16 INFO - Return code: 0 14:53:16 INFO - Running post-action listener: _resource_record_post_action 14:53:16 INFO - [mozharness: 2016-10-13 21:53:16.468091Z] Finished run-tests step (success) 14:53:16 INFO - Running post-run listener: _resource_record_post_run 14:53:16 INFO - Total resource usage - Wall time: 1215s; CPU: 54.0%; Read bytes: 37377024; Write bytes: 708937216; Read time: 243; Write time: 1969 14:53:16 INFO - TinderboxPrint: CPU usage
53.9% 14:53:16 INFO - TinderboxPrint: I/O read bytes / time
37,377,024 / 243 14:53:16 INFO - TinderboxPrint: I/O write bytes / time
708,937,216 / 1,969 14:53:16 INFO - TinderboxPrint: CPU idle
2,241.8 (46.1%) 14:53:16 INFO - TinderboxPrint: CPU system
977.3 (20.1%) 14:53:16 INFO - TinderboxPrint: CPU user
1,642.6 (33.8%) 14:53:16 INFO - TinderboxPrint: Swap in / out
444,551,168 / 0 14:53:16 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 399610368; Write bytes: 390891008; Read time: 29570; Write time: 808 14:53:16 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 35378176; Write bytes: 299024384; Read time: 225; Write time: 1113 14:53:16 INFO - Running post-run listener: _upload_blobber_files 14:53:16 INFO - Blob upload gear active. 14:53:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:53:16 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 14:53:16 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', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:53:16 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 Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:53:17 INFO - (blobuploader) - INFO - Open directory for files ... 14:53:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:53:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:53:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:53:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:53:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:53:18 INFO - (blobuploader) - INFO - Done attempting. 14:53:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:53:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:53:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:53:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:53:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:53:20 INFO - (blobuploader) - INFO - Done attempting. 14:53:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:53:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:53:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:53:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:53:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:53:24 INFO - (blobuploader) - INFO - Done attempting. 14:53:24 INFO - (blobuploader) - INFO - Iteration through files over. 14:53:24 INFO - Return code: 0 14:53:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:53:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:53:24 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/6a25cc16e676f56fcdbccf64854a67d4b397ab8ed03a09a3de506624c204e34261ba82d379fe4cfdfa5a9aafc50ea6f7d6527a0d03d23c1a2ffea87c989c12cb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e2cb01be948ccdc056c5f552e26e2794dc5bff6b452f58db4d5eee9398a9e7f91cbfecdbe910964c40ee69e8103af771452e110edb0913a7da843e0bda1fa8d9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3db97d455570dd3da18916db04048a9cb7cfc49a22b23f9ee636dc607b284956aff8b9d1695133e9101746bedf2e59f18a6c35b34c92f0485cf761f9335baebe"} 14:53:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:53:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:53:24 INFO - Contents: 14:53:24 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/6a25cc16e676f56fcdbccf64854a67d4b397ab8ed03a09a3de506624c204e34261ba82d379fe4cfdfa5a9aafc50ea6f7d6527a0d03d23c1a2ffea87c989c12cb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e2cb01be948ccdc056c5f552e26e2794dc5bff6b452f58db4d5eee9398a9e7f91cbfecdbe910964c40ee69e8103af771452e110edb0913a7da843e0bda1fa8d9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3db97d455570dd3da18916db04048a9cb7cfc49a22b23f9ee636dc607b284956aff8b9d1695133e9101746bedf2e59f18a6c35b34c92f0485cf761f9335baebe"} 14:53:24 INFO - Running post-run listener: copy_logs_to_upload_dir 14:53:24 INFO - Copying logs to upload dir... 14:53:24 INFO - mkdir: /builds/slave/test/build/upload/logs 14:53:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1279.363817 ========= master_lag: 0.08 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-10-13 14:53:24.842638) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-13 14:53:24.851269) ========= 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.QhSsA2eNqg/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.jznVOVHfRU/Listeners TMPDIR=/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/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/Autoland/sha512/6a25cc16e676f56fcdbccf64854a67d4b397ab8ed03a09a3de506624c204e34261ba82d379fe4cfdfa5a9aafc50ea6f7d6527a0d03d23c1a2ffea87c989c12cb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e2cb01be948ccdc056c5f552e26e2794dc5bff6b452f58db4d5eee9398a9e7f91cbfecdbe910964c40ee69e8103af771452e110edb0913a7da843e0bda1fa8d9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3db97d455570dd3da18916db04048a9cb7cfc49a22b23f9ee636dc607b284956aff8b9d1695133e9101746bedf2e59f18a6c35b34c92f0485cf761f9335baebe"} build_url:https://queue.taskcluster.net/v1/task/KRCRqzQnS_-aWRvi8TZKBw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010915 build_url: 'https://queue.taskcluster.net/v1/task/KRCRqzQnS_-aWRvi8TZKBw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/6a25cc16e676f56fcdbccf64854a67d4b397ab8ed03a09a3de506624c204e34261ba82d379fe4cfdfa5a9aafc50ea6f7d6527a0d03d23c1a2ffea87c989c12cb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e2cb01be948ccdc056c5f552e26e2794dc5bff6b452f58db4d5eee9398a9e7f91cbfecdbe910964c40ee69e8103af771452e110edb0913a7da843e0bda1fa8d9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3db97d455570dd3da18916db04048a9cb7cfc49a22b23f9ee636dc607b284956aff8b9d1695133e9101746bedf2e59f18a6c35b34c92f0485cf761f9335baebe"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-13 14:53:24.890711) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 14:53:24.893113) ========= 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.QhSsA2eNqg/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.jznVOVHfRU/Listeners TMPDIR=/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/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.005008 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 14:53:24.943922) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-13 14:53:24.944283) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-13 14:53:24.944649) ========= ========= Total master_lag: 0.22 =========