builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0368 starttime: 1484005477.73 results: success (0) buildid: 20170109140908 builduid: 75233de3538247f58b57d18b823f20ba revision: 10e1849db4be083d35c4e40a7d7a7b32a0437817 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-09 15:44:37.728004) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-09 15:44:37.728417) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-09 15:44:37.728703) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-09 15:44:38.060861) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 15:44:38.061257) ========= 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.nIwEdLWObo/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.9AHDdB2KSf/Listeners TMPDIR=/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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 --2017-01-09 15:44:38-- 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% 161M=0s 2017-01-09 15:44:38 (161 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.288601 ========= master_lag: 0.22 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 15:44:38.569236) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 15:44:38.572530) ========= 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.nIwEdLWObo/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.9AHDdB2KSf/Listeners TMPDIR=/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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.025170 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 15:44:38.619852) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-09 15:44:38.620155) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 10e1849db4be083d35c4e40a7d7a7b32a0437817 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 10e1849db4be083d35c4e40a7d7a7b32a0437817 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.nIwEdLWObo/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.9AHDdB2KSf/Listeners TMPDIR=/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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 2017-01-09 15:44:38,719 truncating revision to first 12 chars 2017-01-09 15:44:38,719 Setting DEBUG logging. 2017-01-09 15:44:38,719 attempt 1/10 2017-01-09 15:44:38,719 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/10e1849db4be?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-09 15:44:39,536 unpacking tar archive at: mozilla-aurora-10e1849db4be/testing/mozharness/ program finished with exit code 0 elapsedTime=1.096259 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-09 15:44:39.742210) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-09 15:44:39.745527) ========= script_repo_revision: 10e1849db4be083d35c4e40a7d7a7b32a0437817 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-09 15:44:39.745920) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-09 15:44:39.746184) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-09 15:44:39.765554) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2017-01-09 15:44:39.765817) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.nIwEdLWObo/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.9AHDdB2KSf/Listeners TMPDIR=/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 15:44:39 INFO - MultiFileLogger online at 20170109 15:44:39 in /builds/slave/test 15:44:39 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 15:44:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:44:39 INFO - {'append_to_log': False, 15:44:39 INFO - 'base_work_dir': '/builds/slave/test', 15:44:39 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 15:44:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:44:39 INFO - 'branch': 'Mozilla-Aurora', 15:44:39 INFO - 'buildbot_json_path': 'buildprops.json', 15:44:39 INFO - 'config_files': ('talos/mac_config.py',), 15:44:39 INFO - 'default_actions': ('clobber', 15:44:39 INFO - 'read-buildbot-config', 15:44:39 INFO - 'download-and-extract', 15:44:39 INFO - 'populate-webroot', 15:44:39 INFO - 'create-virtualenv', 15:44:39 INFO - 'install', 15:44:39 INFO - 'run-tests'), 15:44:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:44:39 INFO - 'download_minidump_stackwalk': True, 15:44:39 INFO - 'download_symbols': 'ondemand', 15:44:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:44:39 INFO - 'tooltool.py': '/tools/tooltool.py', 15:44:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:44:39 INFO - '/tools/misc-python/virtualenv.py')}, 15:44:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:44:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:44:39 INFO - 'installer_path': 'installer.exe', 15:44:39 INFO - 'log_level': 'info', 15:44:39 INFO - 'log_name': 'talos', 15:44:39 INFO - 'log_to_console': True, 15:44:39 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:44:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:44:39 INFO - 'opt_config_files': (), 15:44:39 INFO - 'pip_index': False, 15:44:39 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:44:39 INFO - 'cmd': ('bash', 15:44:39 INFO - '-c', 15:44:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:44:39 INFO - 'enabled': True, 15:44:39 INFO - 'halt_on_failure': False, 15:44:39 INFO - 'name': 'check_screen_resolution'},), 15:44:39 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:44:39 INFO - 'cmd': ('bash', 15:44:39 INFO - '-c', 15:44:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:44:39 INFO - 'enabled': True, 15:44:39 INFO - 'halt_on_failure': False, 15:44:39 INFO - 'name': 'check_screen_resolution'},), 15:44:39 INFO - 'run_cmd_checks_enabled': True, 15:44:39 INFO - 'sps_profile': False, 15:44:39 INFO - 'sps_profile_interval': 0, 15:44:39 INFO - 'suite': 'dromaeojs', 15:44:39 INFO - 'system_bits': '32', 15:44:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:44:39 INFO - 'title': 't-yosemite-r7-0368', 15:44:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:44:39 INFO - 'use_talos_json': True, 15:44:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:44:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:44:39 INFO - 'work_dir': 'build'} 15:44:39 INFO - [mozharness: 2017-01-09 23:44:39.964493Z] Running clobber step. 15:44:39 INFO - Running pre-action listener: _resource_record_pre_action 15:44:39 INFO - Running main action method: clobber 15:44:39 INFO - rmtree: /builds/slave/test/build 15:44:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:44:42 INFO - Running post-action listener: _resource_record_post_action 15:44:42 INFO - [mozharness: 2017-01-09 23:44:42.742240Z] Finished clobber step (success) 15:44:42 INFO - [mozharness: 2017-01-09 23:44:42.742375Z] Running read-buildbot-config step. 15:44:42 INFO - Running pre-action listener: _resource_record_pre_action 15:44:42 INFO - Running main action method: read_buildbot_config 15:44:42 INFO - Using buildbot properties: 15:44:42 INFO - { 15:44:42 INFO - "project": "", 15:44:42 INFO - "product": "firefox", 15:44:42 INFO - "script_repo_revision": "production", 15:44:42 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 15:44:42 INFO - "repository": "", 15:44:42 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 15:44:42 INFO - "buildid": "20170109140908", 15:44:42 INFO - "pgo_build": "False", 15:44:42 INFO - "basedir": "/builds/slave/test", 15:44:42 INFO - "buildnumber": 15, 15:44:42 INFO - "slavename": "t-yosemite-r7-0368", 15:44:42 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 15:44:42 INFO - "platform": "yosemite_r7", 15:44:42 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 15:44:42 INFO - "branch": "mozilla-aurora", 15:44:42 INFO - "repo_path": "releases/mozilla-aurora", 15:44:42 INFO - "revision": "10e1849db4be083d35c4e40a7d7a7b32a0437817", 15:44:42 INFO - "stage_platform": "macosx64", 15:44:42 INFO - "builduid": "75233de3538247f58b57d18b823f20ba", 15:44:42 INFO - "slavebuilddir": "test" 15:44:42 INFO - } 15:44:42 INFO - Found installer url https://queue.taskcluster.net/v1/task/WYpkRzh6T2ep9ZnE_tBOhg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 15:44:42 INFO - Running post-action listener: _resource_record_post_action 15:44:42 INFO - [mozharness: 2017-01-09 23:44:42.745729Z] Finished read-buildbot-config step (success) 15:44:42 INFO - [mozharness: 2017-01-09 23:44:42.745843Z] Running download-and-extract step. 15:44:42 INFO - Running pre-action listener: _resource_record_pre_action 15:44:42 INFO - Running main action method: download_and_extract 15:44:42 INFO - mkdir: /builds/slave/test/build/tests 15:44:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:44:42 INFO - trying https://queue.taskcluster.net/v1/task/WYpkRzh6T2ep9ZnE_tBOhg/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 15:44:42 INFO - Downloading https://queue.taskcluster.net/v1/task/WYpkRzh6T2ep9ZnE_tBOhg/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 15:44:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WYpkRzh6T2ep9ZnE_tBOhg/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 15:44:43 INFO - Downloaded 1199 bytes. 15:44:43 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 15:44:43 INFO - Using the following test package requirements: 15:44:43 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 15:44:43 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:44:43 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 15:44:43 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:44:43 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 15:44:43 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:44:43 INFO - u'jsshell-mac.zip'], 15:44:43 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:44:43 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 15:44:43 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 15:44:43 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:44:43 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 15:44:43 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:44:43 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 15:44:43 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:44:43 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 15:44:43 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:44:43 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 15:44:43 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 15:44:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WYpkRzh6T2ep9ZnE_tBOhg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 15:44:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WYpkRzh6T2ep9ZnE_tBOhg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 15:44:43 INFO - Fetch https://queue.taskcluster.net/v1/task/WYpkRzh6T2ep9ZnE_tBOhg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 15:44:43 INFO - Http code: 200 15:44:43 INFO - via: 1.1 1b52a5dd431f9e3c81753e61dfdf467a.cloudfront.net (CloudFront) 15:44:43 INFO - x-amz-cf-id: xdl4v95_KFf0mb6_hHbB19tfTFnU0ub7m4mgPAWKBpeNsqgbczL4jA== 15:44:43 INFO - x-amz-version-id: V3HDe3BADLEcQynKV_u7k8CB.NpTixEw 15:44:44 INFO - Expected file size: 16270864 15:44:44 INFO - Obtained file size: 16270864 15:44:50 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 15:44:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WYpkRzh6T2ep9ZnE_tBOhg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 15:44:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WYpkRzh6T2ep9ZnE_tBOhg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 15:44:50 INFO - Fetch https://queue.taskcluster.net/v1/task/WYpkRzh6T2ep9ZnE_tBOhg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 15:44:51 INFO - Http code: 200 15:44:51 INFO - via: 1.1 336f0e6ef9a3462f682d6ca49029b665.cloudfront.net (CloudFront) 15:44:51 INFO - x-amz-cf-id: 239i-3GPO_uaEWqHWoJffVxJW5pPjmx8Ss0Knpq_mdRmlpsoQ-9NDA== 15:44:51 INFO - x-amz-version-id: V3HDe3BADLEcQynKV_u7k8CB.NpTixEw 15:44:51 INFO - Expected file size: 16270864 15:44:51 INFO - Obtained file size: 16270864 15:44:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WYpkRzh6T2ep9ZnE_tBOhg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 15:44:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WYpkRzh6T2ep9ZnE_tBOhg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 15:44:57 INFO - Fetch https://queue.taskcluster.net/v1/task/WYpkRzh6T2ep9ZnE_tBOhg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 15:44:58 INFO - Http code: 200 15:44:58 INFO - via: 1.1 1b52a5dd431f9e3c81753e61dfdf467a.cloudfront.net (CloudFront) 15:44:58 INFO - x-amz-cf-id: V4sGz8QMFh3sE6Dv8OZ20-sZlGN4XO6klBnh5XoGOjHQx7LARAStzA== 15:44:58 INFO - x-amz-version-id: Lk7xxrgRPTpXT_egsqiJlNtfbN_yBwUC 15:44:59 INFO - Expected file size: 14409038 15:44:59 INFO - Obtained file size: 14409038 15:44:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:44:59 INFO - trying https://queue.taskcluster.net/v1/task/WYpkRzh6T2ep9ZnE_tBOhg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 15:44:59 INFO - Downloading https://queue.taskcluster.net/v1/task/WYpkRzh6T2ep9ZnE_tBOhg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 15:44:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WYpkRzh6T2ep9ZnE_tBOhg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 15:45:02 INFO - Downloaded 109878424 bytes. 15:45:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WYpkRzh6T2ep9ZnE_tBOhg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 15:45:02 INFO - mkdir: /builds/slave/test/properties 15:45:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:45:02 INFO - Writing to file /builds/slave/test/properties/build_url 15:45:02 INFO - Contents: 15:45:02 INFO - build_url:https://queue.taskcluster.net/v1/task/WYpkRzh6T2ep9ZnE_tBOhg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 15:45:03 INFO - Running post-action listener: _resource_record_post_action 15:45:03 INFO - Running post-action listener: set_extra_try_arguments 15:45:03 INFO - [mozharness: 2017-01-09 23:45:03.361750Z] Finished download-and-extract step (success) 15:45:03 INFO - [mozharness: 2017-01-09 23:45:03.361890Z] Running populate-webroot step. 15:45:03 INFO - Running pre-action listener: _resource_record_pre_action 15:45:03 INFO - Running main action method: populate_webroot 15:45:03 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:45:03 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:45:03 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:45:03 INFO - '--tppagecycles', 15:45:03 INFO - '7'], 15:45:03 INFO - 'tests': ['tsvgm']}}, 15:45:03 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:45:03 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:45:03 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:45:03 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:45:03 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:45:03 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:45:03 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:45:03 INFO - 'talos_options': ['--disable-e10s'], 15:45:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:45:03 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:45:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:45:03 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:45:03 INFO - 'talos_options': ['--disable-e10s'], 15:45:03 INFO - 'tests': ['damp', 'tps']}, 15:45:03 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:45:03 INFO - 'tests': ['damp', 'tps']}, 15:45:03 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:45:03 INFO - 'tests': ['dromaeo_dom']}, 15:45:03 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:45:03 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:45:03 INFO - 'tests': ['basic_compositor_video']}, 15:45:03 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:45:03 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:45:03 INFO - 'tests': ['a11yr', 15:45:03 INFO - 'ts_paint', 15:45:03 INFO - 'tpaint', 15:45:03 INFO - 'sessionrestore', 15:45:03 INFO - 'sessionrestore_no_auto_restore', 15:45:03 INFO - 'tabpaint']}, 15:45:03 INFO - 'other-e10s': {'tests': ['a11yr', 15:45:03 INFO - 'ts_paint', 15:45:03 INFO - 'tpaint', 15:45:03 INFO - 'sessionrestore', 15:45:03 INFO - 'sessionrestore_no_auto_restore', 15:45:03 INFO - 'tabpaint']}, 15:45:03 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:45:03 INFO - 'ts_paint', 15:45:03 INFO - 'tpaint', 15:45:03 INFO - 'sessionrestore', 15:45:03 INFO - 'sessionrestore_no_auto_restore', 15:45:03 INFO - 'tabpaint']}, 15:45:03 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:45:03 INFO - 'ts_paint', 15:45:03 INFO - 'tpaint', 15:45:03 INFO - 'sessionrestore', 15:45:03 INFO - 'sessionrestore_no_auto_restore', 15:45:03 INFO - 'tabpaint']}, 15:45:03 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:45:03 INFO - 'tests': ['a11yr', 15:45:03 INFO - 'ts_paint', 15:45:03 INFO - 'tpaint', 15:45:03 INFO - 'sessionrestore', 15:45:03 INFO - 'sessionrestore_no_auto_restore', 15:45:03 INFO - 'tabpaint']}, 15:45:03 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:45:03 INFO - 'tests': ['a11yr', 15:45:03 INFO - 'ts_paint', 15:45:03 INFO - 'tpaint', 15:45:03 INFO - 'sessionrestore', 15:45:03 INFO - 'sessionrestore_no_auto_restore', 15:45:03 INFO - 'tabpaint']}, 15:45:03 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:45:03 INFO - 'tests': ['tsvgx', 15:45:03 INFO - 'tsvgr_opacity', 15:45:03 INFO - 'tart', 15:45:03 INFO - 'tscrollx', 15:45:03 INFO - 'cart']}, 15:45:03 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:45:03 INFO - 'tsvgr_opacity', 15:45:03 INFO - 'tart', 15:45:03 INFO - 'tscrollx', 15:45:03 INFO - 'cart']}, 15:45:03 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:45:03 INFO - 'talos_options': ['--disable-e10s'], 15:45:03 INFO - 'tests': ['tp5o']}, 15:45:03 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:45:03 INFO - 'tests': ['tp5o']}, 15:45:03 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:45:03 INFO - 'talos_options': ['--disable-e10s', 15:45:03 INFO - '--xperf_path', 15:45:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:45:03 INFO - 'tests': ['tp5n']}, 15:45:03 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:45:03 INFO - 'talos_options': ['--xperf_path', 15:45:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:45:03 INFO - 'tests': ['tp5n']}}, 15:45:03 INFO - 'talos.zip': {'path': '', 15:45:03 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:45:03 INFO - Running post-action listener: _resource_record_post_action 15:45:03 INFO - [mozharness: 2017-01-09 23:45:03.380348Z] Finished populate-webroot step (success) 15:45:03 INFO - [mozharness: 2017-01-09 23:45:03.380429Z] Running create-virtualenv step. 15:45:03 INFO - Running pre-action listener: _resource_record_pre_action 15:45:03 INFO - Running main action method: create_virtualenv 15:45:03 INFO - Creating virtualenv /builds/slave/test/build/venv 15:45:03 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:45:03 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:45:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:45:03 INFO - Using real prefix '/tools/python27' 15:45:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:45:04 INFO - Installing distribute.............................................................................................................................................................................................done. 15:45:07 INFO - Installing pip.................done. 15:45:07 INFO - Return code: 0 15:45:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:45:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:45:07 INFO - Reading from file tmpfile_stdout 15:45:07 INFO - Output received: 15:45:07 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 15:45:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:45: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')]} 15:45:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:45:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45: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')]} 15:45:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:45:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:07 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 0x101cbe490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c34570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde52483030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ad2d30>, '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 0x101938db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101acab20>, '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.nIwEdLWObo/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.9AHDdB2KSf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:45:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:45:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:45:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nIwEdLWObo/Render', 15:45:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:45:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:45:07 INFO - 'HOME': '/Users/cltbld', 15:45:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:45:07 INFO - 'LOGNAME': 'cltbld', 15:45:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:45:07 INFO - 'MOZ_NO_REMOTE': '1', 15:45:07 INFO - 'NO_EM_RESTART': '1', 15:45:07 INFO - 'PAGER': '/bin/cat', 15:45:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:45:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:45:07 INFO - 'PWD': '/builds/slave/test', 15:45:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:45:07 INFO - 'SHELL': '/bin/bash', 15:45:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9AHDdB2KSf/Listeners', 15:45:07 INFO - 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', 15:45:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:45:07 INFO - 'USER': 'cltbld', 15:45:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:45:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:45:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:45:07 INFO - 'XPC_FLAGS': '0x0', 15:45:07 INFO - 'XPC_SERVICE_NAME': '0', 15:45:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:45:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:45:07 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 15:45:07 INFO - Cleaning up... 15:45:07 INFO - Return code: 0 15:45:07 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:45: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')]} 15:45:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:45:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45: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')]} 15:45:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:45:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:07 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 0x101cbe490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c34570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde52483030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ad2d30>, '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 0x101938db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101acab20>, '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.nIwEdLWObo/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.9AHDdB2KSf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:45:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:45:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:45:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nIwEdLWObo/Render', 15:45:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:45:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:45:07 INFO - 'HOME': '/Users/cltbld', 15:45:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:45:07 INFO - 'LOGNAME': 'cltbld', 15:45:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:45:07 INFO - 'MOZ_NO_REMOTE': '1', 15:45:07 INFO - 'NO_EM_RESTART': '1', 15:45:07 INFO - 'PAGER': '/bin/cat', 15:45:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:45:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:45:07 INFO - 'PWD': '/builds/slave/test', 15:45:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:45:07 INFO - 'SHELL': '/bin/bash', 15:45:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9AHDdB2KSf/Listeners', 15:45:07 INFO - 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', 15:45:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:45:07 INFO - 'USER': 'cltbld', 15:45:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:45:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:45:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:45:07 INFO - 'XPC_FLAGS': '0x0', 15:45:07 INFO - 'XPC_SERVICE_NAME': '0', 15:45:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:45:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:45:07 INFO - Downloading/unpacking psutil>=3.1.1 15:45:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:45:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:45:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:45:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:45:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:45:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:45:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:45:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:45:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:45:11 INFO - Installing collected packages: psutil 15:45:11 INFO - Running setup.py install for psutil 15:45:11 INFO - building 'psutil._psutil_osx' extension 15:45:11 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 15:45:11 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 15:45:11 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 15:45:12 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 15:45:12 INFO - building 'psutil._psutil_posix' extension 15:45:12 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 15:45:12 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:45:12 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:45:12 INFO - ^ 15:45:12 INFO - 1 warning generated. 15:45:12 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 15:45:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:45:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:45:12 INFO - Successfully installed psutil 15:45:12 INFO - Cleaning up... 15:45:12 INFO - Return code: 0 15:45:12 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:45:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:45:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:45:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101cbe490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c34570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde52483030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ad2d30>, '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 0x101938db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101acab20>, '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.nIwEdLWObo/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.9AHDdB2KSf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:45:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:45:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:45:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nIwEdLWObo/Render', 15:45:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:45:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:45:12 INFO - 'HOME': '/Users/cltbld', 15:45:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:45:12 INFO - 'LOGNAME': 'cltbld', 15:45:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:45:12 INFO - 'MOZ_NO_REMOTE': '1', 15:45:12 INFO - 'NO_EM_RESTART': '1', 15:45:12 INFO - 'PAGER': '/bin/cat', 15:45:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:45:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:45:12 INFO - 'PWD': '/builds/slave/test', 15:45:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:45:12 INFO - 'SHELL': '/bin/bash', 15:45:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9AHDdB2KSf/Listeners', 15:45:12 INFO - 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', 15:45:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:45:12 INFO - 'USER': 'cltbld', 15:45:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:45:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:45:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:45:12 INFO - 'XPC_FLAGS': '0x0', 15:45:12 INFO - 'XPC_SERVICE_NAME': '0', 15:45:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:45:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:45:12 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:45:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:45:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:45:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:45:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:45:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:45:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:45:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:45:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:45:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:45:15 INFO - Installing collected packages: mozsystemmonitor 15:45:15 INFO - Running setup.py install for mozsystemmonitor 15:45:15 INFO - Successfully installed mozsystemmonitor 15:45:15 INFO - Cleaning up... 15:45:15 INFO - Return code: 0 15:45:15 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:45:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:45:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:45:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:15 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 0x101cbe490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c34570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde52483030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ad2d30>, '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 0x101938db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101acab20>, '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.nIwEdLWObo/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.9AHDdB2KSf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:45:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:45:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:45:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nIwEdLWObo/Render', 15:45:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:45:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:45:15 INFO - 'HOME': '/Users/cltbld', 15:45:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:45:15 INFO - 'LOGNAME': 'cltbld', 15:45:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:45:15 INFO - 'MOZ_NO_REMOTE': '1', 15:45:15 INFO - 'NO_EM_RESTART': '1', 15:45:15 INFO - 'PAGER': '/bin/cat', 15:45:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:45:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:45:15 INFO - 'PWD': '/builds/slave/test', 15:45:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:45:15 INFO - 'SHELL': '/bin/bash', 15:45:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9AHDdB2KSf/Listeners', 15:45:15 INFO - 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', 15:45:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:45:15 INFO - 'USER': 'cltbld', 15:45:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:45:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:45:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:45:15 INFO - 'XPC_FLAGS': '0x0', 15:45:15 INFO - 'XPC_SERVICE_NAME': '0', 15:45:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:45:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:45:15 INFO - Downloading/unpacking jsonschema==2.5.1 15:45:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:45:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:45:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:45:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:45:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:45:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:45:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:45:18 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:45:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:45:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:45:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:45:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:45:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:45:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:45:18 INFO - Downloading functools32-3.2.3-2.tar.gz 15:45:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:45:18 INFO - warning: no files found matching '*.txt' 15:45:18 INFO - no previously-included directories found matching 'build' 15:45:18 INFO - no previously-included directories found matching 'dist' 15:45:18 INFO - no previously-included directories found matching '.git*' 15:45:18 INFO - Installing collected packages: jsonschema, functools32 15:45:18 INFO - Running setup.py install for functools32 15:45:19 INFO - warning: no files found matching '*.txt' 15:45:19 INFO - no previously-included directories found matching 'build' 15:45:19 INFO - no previously-included directories found matching 'dist' 15:45:19 INFO - no previously-included directories found matching '.git*' 15:45:19 INFO - Successfully installed jsonschema functools32 15:45:19 INFO - Cleaning up... 15:45:19 INFO - Return code: 0 15:45:19 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:45:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:45:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:45:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:19 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 0x101cbe490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c34570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde52483030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ad2d30>, '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 0x101938db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101acab20>, '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.nIwEdLWObo/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.9AHDdB2KSf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:45:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:45:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 15:45:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nIwEdLWObo/Render', 15:45:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:45:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:45:19 INFO - 'HOME': '/Users/cltbld', 15:45:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:45:19 INFO - 'LOGNAME': 'cltbld', 15:45:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:45:19 INFO - 'MOZ_NO_REMOTE': '1', 15:45:19 INFO - 'NO_EM_RESTART': '1', 15:45:19 INFO - 'PAGER': '/bin/cat', 15:45:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:45:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:45:19 INFO - 'PWD': '/builds/slave/test', 15:45:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:45:19 INFO - 'SHELL': '/bin/bash', 15:45:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9AHDdB2KSf/Listeners', 15:45:19 INFO - 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', 15:45:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:45:19 INFO - 'USER': 'cltbld', 15:45:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:45:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:45:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:45:19 INFO - 'XPC_FLAGS': '0x0', 15:45:19 INFO - 'XPC_SERVICE_NAME': '0', 15:45:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:45:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:45:19 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:45:19 INFO - Cleaning up... 15:45:19 INFO - Return code: 0 15:45:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:45:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:45:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:45:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:19 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 0x101cbe490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c34570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde52483030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ad2d30>, '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 0x101938db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101acab20>, '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.nIwEdLWObo/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.9AHDdB2KSf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:45:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:45:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:45:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nIwEdLWObo/Render', 15:45:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:45:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:45:19 INFO - 'HOME': '/Users/cltbld', 15:45:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:45:19 INFO - 'LOGNAME': 'cltbld', 15:45:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:45:19 INFO - 'MOZ_NO_REMOTE': '1', 15:45:19 INFO - 'NO_EM_RESTART': '1', 15:45:19 INFO - 'PAGER': '/bin/cat', 15:45:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:45:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:45:19 INFO - 'PWD': '/builds/slave/test', 15:45:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:45:19 INFO - 'SHELL': '/bin/bash', 15:45:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9AHDdB2KSf/Listeners', 15:45:19 INFO - 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', 15:45:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:45:19 INFO - 'USER': 'cltbld', 15:45:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:45:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:45:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:45:19 INFO - 'XPC_FLAGS': '0x0', 15:45:19 INFO - 'XPC_SERVICE_NAME': '0', 15:45:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:45:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:45:19 INFO - Downloading/unpacking blobuploader==1.2.4 15:45:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:45:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:45:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:45:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:45:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:45:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:45:22 INFO - Downloading blobuploader-1.2.4.tar.gz 15:45:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:45:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:45:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:45:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:45:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:45:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:45:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:45:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:45:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:45:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:45:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:45:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:45:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:45:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:45:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:45:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:45:23 INFO - Downloading docopt-0.6.1.tar.gz 15:45:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:45:23 INFO - Installing collected packages: blobuploader, requests, docopt 15:45:23 INFO - Running setup.py install for blobuploader 15:45:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:45:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:45:23 INFO - Running setup.py install for requests 15:45:24 INFO - Running setup.py install for docopt 15:45:24 INFO - Successfully installed blobuploader requests docopt 15:45:24 INFO - Cleaning up... 15:45:24 INFO - Return code: 0 15:45:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:45:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:45:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:45:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:24 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 0x101cbe490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c34570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde52483030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ad2d30>, '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 0x101938db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101acab20>, '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.nIwEdLWObo/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.9AHDdB2KSf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:45:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:45:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:45:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nIwEdLWObo/Render', 15:45:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:45:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:45:24 INFO - 'HOME': '/Users/cltbld', 15:45:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:45:24 INFO - 'LOGNAME': 'cltbld', 15:45:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:45:24 INFO - 'MOZ_NO_REMOTE': '1', 15:45:24 INFO - 'NO_EM_RESTART': '1', 15:45:24 INFO - 'PAGER': '/bin/cat', 15:45:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:45:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:45:24 INFO - 'PWD': '/builds/slave/test', 15:45:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:45:24 INFO - 'SHELL': '/bin/bash', 15:45:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9AHDdB2KSf/Listeners', 15:45:24 INFO - 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', 15:45:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:45:24 INFO - 'USER': 'cltbld', 15:45:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:45:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:45:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:45:24 INFO - 'XPC_FLAGS': '0x0', 15:45:24 INFO - 'XPC_SERVICE_NAME': '0', 15:45:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:45:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:45:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:45:24 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-razxFP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:45:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:45:24 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-SWpiMY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:45:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:45:25 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-u3OqPU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:45:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:45:25 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-ZL4sRM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:45:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:45:25 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-rkoKUO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:45:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:45:25 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-sTLja4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:45:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:45:25 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-dJLu3f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:45:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:45:25 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-URIKPi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:45:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:45:25 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-AFt02w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:45:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:45:25 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-v1p3Be-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:45:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:45:25 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-It8AGI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:45:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:45:26 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-OHkxgh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:45:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:45:26 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-bQVQpn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:45:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:45:26 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-04qL6A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:45:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:45:26 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-wGDq5v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:45:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:45:26 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-HulDTS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:45:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:45:26 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-Xt3esT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:45:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:45:26 INFO - Running setup.py install for manifestparser 15:45:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:45:26 INFO - Running setup.py install for mozcrash 15:45:26 INFO - Running setup.py install for mozdebug 15:45:27 INFO - Running setup.py install for mozdevice 15:45:27 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:45:27 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:45:27 INFO - Running setup.py install for mozfile 15:45:27 INFO - Running setup.py install for mozhttpd 15:45:27 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:45:27 INFO - Running setup.py install for mozinfo 15:45:27 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:45:27 INFO - Running setup.py install for mozInstall 15:45:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:45:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:45:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:45:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:45:27 INFO - Running setup.py install for mozleak 15:45:27 INFO - Running setup.py install for mozlog 15:45:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:45:28 INFO - Running setup.py install for moznetwork 15:45:28 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:45:28 INFO - Running setup.py install for mozprocess 15:45:28 INFO - Running setup.py install for mozprofile 15:45:28 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:45:28 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:45:28 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:45:28 INFO - Running setup.py install for mozrunner 15:45:28 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:45:28 INFO - Running setup.py install for mozscreenshot 15:45:28 INFO - Running setup.py install for moztest 15:45:29 INFO - Running setup.py install for mozversion 15:45:29 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:45:29 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:45:29 INFO - Cleaning up... 15:45:29 INFO - Return code: 0 15:45:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:45:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:45:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:45:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:29 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 0x101cbe490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c34570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde52483030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ad2d30>, '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 0x101938db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101acab20>, '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.nIwEdLWObo/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.9AHDdB2KSf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:45:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:45:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:45:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nIwEdLWObo/Render', 15:45:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:45:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:45:29 INFO - 'HOME': '/Users/cltbld', 15:45:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:45:29 INFO - 'LOGNAME': 'cltbld', 15:45:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:45:29 INFO - 'MOZ_NO_REMOTE': '1', 15:45:29 INFO - 'NO_EM_RESTART': '1', 15:45:29 INFO - 'PAGER': '/bin/cat', 15:45:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:45:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:45:29 INFO - 'PWD': '/builds/slave/test', 15:45:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:45:29 INFO - 'SHELL': '/bin/bash', 15:45:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9AHDdB2KSf/Listeners', 15:45:29 INFO - 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', 15:45:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:45:29 INFO - 'USER': 'cltbld', 15:45:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:45:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:45:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:45:29 INFO - 'XPC_FLAGS': '0x0', 15:45:29 INFO - 'XPC_SERVICE_NAME': '0', 15:45:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:45:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:45:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:45:29 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-dW5wJO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:45:29 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 15:45:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:45:29 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-weWyis-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:45:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:45:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:45:30 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-GbRItv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:45:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:45:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:45:30 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-2XsU2o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:45:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:45:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:45:30 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-chRB5i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:45:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 15:45:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:45:30 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-3rTY_6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:45:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 15:45:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:45:30 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-CQ8VXq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:45:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 15:45:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:45:30 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-spJyzo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:45:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:45:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:45:30 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-DA0ApZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:45:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 15:45:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:45:30 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-z2bZru-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:45:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:45:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:45:30 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-V213hB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:45:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 15:45:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:45:31 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-Yu0JUD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:45:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:45:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:45:31 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-xx8NvZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:45:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 15:45:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:45:31 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-IcIyDr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:45:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 15:45:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:45:31 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-ZldPvS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:45:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 15:45:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:45:31 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-a4QY9d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:45:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 15:45:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:45:31 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-oG1t7h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:45:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 15:45:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:45:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:45:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:45:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:45:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:45:31 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:45:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:45:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:45:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:45:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:45:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:45:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:45:34 INFO - Downloading blessings-1.6.tar.gz 15:45:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:45:34 INFO - Installing collected packages: blessings 15:45:34 INFO - Running setup.py install for blessings 15:45:34 INFO - Successfully installed blessings 15:45:34 INFO - Cleaning up... 15:45:34 INFO - Return code: 0 15:45:34 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:45:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:45:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:45:35 INFO - Reading from file tmpfile_stdout 15:45:35 INFO - Current package versions: 15:45:35 INFO - blessings == 1.6 15:45:35 INFO - blobuploader == 1.2.4 15:45:35 INFO - docopt == 0.6.1 15:45:35 INFO - functools32 == 3.2.3-2 15:45:35 INFO - jsonschema == 2.5.1 15:45:35 INFO - manifestparser == 1.1 15:45:35 INFO - mozInstall == 1.12 15:45:35 INFO - mozcrash == 1.0 15:45:35 INFO - mozdebug == 0.1 15:45:35 INFO - mozdevice == 0.48 15:45:35 INFO - mozfile == 1.2 15:45:35 INFO - mozhttpd == 0.7 15:45:35 INFO - mozinfo == 0.9 15:45:35 INFO - mozleak == 0.1 15:45:35 INFO - mozlog == 3.4 15:45:35 INFO - moznetwork == 0.27 15:45:35 INFO - mozprocess == 0.23 15:45:35 INFO - mozprofile == 0.28 15:45:35 INFO - mozrunner == 6.13 15:45:35 INFO - mozscreenshot == 0.1 15:45:35 INFO - mozsystemmonitor == 0.3 15:45:35 INFO - moztest == 0.8 15:45:35 INFO - mozversion == 1.4 15:45:35 INFO - psutil == 3.1.1 15:45:35 INFO - requests == 1.2.3 15:45:35 INFO - wsgiref == 0.1.2 15:45:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:45:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:45:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:45:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:35 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 0x101cbe490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c34570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde52483030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ad2d30>, '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 0x101938db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101acab20>, '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.nIwEdLWObo/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.9AHDdB2KSf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:45:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 15:45:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:45:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nIwEdLWObo/Render', 15:45:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:45:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:45:35 INFO - 'HOME': '/Users/cltbld', 15:45:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:45:35 INFO - 'LOGNAME': 'cltbld', 15:45:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:45:35 INFO - 'MOZ_NO_REMOTE': '1', 15:45:35 INFO - 'NO_EM_RESTART': '1', 15:45:35 INFO - 'PAGER': '/bin/cat', 15:45:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:45:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:45:35 INFO - 'PWD': '/builds/slave/test', 15:45:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:45:35 INFO - 'SHELL': '/bin/bash', 15:45:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9AHDdB2KSf/Listeners', 15:45:35 INFO - 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', 15:45:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:45:35 INFO - 'USER': 'cltbld', 15:45:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:45:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:45:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:45:35 INFO - 'XPC_FLAGS': '0x0', 15:45:35 INFO - 'XPC_SERVICE_NAME': '0', 15:45:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:45:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:45:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:45:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 15:45:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 15:45:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:45:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 15:45:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 15:45:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 15:45:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 15:45:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 15:45:35 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:45:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:45:35 INFO - Cleaning up... 15:45:35 INFO - Return code: 0 15:45:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:45:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:45:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:45:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101cbe490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c34570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde52483030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ad2d30>, '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 0x101938db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101acab20>, '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.nIwEdLWObo/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.9AHDdB2KSf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:45:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:45:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:45:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nIwEdLWObo/Render', 15:45:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:45:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:45:35 INFO - 'HOME': '/Users/cltbld', 15:45:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:45:35 INFO - 'LOGNAME': 'cltbld', 15:45:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:45:35 INFO - 'MOZ_NO_REMOTE': '1', 15:45:35 INFO - 'NO_EM_RESTART': '1', 15:45:35 INFO - 'PAGER': '/bin/cat', 15:45:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:45:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:45:35 INFO - 'PWD': '/builds/slave/test', 15:45:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:45:35 INFO - 'SHELL': '/bin/bash', 15:45:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9AHDdB2KSf/Listeners', 15:45:35 INFO - 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', 15:45:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:45:35 INFO - 'USER': 'cltbld', 15:45:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:45:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:45:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:45:35 INFO - 'XPC_FLAGS': '0x0', 15:45:35 INFO - 'XPC_SERVICE_NAME': '0', 15:45:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:45:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:45:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:45:35 INFO - Cleaning up... 15:45:35 INFO - Return code: 0 15:45:35 INFO - Running post-action listener: _resource_record_post_action 15:45:35 INFO - Running post-action listener: _start_resource_monitoring 15:45:35 INFO - Starting resource monitoring. 15:45:35 INFO - [mozharness: 2017-01-09 23:45:35.752978Z] Finished create-virtualenv step (success) 15:45:35 INFO - [mozharness: 2017-01-09 23:45:35.753969Z] Running install step. 15:45:35 INFO - Running pre-action listener: _resource_record_pre_action 15:45:35 INFO - Running main action method: install 15:45:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:45:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:45:36 INFO - Reading from file tmpfile_stdout 15:45:36 INFO - Detecting whether we're running mozinstall >=1.0... 15:45:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:45:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:45:36 INFO - Reading from file tmpfile_stdout 15:45:36 INFO - Output received: 15:45:36 INFO - Usage: mozinstall [options] installer 15:45:36 INFO - Options: 15:45:36 INFO - -h, --help show this help message and exit 15:45:36 INFO - -d DEST, --destination=DEST 15:45:36 INFO - Directory to install application into. [default: 15:45:36 INFO - "/builds/slave/test"] 15:45:36 INFO - --app=APP Application being installed. [default: firefox] 15:45:36 INFO - mkdir: /builds/slave/test/build/application 15:45:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 15:45:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 15:46:06 INFO - Reading from file tmpfile_stdout 15:46:06 INFO - Output received: 15:46:06 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 15:46:06 INFO - Running post-action listener: _resource_record_post_action 15:46:06 INFO - [mozharness: 2017-01-09 23:46:06.957283Z] Finished install step (success) 15:46:06 INFO - [mozharness: 2017-01-09 23:46:06.957430Z] Running run-tests step. 15:46:06 INFO - Running pre-action listener: _resource_record_pre_action 15:46:06 INFO - Running main action method: run_tests 15:46:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:46:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:46:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:46:06 INFO - 2017-01-09 15:46:06.971 screenresolution[1632:7510] starting screenresolution argv=screenresolution get 15:46:06 INFO - 2017-01-09 15:46:06.974 screenresolution[1632:7510] Display 0: 1600x1200x32@60 15:46:06 INFO - 2017-01-09 15:46:06.980 screenresolution[1633:7514] starting screenresolution argv=screenresolution list 15:46:06 INFO - Available Modes on Display 0 15:46:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:46:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:46:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:46:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:46:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:46:07 INFO - Intel Iris: 15:46:07 INFO - Chipset Model: Intel Iris 15:46:07 INFO - Type: GPU 15:46:07 INFO - Bus: Built-In 15:46:07 INFO - VRAM (Dynamic, Max): 1536 MB 15:46:07 INFO - Vendor: Intel (0x8086) 15:46:07 INFO - Device ID: 0x0a2e 15:46:07 INFO - Revision ID: 0x0009 15:46:07 INFO - Displays: 15:46:07 INFO - Display: 15:46:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:46:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:46:07 INFO - Display Serial Number: 1600x1200 60 15:46:07 INFO - Main Display: Yes 15:46:07 INFO - Mirror: Off 15:46:07 INFO - Online: Yes 15:46:07 INFO - Rotation: Supported 15:46:07 INFO - Return code: 0 15:46:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:46:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:46:07 INFO - Python 2.7.3 15:46:07 INFO - Return code: 0 15:46:07 INFO - grabbing minidump binary from tooltool 15:46: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')]} 15:46:07 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 0x101ad2d30>, '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 0x101938db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101acab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:46:07 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 15:46:07 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 15:46:07 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 15:46:07 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:46:07 INFO - Return code: 0 15:46:07 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:46:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:46:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:46:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:46:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:46:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WYpkRzh6T2ep9ZnE_tBOhg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0368', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 15:46:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/WYpkRzh6T2ep9ZnE_tBOhg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0368 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 15:46:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nIwEdLWObo/Render', 15:46:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:46:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:46:07 INFO - 'HOME': '/Users/cltbld', 15:46:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:46:07 INFO - 'LOGNAME': 'cltbld', 15:46:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:46:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:46:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:46:07 INFO - 'MOZ_NO_REMOTE': '1', 15:46:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:46:07 INFO - 'NO_EM_RESTART': '1', 15:46:07 INFO - 'PAGER': '/bin/cat', 15:46:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:46:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:46:07 INFO - 'PWD': '/builds/slave/test', 15:46:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:46:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:46:07 INFO - 'SHELL': '/bin/bash', 15:46:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9AHDdB2KSf/Listeners', 15:46:07 INFO - 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', 15:46:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:46:07 INFO - 'USER': 'cltbld', 15:46:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:46:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:46:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:46:07 INFO - 'XPC_FLAGS': '0x0', 15:46:07 INFO - 'XPC_SERVICE_NAME': '0', 15:46:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:46:07 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WYpkRzh6T2ep9ZnE_tBOhg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0368', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 15:46:07 INFO - mozversion application_buildid: 20170109140908 15:46:07 INFO - mozversion application_changeset: 10e1849db4be083d35c4e40a7d7a7b32a0437817 15:46:07 INFO - mozversion application_display_name: FirefoxDeveloperEdition 15:46:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:46:07 INFO - mozversion application_name: Firefox 15:46:07 INFO - mozversion application_remotingname: firefox-dev 15:46:07 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:46:07 INFO - mozversion application_vendor: Mozilla 15:46:07 INFO - mozversion application_version: 52.0a2 15:46:07 INFO - mozversion platform_buildid: 20170109140908 15:46:07 INFO - mozversion platform_changeset: 10e1849db4be083d35c4e40a7d7a7b32a0437817 15:46:07 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:46:07 INFO - mozversion platform_version: 52.0a2 15:46:07 INFO - using testdate: 1484005567 15:46:07 INFO - actual date: 1484005567 15:46:07 INFO - starting webserver on 'localhost:49241' 15:46:07 INFO - SUITE-START | Running 2 tests 15:46:07 INFO - TEST-START | dromaeo_css 15:46:07 INFO - operating with platform_type : mac_ 15:46:07 INFO - Initialising browser for dromaeo_css test... 15:46:07 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/tmpth94Eh/profile http://localhost:49241/getInfo.html) 15:46:10 INFO - PROCESS | 1643 | __metrics Screen width/height:1600/1200 15:46:10 INFO - PROCESS | 1643 | colorDepth:24 15:46:10 INFO - PROCESS | 1643 | Browser inner width/height: 1024/689 15:46:10 INFO - PROCESS | 1643 | __metrics 15:46:10 INFO - PROCESS | 1643 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:46:10 INFO - PROCESS | 1643 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:46:11 INFO - TEST-INFO | 1643: exit 0 15:46:11 INFO - Browser initialized. 15:46:11 INFO - Running cycle 1/1 for dromaeo_css test... 15:46:11 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/tmpth94Eh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:46:23 INFO - PROCESS | 1645 | RSS: Main: 222806016 15:46:23 INFO - PROCESS | 1645 | 15:49:27 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 15:49:29 INFO - PROCESS | 1645 | RSS: Main: 329773056 15:49:29 INFO - PROCESS | 1645 | 15:52:32 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 15:52:32 INFO - PROCESS | 1645 | RSS: Main: 346992640 15:52:32 INFO - PROCESS | 1645 | 15:55:35 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 15:55:35 INFO - PROCESS | 1645 | RSS: Main: 315625472 15:55:35 INFO - PROCESS | 1645 | 15:58:38 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 15:58:38 INFO - PROCESS | 1645 | RSS: Main: 320909312 15:58:38 INFO - PROCESS | 1645 | 16:01:42 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 16:01:42 INFO - PROCESS | 1645 | RSS: Main: 312377344 16:01:42 INFO - PROCESS | 1645 | 16:04:45 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 16:04:46 INFO - PROCESS | 1645 | RSS: Main: 396791808 16:04:46 INFO - PROCESS | 1645 | 16:04:46 INFO - PROCESS | 1645 | __start_tp_report 16:04:46 INFO - PROCESS | 1645 | _x_x_mozilla_page_load 16:04:46 INFO - PROCESS | 1645 | _x_x_mozilla_page_load_details 16:04:46 INFO - PROCESS | 1645 | |i|pagename|runs| 16:04:46 INFO - PROCESS | 1645 | |0;dojo.html;3277.8197199154047;3285.359354925789;3306.1238771725484;3306.9503957440643;3326.9168270793225;5573.442655734435;5605.131204663274;5622.859428514289;5641.323041235052;5687.658740475575;5568.136938774486;5639.165009940358;5640.125780504029;5654.434556544354;5706.885862282752;5093.2105523643895;5115.718635475047;5134.2812006319145;5159.819406320779;5180.2488639147305;5108.361454818148;5116.565091967187;5119.488051194888;5195.948040519603;5199.506046925536;5127.359080114986;5153.613478989072;5186.740662966856;5193.091209038422;5195.220716892459;5175.611829112831;5186.714730689791;5202.76587553561;5214.687118772886;5220.347456567929;5071.1632580624155;5180.481951804827;5188.247704082887;5199.454057323965;5222.4255331913455;5511.917321240185;5549.1676248563035;5563.443655634444;5578.191162281447;5670.518005969498;6115.097174815158;6134.9079763803575;6155.753769849201;6167.414095660905;6168.84577885552;5336.706481143536;6227.968860155715;6230;6230;6232.781852635181;5591.52472039873;5634.830955071355;5670.404607516151;5734.684592347419;5741.942580574183;6201.286852011986;6242.282137554149;6257.468115210167;6263.749450022039;6277.780277690259;6046.909296360558;6051.092336149525;6051.969740151271;6059.7273122709585;6108.75564977396;6025.969870150621;6186.597871138361;6204.968975155186;6208.317085120633;6209.099680546386;5989.880202395928;6033.5474839387225;6046.425589569072;6055.2430946131735;6120.724567394478;6118.284172697598;6120.752726168788;6128.724207410588;6157.384261573807;6161.4146656067605;4839;4849.660523763373;4860.1008813369635;4861.538153875376;4869.902601947942;4281.37920001598;4299.656027517791;4302.161078589645;4346.956530434719;4348.826046958149;4826.975865120652;5471.179323101487;5563.471470210324;5581.413951535112;5585.916211256835;6111.98679349419;6113.628337950562;6125.601835880653;6131.816045518642;6177.135201071854;5590.5248053915875;5641.774329026875;5652.095664693629;5656.264685590847;5659.518940889988;5352.518273355339;5355.143177091724;5355.384130825005;5371.462853714597;5392.110301800238;6241.719122639583;6298.11826344303;6305.779297724741;6332.33510481402;6341.651209183447;5199.506046925536;6122.877542449215;6123.892747182578;6140.50424869748;6224.875502489835;6133.754649813957;6227.065940108839;6317.6209427434505;6355.428011478989;6359.395857393541;5946.851328716817;6075.453209211192;6090.177825993434;6096.817095487142;6100.54245931548;5448.972755136358;6092.512598992159;6134.099846298167;6137.376985335611;6141.731496609813;5888.646681199141;5925.792597259076;5936.762529498772;5943.91084133747;5974.014287642491;5892;5915.852103697442;5944.4352786485215;5945.256842894638;5962.046072628359;5683.232763576864;5734.283214598176;5748.511376532874;5791.305043394819;5802.65184088956;5921.733521991435;5992.221011268507;6013.248343957005;6036.577439579188;6049.032154855202;5785.942140578541;5799.507041901317;5803.65178089316;5831.708414579339;5859.355470898113 16:04:46 INFO - PROCESS | 1645 | |1;ext.html;488.5895847488109;509.0302972836745;524.7454984332601;552.5634748548647;556.3740791609439;37157.81421092898;38570.61429385712;38651.80674096633;38669.41995870061;38733.64432244872;38591.61408385915;39045;39067.609323906756;39071;39090;8155.95922020389;9229.723108306745;9423.246140308793;9441.858372124423;9498.002709715449;9335.159835614793;9409.62361505539;9449.952750236274;9459.1959683427;9485.430874147538;9409.85885211719;9449.43303401798;9456.054394560557;9462.053794620551;9468.526573671323;9375.906240937571;9419.576119074658;9424.528773561295;9435.339526233167;9463.432194068344;9263.397879137901;9360.11078947499;9369.203617692436;9375.249980001583;9409.717708468757;30115.849420752977;30368.54447183294;30424.391512169634;30436.54345184824;30479.542806857913;3332.8166950817695;3411.0449074259245;3472.73954453417;3716.4425336199515;3887.591802860687;28393;28927.710722892713;29017.98437054693;29361.559576606367;29519;3135.498320268769;3135.733462655677;3156.826374549422;3186.920326991844;3287.161773747679;486361.13638863515;486630;487490;487860.5606971978;490039;55075.724621376634;55420.89158216813;55574.444255556926;55595.44404555903;55620.72189639107;34188.82905585506;34576.30847383038;35023.29953400969;35993;36015.4597681035;15749.212539372986;15916.283767230503;15930.920345398199;15937.442189523312;15968.600784980235;10448.477576121315;12086.15396922207;12150.21023633461;12156.574519891761;12172.75654486923;18185.181666825025;18264.817351826576;18320.54198645018;18350.26598936054;18352.265909363476;7452.962735186289;7561.659725312375;7567.956651704119;7643.579603121771;7654.119776225694;842.5913431985482;939.9530023498797;1018.0125278479862;1023.6826583759058;1071.8981696738797;26525.86737066302;26674.466510669678;29147.271318217216;29635.407291854473;29747.405051899274;828.8549503836925;829.4815740162398;876.4303202918154;959.5681943125621;1015.3451024089421;14771;15017.122859642544;15223.471346311993;15332.923335383475;15594.986325888782;650128;656016;657396;658306;663068;55518.72240638771;55901.4844332006;55928;56108.438915611136;56242;1356.9457221711218;1381.7789153735355;1396.4553824008644;1397.7064816388672;1399.3912647998154;28121.859390702917;28253.434931300846;28970.7102928968;29511.85244073852;29756.10731678053;46987.765061174476;48942.75528622334;50343.74828125836;54532.45467545274;57467.850642987745;586224.0688796529;591517;594462;594609.0539094553;596008;34202.65797341995;40536;40772.184556308115;41240.58759412367;41405.171896561296;44907.55092449034;45108.87227819331;45371.77314113409;45607.54392456033;45752;30406.543901841047;31319;35948.84306941592;36967;37145.628543714214;36623;36752;36818.447723283636;36927.63072369242;36939.26121477609;36451.90644280676;36498.08754781046;37155.44266835946;37229.62770372262;37442.43836342511;31058.68941310558;33111.69881612308;33758.98723038313;33787.66212337845;34017;24530.89610967475;24941.501169976862;25029;25058.62412063857;25407.491850163264 16:04:46 INFO - PROCESS | 1645 | |2;jquery.html;351.83639607582467;352.5962772625344;376.24750499001993;381.9904502387441;403.07293225581157;14675.266236688163;14926.552203433901;15574.766378504331;19585.41243762686;20075.096620652104;19324.71012934807;19357.612847743072;19871.205151793947;19998.200071997104;20282.29011984581;5829.125631155329;5963.582549221556;6023.668698221618;6026.638401695912;6060.939390606103;4536.387587675655;5969.22400087988;6000.789972350947;6015.398460153971;6032.426919442668;5637.520810731072;5945.821625351246;5948.434898684618;5956.076808094752;5957.970210148943;5712.742926568314;5808.912866307009;5826.067829147316;5828.009238429456;5828.5337173026055;4896.583790377822;4964.15609346412;5095.643304968654;5096.388433388017;5207.0887594670785;12964.611061668165;13226.404811783494;13232.470701172038;13236.933815330958;20273.08771105304;2175.9020844062056;2180.6728990651372;2282.9771702282933;2322.6980492535863;2412.8069754419603;9786.75533111678;10167.186625069977;10391.896081039244;10836.295640783403;14198.858011419858;1782.9554261143446;1832.2945665918717;1842.0973722875829;1871.1205733305326;1881.106474424651;98762.5061874686;98842;98956;99753.5036974446;105758;9619.56711947964;11431;11741.354225517507;11811.468483918245;11867.881321186851;14539.3457294422;14546.418143274363;14548;14563.980521363615;14584.781228281585;8326;8471.872921906177;8472.49165050099;8575.614097365633;8585.141485851489;8366.539840308842;8422.031466381444;8423.789405264917;8555.443896146731;8653.870191947126;4391.055922976511;4500.279955207151;4517.570830771071;4570.702904311209;4651.418572678415;1875.2030387085433;1911.579452520471;1933.1494142577503;1937.5059359863144;1976.1305025788895;605.5307136968885;698.322627051759;808.9635966381527;811.4360519438933;923.3905622289257;19165.425037248628;20777.168913243604;20805.583888322148;20833.374998750063;20840.58318833615;748.5059860492049;894.4454438248198;899.4153800030033;904.8778414914034;936.0171819589403;10438.478076096164;10812.405317707444;10935.83596246057;10941.945290273497;10981.956714112146;588107;597223;598527;606856;607115;135286;136025;140404.95950404878;141663;141674.2916285412;908.1554154636252;968.6561270748782;991.0287917840557;1006.2503434940869;1029.5933106422922;15129.47046853355;17357.913210433868;17792.822071779116;18078.276868925095;18756.15597298152;49172.58860497874;49642.255366168814;51689.898606083756;52794.94410111699;55053.44946550643;59487.70256148691;71034;71127.93308100279;71902.64048679933;72089.27910720826;7968.0836703779805;8087.353011758927;8112.067112282163;8295.419320647497;8476.23713865755;11188.21682482251;12516.687082822638;12594.559190428658;12958.22250664963;13026.023048271229;5559.16612508127;10832.964442211918;10971.23201375928;11229.66311010671;11652.592159274385;10989.670309890716;11235.382053986783;11265.71835704114;11361;11652.533898644298;11489.657309350858;11671.766564668489;11801.881981180422;11936.58221962227;11964.64106076819;8170.10128885834;8365.498070115813;8372.246497815226;8487.915120848713;8527.488350698977;9605.519724013631;11348.148888833206;11722.648320550397;11747.530098795953;11754.471048802654 16:04:46 INFO - PROCESS | 1645 | |3;mootools.html;1461.6930444606633;1493.6191271225837;1647.2175716534648;1798.514401111699;1835.3209312554347;7328.780136595904;7988.560629165401;8409.83180336393;8428.988521377429;8436.746897593082;7361.227071157532;7649.502782319146;7656.923430765691;7740.961295193516;7746.186650401697;2100.821430178433;2259.6497542880797;2336.789688927996;2339.099446713019;2342.9456744464974;2131.7015617813518;2329.5457385809773;2358.3278765551822;2361.7992470640015;2367.396313939943;2319.895604697784;2341.8711970841596;2342.5549145662353;2343.2970108967243;2344.0858065354523;2294.9082036718596;2329.6738456616085;2330.4406942333885;2330.498942727305;2333.9533209335805;2332.0555175153922;2332.603457412244;2358.870262135582;2359.858408495496;2363.1256435118944;8361.456505327134;8365.456245344094;8449.831003379897;8450.746477605682;8454.070052294242;5051.368578927634;5205.219217117425;5220.060389129955;5220.451852555465;5226.895462090737;3141.4345417824784;3330.81680507572;3440.363532746425;3448.620651728308;3502.124468882779;996.342414006752;1045.456362691404;1051.7326621421169;1052.310736467615;1094.5457635081432;10914.508847101899;10965.56137754489;10970.012698857141;10974.067204287645;11008.449577521173;4516.841910533116;4588.770561471913;4664.930026049612;4676.789544470507;4757.333973243749;8557.443766155304;8693.608787604573;8702.21680048786;8703.695370661997;8734.912650873537;5093.796248150033;5096.286519887219;5097.490250974947;5101.362329708786;5104.668196567285;4257.1911336846515;4582.541745825458;4688.390509233778;4786.401699787527;4790.449098353734;3424.383610950003;3602.0454579536445;3708.721845861571;3710.7959062251834;3724.385476396364;1118.4128332625437;1144.3362849547243;1153.1524329617723;1153.2734377342388;1163.2903928603546;764.7820371194183;765.1124695353475;856.9957150214334;874.7463235661714;904.5929331800716;5261.289725887033;5283.365996080495;5377.300950876361;5510.476504732044;5726.942730572724;859.4198915731856;887.973360799177;898.5192921786943;927.9118483744033;959.5777857742571;3668.3947148720163;3677.8344974476213;3682.2451397463456;3753.6997040236715;3798.8860334190017;9456.14894659484;10020.899791002143;10148.898511014795;10162.695119146438;10181.592736290466;9805.558749856273;10097.596096156218;10126.13927816144;10126.291159618904;10143.188544916537;7710.383169346439;7865.977422934981;7870.763877083697;7873.724419645399;7900.84198316042;5315.973420132875;5406.107992181246;5490.91763623538;5530.142827861688;5541.667499950016;9257.814843703223;9272.258219342571;9360.859587106062;9386.577604007973;9514.191293739832;9317.068293170629;9465.716028519155;9571.808563828823;9593.520323983912;9733.318667693193;6718.025886246564;6747.662616869039;6830.45356371499;6839.452843772586;6842;7643.082830060428;7656.4640475168435;7764.262395072459;7766.611669416619;7798.220177982156;6871.931280687129;7021.192562855337;7079.1858936223;7112.715491380493;7193.496455248292;5664.433556644302;6997;7011.9649401754705;7026.297370262932;7047.330503602149;6609.372109649576;6636.634985075674;6676.599404035774;6758.966205168943;6810.489213308922;4713.08094921487;4721.693089949143;4818.397700287464;4824.317341706673;4925.507449255189;4097.262492751333;4821.590164836029;4866.29438731389;4934.802607895787;5011.198208286657 16:04:46 INFO - PROCESS | 1645 | |4;prototype.html;259.45255510872056;279.1374652324318;304.8338655432789;312.92333378322314;317.71881884532183;4472.463304403476;5049.722265275408;5078.99379745001;5087.313212716283;5107.616928730342;4207.873763787084;4986.304656416817;5089.872753181172;5099.36257967754;5147.794088236466;3049.0090720515864;3320.165209183397;3751.5498140223212;3836.808159592023;3866.516685414323;3315.436375816117;3907.788585538492;3931.429942658313;3937.8424863005444;3952.703547233969;3302.8935306672292;3528.894133176009;3818.5417749870053;3847;3862.439946207798;3705.1905377878766;3828.2917660232947;3858.86493972711;3865.4201869719486;3892.2020985697864;3411.3177364527196;3413.5903691557173;3426.886261964871;3429.9314013719586;3430.5540279763723;4173.770442625654;4382.693211475198;4504;4891.434740882919;4895.828645997373;3756.849726010956;3905.8517815201526;4304.78476076195;4395.802188901507;4423.014465235953;4881.827586723909;5257.0811653746105;5265.052290587692;5290.8889133282055;5296.523312901857;3383.154211447138;3489.2672538766637;3683.7605555638797;3695.5565332160045;3700.333939890819;5530.861728456781;5591.309735574501;5608.775648974036;5612.130119831432;5623.437656234344;3388.525606415104;3505.8422370993367;3699.426588878728;3747.9437808432895;3769.3215221260434;3151.747860171181;3341.5655964724915;3504.1064528545567;3812.866549670748;3826.2347530493457;3521.344967865099;4049.4128351388886;4120.011197312623;4326.6538676905775;4365.912681746411;5129.487051294841;5318.601104917069;5351.518363347317;5360.383555891044;5362.463753624685;1225.601679454181;1225.81612758087;1228.705110773408;1232.8212409200617;1234.9444275007647;754.6906117885464;812.9512229266263;846.8821156073923;848.7708318753902;858.5149390594295;4554.017118787199;4816.084943860656;4859.319695242669;4934.185859333242;5052.444231134646;4851.514848515191;5228.581713462914;5487.341519017744;5505.329914991226;5505.476979686923;4415.580519850609;4745.098431298112;4972.403311602631;4983;5013.849584512471;2370.13490076121;2424.563578555877;2591.248537923981;2720.578310361897;2729.3176705823544;4472.1502914446155;5247.790088396421;5345.492178243138;5365.275687782099;5367.543758780469;4877.902531892274;4983.401991760939;5222.712750798667;5251.474852514794;5313.787448502094;4665.580097791215;5103.2855400244;5201.973990130025;5359.775693087403;5372.140457526777;5116.488351164854;5127.897442051213;5160.148575485003;5179.581476225673;5243.528082472596;4863.610911127172;5083.847484575469;5127.9744051190655;5144.408393034699;5154.922676159936;4782.7521065099345;4936.58039066669;5132.5124113209185;5350.678959262456;5390.272313237662;3068.67778883223;3400.9319813604084;3459.342724882365;3490.5636795400574;3506.193575477603;4417.580329868657;4453.995701934078;4804.303376010529;5220.9738951305;5253.868653283698;4564.657650676146;4886.853394398174;5009.493354651831;5040.546350828443;5044.1424957756535;4237.936430953477;4314.805833737427;4770.2007218197805;4865.537773911473;5100.387953445462;4681.159058576802;4796.496367881322;4928.556429921325;4997.525235102659;5021.372328458942;4639.979204574988;4879.4876537963;5067.7212753297445;5266.683998960075;5278.419373868948;4534.1183408884945;4865.683730557644;4971.701697898137;5178.6374953752875;5207.3751149862255 16:04:46 INFO - PROCESS | 1645 | |5;yui.html;694.7255833945588;695.5235663570454;697.9197392299887;759.2255898983037;795.5982228974367;14391.42434302628;14702.191379474125;14716.190609516472;14723.337449814757;14762.778558321621;13130;13165.144265622728;13171.868281317184;13171.93414032931;13200.603981880535;642.8007317731518;657.1489920552896;657.828964469239;664.110092476082;808.4058217210331;666.3037126203105;668.595499722668;788.7515432638701;808.0666829811577;813.0934008580449;792.2988155482411;798.4410912361341;863.4905405810571;863.7970077031893;869.0961400143843;861.6165806216236;861.7716305179133;862.1550880137501;863.8013256950874;863.9438436501607;502.3143409246389;519.4960887938694;535.5929493584864;640.337250945274;641.0672471553878;13611.863881361158;14092;14097.85902140986;14132.717345653133;14133.434662613483;12030.218035827644;12264.448099835528;12340.444679989421;12407.069469789802;12624.873751262461;11922.940385298105;12059.879401205962;12188.10123939208;12258.938705306506;12341.75316493665;4231.30183519722;4246.341817018367;4324.502682191557;4474.507804141542;4479.014616784302;83123.58438207771;85007;90986.09013909842;91717;91947.54026229893;9748.707538773848;9976.002399759966;10188.752404766261;10336.948315258376;10492.842607360895;18953.241870325033;19136;19197.90401047986;19269.22923083089;19280.517987050436;7208.4954053216825;7343.63281835906;7359.448041396917;7505.099388073466;7546.773596792105;4013.2082790447566;4048.6477037185145;4064.1785769401386;4099.206171233836;4124.886280704193;3261.7879837810465;3327.800331980089;3370.3090866372336;3371.3425881953276;3373.2916087621384;635.4725577770439;640.69887153038;647.0779139725965;648.3905129178557;652.0968458684756;513.6866511428026;567.7218163100051;606.2270604978687;611.9718872294587;659.2649196146263;8392;8574.957125214334;8577.699780507652;8646.827063458695;8938.553072346356;590.4302348233974;599.5203836930439;601.3775742106985;609.3601718195877;626.6396821827467;3744;4064.349704047397;4283.785810709452;4285.6142947134285;4292.785360731951;105234.47382763037;105286.94713052924;106235;108761.45619271853;110036.4498177504;91224.54387728019;94251;94648.52675736578;96832.51583742177;97828;75159;79087.60456197683;83028;84060;85822.57088714516;7017.8582094010335;8199.754007379788;8245.381596380415;8463.49219046859;8476.194761497647;47990;51046.97906041784;53362.73318633382;54679;55389.723051384484;73868.52262954818;74916.62541687257;75249.49501010058;77462.6126869362;79036.20963790288;10413.42726150069;10441.68674939753;10896.618618348637;11824.054075674097;12251.877481225074;13171.341432928124;13269.66825829362;14098.224597647228;14112.223827689575;14871.702565948404;12979.286139262054;13327.800082998956;13571.86428135706;13845.961552883373;13860.792088118485;12590.748185036431;13080.411381487665;13320;13365.39855706515;13815.102018368774;12712.74574508523;12922.612321630366;13118.934405328295;13702;13738.313084345942;10626.651378433497;11024.173187010845;11050.778984420427;11105.94447027792;11554.480048397674;11154.274972126786;11332.716682083013;11390.400247812848;11397.544098236309;11511.481983310603 16:04:46 INFO - PROCESS | 1645 | __end_tp_report 16:04:46 INFO - PROCESS | 1645 | __start_cc_report 16:04:46 INFO - PROCESS | 1645 | _x_x_mozilla_cycle_collect,1807 16:04:46 INFO - PROCESS | 1645 | __end_cc_report 16:04:46 INFO - PROCESS | 1645 | __startTimestamp1484006686177__endTimestamp 16:04:46 INFO - PROCESS | 1645 | 16:04:46 INFO - PROCESS | 1645 | ------- Summary: start ------- 16:04:46 INFO - PROCESS | 1645 | Number of tests: 6 16:04:46 INFO - PROCESS | 1645 | 16:04:46 INFO - PROCESS | 1645 | [#0] dojo.html Cycles:165 Average:5644.26 Median:5791.31 stddev:631.86 (10.9%) stddev-sans-first:605.91 16:04:46 INFO - PROCESS | 1645 | Values: 3277.8 3285.4 3306.1 3307.0 3326.9 5573.4 5605.1 5622.9 5641.3 5687.7 5568.1 5639.2 5640.1 5654.4 5706.9 5093.2 5115.7 5134.3 5159.8 5180.2 5108.4 5116.6 5119.5 5195.9 5199.5 5127.4 5153.6 5186.7 5193.1 5195.2 5175.6 5186.7 5202.8 5214.7 5220.3 5071.2 5180.5 5188.2 5199.5 5222.4 5511.9 5549.2 5563.4 5578.2 5670.5 6115.1 6134.9 6155.8 6167.4 6168.8 5336.7 6228.0 6230.0 6230.0 6232.8 5591.5 5634.8 5670.4 5734.7 5741.9 6201.3 6242.3 6257.5 6263.7 6277.8 6046.9 6051.1 6052.0 6059.7 6108.8 6026.0 6186.6 6205.0 6208.3 6209.1 5989.9 6033.5 6046.4 6055.2 6120.7 6118.3 6120.8 6128.7 6157.4 6161.4 4839.0 4849.7 4860.1 4861.5 4869.9 4281.4 4299.7 4302.2 4347.0 4348.8 4827.0 5471.2 5563.5 5581.4 5585.9 6112.0 6113.6 6125.6 6131.8 6177.1 5590.5 5641.8 5652.1 5656.3 5659.5 5352.5 5355.1 5355.4 5371.5 5392.1 6241.7 6298.1 6305.8 6332.3 6341.7 5199.5 6122.9 6123.9 6140.5 6224.9 6133.8 6227.1 6317.6 6355.4 6359.4 5946.9 6075.5 6090.2 6096.8 6100.5 5449.0 6092.5 6134.1 6137.4 6141.7 5888.6 5925.8 5936.8 5943.9 5974.0 5892.0 5915.9 5944.4 5945.3 5962.0 5683.2 5734.3 5748.5 5791.3 5802.7 5921.7 5992.2 6013.2 6036.6 6049.0 5785.9 5799.5 5803.7 5831.7 5859.4 16:04:46 INFO - PROCESS | 1645 | 16:04:46 INFO - PROCESS | 1645 | [#1] ext.html Cycles:180 Average:69633.18 Median:38581.11 stddev:156188.45 (404.8%) stddev-sans-first:156540.32 16:04:46 INFO - PROCESS | 1645 | Values: 488.6 509.0 524.7 552.6 556.4 37157.8 38570.6 38651.8 38669.4 38733.6 38591.6 39045.0 39067.6 39071.0 39090.0 8156.0 9229.7 9423.2 9441.9 9498.0 9335.2 9409.6 9450.0 9459.2 9485.4 9409.9 9449.4 9456.1 9462.1 9468.5 9375.9 9419.6 9424.5 9435.3 9463.4 9263.4 9360.1 9369.2 9375.2 9409.7 30115.8 30368.5 30424.4 30436.5 30479.5 3332.8 3411.0 3472.7 3716.4 3887.6 28393.0 28927.7 29018.0 29361.6 29519.0 3135.5 3135.7 3156.8 3186.9 3287.2 486361.1 486630.0 487490.0 487860.6 490039.0 55075.7 55420.9 55574.4 55595.4 55620.7 34188.8 34576.3 35023.3 35993.0 36015.5 15749.2 15916.3 15930.9 15937.4 15968.6 10448.5 12086.2 12150.2 12156.6 12172.8 18185.2 18264.8 18320.5 18350.3 18352.3 7453.0 7561.7 7568.0 7643.6 7654.1 842.6 940.0 1018.0 1023.7 1071.9 26525.9 26674.5 29147.3 29635.4 29747.4 828.9 829.5 876.4 959.6 1015.3 14771.0 15017.1 15223.5 15332.9 15595.0 650128.0 656016.0 657396.0 658306.0 663068.0 55518.7 55901.5 55928.0 56108.4 56242.0 1356.9 1381.8 1396.5 1397.7 1399.4 28121.9 28253.4 28970.7 29511.9 29756.1 46987.8 48942.8 50343.7 54532.5 57467.9 586224.1 591517.0 594462.0 594609.1 596008.0 34202.7 40536.0 40772.2 41240.6 41405.2 44907.6 45108.9 45371.8 45607.5 45752.0 30406.5 31319.0 35948.8 36967.0 37145.6 36623.0 36752.0 36818.4 36927.6 36939.3 36451.9 36498.1 37155.4 37229.6 37442.4 31058.7 33111.7 33759.0 33787.7 34017.0 24530.9 24941.5 25029.0 25058.6 25407.5 16:04:46 INFO - PROCESS | 1645 | 16:04:46 INFO - PROCESS | 1645 | [#2] jquery.html Cycles:180 Average:34142.47 Median:364.42 stddev:99973.30 (27433.4%) stddev-sans-first:100221.56 16:04:46 INFO - PROCESS | 1645 | Values: 351.8 352.6 376.2 382.0 403.1 14675.3 14926.6 15574.8 19585.4 20075.1 19324.7 19357.6 19871.2 19998.2 20282.3 5829.1 5963.6 6023.7 6026.6 6060.9 4536.4 5969.2 6000.8 6015.4 6032.4 5637.5 5945.8 5948.4 5956.1 5958.0 5712.7 5808.9 5826.1 5828.0 5828.5 4896.6 4964.2 5095.6 5096.4 5207.1 12964.6 13226.4 13232.5 13236.9 20273.1 2175.9 2180.7 2283.0 2322.7 2412.8 9786.8 10167.2 10391.9 10836.3 14198.9 1783.0 1832.3 1842.1 1871.1 1881.1 98762.5 98842.0 98956.0 99753.5 105758.0 9619.6 11431.0 11741.4 11811.5 11867.9 14539.3 14546.4 14548.0 14564.0 14584.8 8326.0 8471.9 8472.5 8575.6 8585.1 8366.5 8422.0 8423.8 8555.4 8653.9 4391.1 4500.3 4517.6 4570.7 4651.4 1875.2 1911.6 1933.1 1937.5 1976.1 605.5 698.3 809.0 811.4 923.4 19165.4 20777.2 20805.6 20833.4 20840.6 748.5 894.4 899.4 904.9 936.0 10438.5 10812.4 10935.8 10941.9 10982.0 588107.0 597223.0 598527.0 606856.0 607115.0 135286.0 136025.0 140405.0 141663.0 141674.3 908.2 968.7 991.0 1006.3 1029.6 15129.5 17357.9 17792.8 18078.3 18756.2 49172.6 49642.3 51689.9 52794.9 55053.4 59487.7 71034.0 71127.9 71902.6 72089.3 7968.1 8087.4 8112.1 8295.4 8476.2 11188.2 12516.7 12594.6 12958.2 13026.0 5559.2 10833.0 10971.2 11229.7 11652.6 10989.7 11235.4 11265.7 11361.0 11652.5 11489.7 11671.8 11801.9 11936.6 11964.6 8170.1 8365.5 8372.2 8487.9 8527.5 9605.5 11348.1 11722.6 11747.5 11754.5 16:04:46 INFO - PROCESS | 1645 | 16:04:46 INFO - PROCESS | 1645 | [#3] mootools.html Cycles:180 Average:5375.31 Median:5099.43 stddev:2947.75 (57.8%) stddev-sans-first:2941.35 16:04:46 INFO - PROCESS | 1645 | Values: 1461.7 1493.6 1647.2 1798.5 1835.3 7328.8 7988.6 8409.8 8429.0 8436.7 7361.2 7649.5 7656.9 7741.0 7746.2 2100.8 2259.6 2336.8 2339.1 2342.9 2131.7 2329.5 2358.3 2361.8 2367.4 2319.9 2341.9 2342.6 2343.3 2344.1 2294.9 2329.7 2330.4 2330.5 2334.0 2332.1 2332.6 2358.9 2359.9 2363.1 8361.5 8365.5 8449.8 8450.7 8454.1 5051.4 5205.2 5220.1 5220.5 5226.9 3141.4 3330.8 3440.4 3448.6 3502.1 996.3 1045.5 1051.7 1052.3 1094.5 10914.5 10965.6 10970.0 10974.1 11008.4 4516.8 4588.8 4664.9 4676.8 4757.3 8557.4 8693.6 8702.2 8703.7 8734.9 5093.8 5096.3 5097.5 5101.4 5104.7 4257.2 4582.5 4688.4 4786.4 4790.4 3424.4 3602.0 3708.7 3710.8 3724.4 1118.4 1144.3 1153.2 1153.3 1163.3 764.8 765.1 857.0 874.7 904.6 5261.3 5283.4 5377.3 5510.5 5726.9 859.4 888.0 898.5 927.9 959.6 3668.4 3677.8 3682.2 3753.7 3798.9 9456.1 10020.9 10148.9 10162.7 10181.6 9805.6 10097.6 10126.1 10126.3 10143.2 7710.4 7866.0 7870.8 7873.7 7900.8 5316.0 5406.1 5490.9 5530.1 5541.7 9257.8 9272.3 9360.9 9386.6 9514.2 9317.1 9465.7 9571.8 9593.5 9733.3 6718.0 6747.7 6830.5 6839.5 6842.0 7643.1 7656.5 7764.3 7766.6 7798.2 6871.9 7021.2 7079.2 7112.7 7193.5 5664.4 6997.0 7012.0 7026.3 7047.3 6609.4 6636.6 6676.6 6759.0 6810.5 4713.1 4721.7 4818.4 4824.3 4925.5 4097.3 4821.6 4866.3 4934.8 5011.2 16:04:46 INFO - PROCESS | 1645 | 16:04:46 INFO - PROCESS | 1645 | [#4] prototype.html Cycles:180 Average:4198.28 Median:4800.40 stddev:1280.34 (26.7%) stddev-sans-first:1249.33 16:04:46 INFO - PROCESS | 1645 | Values: 259.5 279.1 304.8 312.9 317.7 4472.5 5049.7 5079.0 5087.3 5107.6 4207.9 4986.3 5089.9 5099.4 5147.8 3049.0 3320.2 3751.5 3836.8 3866.5 3315.4 3907.8 3931.4 3937.8 3952.7 3302.9 3528.9 3818.5 3847.0 3862.4 3705.2 3828.3 3858.9 3865.4 3892.2 3411.3 3413.6 3426.9 3429.9 3430.6 4173.8 4382.7 4504.0 4891.4 4895.8 3756.8 3905.9 4304.8 4395.8 4423.0 4881.8 5257.1 5265.1 5290.9 5296.5 3383.2 3489.3 3683.8 3695.6 3700.3 5530.9 5591.3 5608.8 5612.1 5623.4 3388.5 3505.8 3699.4 3747.9 3769.3 3151.7 3341.6 3504.1 3812.9 3826.2 3521.3 4049.4 4120.0 4326.7 4365.9 5129.5 5318.6 5351.5 5360.4 5362.5 1225.6 1225.8 1228.7 1232.8 1234.9 754.7 813.0 846.9 848.8 858.5 4554.0 4816.1 4859.3 4934.2 5052.4 4851.5 5228.6 5487.3 5505.3 5505.5 4415.6 4745.1 4972.4 4983.0 5013.8 2370.1 2424.6 2591.2 2720.6 2729.3 4472.2 5247.8 5345.5 5365.3 5367.5 4877.9 4983.4 5222.7 5251.5 5313.8 4665.6 5103.3 5202.0 5359.8 5372.1 5116.5 5127.9 5160.1 5179.6 5243.5 4863.6 5083.8 5128.0 5144.4 5154.9 4782.8 4936.6 5132.5 5350.7 5390.3 3068.7 3400.9 3459.3 3490.6 3506.2 4417.6 4454.0 4804.3 5221.0 5253.9 4564.7 4886.9 5009.5 5040.5 5044.1 4237.9 4314.8 4770.2 4865.5 5100.4 4681.2 4796.5 4928.6 4997.5 5021.4 4640.0 4879.5 5067.7 5266.7 5278.4 4534.1 4865.7 4971.7 5178.6 5207.4 16:04:46 INFO - PROCESS | 1645 | 16:04:46 INFO - PROCESS | 1645 | [#5] yui.html Cycles:180 Average:20279.43 Median:4399.51 stddev:29651.70 (674.0%) stddev-sans-first:29698.41 16:04:46 INFO - PROCESS | 1645 | Values: 694.7 695.5 697.9 759.2 795.6 14391.4 14702.2 14716.2 14723.3 14762.8 13130.0 13165.1 13171.9 13171.9 13200.6 642.8 657.1 657.8 664.1 808.4 666.3 668.6 788.8 808.1 813.1 792.3 798.4 863.5 863.8 869.1 861.6 861.8 862.2 863.8 863.9 502.3 519.5 535.6 640.3 641.1 13611.9 14092.0 14097.9 14132.7 14133.4 12030.2 12264.4 12340.4 12407.1 12624.9 11922.9 12059.9 12188.1 12258.9 12341.8 4231.3 4246.3 4324.5 4474.5 4479.0 83123.6 85007.0 90986.1 91717.0 91947.5 9748.7 9976.0 10188.8 10336.9 10492.8 18953.2 19136.0 19197.9 19269.2 19280.5 7208.5 7343.6 7359.4 7505.1 7546.8 4013.2 4048.6 4064.2 4099.2 4124.9 3261.8 3327.8 3370.3 3371.3 3373.3 635.5 640.7 647.1 648.4 652.1 513.7 567.7 606.2 612.0 659.3 8392.0 8575.0 8577.7 8646.8 8938.6 590.4 599.5 601.4 609.4 626.6 3744.0 4064.3 4283.8 4285.6 4292.8 105234.5 105286.9 106235.0 108761.5 110036.4 91224.5 94251.0 94648.5 96832.5 97828.0 75159.0 79087.6 83028.0 84060.0 85822.6 7017.9 8199.8 8245.4 8463.5 8476.2 47990.0 51047.0 53362.7 54679.0 55389.7 73868.5 74916.6 75249.5 77462.6 79036.2 10413.4 10441.7 10896.6 11824.1 12251.9 13171.3 13269.7 14098.2 14112.2 14871.7 12979.3 13327.8 13571.9 13846.0 13860.8 12590.7 13080.4 13320.0 13365.4 13815.1 12712.7 12922.6 13118.9 13702.0 13738.3 10626.7 11024.2 11050.8 11105.9 11554.5 11154.3 11332.7 11390.4 11397.5 11511.5 16:04:46 INFO - PROCESS | 1645 | -------- Summary: end -------- 16:04:46 INFO - PROCESS | 1645 | 16:04:46 INFO - TEST-INFO | 1645: exit 0 16:04:46 INFO - TEST-OK | dromaeo_css | took 1119280ms 16:04:46 INFO - TEST-START | kraken 16:04:46 INFO - operating with platform_type : mac_ 16:04:46 INFO - Initialising browser for kraken test... 16:04:46 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/tmpwMw21e/profile http://localhost:49241/getInfo.html) 16:04:49 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 16:04:49 INFO - PROCESS | 1655 | colorDepth:24 16:04:49 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 16:04:49 INFO - PROCESS | 1655 | __metrics 16:04:49 INFO - PROCESS | 1655 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:04:49 INFO - PROCESS | 1655 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:04:50 INFO - TEST-INFO | 1655: exit 0 16:04:50 INFO - Browser initialized. 16:04:50 INFO - Running cycle 1/1 for kraken test... 16:04:50 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/tmpwMw21e/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:05:02 INFO - PROCESS | 1656 | RSS: Main: 223354880 16:05:02 INFO - PROCESS | 1656 | 16:05:05 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 16:05:06 INFO - PROCESS | 1656 | RSS: Main: 272785408 16:05:06 INFO - PROCESS | 1656 | 16:05:09 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 16:05:09 INFO - PROCESS | 1656 | RSS: Main: 284499968 16:05:09 INFO - PROCESS | 1656 | 16:05:12 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 16:05:13 INFO - PROCESS | 1656 | RSS: Main: 380452864 16:05:13 INFO - PROCESS | 1656 | 16:05:15 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 16:05:16 INFO - PROCESS | 1656 | RSS: Main: 283619328 16:05:16 INFO - PROCESS | 1656 | 16:05:18 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:05:18 INFO - PROCESS | 1656 | RSS: Main: 284758016 16:05:18 INFO - PROCESS | 1656 | 16:05:21 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 16:05:22 INFO - PROCESS | 1656 | RSS: Main: 326488064 16:05:22 INFO - PROCESS | 1656 | 16:05:25 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 16:05:26 INFO - PROCESS | 1656 | RSS: Main: 326979584 16:05:26 INFO - PROCESS | 1656 | 16:05:28 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 16:05:29 INFO - PROCESS | 1656 | RSS: Main: 323731456 16:05:29 INFO - PROCESS | 1656 | 16:05:30 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:05:31 INFO - PROCESS | 1656 | RSS: Main: 300531712 16:05:31 INFO - PROCESS | 1656 | 16:05:33 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:05:34 INFO - PROCESS | 1656 | RSS: Main: 301592576 16:05:34 INFO - PROCESS | 1656 | 16:05:36 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:05:37 INFO - PROCESS | 1656 | RSS: Main: 304820224 16:05:37 INFO - PROCESS | 1656 | 16:05:40 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:05:40 INFO - PROCESS | 1656 | RSS: Main: 313417728 16:05:40 INFO - PROCESS | 1656 | 16:05:43 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:05:44 INFO - PROCESS | 1656 | RSS: Main: 313102336 16:05:44 INFO - PROCESS | 1656 | 16:05:46 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 16:05:46 INFO - PROCESS | 1656 | RSS: Main: 356900864 16:05:46 INFO - PROCESS | 1656 | 16:05:46 INFO - PROCESS | 1656 | __start_tp_report 16:05:46 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 16:05:46 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 16:05:46 INFO - PROCESS | 1656 | |i|pagename|runs| 16:05:46 INFO - PROCESS | 1656 | |0;ai-astar;117;114;114;115;115;116;160;164;115;113 16:05:46 INFO - PROCESS | 1656 | |1;audio-beat-detection;143;109;108;147;111;111;110;110;142;109 16:05:46 INFO - PROCESS | 1656 | |2;audio-dft;185;185;183;176;181;183;181;184;183;184 16:05:46 INFO - PROCESS | 1656 | |3;audio-fft;78;75;83;112;76;59;58;59;82;59 16:05:46 INFO - PROCESS | 1656 | |4;audio-oscillator;89;70;95;70;68;70;89;68;68;91 16:05:46 INFO - PROCESS | 1656 | |5;imaging-gaussian-blur;87;88;133;86;103;129;132;85;88;84 16:05:46 INFO - PROCESS | 1656 | |6;imaging-darkroom;95;96;96;97;108;142;97;96;95;97 16:05:46 INFO - PROCESS | 1656 | |7;imaging-desaturate;83;85;88;80;85;81;84;86;84;81 16:05:46 INFO - PROCESS | 1656 | |8;json-parse-financial;55;54;55;52;56;59;58;58;57;56 16:05:46 INFO - PROCESS | 1656 | |9;json-stringify-tinderbox;63;71;71;92;105;97;85;70;70;70 16:05:46 INFO - PROCESS | 1656 | |10;stanford-crypto-aes;82;83;89;98;79;76;96;98;103;100 16:05:46 INFO - PROCESS | 1656 | |11;stanford-crypto-ccm;139;139;140;139;149;143;133;152;218;141 16:05:46 INFO - PROCESS | 1656 | |12;stanford-crypto-pbkdf2;165;161;168;164;162;226;226;175;164;161 16:05:46 INFO - PROCESS | 1656 | |13;stanford-crypto-sha256-iterative;67;69;80;85;84;84;66;68;68;65 16:05:46 INFO - PROCESS | 1656 | __end_tp_report 16:05:46 INFO - PROCESS | 1656 | __start_cc_report 16:05:46 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,1953 16:05:46 INFO - PROCESS | 1656 | __end_cc_report 16:05:46 INFO - PROCESS | 1656 | __startTimestamp1484006746565__endTimestamp 16:05:46 INFO - PROCESS | 1656 | 16:05:46 INFO - PROCESS | 1656 | ------- Summary: start ------- 16:05:46 INFO - PROCESS | 1656 | Number of tests: 14 16:05:46 INFO - PROCESS | 1656 | 16:05:46 INFO - PROCESS | 1656 | [#0] ai-astar Cycles:10 Average:124.30 Median:115.50 stddev:19.92 (17.2%) stddev-sans-first:20.95 16:05:46 INFO - PROCESS | 1656 | Values: 117.0 114.0 114.0 115.0 115.0 116.0 160.0 164.0 115.0 113.0 16:05:46 INFO - PROCESS | 1656 | 16:05:46 INFO - PROCESS | 1656 | [#1] audio-beat-detection Cycles:10 Average:120.00 Median:111.00 stddev:16.63 (15.0%) stddev-sans-first:15.42 16:05:46 INFO - PROCESS | 1656 | Values: 143.0 109.0 108.0 147.0 111.0 111.0 110.0 110.0 142.0 109.0 16:05:46 INFO - PROCESS | 1656 | 16:05:46 INFO - PROCESS | 1656 | [#2] audio-dft Cycles:10 Average:182.50 Median:183.50 stddev:2.68 (1.5%) stddev-sans-first:2.68 16:05:46 INFO - PROCESS | 1656 | Values: 185.0 185.0 183.0 176.0 181.0 183.0 181.0 184.0 183.0 184.0 16:05:46 INFO - PROCESS | 1656 | 16:05:46 INFO - PROCESS | 1656 | [#3] audio-fft Cycles:10 Average:74.10 Median:75.50 stddev:16.80 (22.3%) stddev-sans-first:17.76 16:05:46 INFO - PROCESS | 1656 | Values: 78.0 75.0 83.0 112.0 76.0 59.0 58.0 59.0 82.0 59.0 16:05:46 INFO - PROCESS | 1656 | 16:05:46 INFO - PROCESS | 1656 | [#4] audio-oscillator Cycles:10 Average:77.80 Median:79.50 stddev:11.51 (14.5%) stddev-sans-first:11.47 16:05:46 INFO - PROCESS | 1656 | Values: 89.0 70.0 95.0 70.0 68.0 70.0 89.0 68.0 68.0 91.0 16:05:46 INFO - PROCESS | 1656 | 16:05:46 INFO - PROCESS | 1656 | [#5] imaging-gaussian-blur Cycles:10 Average:101.50 Median:85.50 stddev:21.28 (24.9%) stddev-sans-first:21.91 16:05:46 INFO - PROCESS | 1656 | Values: 87.0 88.0 133.0 86.0 103.0 129.0 132.0 85.0 88.0 84.0 16:05:46 INFO - PROCESS | 1656 | 16:05:46 INFO - PROCESS | 1656 | [#6] imaging-darkroom Cycles:10 Average:101.90 Median:96.00 stddev:14.59 (15.2%) stddev-sans-first:15.26 16:05:46 INFO - PROCESS | 1656 | Values: 95.0 96.0 96.0 97.0 108.0 142.0 97.0 96.0 95.0 97.0 16:05:46 INFO - PROCESS | 1656 | 16:05:46 INFO - PROCESS | 1656 | [#7] imaging-desaturate Cycles:10 Average:83.70 Median:84.50 stddev:2.50 (3.0%) stddev-sans-first:2.64 16:05:46 INFO - PROCESS | 1656 | Values: 83.0 85.0 88.0 80.0 85.0 81.0 84.0 86.0 84.0 81.0 16:05:46 INFO - PROCESS | 1656 | 16:05:46 INFO - PROCESS | 1656 | [#8] json-parse-financial Cycles:10 Average:56.00 Median:56.50 stddev:2.11 (3.7%) stddev-sans-first:2.20 16:05:46 INFO - PROCESS | 1656 | Values: 55.0 54.0 55.0 52.0 56.0 59.0 58.0 58.0 57.0 56.0 16:05:46 INFO - PROCESS | 1656 | 16:05:46 INFO - PROCESS | 1656 | [#9] json-stringify-tinderbox Cycles:10 Average:79.40 Median:71.00 stddev:14.26 (20.1%) stddev-sans-first:13.84 16:05:46 INFO - PROCESS | 1656 | Values: 63.0 71.0 71.0 92.0 105.0 97.0 85.0 70.0 70.0 70.0 16:05:46 INFO - PROCESS | 1656 | 16:05:46 INFO - PROCESS | 1656 | [#10] stanford-crypto-aes Cycles:10 Average:90.40 Median:86.00 stddev:9.79 (11.4%) stddev-sans-first:9.90 16:05:46 INFO - PROCESS | 1656 | Values: 82.0 83.0 89.0 98.0 79.0 76.0 96.0 98.0 103.0 100.0 16:05:46 INFO - PROCESS | 1656 | 16:05:46 INFO - PROCESS | 1656 | [#11] stanford-crypto-ccm Cycles:10 Average:149.30 Median:142.00 stddev:24.73 (17.4%) stddev-sans-first:25.95 16:05:46 INFO - PROCESS | 1656 | Values: 139.0 139.0 140.0 139.0 149.0 143.0 133.0 152.0 218.0 141.0 16:05:46 INFO - PROCESS | 1656 | 16:05:46 INFO - PROCESS | 1656 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.20 Median:166.50 stddev:26.05 (15.6%) stddev-sans-first:27.25 16:05:46 INFO - PROCESS | 1656 | Values: 165.0 161.0 168.0 164.0 162.0 226.0 226.0 175.0 164.0 161.0 16:05:46 INFO - PROCESS | 1656 | 16:05:46 INFO - PROCESS | 1656 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.60 Median:74.50 stddev:8.47 (11.4%) stddev-sans-first:8.65 16:05:46 INFO - PROCESS | 1656 | Values: 67.0 69.0 80.0 85.0 84.0 84.0 66.0 68.0 68.0 65.0 16:05:46 INFO - PROCESS | 1656 | -------- Summary: end -------- 16:05:46 INFO - PROCESS | 1656 | 16:05:47 INFO - TEST-INFO | 1656: exit 0 16:05:47 INFO - TEST-OK | kraken | took 61021ms 16:05:47 INFO - SUITE-END | took 1180s 16:05:48 INFO - Completed test suite (00:19:40) 16:05:48 INFO - javascript benchmark 16:05:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3277.8197199154047, 3285.359354925789, 3306.1238771725484, 3306.9503957440643, 3326.9168270793225, 5573.442655734435, 5605.131204663274, 5622.859428514289, 5641.323041235052, 5687.658740475575, 5568.136938774486, 5639.165009940358, 5640.125780504029, 5654.434556544354, 5706.885862282752, 5093.2105523643895, 5115.718635475047, 5134.2812006319145, 5159.819406320779, 5180.2488639147305, 5108.361454818148, 5116.565091967187, 5119.488051194888, 5195.948040519603, 5199.506046925536, 5127.359080114986, 5153.613478989072, 5186.740662966856, 5193.091209038422, 5195.220716892459, 5175.611829112831, 5186.714730689791, 5202.76587553561, 5214.687118772886, 5220.347456567929, 5071.1632580624155, 5180.481951804827, 5188.247704082887, 5199.454057323965, 5222.4255331913455, 5511.917321240185, 5549.1676248563035, 5563.443655634444, 5578.191162281447, 5670.518005969498, 6115.097174815158, 6134.9079763803575, 6155.753769849201, 6167.414095660905, 6168.84577885552, 5336.706481143536, 6227.968860155715, 6230.0, 6230.0, 6232.781852635181, 5591.52472039873, 5634.830955071355, 5670.404607516151, 5734.684592347419, 5741.942580574183, 6201.286852011986, 6242.282137554149, 6257.468115210167, 6263.749450022039, 6277.780277690259, 6046.909296360558, 6051.092336149525, 6051.969740151271, 6059.7273122709585, 6108.75564977396, 6025.969870150621, 6186.597871138361, 6204.968975155186, 6208.317085120633, 6209.099680546386, 5989.880202395928, 6033.5474839387225, 6046.425589569072, 6055.2430946131735, 6120.724567394478, 6118.284172697598, 6120.752726168788, 6128.724207410588, 6157.384261573807, 6161.4146656067605, 4839.0, 4849.660523763373, 4860.1008813369635, 4861.538153875376, 4869.902601947942, 4281.37920001598, 4299.656027517791, 4302.161078589645, 4346.956530434719, 4348.826046958149, 4826.975865120652, 5471.179323101487, 5563.471470210324, 5581.413951535112, 5585.916211256835, 6111.98679349419, 6113.628337950562, 6125.601835880653, 6131.816045518642, 6177.135201071854, 5590.5248053915875, 5641.774329026875, 5652.095664693629, 5656.264685590847, 5659.518940889988, 5352.518273355339, 5355.143177091724, 5355.384130825005, 5371.462853714597, 5392.110301800238, 6241.719122639583, 6298.11826344303, 6305.779297724741, 6332.33510481402, 6341.651209183447, 5199.506046925536, 6122.877542449215, 6123.892747182578, 6140.50424869748, 6224.875502489835, 6133.754649813957, 6227.065940108839, 6317.6209427434505, 6355.428011478989, 6359.395857393541, 5946.851328716817, 6075.453209211192, 6090.177825993434, 6096.817095487142, 6100.54245931548, 5448.972755136358, 6092.512598992159, 6134.099846298167, 6137.376985335611, 6141.731496609813, 5888.646681199141, 5925.792597259076, 5936.762529498772, 5943.91084133747, 5974.014287642491, 5892.0, 5915.852103697442, 5944.4352786485215, 5945.256842894638, 5962.046072628359, 5683.232763576864, 5734.283214598176, 5748.511376532874, 5791.305043394819, 5802.65184088956, 5921.733521991435, 5992.221011268507, 6013.248343957005, 6036.577439579188, 6049.032154855202, 5785.942140578541, 5799.507041901317, 5803.65178089316, 5831.708414579339, 5859.355470898113], "value": 5603.783722917396, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [488.5895847488109, 509.0302972836745, 524.7454984332601, 552.5634748548647, 556.3740791609439, 37157.81421092898, 38570.61429385712, 38651.80674096633, 38669.41995870061, 38733.64432244872, 38591.61408385915, 39045.0, 39067.609323906756, 39071.0, 39090.0, 8155.95922020389, 9229.723108306745, 9423.246140308793, 9441.858372124423, 9498.002709715449, 9335.159835614793, 9409.62361505539, 9449.952750236274, 9459.1959683427, 9485.430874147538, 9409.85885211719, 9449.43303401798, 9456.054394560557, 9462.053794620551, 9468.526573671323, 9375.906240937571, 9419.576119074658, 9424.528773561295, 9435.339526233167, 9463.432194068344, 9263.397879137901, 9360.11078947499, 9369.203617692436, 9375.249980001583, 9409.717708468757, 30115.849420752977, 30368.54447183294, 30424.391512169634, 30436.54345184824, 30479.542806857913, 3332.8166950817695, 3411.0449074259245, 3472.73954453417, 3716.4425336199515, 3887.591802860687, 28393.0, 28927.710722892713, 29017.98437054693, 29361.559576606367, 29519.0, 3135.498320268769, 3135.733462655677, 3156.826374549422, 3186.920326991844, 3287.161773747679, 486361.13638863515, 486630.0, 487490.0, 487860.5606971978, 490039.0, 55075.724621376634, 55420.89158216813, 55574.444255556926, 55595.44404555903, 55620.72189639107, 34188.82905585506, 34576.30847383038, 35023.29953400969, 35993.0, 36015.4597681035, 15749.212539372986, 15916.283767230503, 15930.920345398199, 15937.442189523312, 15968.600784980235, 10448.477576121315, 12086.15396922207, 12150.21023633461, 12156.574519891761, 12172.75654486923, 18185.181666825025, 18264.817351826576, 18320.54198645018, 18350.26598936054, 18352.265909363476, 7452.962735186289, 7561.659725312375, 7567.956651704119, 7643.579603121771, 7654.119776225694, 842.5913431985482, 939.9530023498797, 1018.0125278479862, 1023.6826583759058, 1071.8981696738797, 26525.86737066302, 26674.466510669678, 29147.271318217216, 29635.407291854473, 29747.405051899274, 828.8549503836925, 829.4815740162398, 876.4303202918154, 959.5681943125621, 1015.3451024089421, 14771.0, 15017.122859642544, 15223.471346311993, 15332.923335383475, 15594.986325888782, 650128.0, 656016.0, 657396.0, 658306.0, 663068.0, 55518.72240638771, 55901.4844332006, 55928.0, 56108.438915611136, 56242.0, 1356.9457221711218, 1381.7789153735355, 1396.4553824008644, 1397.7064816388672, 1399.3912647998154, 28121.859390702917, 28253.434931300846, 28970.7102928968, 29511.85244073852, 29756.10731678053, 46987.765061174476, 48942.75528622334, 50343.74828125836, 54532.45467545274, 57467.850642987745, 586224.0688796529, 591517.0, 594462.0, 594609.0539094553, 596008.0, 34202.65797341995, 40536.0, 40772.184556308115, 41240.58759412367, 41405.171896561296, 44907.55092449034, 45108.87227819331, 45371.77314113409, 45607.54392456033, 45752.0, 30406.543901841047, 31319.0, 35948.84306941592, 36967.0, 37145.628543714214, 36623.0, 36752.0, 36818.447723283636, 36927.63072369242, 36939.26121477609, 36451.90644280676, 36498.08754781046, 37155.44266835946, 37229.62770372262, 37442.43836342511, 31058.68941310558, 33111.69881612308, 33758.98723038313, 33787.66212337845, 34017.0, 24530.89610967475, 24941.501169976862, 25029.0, 25058.62412063857, 25407.491850163264], "value": 19231.516201748786, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.83639607582467, 352.5962772625344, 376.24750499001993, 381.9904502387441, 403.07293225581157, 14675.266236688163, 14926.552203433901, 15574.766378504331, 19585.41243762686, 20075.096620652104, 19324.71012934807, 19357.612847743072, 19871.205151793947, 19998.200071997104, 20282.29011984581, 5829.125631155329, 5963.582549221556, 6023.668698221618, 6026.638401695912, 6060.939390606103, 4536.387587675655, 5969.22400087988, 6000.789972350947, 6015.398460153971, 6032.426919442668, 5637.520810731072, 5945.821625351246, 5948.434898684618, 5956.076808094752, 5957.970210148943, 5712.742926568314, 5808.912866307009, 5826.067829147316, 5828.009238429456, 5828.5337173026055, 4896.583790377822, 4964.15609346412, 5095.643304968654, 5096.388433388017, 5207.0887594670785, 12964.611061668165, 13226.404811783494, 13232.470701172038, 13236.933815330958, 20273.08771105304, 2175.9020844062056, 2180.6728990651372, 2282.9771702282933, 2322.6980492535863, 2412.8069754419603, 9786.75533111678, 10167.186625069977, 10391.896081039244, 10836.295640783403, 14198.858011419858, 1782.9554261143446, 1832.2945665918717, 1842.0973722875829, 1871.1205733305326, 1881.106474424651, 98762.5061874686, 98842.0, 98956.0, 99753.5036974446, 105758.0, 9619.56711947964, 11431.0, 11741.354225517507, 11811.468483918245, 11867.881321186851, 14539.3457294422, 14546.418143274363, 14548.0, 14563.980521363615, 14584.781228281585, 8326.0, 8471.872921906177, 8472.49165050099, 8575.614097365633, 8585.141485851489, 8366.539840308842, 8422.031466381444, 8423.789405264917, 8555.443896146731, 8653.870191947126, 4391.055922976511, 4500.279955207151, 4517.570830771071, 4570.702904311209, 4651.418572678415, 1875.2030387085433, 1911.579452520471, 1933.1494142577503, 1937.5059359863144, 1976.1305025788895, 605.5307136968885, 698.322627051759, 808.9635966381527, 811.4360519438933, 923.3905622289257, 19165.425037248628, 20777.168913243604, 20805.583888322148, 20833.374998750063, 20840.58318833615, 748.5059860492049, 894.4454438248198, 899.4153800030033, 904.8778414914034, 936.0171819589403, 10438.478076096164, 10812.405317707444, 10935.83596246057, 10941.945290273497, 10981.956714112146, 588107.0, 597223.0, 598527.0, 606856.0, 607115.0, 135286.0, 136025.0, 140404.95950404878, 141663.0, 141674.2916285412, 908.1554154636252, 968.6561270748782, 991.0287917840557, 1006.2503434940869, 1029.5933106422922, 15129.47046853355, 17357.913210433868, 17792.822071779116, 18078.276868925095, 18756.15597298152, 49172.58860497874, 49642.255366168814, 51689.898606083756, 52794.94410111699, 55053.44946550643, 59487.70256148691, 71034.0, 71127.93308100279, 71902.64048679933, 72089.27910720826, 7968.0836703779805, 8087.353011758927, 8112.067112282163, 8295.419320647497, 8476.23713865755, 11188.21682482251, 12516.687082822638, 12594.559190428658, 12958.22250664963, 13026.023048271229, 5559.16612508127, 10832.964442211918, 10971.23201375928, 11229.66311010671, 11652.592159274385, 10989.670309890716, 11235.382053986783, 11265.71835704114, 11361.0, 11652.533898644298, 11489.657309350858, 11671.766564668489, 11801.881981180422, 11936.58221962227, 11964.64106076819, 8170.10128885834, 8365.498070115813, 8372.246497815226, 8487.915120848713, 8527.488350698977, 9605.519724013631, 11348.148888833206, 11722.648320550397, 11747.530098795953, 11754.471048802654], "value": 9206.210436565412, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1461.6930444606633, 1493.6191271225837, 1647.2175716534648, 1798.514401111699, 1835.3209312554347, 7328.780136595904, 7988.560629165401, 8409.83180336393, 8428.988521377429, 8436.746897593082, 7361.227071157532, 7649.502782319146, 7656.923430765691, 7740.961295193516, 7746.186650401697, 2100.821430178433, 2259.6497542880797, 2336.789688927996, 2339.099446713019, 2342.9456744464974, 2131.7015617813518, 2329.5457385809773, 2358.3278765551822, 2361.7992470640015, 2367.396313939943, 2319.895604697784, 2341.8711970841596, 2342.5549145662353, 2343.2970108967243, 2344.0858065354523, 2294.9082036718596, 2329.6738456616085, 2330.4406942333885, 2330.498942727305, 2333.9533209335805, 2332.0555175153922, 2332.603457412244, 2358.870262135582, 2359.858408495496, 2363.1256435118944, 8361.456505327134, 8365.456245344094, 8449.831003379897, 8450.746477605682, 8454.070052294242, 5051.368578927634, 5205.219217117425, 5220.060389129955, 5220.451852555465, 5226.895462090737, 3141.4345417824784, 3330.81680507572, 3440.363532746425, 3448.620651728308, 3502.124468882779, 996.342414006752, 1045.456362691404, 1051.7326621421169, 1052.310736467615, 1094.5457635081432, 10914.508847101899, 10965.56137754489, 10970.012698857141, 10974.067204287645, 11008.449577521173, 4516.841910533116, 4588.770561471913, 4664.930026049612, 4676.789544470507, 4757.333973243749, 8557.443766155304, 8693.608787604573, 8702.21680048786, 8703.695370661997, 8734.912650873537, 5093.796248150033, 5096.286519887219, 5097.490250974947, 5101.362329708786, 5104.668196567285, 4257.1911336846515, 4582.541745825458, 4688.390509233778, 4786.401699787527, 4790.449098353734, 3424.383610950003, 3602.0454579536445, 3708.721845861571, 3710.7959062251834, 3724.385476396364, 1118.4128332625437, 1144.3362849547243, 1153.1524329617723, 1153.2734377342388, 1163.2903928603546, 764.7820371194183, 765.1124695353475, 856.9957150214334, 874.7463235661714, 904.5929331800716, 5261.289725887033, 5283.365996080495, 5377.300950876361, 5510.476504732044, 5726.942730572724, 859.4198915731856, 887.973360799177, 898.5192921786943, 927.9118483744033, 959.5777857742571, 3668.3947148720163, 3677.8344974476213, 3682.2451397463456, 3753.6997040236715, 3798.8860334190017, 9456.14894659484, 10020.899791002143, 10148.898511014795, 10162.695119146438, 10181.592736290466, 9805.558749856273, 10097.596096156218, 10126.13927816144, 10126.291159618904, 10143.188544916537, 7710.383169346439, 7865.977422934981, 7870.763877083697, 7873.724419645399, 7900.84198316042, 5315.973420132875, 5406.107992181246, 5490.91763623538, 5530.142827861688, 5541.667499950016, 9257.814843703223, 9272.258219342571, 9360.859587106062, 9386.577604007973, 9514.191293739832, 9317.068293170629, 9465.716028519155, 9571.808563828823, 9593.520323983912, 9733.318667693193, 6718.025886246564, 6747.662616869039, 6830.45356371499, 6839.452843772586, 6842.0, 7643.082830060428, 7656.4640475168435, 7764.262395072459, 7766.611669416619, 7798.220177982156, 6871.931280687129, 7021.192562855337, 7079.1858936223, 7112.715491380493, 7193.496455248292, 5664.433556644302, 6997.0, 7011.9649401754705, 7026.297370262932, 7047.330503602149, 6609.372109649576, 6636.634985075674, 6676.599404035774, 6758.966205168943, 6810.489213308922, 4713.08094921487, 4721.693089949143, 4818.397700287464, 4824.317341706673, 4925.507449255189, 4097.262492751333, 4821.590164836029, 4866.29438731389, 4934.802607895787, 5011.198208286657], "value": 4357.1900822140615, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [259.45255510872056, 279.1374652324318, 304.8338655432789, 312.92333378322314, 317.71881884532183, 4472.463304403476, 5049.722265275408, 5078.99379745001, 5087.313212716283, 5107.616928730342, 4207.873763787084, 4986.304656416817, 5089.872753181172, 5099.36257967754, 5147.794088236466, 3049.0090720515864, 3320.165209183397, 3751.5498140223212, 3836.808159592023, 3866.516685414323, 3315.436375816117, 3907.788585538492, 3931.429942658313, 3937.8424863005444, 3952.703547233969, 3302.8935306672292, 3528.894133176009, 3818.5417749870053, 3847.0, 3862.439946207798, 3705.1905377878766, 3828.2917660232947, 3858.86493972711, 3865.4201869719486, 3892.2020985697864, 3411.3177364527196, 3413.5903691557173, 3426.886261964871, 3429.9314013719586, 3430.5540279763723, 4173.770442625654, 4382.693211475198, 4504.0, 4891.434740882919, 4895.828645997373, 3756.849726010956, 3905.8517815201526, 4304.78476076195, 4395.802188901507, 4423.014465235953, 4881.827586723909, 5257.0811653746105, 5265.052290587692, 5290.8889133282055, 5296.523312901857, 3383.154211447138, 3489.2672538766637, 3683.7605555638797, 3695.5565332160045, 3700.333939890819, 5530.861728456781, 5591.309735574501, 5608.775648974036, 5612.130119831432, 5623.437656234344, 3388.525606415104, 3505.8422370993367, 3699.426588878728, 3747.9437808432895, 3769.3215221260434, 3151.747860171181, 3341.5655964724915, 3504.1064528545567, 3812.866549670748, 3826.2347530493457, 3521.344967865099, 4049.4128351388886, 4120.011197312623, 4326.6538676905775, 4365.912681746411, 5129.487051294841, 5318.601104917069, 5351.518363347317, 5360.383555891044, 5362.463753624685, 1225.601679454181, 1225.81612758087, 1228.705110773408, 1232.8212409200617, 1234.9444275007647, 754.6906117885464, 812.9512229266263, 846.8821156073923, 848.7708318753902, 858.5149390594295, 4554.017118787199, 4816.084943860656, 4859.319695242669, 4934.185859333242, 5052.444231134646, 4851.514848515191, 5228.581713462914, 5487.341519017744, 5505.329914991226, 5505.476979686923, 4415.580519850609, 4745.098431298112, 4972.403311602631, 4983.0, 5013.849584512471, 2370.13490076121, 2424.563578555877, 2591.248537923981, 2720.578310361897, 2729.3176705823544, 4472.1502914446155, 5247.790088396421, 5345.492178243138, 5365.275687782099, 5367.543758780469, 4877.902531892274, 4983.401991760939, 5222.712750798667, 5251.474852514794, 5313.787448502094, 4665.580097791215, 5103.2855400244, 5201.973990130025, 5359.775693087403, 5372.140457526777, 5116.488351164854, 5127.897442051213, 5160.148575485003, 5179.581476225673, 5243.528082472596, 4863.610911127172, 5083.847484575469, 5127.9744051190655, 5144.408393034699, 5154.922676159936, 4782.7521065099345, 4936.58039066669, 5132.5124113209185, 5350.678959262456, 5390.272313237662, 3068.67778883223, 3400.9319813604084, 3459.342724882365, 3490.5636795400574, 3506.193575477603, 4417.580329868657, 4453.995701934078, 4804.303376010529, 5220.9738951305, 5253.868653283698, 4564.657650676146, 4886.853394398174, 5009.493354651831, 5040.546350828443, 5044.1424957756535, 4237.936430953477, 4314.805833737427, 4770.2007218197805, 4865.537773911473, 5100.387953445462, 4681.159058576802, 4796.496367881322, 4928.556429921325, 4997.525235102659, 5021.372328458942, 4639.979204574988, 4879.4876537963, 5067.7212753297445, 5266.683998960075, 5278.419373868948, 4534.1183408884945, 4865.683730557644, 4971.701697898137, 5178.6374953752875, 5207.3751149862255], "value": 3792.141921067831, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [694.7255833945588, 695.5235663570454, 697.9197392299887, 759.2255898983037, 795.5982228974367, 14391.42434302628, 14702.191379474125, 14716.190609516472, 14723.337449814757, 14762.778558321621, 13130.0, 13165.144265622728, 13171.868281317184, 13171.93414032931, 13200.603981880535, 642.8007317731518, 657.1489920552896, 657.828964469239, 664.110092476082, 808.4058217210331, 666.3037126203105, 668.595499722668, 788.7515432638701, 808.0666829811577, 813.0934008580449, 792.2988155482411, 798.4410912361341, 863.4905405810571, 863.7970077031893, 869.0961400143843, 861.6165806216236, 861.7716305179133, 862.1550880137501, 863.8013256950874, 863.9438436501607, 502.3143409246389, 519.4960887938694, 535.5929493584864, 640.337250945274, 641.0672471553878, 13611.863881361158, 14092.0, 14097.85902140986, 14132.717345653133, 14133.434662613483, 12030.218035827644, 12264.448099835528, 12340.444679989421, 12407.069469789802, 12624.873751262461, 11922.940385298105, 12059.879401205962, 12188.10123939208, 12258.938705306506, 12341.75316493665, 4231.30183519722, 4246.341817018367, 4324.502682191557, 4474.507804141542, 4479.014616784302, 83123.58438207771, 85007.0, 90986.09013909842, 91717.0, 91947.54026229893, 9748.707538773848, 9976.002399759966, 10188.752404766261, 10336.948315258376, 10492.842607360895, 18953.241870325033, 19136.0, 19197.90401047986, 19269.22923083089, 19280.517987050436, 7208.4954053216825, 7343.63281835906, 7359.448041396917, 7505.099388073466, 7546.773596792105, 4013.2082790447566, 4048.6477037185145, 4064.1785769401386, 4099.206171233836, 4124.886280704193, 3261.7879837810465, 3327.800331980089, 3370.3090866372336, 3371.3425881953276, 3373.2916087621384, 635.4725577770439, 640.69887153038, 647.0779139725965, 648.3905129178557, 652.0968458684756, 513.6866511428026, 567.7218163100051, 606.2270604978687, 611.9718872294587, 659.2649196146263, 8392.0, 8574.957125214334, 8577.699780507652, 8646.827063458695, 8938.553072346356, 590.4302348233974, 599.5203836930439, 601.3775742106985, 609.3601718195877, 626.6396821827467, 3744.0, 4064.349704047397, 4283.785810709452, 4285.6142947134285, 4292.785360731951, 105234.47382763037, 105286.94713052924, 106235.0, 108761.45619271853, 110036.4498177504, 91224.54387728019, 94251.0, 94648.52675736578, 96832.51583742177, 97828.0, 75159.0, 79087.60456197683, 83028.0, 84060.0, 85822.57088714516, 7017.8582094010335, 8199.754007379788, 8245.381596380415, 8463.49219046859, 8476.194761497647, 47990.0, 51046.97906041784, 53362.73318633382, 54679.0, 55389.723051384484, 73868.52262954818, 74916.62541687257, 75249.49501010058, 77462.6126869362, 79036.20963790288, 10413.42726150069, 10441.68674939753, 10896.618618348637, 11824.054075674097, 12251.877481225074, 13171.341432928124, 13269.66825829362, 14098.224597647228, 14112.223827689575, 14871.702565948404, 12979.286139262054, 13327.800082998956, 13571.86428135706, 13845.961552883373, 13860.792088118485, 12590.748185036431, 13080.411381487665, 13320.0, 13365.39855706515, 13815.102018368774, 12712.74574508523, 12922.612321630366, 13118.934405328295, 13702.0, 13738.313084345942, 10626.651378433497, 11024.173187010845, 11050.778984420427, 11105.94447027792, 11554.480048397674, 11154.274972126786, 11332.716682083013, 11390.400247812848, 11397.544098236309, 11511.481983310603], "value": 7117.542475573863, "unit": "score"}], "name": "dromaeo_css", "value": 6990.492498440285, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 114.0, 114.0, 115.0, 115.0, 116.0, 160.0, 164.0, 115.0, 113.0], "value": 124.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 109.0, 108.0, 147.0, 111.0, 111.0, 110.0, 110.0, 142.0, 109.0], "value": 120.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 185.0, 183.0, 176.0, 181.0, 183.0, 181.0, 184.0, 183.0, 184.0], "value": 182.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 75.0, 83.0, 112.0, 76.0, 59.0, 58.0, 59.0, 82.0, 59.0], "value": 74.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 70.0, 95.0, 70.0, 68.0, 70.0, 89.0, 68.0, 68.0, 91.0], "value": 77.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 88.0, 133.0, 86.0, 103.0, 129.0, 132.0, 85.0, 88.0, 84.0], "value": 101.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 96.0, 97.0, 108.0, 142.0, 97.0, 96.0, 95.0, 97.0], "value": 101.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 88.0, 80.0, 85.0, 81.0, 84.0, 86.0, 84.0, 81.0], "value": 83.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 55.0, 52.0, 56.0, 59.0, 58.0, 58.0, 57.0, 56.0], "value": 56.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 71.0, 71.0, 92.0, 105.0, 97.0, 85.0, 70.0, 70.0, 70.0], "value": 79.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 89.0, 98.0, 79.0, 76.0, 96.0, 98.0, 103.0, 100.0], "value": 90.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 139.0, 140.0, 139.0, 149.0, 143.0, 133.0, 152.0, 218.0, 141.0], "value": 149.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 161.0, 168.0, 164.0, 162.0, 226.0, 226.0, 175.0, 164.0, 161.0], "value": 177.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 80.0, 85.0, 84.0, 84.0, 66.0, 68.0, 68.0, 65.0], "value": 73.6, "unit": "score"}], "name": "kraken", "value": 1491.6999999999998, "alertThreshold": 2.0}]} 16:05:48 INFO - Return code: 0 16:05:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:05:48 INFO - # TBPL SUCCESS # 16:05:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:05:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:05:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:05:48 INFO - 2017-01-09 16:05:48.413 screenresolution[1660:9933] starting screenresolution argv=screenresolution get 16:05:48 INFO - 2017-01-09 16:05:48.416 screenresolution[1660:9933] Display 0: 1600x1200x32@60 16:05:48 INFO - 2017-01-09 16:05:48.421 screenresolution[1661:9936] starting screenresolution argv=screenresolution list 16:05:48 INFO - Available Modes on Display 0 16:05:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:05:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:05:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:05:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:05:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:05:48 INFO - Intel Iris: 16:05:48 INFO - Chipset Model: Intel Iris 16:05:48 INFO - Type: GPU 16:05:48 INFO - Bus: Built-In 16:05:48 INFO - VRAM (Dynamic, Max): 1536 MB 16:05:48 INFO - Vendor: Intel (0x8086) 16:05:48 INFO - Device ID: 0x0a2e 16:05:48 INFO - Revision ID: 0x0009 16:05:48 INFO - Displays: 16:05:48 INFO - Display: 16:05:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:05:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:05:48 INFO - Display Serial Number: 1600x1200 60 16:05:48 INFO - Main Display: Yes 16:05:48 INFO - Mirror: Off 16:05:48 INFO - Online: Yes 16:05:48 INFO - Rotation: Supported 16:05:48 INFO - Return code: 0 16:05:48 INFO - Running post-action listener: _resource_record_post_action 16:05:48 INFO - [mozharness: 2017-01-10 00:05:48.663722Z] Finished run-tests step (success) 16:05:48 INFO - Running post-run listener: _resource_record_post_run 16:05:48 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 37753856; Write bytes: 658498560; Read time: 240; Write time: 1867 16:05:48 INFO - TinderboxPrint: CPU usage
53.7% 16:05:48 INFO - TinderboxPrint: I/O read bytes / time
37,753,856 / 240 16:05:48 INFO - TinderboxPrint: I/O write bytes / time
658,498,560 / 1,867 16:05:48 INFO - TinderboxPrint: CPU idle
2,245.9 (46.3%) 16:05:48 INFO - TinderboxPrint: CPU system
973.5 (20.1%) 16:05:48 INFO - TinderboxPrint: CPU user
1,630.2 (33.6%) 16:05:48 INFO - TinderboxPrint: Swap in / out
387,010,560 / 0 16:05:48 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 348518912; Write bytes: 346126336; Read time: 26781; Write time: 689 16:05:48 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 34403328; Write bytes: 305081344; Read time: 213; Write time: 1148 16:05:49 INFO - Running post-run listener: _upload_blobber_files 16:05:49 INFO - Blob upload gear active. 16:05:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:05:49 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 16:05:49 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:05:49 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:05:49 INFO - (blobuploader) - INFO - Open directory for files ... 16:05:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:05:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:05:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:05:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:05:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:05:50 INFO - (blobuploader) - INFO - Done attempting. 16:05:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:05:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:05:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:05:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:05:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:05:52 INFO - (blobuploader) - INFO - Done attempting. 16:05:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:05:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:05:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:05:54 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:05:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:05:54 INFO - (blobuploader) - INFO - Done attempting. 16:05:54 INFO - (blobuploader) - INFO - Iteration through files over. 16:05:54 INFO - Return code: 0 16:05:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:05:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:05:54 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/de14502f62f5d3d6fec8a7b3634ae689bc4c0d19a9e8eee9acda9044cd87d75fb45570b136a397a0c9bc4bc50acf3a11385591f5f61c5e713e41a4309df8cffb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/893746b7310aec6b238d5bc8218ca1b4285ba4d4dccc92dee978a3abd368685d99db838de0f684ba93a54df5ae24aac5e8486b740bc8f886ebb0a8f67fe2deb8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bba3c1b90d2a4147d4f0b35141cb8c15b850a33b73377c87999332ce7b4648239fe8eecc713525edd510b43c2cde916a56c648e7b5e01d11170f7be0c6099e31"} 16:05:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:05:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:05:54 INFO - Contents: 16:05:54 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/de14502f62f5d3d6fec8a7b3634ae689bc4c0d19a9e8eee9acda9044cd87d75fb45570b136a397a0c9bc4bc50acf3a11385591f5f61c5e713e41a4309df8cffb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/893746b7310aec6b238d5bc8218ca1b4285ba4d4dccc92dee978a3abd368685d99db838de0f684ba93a54df5ae24aac5e8486b740bc8f886ebb0a8f67fe2deb8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bba3c1b90d2a4147d4f0b35141cb8c15b850a33b73377c87999332ce7b4648239fe8eecc713525edd510b43c2cde916a56c648e7b5e01d11170f7be0c6099e31"} 16:05:54 INFO - Running post-run listener: copy_logs_to_upload_dir 16:05:54 INFO - Copying logs to upload dir... 16:05:54 INFO - mkdir: /builds/slave/test/build/upload/logs 16:05:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.836714 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2017-01-09 16:05:54.655464) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-09 16:05:54.659793) ========= 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.nIwEdLWObo/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.9AHDdB2KSf/Listeners TMPDIR=/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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/Mozilla-Aurora/sha512/de14502f62f5d3d6fec8a7b3634ae689bc4c0d19a9e8eee9acda9044cd87d75fb45570b136a397a0c9bc4bc50acf3a11385591f5f61c5e713e41a4309df8cffb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/893746b7310aec6b238d5bc8218ca1b4285ba4d4dccc92dee978a3abd368685d99db838de0f684ba93a54df5ae24aac5e8486b740bc8f886ebb0a8f67fe2deb8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bba3c1b90d2a4147d4f0b35141cb8c15b850a33b73377c87999332ce7b4648239fe8eecc713525edd510b43c2cde916a56c648e7b5e01d11170f7be0c6099e31"} build_url:https://queue.taskcluster.net/v1/task/WYpkRzh6T2ep9ZnE_tBOhg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011013 build_url: 'https://queue.taskcluster.net/v1/task/WYpkRzh6T2ep9ZnE_tBOhg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/de14502f62f5d3d6fec8a7b3634ae689bc4c0d19a9e8eee9acda9044cd87d75fb45570b136a397a0c9bc4bc50acf3a11385591f5f61c5e713e41a4309df8cffb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/893746b7310aec6b238d5bc8218ca1b4285ba4d4dccc92dee978a3abd368685d99db838de0f684ba93a54df5ae24aac5e8486b740bc8f886ebb0a8f67fe2deb8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bba3c1b90d2a4147d4f0b35141cb8c15b850a33b73377c87999332ce7b4648239fe8eecc713525edd510b43c2cde916a56c648e7b5e01d11170f7be0c6099e31"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-09 16:05:54.695893) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 16:05:54.696175) ========= 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.nIwEdLWObo/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.9AHDdB2KSf/Listeners TMPDIR=/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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.005448 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 16:05:54.743886) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-09 16:05:54.744157) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-09 16:05:54.744429) ========= ========= Total master_lag: 0.39 =========