builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0391 starttime: 1482341977.77 results: success (0) buildid: 20161221063559 builduid: 71e38918ea854aa28b24696d226279a6 revision: 38c65075d65e662c265bb5d215f1507798497a98 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-21 09:39:37.774130) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-21 09:39:37.774560) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-21 09:39:37.774848) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-21 09:39:37.802792) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 09:39:37.803461) ========= 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.w2Pr9vPCgP/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.5uUsINHt96/Listeners TMPDIR=/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-21 09:39:37-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.3M=0.001s 2016-12-21 09:39:37 (18.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.122799 ========= master_lag: 0.82 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 09:39:38.742846) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 09:39:38.743151) ========= 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.w2Pr9vPCgP/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.5uUsINHt96/Listeners TMPDIR=/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/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.024097 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 09:39:38.791203) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-21 09:39:38.791537) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 38c65075d65e662c265bb5d215f1507798497a98 --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 38c65075d65e662c265bb5d215f1507798497a98 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.w2Pr9vPCgP/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.5uUsINHt96/Listeners TMPDIR=/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-21 09:39:38,889 truncating revision to first 12 chars 2016-12-21 09:39:38,890 Setting DEBUG logging. 2016-12-21 09:39:38,890 attempt 1/10 2016-12-21 09:39:38,890 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/38c65075d65e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-21 09:39:39,900 unpacking tar archive at: mozilla-aurora-38c65075d65e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.264098 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-21 09:39:40.081962) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-21 09:39:40.082280) ========= script_repo_revision: 38c65075d65e662c265bb5d215f1507798497a98 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-21 09:39:40.082658) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-21 09:39:40.082935) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-21 09:39:40.102116) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-12-21 09:39:40.102384) ========= /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.w2Pr9vPCgP/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.5uUsINHt96/Listeners TMPDIR=/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/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 09:39:40 INFO - MultiFileLogger online at 20161221 09:39:40 in /builds/slave/test 09:39:40 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 09:39:40 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:39:40 INFO - {'append_to_log': False, 09:39:40 INFO - 'base_work_dir': '/builds/slave/test', 09:39:40 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:39:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:39:40 INFO - 'branch': 'Mozilla-Aurora', 09:39:40 INFO - 'buildbot_json_path': 'buildprops.json', 09:39:40 INFO - 'config_files': ('talos/mac_config.py',), 09:39:40 INFO - 'default_actions': ('clobber', 09:39:40 INFO - 'read-buildbot-config', 09:39:40 INFO - 'download-and-extract', 09:39:40 INFO - 'populate-webroot', 09:39:40 INFO - 'create-virtualenv', 09:39:40 INFO - 'install', 09:39:40 INFO - 'run-tests'), 09:39:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:39:40 INFO - 'download_minidump_stackwalk': True, 09:39:40 INFO - 'download_symbols': 'ondemand', 09:39:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:39:40 INFO - 'tooltool.py': '/tools/tooltool.py', 09:39:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:39:40 INFO - '/tools/misc-python/virtualenv.py')}, 09:39:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:39:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:39:40 INFO - 'installer_path': 'installer.exe', 09:39:40 INFO - 'log_level': 'info', 09:39:40 INFO - 'log_name': 'talos', 09:39:40 INFO - 'log_to_console': True, 09:39:40 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:39:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:39:40 INFO - 'opt_config_files': (), 09:39:40 INFO - 'pip_index': False, 09:39:40 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:39:40 INFO - 'cmd': ('bash', 09:39:40 INFO - '-c', 09:39:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:39:40 INFO - 'enabled': True, 09:39:40 INFO - 'halt_on_failure': False, 09:39:40 INFO - 'name': 'check_screen_resolution'},), 09:39:40 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:39:40 INFO - 'cmd': ('bash', 09:39:40 INFO - '-c', 09:39:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:39:40 INFO - 'enabled': True, 09:39:40 INFO - 'halt_on_failure': False, 09:39:40 INFO - 'name': 'check_screen_resolution'},), 09:39:40 INFO - 'run_cmd_checks_enabled': True, 09:39:40 INFO - 'sps_profile': False, 09:39:40 INFO - 'sps_profile_interval': 0, 09:39:40 INFO - 'suite': 'dromaeojs', 09:39:40 INFO - 'system_bits': '32', 09:39:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:39:40 INFO - 'title': 't-yosemite-r7-0391', 09:39:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:39:40 INFO - 'use_talos_json': True, 09:39:40 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:39:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:39:40 INFO - 'work_dir': 'build'} 09:39:40 INFO - [mozharness: 2016-12-21 17:39:40.301650Z] Running clobber step. 09:39:40 INFO - Running pre-action listener: _resource_record_pre_action 09:39:40 INFO - Running main action method: clobber 09:39:40 INFO - rmtree: /builds/slave/test/build 09:39:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:39:42 INFO - Running post-action listener: _resource_record_post_action 09:39:42 INFO - [mozharness: 2016-12-21 17:39:42.451333Z] Finished clobber step (success) 09:39:42 INFO - [mozharness: 2016-12-21 17:39:42.451475Z] Running read-buildbot-config step. 09:39:42 INFO - Running pre-action listener: _resource_record_pre_action 09:39:42 INFO - Running main action method: read_buildbot_config 09:39:42 INFO - Using buildbot properties: 09:39:42 INFO - { 09:39:42 INFO - "project": "", 09:39:42 INFO - "product": "firefox", 09:39:42 INFO - "script_repo_revision": "production", 09:39:42 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 09:39:42 INFO - "repository": "", 09:39:42 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 09:39:42 INFO - "buildid": "20161221063559", 09:39:42 INFO - "pgo_build": "False", 09:39:42 INFO - "basedir": "/builds/slave/test", 09:39:42 INFO - "buildnumber": 19, 09:39:42 INFO - "slavename": "t-yosemite-r7-0391", 09:39:42 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 09:39:42 INFO - "platform": "yosemite_r7", 09:39:42 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 09:39:42 INFO - "branch": "mozilla-aurora", 09:39:42 INFO - "repo_path": "releases/mozilla-aurora", 09:39:42 INFO - "revision": "38c65075d65e662c265bb5d215f1507798497a98", 09:39:42 INFO - "stage_platform": "macosx64", 09:39:42 INFO - "builduid": "71e38918ea854aa28b24696d226279a6", 09:39:42 INFO - "slavebuilddir": "test" 09:39:42 INFO - } 09:39:42 INFO - Found installer url https://queue.taskcluster.net/v1/task/cha5FFBzTy2P3AtTOnYT8g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 09:39:42 INFO - Running post-action listener: _resource_record_post_action 09:39:42 INFO - [mozharness: 2016-12-21 17:39:42.454723Z] Finished read-buildbot-config step (success) 09:39:42 INFO - [mozharness: 2016-12-21 17:39:42.454859Z] Running download-and-extract step. 09:39:42 INFO - Running pre-action listener: _resource_record_pre_action 09:39:42 INFO - Running main action method: download_and_extract 09:39:42 INFO - mkdir: /builds/slave/test/build/tests 09:39: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')]} 09:39:42 INFO - trying https://queue.taskcluster.net/v1/task/cha5FFBzTy2P3AtTOnYT8g/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 09:39:42 INFO - Downloading https://queue.taskcluster.net/v1/task/cha5FFBzTy2P3AtTOnYT8g/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 09:39:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cha5FFBzTy2P3AtTOnYT8g/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 09:39:43 INFO - Downloaded 1199 bytes. 09:39:43 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 09:39:43 INFO - Using the following test package requirements: 09:39:43 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 09:39:43 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:39:43 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 09:39:43 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:39:43 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 09:39:43 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:39:43 INFO - u'jsshell-mac.zip'], 09:39:43 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:39:43 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 09:39:43 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 09:39:43 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:39:43 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 09:39:43 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:39:43 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 09:39:43 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:39:43 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 09:39:43 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:39:43 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 09:39:43 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 09:39:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cha5FFBzTy2P3AtTOnYT8g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 09:39:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cha5FFBzTy2P3AtTOnYT8g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:39:43 INFO - Fetch https://queue.taskcluster.net/v1/task/cha5FFBzTy2P3AtTOnYT8g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 09:39:44 INFO - Http code: 200 09:39:44 INFO - via: 1.1 37af14ee951b12568cf1cc160d08abe6.cloudfront.net (CloudFront) 09:39:44 INFO - x-amz-cf-id: 9S4o7HTuak8Kp8npqk_22rXF4wPzy24z7yLUeQjlKOI_NNlVHcahtg== 09:39:44 INFO - x-amz-version-id: 5q9cS3jGZWsdIR6Z4HJ9d_xXhvMHon_Y 09:39:45 INFO - Expected file size: 16267604 09:39:45 INFO - Obtained file size: 16267604 09:39:52 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 09:39:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cha5FFBzTy2P3AtTOnYT8g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 09:39:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cha5FFBzTy2P3AtTOnYT8g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:39:52 INFO - Fetch https://queue.taskcluster.net/v1/task/cha5FFBzTy2P3AtTOnYT8g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 09:39:52 INFO - Http code: 200 09:39:52 INFO - via: 1.1 5ed093c37d643652e278a198e0556a40.cloudfront.net (CloudFront) 09:39:52 INFO - x-amz-cf-id: Rs7rs-WuTbEVZTmop1aDQ-zVsJTiI-oKNK9SBKz9VROJgGGaBSbiLg== 09:39:52 INFO - x-amz-version-id: 5q9cS3jGZWsdIR6Z4HJ9d_xXhvMHon_Y 09:39:53 INFO - Expected file size: 16267604 09:39:53 INFO - Obtained file size: 16267604 09:39:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cha5FFBzTy2P3AtTOnYT8g/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 09:39:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cha5FFBzTy2P3AtTOnYT8g/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 09:39:59 INFO - Fetch https://queue.taskcluster.net/v1/task/cha5FFBzTy2P3AtTOnYT8g/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 09:40:00 INFO - Http code: 200 09:40:00 INFO - via: 1.1 0cf1d7257c633ae75623c5e75bf3805e.cloudfront.net (CloudFront) 09:40:00 INFO - x-amz-cf-id: m9qXfZf7A1FXCpr7iM0lWrvj6O5f1bfnNrE72aaPhQcpG4j2WrucwA== 09:40:00 INFO - x-amz-version-id: 5bE88hIUsvvgwuvBtR4kVnmDE99xXR.8 09:40:00 INFO - Expected file size: 14409038 09:40:00 INFO - Obtained file size: 14409038 09:40:01 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')]} 09:40:01 INFO - trying https://queue.taskcluster.net/v1/task/cha5FFBzTy2P3AtTOnYT8g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:40:01 INFO - Downloading https://queue.taskcluster.net/v1/task/cha5FFBzTy2P3AtTOnYT8g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 09:40:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cha5FFBzTy2P3AtTOnYT8g/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 09:40:04 INFO - Downloaded 109777637 bytes. 09:40:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cha5FFBzTy2P3AtTOnYT8g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:40:04 INFO - mkdir: /builds/slave/test/properties 09:40:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:40:04 INFO - Writing to file /builds/slave/test/properties/build_url 09:40:04 INFO - Contents: 09:40:04 INFO - build_url:https://queue.taskcluster.net/v1/task/cha5FFBzTy2P3AtTOnYT8g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:40:05 INFO - Running post-action listener: _resource_record_post_action 09:40:05 INFO - Running post-action listener: set_extra_try_arguments 09:40:05 INFO - [mozharness: 2016-12-21 17:40:05.107977Z] Finished download-and-extract step (success) 09:40:05 INFO - [mozharness: 2016-12-21 17:40:05.108097Z] Running populate-webroot step. 09:40:05 INFO - Running pre-action listener: _resource_record_pre_action 09:40:05 INFO - Running main action method: populate_webroot 09:40:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:40:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:40:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:40:05 INFO - '--tppagecycles', 09:40:05 INFO - '7'], 09:40:05 INFO - 'tests': ['tsvgm']}}, 09:40:05 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:40:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:40:05 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:40:05 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:40:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:40:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:40:05 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:40:05 INFO - 'talos_options': ['--disable-e10s'], 09:40:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:40:05 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:40:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:40:05 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:40:05 INFO - 'talos_options': ['--disable-e10s'], 09:40:05 INFO - 'tests': ['damp', 'tps']}, 09:40:05 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:40:05 INFO - 'tests': ['damp', 'tps']}, 09:40:05 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:40:05 INFO - 'tests': ['dromaeo_dom']}, 09:40:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:40:05 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:40:05 INFO - 'tests': ['basic_compositor_video']}, 09:40:05 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:40:05 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:40:05 INFO - 'tests': ['a11yr', 09:40:05 INFO - 'ts_paint', 09:40:05 INFO - 'tpaint', 09:40:05 INFO - 'sessionrestore', 09:40:05 INFO - 'sessionrestore_no_auto_restore', 09:40:05 INFO - 'tabpaint']}, 09:40:05 INFO - 'other-e10s': {'tests': ['a11yr', 09:40:05 INFO - 'ts_paint', 09:40:05 INFO - 'tpaint', 09:40:05 INFO - 'sessionrestore', 09:40:05 INFO - 'sessionrestore_no_auto_restore', 09:40:05 INFO - 'tabpaint']}, 09:40:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:40:05 INFO - 'ts_paint', 09:40:05 INFO - 'tpaint', 09:40:05 INFO - 'sessionrestore', 09:40:05 INFO - 'sessionrestore_no_auto_restore', 09:40:05 INFO - 'tabpaint']}, 09:40:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:40:05 INFO - 'ts_paint', 09:40:05 INFO - 'tpaint', 09:40:05 INFO - 'sessionrestore', 09:40:05 INFO - 'sessionrestore_no_auto_restore', 09:40:05 INFO - 'tabpaint']}, 09:40:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:40:05 INFO - 'tests': ['a11yr', 09:40:05 INFO - 'ts_paint', 09:40:05 INFO - 'tpaint', 09:40:05 INFO - 'sessionrestore', 09:40:05 INFO - 'sessionrestore_no_auto_restore', 09:40:05 INFO - 'tabpaint']}, 09:40:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:40:05 INFO - 'tests': ['a11yr', 09:40:05 INFO - 'ts_paint', 09:40:05 INFO - 'tpaint', 09:40:05 INFO - 'sessionrestore', 09:40:05 INFO - 'sessionrestore_no_auto_restore', 09:40:05 INFO - 'tabpaint']}, 09:40:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:40:05 INFO - 'tests': ['tsvgx', 09:40:05 INFO - 'tsvgr_opacity', 09:40:05 INFO - 'tart', 09:40:05 INFO - 'tscrollx', 09:40:05 INFO - 'cart']}, 09:40:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:40:05 INFO - 'tsvgr_opacity', 09:40:05 INFO - 'tart', 09:40:05 INFO - 'tscrollx', 09:40:05 INFO - 'cart']}, 09:40:05 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:40:05 INFO - 'talos_options': ['--disable-e10s'], 09:40:05 INFO - 'tests': ['tp5o']}, 09:40:05 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:40:05 INFO - 'tests': ['tp5o']}, 09:40:05 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:40:05 INFO - 'talos_options': ['--disable-e10s', 09:40:05 INFO - '--xperf_path', 09:40:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:40:05 INFO - 'tests': ['tp5n']}, 09:40:05 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:40:05 INFO - 'talos_options': ['--xperf_path', 09:40:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:40:05 INFO - 'tests': ['tp5n']}}, 09:40:05 INFO - 'talos.zip': {'path': '', 09:40:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:40:05 INFO - Running post-action listener: _resource_record_post_action 09:40:05 INFO - [mozharness: 2016-12-21 17:40:05.127372Z] Finished populate-webroot step (success) 09:40:05 INFO - [mozharness: 2016-12-21 17:40:05.127512Z] Running create-virtualenv step. 09:40:05 INFO - Running pre-action listener: _resource_record_pre_action 09:40:05 INFO - Running main action method: create_virtualenv 09:40:05 INFO - Creating virtualenv /builds/slave/test/build/venv 09:40:05 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 09:40:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:40:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:40:05 INFO - Using real prefix '/tools/python27' 09:40:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:40:05 INFO - Installing distribute.............................................................................................................................................................................................done. 09:40:08 INFO - Installing pip.................done. 09:40:08 INFO - Return code: 0 09:40:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:40:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:40:09 INFO - Reading from file tmpfile_stdout 09:40:09 INFO - Output received: 09:40:09 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) 09:40:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:40:09 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')]} 09:40:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:40:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:09 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')]} 09:40:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:40:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:09 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 0x10fdae490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd24570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feab95c1000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fbc2d30>, '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 0x10fa28db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fbbab20>, '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.w2Pr9vPCgP/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.5uUsINHt96/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/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 09:40:09 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 09:40:09 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 09:40:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w2Pr9vPCgP/Render', 09:40:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:40:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:40:09 INFO - 'HOME': '/Users/cltbld', 09:40:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:40:09 INFO - 'LOGNAME': 'cltbld', 09:40:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:40:09 INFO - 'MOZ_NO_REMOTE': '1', 09:40:09 INFO - 'NO_EM_RESTART': '1', 09:40:09 INFO - 'PAGER': '/bin/cat', 09:40:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:40:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:40:09 INFO - 'PWD': '/builds/slave/test', 09:40:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:40:09 INFO - 'SHELL': '/bin/bash', 09:40:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5uUsINHt96/Listeners', 09:40:09 INFO - 'TMPDIR': '/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/', 09:40:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:40:09 INFO - 'USER': 'cltbld', 09:40:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:40:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:40:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:40:09 INFO - 'XPC_FLAGS': '0x0', 09:40:09 INFO - 'XPC_SERVICE_NAME': '0', 09:40:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:40:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:40:09 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 09:40:09 INFO - Cleaning up... 09:40:09 INFO - Return code: 0 09:40:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:40:09 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')]} 09:40:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:40:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:09 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')]} 09:40:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:40:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:09 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 0x10fdae490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd24570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feab95c1000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fbc2d30>, '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 0x10fa28db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fbbab20>, '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.w2Pr9vPCgP/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.5uUsINHt96/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/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 09:40:09 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 09:40:09 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 09:40:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w2Pr9vPCgP/Render', 09:40:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:40:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:40:09 INFO - 'HOME': '/Users/cltbld', 09:40:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:40:09 INFO - 'LOGNAME': 'cltbld', 09:40:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:40:09 INFO - 'MOZ_NO_REMOTE': '1', 09:40:09 INFO - 'NO_EM_RESTART': '1', 09:40:09 INFO - 'PAGER': '/bin/cat', 09:40:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:40:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:40:09 INFO - 'PWD': '/builds/slave/test', 09:40:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:40:09 INFO - 'SHELL': '/bin/bash', 09:40:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5uUsINHt96/Listeners', 09:40:09 INFO - 'TMPDIR': '/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/', 09:40:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:40:09 INFO - 'USER': 'cltbld', 09:40:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:40:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:40:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:40:09 INFO - 'XPC_FLAGS': '0x0', 09:40:09 INFO - 'XPC_SERVICE_NAME': '0', 09:40:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:40:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:40:09 INFO - Downloading/unpacking psutil>=3.1.1 09:40:09 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 09:40:09 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 09:40:09 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 09:40:09 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 09:40:09 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 09:40:09 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 09:40:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:40:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:40:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:40:13 INFO - Installing collected packages: psutil 09:40:13 INFO - Running setup.py install for psutil 09:40:13 INFO - building 'psutil._psutil_osx' extension 09:40:13 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 09:40:13 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 09:40:13 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 09:40:13 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 09:40:14 INFO - building 'psutil._psutil_posix' extension 09:40:14 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 09:40:14 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:40:14 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:40:14 INFO - ^ 09:40:14 INFO - 1 warning generated. 09:40:14 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 09:40:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:40:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:40:14 INFO - Successfully installed psutil 09:40:14 INFO - Cleaning up... 09:40:14 INFO - Return code: 0 09:40:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:40:14 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')]} 09:40:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:40:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:14 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')]} 09:40:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:40:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:14 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 0x10fdae490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd24570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feab95c1000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fbc2d30>, '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 0x10fa28db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fbbab20>, '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.w2Pr9vPCgP/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.5uUsINHt96/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/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 09:40:14 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 09:40:14 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 09:40:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w2Pr9vPCgP/Render', 09:40:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:40:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:40:14 INFO - 'HOME': '/Users/cltbld', 09:40:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:40:14 INFO - 'LOGNAME': 'cltbld', 09:40:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:40:14 INFO - 'MOZ_NO_REMOTE': '1', 09:40:14 INFO - 'NO_EM_RESTART': '1', 09:40:14 INFO - 'PAGER': '/bin/cat', 09:40:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:40:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:40:14 INFO - 'PWD': '/builds/slave/test', 09:40:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:40:14 INFO - 'SHELL': '/bin/bash', 09:40:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5uUsINHt96/Listeners', 09:40:14 INFO - 'TMPDIR': '/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/', 09:40:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:40:14 INFO - 'USER': 'cltbld', 09:40:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:40:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:40:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:40:14 INFO - 'XPC_FLAGS': '0x0', 09:40:14 INFO - 'XPC_SERVICE_NAME': '0', 09:40:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:40:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:40:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:40:14 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 09:40:14 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 09:40:14 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 09:40:14 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 09:40:14 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 09:40:14 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 09:40:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:40:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:40:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:40:17 INFO - Installing collected packages: mozsystemmonitor 09:40:17 INFO - Running setup.py install for mozsystemmonitor 09:40:17 INFO - Successfully installed mozsystemmonitor 09:40:17 INFO - Cleaning up... 09:40:17 INFO - Return code: 0 09:40:17 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:40:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:40:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:40:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:40:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:40:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:17 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 0x10fdae490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd24570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feab95c1000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fbc2d30>, '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 0x10fa28db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fbbab20>, '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.w2Pr9vPCgP/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.5uUsINHt96/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/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 09:40:17 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 09:40:17 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 09:40:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w2Pr9vPCgP/Render', 09:40:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:40:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:40:17 INFO - 'HOME': '/Users/cltbld', 09:40:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:40:17 INFO - 'LOGNAME': 'cltbld', 09:40:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:40:17 INFO - 'MOZ_NO_REMOTE': '1', 09:40:17 INFO - 'NO_EM_RESTART': '1', 09:40:17 INFO - 'PAGER': '/bin/cat', 09:40:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:40:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:40:17 INFO - 'PWD': '/builds/slave/test', 09:40:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:40:17 INFO - 'SHELL': '/bin/bash', 09:40:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5uUsINHt96/Listeners', 09:40:17 INFO - 'TMPDIR': '/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/', 09:40:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:40:17 INFO - 'USER': 'cltbld', 09:40:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:40:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:40:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:40:17 INFO - 'XPC_FLAGS': '0x0', 09:40:17 INFO - 'XPC_SERVICE_NAME': '0', 09:40:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:40:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:40:17 INFO - Downloading/unpacking jsonschema==2.5.1 09:40:17 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 09:40:17 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 09:40:17 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 09:40:17 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 09:40:17 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 09:40:17 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 09:40:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:40:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:40:20 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 09:40:20 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 09:40:20 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 09:40:20 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 09:40:20 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 09:40:20 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 09:40:20 INFO - Downloading functools32-3.2.3-2.tar.gz 09:40:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:40:20 INFO - warning: no files found matching '*.txt' 09:40:20 INFO - no previously-included directories found matching 'build' 09:40:20 INFO - no previously-included directories found matching 'dist' 09:40:20 INFO - no previously-included directories found matching '.git*' 09:40:20 INFO - Installing collected packages: jsonschema, functools32 09:40:21 INFO - Running setup.py install for functools32 09:40:21 INFO - warning: no files found matching '*.txt' 09:40:21 INFO - no previously-included directories found matching 'build' 09:40:21 INFO - no previously-included directories found matching 'dist' 09:40:21 INFO - no previously-included directories found matching '.git*' 09:40:21 INFO - Successfully installed jsonschema functools32 09:40:21 INFO - Cleaning up... 09:40:21 INFO - Return code: 0 09:40:21 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:40:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:40:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:40:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:40:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:40:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10fdae490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd24570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feab95c1000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fbc2d30>, '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 0x10fa28db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fbbab20>, '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.w2Pr9vPCgP/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.5uUsINHt96/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/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 09:40:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:40:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:40:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w2Pr9vPCgP/Render', 09:40:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:40:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:40:21 INFO - 'HOME': '/Users/cltbld', 09:40:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:40:21 INFO - 'LOGNAME': 'cltbld', 09:40:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:40:21 INFO - 'MOZ_NO_REMOTE': '1', 09:40:21 INFO - 'NO_EM_RESTART': '1', 09:40:21 INFO - 'PAGER': '/bin/cat', 09:40:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:40:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:40:21 INFO - 'PWD': '/builds/slave/test', 09:40:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:40:21 INFO - 'SHELL': '/bin/bash', 09:40:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5uUsINHt96/Listeners', 09:40:21 INFO - 'TMPDIR': '/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/', 09:40:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:40:21 INFO - 'USER': 'cltbld', 09:40:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:40:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:40:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:40:21 INFO - 'XPC_FLAGS': '0x0', 09:40:21 INFO - 'XPC_SERVICE_NAME': '0', 09:40:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:40:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:40:21 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:40:21 INFO - Cleaning up... 09:40:21 INFO - Return code: 0 09:40:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:40:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:40:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:40:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:40:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:40:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10fdae490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd24570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feab95c1000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fbc2d30>, '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 0x10fa28db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fbbab20>, '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.w2Pr9vPCgP/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.5uUsINHt96/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/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 09:40:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:40:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:40:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w2Pr9vPCgP/Render', 09:40:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:40:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:40:21 INFO - 'HOME': '/Users/cltbld', 09:40:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:40:21 INFO - 'LOGNAME': 'cltbld', 09:40:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:40:21 INFO - 'MOZ_NO_REMOTE': '1', 09:40:21 INFO - 'NO_EM_RESTART': '1', 09:40:21 INFO - 'PAGER': '/bin/cat', 09:40:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:40:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:40:21 INFO - 'PWD': '/builds/slave/test', 09:40:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:40:21 INFO - 'SHELL': '/bin/bash', 09:40:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5uUsINHt96/Listeners', 09:40:21 INFO - 'TMPDIR': '/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/', 09:40:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:40:21 INFO - 'USER': 'cltbld', 09:40:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:40:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:40:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:40:21 INFO - 'XPC_FLAGS': '0x0', 09:40:21 INFO - 'XPC_SERVICE_NAME': '0', 09:40:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:40:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:40:21 INFO - Downloading/unpacking blobuploader==1.2.4 09:40:21 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 09:40:21 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 09:40:21 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 09:40:21 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 09:40:21 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 09:40:21 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 09:40:24 INFO - Downloading blobuploader-1.2.4.tar.gz 09:40:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:40:24 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:40:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:40:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:40:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:40:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:40:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:40:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:40:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:40:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:40:25 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 09:40:25 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 09:40:25 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 09:40:25 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 09:40:25 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 09:40:25 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 09:40:25 INFO - Downloading docopt-0.6.1.tar.gz 09:40:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:40:25 INFO - Installing collected packages: blobuploader, requests, docopt 09:40:25 INFO - Running setup.py install for blobuploader 09:40:25 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:40:25 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:40:25 INFO - Running setup.py install for requests 09:40:26 INFO - Running setup.py install for docopt 09:40:26 INFO - Successfully installed blobuploader requests docopt 09:40:26 INFO - Cleaning up... 09:40:26 INFO - Return code: 0 09:40:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:40:26 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')]} 09:40:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:40:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:26 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')]} 09:40:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:40:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:26 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 0x10fdae490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd24570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feab95c1000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fbc2d30>, '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 0x10fa28db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fbbab20>, '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.w2Pr9vPCgP/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.5uUsINHt96/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/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 09:40:26 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 09:40:26 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 09:40:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w2Pr9vPCgP/Render', 09:40:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:40:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:40:26 INFO - 'HOME': '/Users/cltbld', 09:40:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:40:26 INFO - 'LOGNAME': 'cltbld', 09:40:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:40:26 INFO - 'MOZ_NO_REMOTE': '1', 09:40:26 INFO - 'NO_EM_RESTART': '1', 09:40:26 INFO - 'PAGER': '/bin/cat', 09:40:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:40:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:40:26 INFO - 'PWD': '/builds/slave/test', 09:40:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:40:26 INFO - 'SHELL': '/bin/bash', 09:40:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5uUsINHt96/Listeners', 09:40:26 INFO - 'TMPDIR': '/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/', 09:40:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:40:26 INFO - 'USER': 'cltbld', 09:40:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:40:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:40:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:40:26 INFO - 'XPC_FLAGS': '0x0', 09:40:26 INFO - 'XPC_SERVICE_NAME': '0', 09:40:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:40:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:40:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:40:26 INFO - Running setup.py (path:/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/pip-miwc6L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:40:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:40:27 INFO - Running setup.py (path:/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/pip-FqOFjU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:40:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:40:27 INFO - Running setup.py (path:/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/pip-oOoefw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:40:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:40:27 INFO - Running setup.py (path:/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/pip-_5vEYe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:40:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:40:27 INFO - Running setup.py (path:/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/pip-2lHnuM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:40:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:40:27 INFO - Running setup.py (path:/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/pip-EjLxe4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:40:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:40:27 INFO - Running setup.py (path:/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/pip-KLmhrI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:40:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:40:27 INFO - Running setup.py (path:/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/pip-xCV1ee-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:40:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:40:27 INFO - Running setup.py (path:/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/pip-tyCi18-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:40:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:40:27 INFO - Running setup.py (path:/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/pip-LoWToe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:40:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:40:27 INFO - Running setup.py (path:/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/pip-32WBPp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:40:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:40:28 INFO - Running setup.py (path:/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/pip-iMof1a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:40:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:40:28 INFO - Running setup.py (path:/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/pip-lDemcE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:40:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:40:28 INFO - Running setup.py (path:/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/pip-yg8pQ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:40:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:40:28 INFO - Running setup.py (path:/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/pip-2XmhhA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:40:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:40:28 INFO - Running setup.py (path:/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/pip-5y0hk0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:40:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:40:28 INFO - Running setup.py (path:/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/pip-0hRC5T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:40:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:40:28 INFO - Running setup.py install for manifestparser 09:40:28 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:40:28 INFO - Running setup.py install for mozcrash 09:40:28 INFO - Running setup.py install for mozdebug 09:40:29 INFO - Running setup.py install for mozdevice 09:40:29 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:40:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:40:29 INFO - Running setup.py install for mozfile 09:40:29 INFO - Running setup.py install for mozhttpd 09:40:29 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:40:29 INFO - Running setup.py install for mozinfo 09:40:29 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:40:29 INFO - Running setup.py install for mozInstall 09:40:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:40:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:40:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:40:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:40:29 INFO - Running setup.py install for mozleak 09:40:29 INFO - Running setup.py install for mozlog 09:40:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:40:30 INFO - Running setup.py install for moznetwork 09:40:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:40:30 INFO - Running setup.py install for mozprocess 09:40:30 INFO - Running setup.py install for mozprofile 09:40:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:40:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:40:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:40:30 INFO - Running setup.py install for mozrunner 09:40:30 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:40:30 INFO - Running setup.py install for mozscreenshot 09:40:30 INFO - Running setup.py install for moztest 09:40:31 INFO - Running setup.py install for mozversion 09:40:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:40:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:40:31 INFO - Cleaning up... 09:40:31 INFO - Return code: 0 09:40:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:40:31 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')]} 09:40:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:40:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:31 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')]} 09:40:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:40:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:31 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 0x10fdae490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd24570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feab95c1000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fbc2d30>, '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 0x10fa28db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fbbab20>, '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.w2Pr9vPCgP/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.5uUsINHt96/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/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 09:40:31 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 09:40:31 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 09:40:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w2Pr9vPCgP/Render', 09:40:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:40:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:40:31 INFO - 'HOME': '/Users/cltbld', 09:40:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:40:31 INFO - 'LOGNAME': 'cltbld', 09:40:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:40:31 INFO - 'MOZ_NO_REMOTE': '1', 09:40:31 INFO - 'NO_EM_RESTART': '1', 09:40:31 INFO - 'PAGER': '/bin/cat', 09:40:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:40:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:40:31 INFO - 'PWD': '/builds/slave/test', 09:40:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:40:31 INFO - 'SHELL': '/bin/bash', 09:40:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5uUsINHt96/Listeners', 09:40:31 INFO - 'TMPDIR': '/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/', 09:40:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:40:31 INFO - 'USER': 'cltbld', 09:40:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:40:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:40:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:40:31 INFO - 'XPC_FLAGS': '0x0', 09:40:31 INFO - 'XPC_SERVICE_NAME': '0', 09:40:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:40:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:40:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:40:31 INFO - Running setup.py (path:/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/pip-aiY8yt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:40:31 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)) 09:40:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:40:31 INFO - Running setup.py (path:/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/pip-lYFq_h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:40:31 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)) 09:40:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:40:31 INFO - Running setup.py (path:/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/pip-4UR_le-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:40:31 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)) 09:40:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:40:31 INFO - Running setup.py (path:/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/pip-E8_QAP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:40:31 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)) 09:40:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:40:31 INFO - Running setup.py (path:/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/pip-feV1Ko-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:40:32 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)) 09:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:40:32 INFO - Running setup.py (path:/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/pip-HUoD0L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:40:32 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)) 09:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:40:32 INFO - Running setup.py (path:/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/pip-B1Khnt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:40:32 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)) 09:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:40:32 INFO - Running setup.py (path:/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/pip-DLvLf3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:40:32 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)) 09:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:40:32 INFO - Running setup.py (path:/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/pip-MhUKu0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:40:32 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)) 09:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:40:32 INFO - Running setup.py (path:/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/pip-Zi1Ywj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:40:32 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)) 09:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:40:32 INFO - Running setup.py (path:/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/pip-yAwXcM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:40:32 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)) 09:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:40:32 INFO - Running setup.py (path:/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/pip-9o8n22-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:40:32 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)) 09:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:40:32 INFO - Running setup.py (path:/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/pip-CrJR0t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:40:32 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)) 09:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:40:32 INFO - Running setup.py (path:/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/pip-Jl5hbe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:40:32 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)) 09:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:40:32 INFO - Running setup.py (path:/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/pip-7QKl5d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:40:33 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)) 09:40:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:40:33 INFO - Running setup.py (path:/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/pip-0NGAVC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:40:33 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:40:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:40:33 INFO - Running setup.py (path:/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/pip-zoHgPU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:40:33 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)) 09:40:33 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)) 09:40:33 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)) 09:40:33 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)) 09:40:33 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)) 09:40:33 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)) 09:40:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:40:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:40:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:40:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:40:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:40:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:40:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:40:36 INFO - Downloading blessings-1.6.tar.gz 09:40:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:40:36 INFO - Installing collected packages: blessings 09:40:36 INFO - Running setup.py install for blessings 09:40:36 INFO - Successfully installed blessings 09:40:36 INFO - Cleaning up... 09:40:36 INFO - Return code: 0 09:40:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:40:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:40:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:40:36 INFO - Reading from file tmpfile_stdout 09:40:36 INFO - Current package versions: 09:40:36 INFO - blessings == 1.6 09:40:36 INFO - blobuploader == 1.2.4 09:40:36 INFO - docopt == 0.6.1 09:40:36 INFO - functools32 == 3.2.3-2 09:40:36 INFO - jsonschema == 2.5.1 09:40:36 INFO - manifestparser == 1.1 09:40:36 INFO - mozInstall == 1.12 09:40:36 INFO - mozcrash == 1.0 09:40:36 INFO - mozdebug == 0.1 09:40:36 INFO - mozdevice == 0.48 09:40:36 INFO - mozfile == 1.2 09:40:36 INFO - mozhttpd == 0.7 09:40:36 INFO - mozinfo == 0.9 09:40:36 INFO - mozleak == 0.1 09:40:36 INFO - mozlog == 3.4 09:40:36 INFO - moznetwork == 0.27 09:40:36 INFO - mozprocess == 0.23 09:40:36 INFO - mozprofile == 0.28 09:40:36 INFO - mozrunner == 6.13 09:40:36 INFO - mozscreenshot == 0.1 09:40:36 INFO - mozsystemmonitor == 0.3 09:40:36 INFO - moztest == 0.7 09:40:36 INFO - mozversion == 1.4 09:40:36 INFO - psutil == 3.1.1 09:40:36 INFO - requests == 1.2.3 09:40:36 INFO - wsgiref == 0.1.2 09:40:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:40:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:40:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:40:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:37 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')]} 09:40:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:40:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:37 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 0x10fdae490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd24570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feab95c1000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fbc2d30>, '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 0x10fa28db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fbbab20>, '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.w2Pr9vPCgP/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.5uUsINHt96/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/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 09:40:37 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 09:40:37 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 09:40:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w2Pr9vPCgP/Render', 09:40:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:40:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:40:37 INFO - 'HOME': '/Users/cltbld', 09:40:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:40:37 INFO - 'LOGNAME': 'cltbld', 09:40:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:40:37 INFO - 'MOZ_NO_REMOTE': '1', 09:40:37 INFO - 'NO_EM_RESTART': '1', 09:40:37 INFO - 'PAGER': '/bin/cat', 09:40:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:40:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:40:37 INFO - 'PWD': '/builds/slave/test', 09:40:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:40:37 INFO - 'SHELL': '/bin/bash', 09:40:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5uUsINHt96/Listeners', 09:40:37 INFO - 'TMPDIR': '/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/', 09:40:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:40:37 INFO - 'USER': 'cltbld', 09:40:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:40:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:40:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:40:37 INFO - 'XPC_FLAGS': '0x0', 09:40:37 INFO - 'XPC_SERVICE_NAME': '0', 09:40:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:40:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:40:37 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)) 09:40:37 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)) 09:40:37 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)) 09:40:37 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)) 09:40:37 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)) 09:40:37 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)) 09:40:37 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)) 09:40:37 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)) 09:40:37 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)) 09:40:37 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)) 09:40:37 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)) 09:40:37 INFO - Cleaning up... 09:40:37 INFO - Return code: 0 09:40:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:40:37 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')]} 09:40:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:40:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:37 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')]} 09:40:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:40:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:37 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 0x10fdae490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd24570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feab95c1000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fbc2d30>, '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 0x10fa28db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fbbab20>, '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.w2Pr9vPCgP/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.5uUsINHt96/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/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 09:40:37 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 09:40:37 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 09:40:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w2Pr9vPCgP/Render', 09:40:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:40:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:40:37 INFO - 'HOME': '/Users/cltbld', 09:40:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:40:37 INFO - 'LOGNAME': 'cltbld', 09:40:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:40:37 INFO - 'MOZ_NO_REMOTE': '1', 09:40:37 INFO - 'NO_EM_RESTART': '1', 09:40:37 INFO - 'PAGER': '/bin/cat', 09:40:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:40:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:40:37 INFO - 'PWD': '/builds/slave/test', 09:40:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:40:37 INFO - 'SHELL': '/bin/bash', 09:40:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5uUsINHt96/Listeners', 09:40:37 INFO - 'TMPDIR': '/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/', 09:40:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:40:37 INFO - 'USER': 'cltbld', 09:40:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:40:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:40:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:40:37 INFO - 'XPC_FLAGS': '0x0', 09:40:37 INFO - 'XPC_SERVICE_NAME': '0', 09:40:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:40:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:40:37 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:40:37 INFO - Cleaning up... 09:40:37 INFO - Return code: 0 09:40:37 INFO - Running post-action listener: _resource_record_post_action 09:40:37 INFO - Running post-action listener: _start_resource_monitoring 09:40:37 INFO - Starting resource monitoring. 09:40:37 INFO - [mozharness: 2016-12-21 17:40:37.905653Z] Finished create-virtualenv step (success) 09:40:37 INFO - [mozharness: 2016-12-21 17:40:37.906658Z] Running install step. 09:40:37 INFO - Running pre-action listener: _resource_record_pre_action 09:40:37 INFO - Running main action method: install 09:40:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:40:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:40:38 INFO - Reading from file tmpfile_stdout 09:40:38 INFO - Detecting whether we're running mozinstall >=1.0... 09:40:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:40:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:40:38 INFO - Reading from file tmpfile_stdout 09:40:38 INFO - Output received: 09:40:38 INFO - Usage: mozinstall [options] installer 09:40:38 INFO - Options: 09:40:38 INFO - -h, --help show this help message and exit 09:40:38 INFO - -d DEST, --destination=DEST 09:40:38 INFO - Directory to install application into. [default: 09:40:38 INFO - "/builds/slave/test"] 09:40:38 INFO - --app=APP Application being installed. [default: firefox] 09:40:38 INFO - mkdir: /builds/slave/test/build/application 09:40:38 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'] 09:40:38 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 09:41:09 INFO - Reading from file tmpfile_stdout 09:41:09 INFO - Output received: 09:41:09 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 09:41:09 INFO - Running post-action listener: _resource_record_post_action 09:41:09 INFO - [mozharness: 2016-12-21 17:41:09.040013Z] Finished install step (success) 09:41:09 INFO - [mozharness: 2016-12-21 17:41:09.040159Z] Running run-tests step. 09:41:09 INFO - Running pre-action listener: _resource_record_pre_action 09:41:09 INFO - Running main action method: run_tests 09:41:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:41:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:41:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:41:09 INFO - 2016-12-21 09:41:09.054 screenresolution[1617:7303] starting screenresolution argv=screenresolution get 09:41:09 INFO - 2016-12-21 09:41:09.057 screenresolution[1617:7303] Display 0: 1600x1200x32@60 09:41:09 INFO - 2016-12-21 09:41:09.063 screenresolution[1618:7306] starting screenresolution argv=screenresolution list 09:41:09 INFO - Available Modes on Display 0 09:41:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:41:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:41:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:41:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:41:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:41:09 INFO - Intel Iris: 09:41:09 INFO - Chipset Model: Intel Iris 09:41:09 INFO - Type: GPU 09:41:09 INFO - Bus: Built-In 09:41:09 INFO - VRAM (Dynamic, Max): 1536 MB 09:41:09 INFO - Vendor: Intel (0x8086) 09:41:09 INFO - Device ID: 0x0a2e 09:41:09 INFO - Revision ID: 0x0009 09:41:09 INFO - Displays: 09:41:09 INFO - Display: 09:41:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:41:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:41:09 INFO - Display Serial Number: 1600x1200 60 09:41:09 INFO - Main Display: Yes 09:41:09 INFO - Mirror: Off 09:41:09 INFO - Online: Yes 09:41:09 INFO - Rotation: Supported 09:41:09 INFO - Return code: 0 09:41:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:41:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:41:09 INFO - Python 2.7.3 09:41:09 INFO - Return code: 0 09:41:09 INFO - grabbing minidump binary from tooltool 09:41:09 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')]} 09:41:09 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 0x10fbc2d30>, '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 0x10fa28db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fbbab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:41:09 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 09:41:09 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 09:41:09 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 09:41:09 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:41:09 INFO - Return code: 0 09:41:09 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:41:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:41:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:41:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:41:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:41:09 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/cha5FFBzTy2P3AtTOnYT8g/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0391', '--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 09:41:09 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/cha5FFBzTy2P3AtTOnYT8g/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0391 --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 09:41:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w2Pr9vPCgP/Render', 09:41:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:41:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:41:09 INFO - 'HOME': '/Users/cltbld', 09:41:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:41:09 INFO - 'LOGNAME': 'cltbld', 09:41:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:41:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:41:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:41:09 INFO - 'MOZ_NO_REMOTE': '1', 09:41:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:41:09 INFO - 'NO_EM_RESTART': '1', 09:41:09 INFO - 'PAGER': '/bin/cat', 09:41:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:41:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:41:09 INFO - 'PWD': '/builds/slave/test', 09:41:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:41:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:41:09 INFO - 'SHELL': '/bin/bash', 09:41:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5uUsINHt96/Listeners', 09:41:09 INFO - 'TMPDIR': '/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/', 09:41:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:41:09 INFO - 'USER': 'cltbld', 09:41:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:41:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:41:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:41:09 INFO - 'XPC_FLAGS': '0x0', 09:41:09 INFO - 'XPC_SERVICE_NAME': '0', 09:41:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:41:09 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/cha5FFBzTy2P3AtTOnYT8g/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0391', '--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 09:41:09 INFO - mozversion application_buildid: 20161221063559 09:41:09 INFO - mozversion application_changeset: 38c65075d65e662c265bb5d215f1507798497a98 09:41:09 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:41:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:41:09 INFO - mozversion application_name: Firefox 09:41:09 INFO - mozversion application_remotingname: firefox-dev 09:41:09 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:41:09 INFO - mozversion application_vendor: Mozilla 09:41:09 INFO - mozversion application_version: 52.0a2 09:41:09 INFO - mozversion platform_buildid: 20161221063559 09:41:09 INFO - mozversion platform_changeset: 38c65075d65e662c265bb5d215f1507798497a98 09:41:09 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:41:09 INFO - mozversion platform_version: 52.0a2 09:41:09 INFO - using testdate: 1482342069 09:41:09 INFO - actual date: 1482342069 09:41:09 INFO - starting webserver on 'localhost:49236' 09:41:09 INFO - SUITE-START | Running 2 tests 09:41:09 INFO - TEST-START | dromaeo_css 09:41:09 INFO - operating with platform_type : mac_ 09:41:09 INFO - Initialising browser for dromaeo_css test... 09:41:09 INFO - TEST-INFO | started process 1628 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/tmpVqCnBq/profile http://localhost:49236/getInfo.html) 09:41:12 INFO - PROCESS | 1628 | __metrics Screen width/height:1600/1200 09:41:12 INFO - PROCESS | 1628 | colorDepth:24 09:41:12 INFO - PROCESS | 1628 | Browser inner width/height: 1024/689 09:41:12 INFO - PROCESS | 1628 | __metrics 09:41:12 INFO - PROCESS | 1628 | 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" 09:41:12 INFO - PROCESS | 1628 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:41:13 INFO - TEST-INFO | 1628: exit 0 09:41:13 INFO - Browser initialized. 09:41:13 INFO - Running cycle 1/1 for dromaeo_css test... 09:41:13 INFO - TEST-INFO | started process 1630 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/tmpVqCnBq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:41:25 INFO - PROCESS | 1630 | RSS: Main: 222711808 09:41:25 INFO - PROCESS | 1630 | 09:42:16 INFO - PROCESS | 1630 | 1482342136689 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:44:29 INFO - PROCESS | 1630 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49236/tests/dromaeo/cssquery-ext.html) 09:44:31 INFO - PROCESS | 1630 | RSS: Main: 342818816 09:44:31 INFO - PROCESS | 1630 | 09:47:34 INFO - PROCESS | 1630 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-ext.html (next: http://localhost:49236/tests/dromaeo/cssquery-jquery.html) 09:47:35 INFO - PROCESS | 1630 | RSS: Main: 345493504 09:47:35 INFO - PROCESS | 1630 | 09:50:37 INFO - PROCESS | 1630 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49236/tests/dromaeo/cssquery-mootools.html) 09:50:38 INFO - PROCESS | 1630 | RSS: Main: 345952256 09:50:38 INFO - PROCESS | 1630 | 09:53:40 INFO - PROCESS | 1630 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49236/tests/dromaeo/cssquery-prototype.html) 09:53:41 INFO - PROCESS | 1630 | RSS: Main: 322473984 09:53:41 INFO - PROCESS | 1630 | 09:56:44 INFO - PROCESS | 1630 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49236/tests/dromaeo/cssquery-yui.html) 09:56:45 INFO - PROCESS | 1630 | RSS: Main: 310374400 09:56:45 INFO - PROCESS | 1630 | 09:59:48 INFO - PROCESS | 1630 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-yui.html (next: http://localhost:49236/tests/dromaeo/cssquery-dojo.html) 09:59:48 INFO - PROCESS | 1630 | RSS: Main: 362397696 09:59:48 INFO - PROCESS | 1630 | 09:59:48 INFO - PROCESS | 1630 | __start_tp_report 09:59:48 INFO - PROCESS | 1630 | _x_x_mozilla_page_load 09:59:48 INFO - PROCESS | 1630 | _x_x_mozilla_page_load_details 09:59:48 INFO - PROCESS | 1630 | |i|pagename|runs| 09:59:48 INFO - PROCESS | 1630 | |0;dojo.html;3203.19920019995;3225.4194245035883;3249.6262929763075;3263.5477212640353;3278.5410042594026;5402.621816472849;5412.864678383043;5429.511343979041;5468.644538105021;5482.068048431771;5454.209139674755;5466.726663666821;5494.615376923617;5505.697186654733;5512.586556008306;5020.924686129711;5023.271625614284;5076.695398276115;5076.873078173038;5126.897462050775;4993.5755460785695;5006.974965125188;5014.724190169539;5095.9745201273945;5130.333056702623;5042.218456139304;5053.519915608012;5074.264231686422;5082.339295891529;5083.262926875601;5084.720340381277;5089.949100508985;5123.538881500645;5130.384353877558;5185;4857.368542089541;5069.188929771219;5070.974645126751;5090.516400941905;5093.770780314895;4502.2796352583755;5402.567794576464;5408.026555220059;5454.209139674765;5459.07195776715;4835.758212089381;5847.502962248172;5866.911996320058;5868.735906884201;5885.058390657474;6025.517958563304;6035.336113027563;6047.395260473962;6052.909206361908;6078.209832721762;5246.422893481714;5285.735713214324;5406.810761623325;5456.6725996439545;5567.47109024646;6076.605020673642;6084.787032453843;6105.175801266861;6111.938880611137;6118.694065296718;5735.11105778605;5736.282964629421;5737.3115226172295;5745.511631511285;5757.568182386254;5815.534757219412;5858.8535286617325;5859.1797148399255;5863.413658634187;5927.644341339534;4853.3447984522345;5759.452851979056;5803.912941305884;5809.0124678804705;5838.620489668158;5848.853778655568;5856.472917437451;5858.970705146447;5867.002609556385;5874.823755287262;4677.64917631179;4695.85912422621;4714.15145273854;4720.984988227548;4731.787069581877;4238.830446782094;4258.574142585779;4287.892802679958;4314.460692413449;4339.522652508222;5607.887842243133;5656.60403771732;5663.405342438985;5669.744861481243;5677.687727175045;5810.273715785527;5986.73059712314;6016.217891674054;6027.276726792813;6048.4858787003595;5539.335279766373;5615.326160860723;5616.410276920865;5621.494065534122;5624.184493248456;5180.6891586504935;5186.040582492251;5187.740612969412;5190.870228244248;5192.350956130484;6308.936910630927;6337.96831015842;6338.049292606146;6339.778107766206;6343.270523889812;6048.032314829607;6097.146399503984;6111.449969502766;6120.479759220338;6136.325004249618;5052.797888084436;6327.240731112296;6374.171357723466;6396.0405939109505;6396.552241343062;4695.413073365829;5742;5766.481016708516;5897.67562784051;5902.4097590240635;5894.174815525744;5898.852528686817;5917.142014407973;5949.940500595112;5964.254468191476;5809.30288365382;5818.301803783573;5834.883302333845;5835.241418615553;5859.384764599656;5800.187973683604;5809.099589563624;5823.534117270524;5825.359210486928;5838.211841401357;4728.479867214535;5383.273258110105;5537.944620553904;5642.548596112383;5702.287214098238;5795.652260864362;5820.82537523875;5837.153612726216;5861.1501332307425;5883.705814709162;5694.288213973254;5699.116636921284;5699.202111704448;5707.372189059283;5764.855878602901 09:59:48 INFO - PROCESS | 1630 | |1;ext.html;461.20902651951894;463.52025653448686;464.51690242148146;492.29110081482673;530.4138926486232;29263;30712.846435767817;30727;38232.61767382325;39312.80343598285;40273.79863100687;40614.59385406145;40642.57750978717;40687;40789.59210407895;8013.95993020037;9217.769555761093;9371.250299975985;9384.530773461334;9402.952985235064;9317;9381.061893810633;9419.48192849393;9430.43417394955;9431.81136377272;9408.106229908148;9444.441667124916;9454.952725236364;9460.195883349923;9460.337776355658;9371.203447706954;9422.670206542738;9431.764205894839;9435.05649435051;9440.763980900498;9273.026332235153;9324.414849475605;9345.813083738356;9402.90597094034;9405.153536181708;31618.841905790323;38479.61520384788;38727.612723872684;38830.223395532215;38911.832645020695;3169.8573564189437;3256.560364350806;3282.5404443377947;3437.4671925851535;3440.552728145325;28775;28885.988990385446;29022.709772902424;29326.26684332909;29420.8528957356;3128.186671465435;3187.5218717192374;3227.564278822352;3261.9184520386943;3331.900042998714;437621;439197;439584;441248;441512.79243604216;27323.863380683368;58497;58920.99999999914;58960.82078358409;58984;33401.83299083489;34579.827100864335;34593.82703086468;34648.30703385918;34661.48007779886;14036.017478776388;14167.433302667983;14176;14202.28988550589;14238.28808559568;11846.289222646496;11942.761144777056;11964.64106076819;11970.40147992597;11975.041996640248;7697;7744.612769361509;7778.8833167502535;7798.922010779819;7798.961005195051;2810.8875644974382;3207.951880721791;3271.7424976766433;3272.443684573628;3284.441644920369;753.4123383760675;895.5253715530779;923.8521836506127;961.1829944547079;1014.2899970020867;28099.859500702365;28670.569941450896;28714.569281460794;29233;29406.705932940826;820.3724151024471;1009.5810238750983;1012.2104758288547;1013.5287091502487;1052.3002203534718;14486.565403037926;15027.398904043934;15118.546443606709;15448.459303924308;15477.303521341566;655811.7209413922;658040;658471;658711;659162;54623.726881366136;55341.44658553443;55388.72305638446;55458.722706387016;55516;1227.1593958138526;1257.3587470390166;1358.4973559782943;1367.9589612311647;1396.0785881318282;29162.854185729786;29779.851100744356;30061.248468788453;30074.947376841705;30776.846115769276;46735.29897051615;47108.29337560008;47311.76344118257;47331.526684732715;47458.81352966203;516521;521421;522225;682913;700738;39286.80356598199;39595;39669.2066158681;39962.40056399098;39982.000449988984;42726.93182670458;42908.78545607377;42942;43211.56788432076;43791.56208438002;36469.08827279339;36475.81762091172;37829.43255851109;39299.60700393074;40487;36474.717275445335;37046.44430333601;37061;37811.43282850814;38132.42801358037;35765;36378.636213637525;37211.44182837205;37302.253954920205;37363.62636373602;32509;33161.66838331682;33890.66109338875;33892.15269618279;35223.82388088043;24563.017479300626;24673.25980220596;24695.259142225044;25195.622065668664;25217.621735674347 09:59:48 INFO - PROCESS | 1630 | |2;jquery.html;353.46097201767316;370.1523511159445;376.3526734017491;379.2585495356577;402.7221217360021;14954.626134346634;15263.923680381597;15876.682466350667;19562.41312760616;20603.793962060376;19707.916064616442;20148.093335799887;20754.27360042399;20844.791552084476;21021.26425575105;4853.878653033667;5925.525957923366;6167.4757645600175;6183.103449999748;6190.41191086846;6060.33336333003;6092.969535152318;6134.4478996890275;6145.108959200936;6166.969165154191;5865.120231965218;5876.059830427112;5974.312954010301;6057.454829065383;6059.575829691923;5801.941980580204;5934.139549765303;5985.850353741148;6005.579609427342;6008.609440386383;4934.8766280843265;5172.5344718975475;5228.346456692913;5244.81643142492;5275.393329767049;23215.187468438693;24661.753382466304;25168.496630067297;25287.11495097681;25816.225513234633;2236.2732112063645;2238.5746708125566;2398.844075135128;2749.2714430675887;2807.05963502227;15140.924295378563;15234.847651523565;15405.460808871749;16177.676446471123;16589;1857.2663797799928;1864.0586503815487;1870.6366728325063;1879.20133943075;1935.680612698903;99054;114673;119193;122119;122121;10745.355278683304;10978.670639880816;11462.254953428;11533.76932461346;11724.70688232801;14344;14349.210793406462;14350.21073840928;14358.784618230526;14365.569032929028;8361.498310101291;8562.657493700306;8636;8766.956165219133;8802.427842190365;8535.359848011423;8576.142385761374;8577.442466239674;8773.473591584525;8775.473471591726;6658.600483970978;6735.764248251386;6775.1531058617675;6778.8644227116165;6790.694418751168;1759.9296028158985;1846.3353192850564;1846.9353572625162;1847.7228415737748;1850.4726153046317;887.8224355129;901.2384535067857;974.468914441631;1007.6725064354114;1015.3755440404246;20735.792642073688;22243.22148724787;22278.88860555687;22339.77660223377;22418.887905560696;771.8070482379405;944.631593678466;970.1705042188968;999.0159692702682;1043.4574021508772;11154.051905587954;11787.882121178678;11788.938995490447;11867.58463453775;11977;551576;561499.1925040348;562372;573777;574400;84274;86878;90837.54581227052;105141.47429262957;111801;932.1936524905908;939.8214339275653;976.6435251133286;978.8238117138841;980.0493521284343;19494.805051949297;20333.642169516217;20451.28420505275;20544.28095016727;20583.176672933514;46495.605131846096;49308;50136.74931625318;51062.23406648978;51366.74316628393;74733.626331868;75242.62378688072;77162.61418692871;77375;78096.60951695206;6734.528582999143;7068.9293107070325;8023.1976802319305;8255.380846436421;8428.157184281768;12084.274943503418;12361.134720569475;13321.267330296927;13628.591142265748;13830.170189788645;11193;11299.491522881326;11745.765084698429;11779.764404711686;11856.881431185579;11137.331760094421;11368.886311136783;11382.772344552897;11445.198836081394;11749.471273792871;11714.472848721658;11714.531418743154;11716.765664686489;11825.526978920747;11900.821487677518;7007.614581198083;7422.888656670262;7481.551106933602;8266.834663306821;11757.882421175678;9506.619735210514;11071.058959988492;11283.774324513628;11711.180217385043;12116.27302361861 09:59:48 INFO - PROCESS | 1630 | |3;mootools.html;1331.3942156318876;1459.2193176650492;1599.3043026283567;1765.3644687912358;1835.6145209505994;8042.396820238484;8476.449030812993;8478.067412584618;8481.91518084819;8494.6177422016;6909.896351554731;7151.642417879111;7203.171635261933;7881.05427348719;8082.110967793538;2099.601075795598;2298.9425264368356;2310.87290199039;2338.695969523968;2348.412896775806;2351.341624345186;2364.243442098529;2406.8194885383577;2412.0592968742117;2415.9516809663796;2070.3995841206033;2151.4943988162768;2287.359539328982;2349.48311371498;2357.4695693469002;2340.2277248508035;2361.480474295652;2361.8819059046978;2368.9052437902465;2369.1707902234252;2357.233898982837;2397.676313697646;2403.07481619576;2404.7955923746504;2410.1684918703018;8262.710805121853;8436.535990520519;8444.788880278042;8457.112003239632;8464.111268316861;5085.491450854922;5253.133233016548;5261.921071183935;5262.447443018505;5266.789328426897;3354.798712077283;3365.528825964367;3582.6059133495296;3648.72634552407;3649.59854416014;1072.4530489450358;1073.4096247064163;1103.2056919018294;1116.0234794554765;1116.8548088748412;10812.891871081189;10916.563337466492;10999.945000275027;11049.005589496985;11066.446677666085;3961.960380396159;4497.572730590588;4586.220342541776;4731.1720448921305;4792.688475249098;9224.723258302261;9414.29392795543;9440.055994400505;9443.622255109718;9454.90545094554;5259.47405259471;5261.789528418821;5263.105272103751;5267.0256002639635;5273.499017593323;4735.337052812609;4790.808367665324;4818.445878723992;4838.250071238964;4843.660943733905;3754.3992961126087;3813.5423749149722;3860.3051450738576;3872.283627528916;3889.1638297766117;1242.2918936206277;1250.0749445410513;1256.108163204117;1270.6886812730754;1278.673938145767;629.5184184099168;708.3129364516411;880.8502554565739;901.855703087516;962.5620342744035;5402.297701298806;5554.583406244547;5593.3847276799515;5835.94164058354;5852.707364631751;943.1747221181466;946.7869729310987;966.2656381150376;987.017917172418;1012.5342342522375;2955.9801868355385;3679.816009199529;3891.3072813326294;3993.6405723485027;4036.8587099451966;9864.901350986542;10108.444035578113;10133.2906696532;10184.439855807854;10278.691639250685;9981.251406144569;9986.550605222783;10081.747956301151;10103.19174466041;10254.58981640741;7027.15674119109;7615.65729542183;7825.843483130306;7975.481593696507;7999.520028798291;5449.6730196188355;5465;5520.53075488588;5748.223989761496;5778.768849246151;9137.177654011171;9502.239820814457;9517.952410237904;9557.474338911426;9653.855192172263;9352.953235233781;9461.38500997433;9534.713958581253;9656.275779316438;9689.321747477608;7176.677049532888;7278.272172782891;7315.15875674283;7337.559746415232;7382.335589796944;6768.763093291711;7484.251574842472;7899.486533375542;7959.283664470226;7969.80075498094;6874.6562671867205;7087.539309944837;7186.856262874818;7239.384652304613;7335.449841262033;6954.218312674971;7071.7878463646175;7114.430845532242;7187.1734750504365;7251.383632391095;6666.066750655009;6674.466042716668;6737.73049078051;6749.5612785168805;6750.3924646782025;4845.685030473008;4889.706617602955;4907.067657145274;4947.356843610308;4988.650794444499;4842.4673285939225;4848.7090774553635;4864.075825593126;5017.57350625201;5029.59763218949 09:59:48 INFO - PROCESS | 1630 | |4;prototype.html;300.28831668944605;302.11781597734625;307.4957070404536;313.74116354007947;314.15648982482037;5129.512696293851;5156.742162891859;5170.534651881339;5211.244369566411;5224.190250511172;4346.86959391218;4736.171170045237;4815.518448155191;4957.305977163191;5108.69347839129;3929.941050884239;3943.487346644932;3957.7031722620773;3974;3991.680665546764;3921.1961547882615;3964.3657014877626;3971.463852379921;3996.300647386696;3996.660283875874;3796.8481260749536;3928.135810121783;3940.2316548272956;3971.6624086952643;3972.324704800191;2938.9559156612668;3741.8316175772156;3901.9464744519105;3938.2714197873483;3945.1123497213184;3443.4146195146886;3446;3448.8448019838916;3451.8274086295582;3458.239187378773;5011.646855349072;5027.7822193796;5028.139588352296;5062.392512898439;5066.392032956032;4095.795210239476;4223.282042052828;4455.977720111411;4477.6417886569325;4486.868737349765;4681.836135735233;4877.902441951177;4926.4827193144565;5160.845174644729;5345.946540534623;3165.857536410867;3498.9475157872653;3573.285342931398;3764.4353346997896;3797.708779015148;4835.975820120912;5025.321581586512;5608.794109266527;5615.410381909923;5672.744726487318;3150.5664922460223;3726.2361215950664;3728.7389882708494;3735.6824669903363;3752.4746535485056;3093.474109401362;3703.055720791181;3796.411556208737;3808.647929984871;3817.7900215488417;4105.753654780723;4446.933296000562;4469.173202957463;4473.3960915276675;4487.618552423015;4799.016201678647;5378.428335914519;5404.32445944257;5425.694080015977;5448.754806033738;1079.6868908016745;1132.5866058888446;1231.4520038582746;1234.623439850854;1240.3922078181627;677.7153595489906;745.4818900863957;767.9155292917775;813.9540690213048;816.3510009542427;4973.875653108701;5194.4026436959475;5207.229541955753;5229.712365819916;5234.293370395024;5554.722413844838;5561.77752889888;5567.220589117527;5588.524975377057;5601.29983752031;4162.41726158338;4178.874633760992;4419.624331931822;4656.8602941911795;5233.738313084331;2757.0350377368163;2757.7793776497833;2762.516559602102;2780.6107144999714;2792.6369571955515;5203.7918483261;5574.414686457944;5583.888322233533;5635.633683810539;5659.745311460994;4805.927911081337;4969.0310389474425;5367.624266301399;5377.596680248997;5402.783888644489;4849.854504364875;5085.821996230115;5571.526420254324;5579.8884022320135;5628.493435590734;3916.195583067699;4902.14212512816;5084.7966081356335;5261.394939581844;5298.602604804578;4397.691212113666;4965.4786247444945;5063.873403164803;5147.897042059213;5175.81884634036;4813.711177329372;5220.399654039834;5535.225068490334;5540.556755459473;5599.800149887585;2877.7337971292563;3127.984360078276;3340.365330587181;3503.8948831535095;3539.876104336439;4610.118675827608;4801.063128234814;4866.95133048665;5166.974165129301;5253.658512196695;4688.507706690884;4979.377577802775;5046.038859495278;5089.643724939367;5089.694618322913;5061.417936937152;5101.336826212569;5131.79472821083;5151.175811870083;5176.221358408307;4619.422572178478;4883.731394773179;5108.514691104339;5326.627136100436;5339.102455207798;5344.35867695879;5362.570994320523;5363.785448582169;5477.890442191214;5504.403722920309;4944.283078953604;4989.25161225819;5361.24942508057;5369.221462887937;5399.811006614907 09:59:48 INFO - PROCESS | 1630 | |5;yui.html;585.9179714839923;731.7512045904394;746.2872956326709;770.3606007014179;778.7235531386358;11907.880921190796;14136;14612.926935365322;14857.702845943075;14998.62503437412;13181.406836692347;13205;13340;13358.66603334917;13397;819.6721311475413;824.5341382119084;832.238501770882;833.2001278772386;837.6398148796015;652.7943697735222;675.2133764164743;809.6761295481825;829.4898637338054;845.4546817302836;840.2605706977881;882.7925437522177;890.1054440287502;892.2594246775161;894.239896088323;834.1408349400098;835.1606635331483;835.5905606252892;851.7700220940374;882.9889776206272;507.036630402235;522.1749635575819;625.2528228766625;626.4706323156925;648.1153225846685;13907.721845563134;13936.651583710489;13942.512012079529;13946.302684865715;13968.511102111377;12448.06639502032;12487.50049997999;12559.120861539695;12692.238465692088;13146.276954767483;8994.415363001384;10357.585696572129;10731.141508679288;11017.779644407146;11330.206885518017;4296.613304802551;4334.891627709301;4357.651387888993;4362.694611377205;4520.163769702583;69009;91720;91761.54119229362;92018.5399073007;93290.13419731635;10147.340422872638;10250.821155567057;10336.17310615149;10411.94794026025;10427.68716938493;19127.13927873249;19197.136128874234;19199.52001199953;19203.231870725293;19242.422727318204;7285.744998925013;7588.127365352944;7673.271039251262;7938.285554300025;7970.880436793453;4240.787960601958;4348.869533913988;4356.259435895905;4366.126774645084;4545.045540436546;3360.3615313090436;3473.7221022318154;3475.14858859581;3481.408160612702;3673;645.3320812958659;666.8699603577256;695.2942763095463;712.9358357747827;714.4641518860855;540.497337476151;618.4681174190193;640.1038546035587;677.502036003537;701.6035939694057;8890.777730556787;9020.524142847122;9098;9154.542272886329;9182.449053056704;545.8309806135887;560.4647561578682;649.302000349626;655.3348351423309;669.5714742564762;4305.698601097955;4446.644268458515;4479.843205487858;4522.253828118323;4596.954030459719;78857.21142788614;84096;107005.4649726762;111185.44407277912;113288;77106.84339734909;78782;88438.11561884299;97702.51148744211;101490.49254753826;57249.71375143181;80374.19625803667;83274.56722245376;83313.58343208366;87003.69494457588;9418.952905235705;9622.807543848943;9714.417134971924;9738.561764720745;9747.220222382051;39953.60046399499;51631.70920727012;52748.73625631847;53953.651158721346;53969.920601588536;70224;71099;72269.27730722836;73142.63428682822;75680.62159689167;11535.423228838692;12044.397780111134;12218.38908054576;12590.181638193488;12611;13276.867231327564;13662.111962722789;14419.567412977627;14472.782908256175;15115.470958516398;13192.868071319164;13210.405531751287;13625.727485450434;13799.51701690436;13834.723305533631;12794.744105118032;12845.807312890502;13211.14127581704;13739.450421983009;13836.584902452943;10416.843747343644;12987.155834870704;13117.409716562586;13134.343282836011;13229.07396482237;10211.897881021094;10390.948045259725;10435;10795.838062429228;10933.45332733376;9001.36990410665;11207.66377008691;11221.831672525082;11357.432128393382;11485.540578377106 09:59:48 INFO - PROCESS | 1630 | __end_tp_report 09:59:48 INFO - PROCESS | 1630 | __start_cc_report 09:59:48 INFO - PROCESS | 1630 | _x_x_mozilla_cycle_collect,1881 09:59:48 INFO - PROCESS | 1630 | __end_cc_report 09:59:48 INFO - PROCESS | 1630 | __startTimestamp1482343188631__endTimestamp 09:59:48 INFO - PROCESS | 1630 | 09:59:48 INFO - PROCESS | 1630 | ------- Summary: start ------- 09:59:48 INFO - PROCESS | 1630 | Number of tests: 6 09:59:48 INFO - PROCESS | 1630 | 09:59:48 INFO - PROCESS | 1630 | [#0] dojo.html Cycles:165 Average:5488.79 Median:5677.69 stddev:622.18 (11.0%) stddev-sans-first:597.70 09:59:48 INFO - PROCESS | 1630 | Values: 3203.2 3225.4 3249.6 3263.5 3278.5 5402.6 5412.9 5429.5 5468.6 5482.1 5454.2 5466.7 5494.6 5505.7 5512.6 5020.9 5023.3 5076.7 5076.9 5126.9 4993.6 5007.0 5014.7 5096.0 5130.3 5042.2 5053.5 5074.3 5082.3 5083.3 5084.7 5089.9 5123.5 5130.4 5185.0 4857.4 5069.2 5071.0 5090.5 5093.8 4502.3 5402.6 5408.0 5454.2 5459.1 4835.8 5847.5 5866.9 5868.7 5885.1 6025.5 6035.3 6047.4 6052.9 6078.2 5246.4 5285.7 5406.8 5456.7 5567.5 6076.6 6084.8 6105.2 6111.9 6118.7 5735.1 5736.3 5737.3 5745.5 5757.6 5815.5 5858.9 5859.2 5863.4 5927.6 4853.3 5759.5 5803.9 5809.0 5838.6 5848.9 5856.5 5859.0 5867.0 5874.8 4677.6 4695.9 4714.2 4721.0 4731.8 4238.8 4258.6 4287.9 4314.5 4339.5 5607.9 5656.6 5663.4 5669.7 5677.7 5810.3 5986.7 6016.2 6027.3 6048.5 5539.3 5615.3 5616.4 5621.5 5624.2 5180.7 5186.0 5187.7 5190.9 5192.4 6308.9 6338.0 6338.0 6339.8 6343.3 6048.0 6097.1 6111.4 6120.5 6136.3 5052.8 6327.2 6374.2 6396.0 6396.6 4695.4 5742.0 5766.5 5897.7 5902.4 5894.2 5898.9 5917.1 5949.9 5964.3 5809.3 5818.3 5834.9 5835.2 5859.4 5800.2 5809.1 5823.5 5825.4 5838.2 4728.5 5383.3 5537.9 5642.5 5702.3 5795.7 5820.8 5837.2 5861.2 5883.7 5694.3 5699.1 5699.2 5707.4 5764.9 09:59:48 INFO - PROCESS | 1630 | 09:59:48 INFO - PROCESS | 1630 | [#1] ext.html Cycles:180 Average:67726.99 Median:38778.92 stddev:153338.68 (395.4%) stddev-sans-first:153685.67 09:59:48 INFO - PROCESS | 1630 | Values: 461.2 463.5 464.5 492.3 530.4 29263.0 30712.8 30727.0 38232.6 39312.8 40273.8 40614.6 40642.6 40687.0 40789.6 8014.0 9217.8 9371.3 9384.5 9403.0 9317.0 9381.1 9419.5 9430.4 9431.8 9408.1 9444.4 9455.0 9460.2 9460.3 9371.2 9422.7 9431.8 9435.1 9440.8 9273.0 9324.4 9345.8 9402.9 9405.2 31618.8 38479.6 38727.6 38830.2 38911.8 3169.9 3256.6 3282.5 3437.5 3440.6 28775.0 28886.0 29022.7 29326.3 29420.9 3128.2 3187.5 3227.6 3261.9 3331.9 437621.0 439197.0 439584.0 441248.0 441512.8 27323.9 58497.0 58921.0 58960.8 58984.0 33401.8 34579.8 34593.8 34648.3 34661.5 14036.0 14167.4 14176.0 14202.3 14238.3 11846.3 11942.8 11964.6 11970.4 11975.0 7697.0 7744.6 7778.9 7798.9 7799.0 2810.9 3208.0 3271.7 3272.4 3284.4 753.4 895.5 923.9 961.2 1014.3 28099.9 28670.6 28714.6 29233.0 29406.7 820.4 1009.6 1012.2 1013.5 1052.3 14486.6 15027.4 15118.5 15448.5 15477.3 655811.7 658040.0 658471.0 658711.0 659162.0 54623.7 55341.4 55388.7 55458.7 55516.0 1227.2 1257.4 1358.5 1368.0 1396.1 29162.9 29779.9 30061.2 30074.9 30776.8 46735.3 47108.3 47311.8 47331.5 47458.8 516521.0 521421.0 522225.0 682913.0 700738.0 39286.8 39595.0 39669.2 39962.4 39982.0 42726.9 42908.8 42942.0 43211.6 43791.6 36469.1 36475.8 37829.4 39299.6 40487.0 36474.7 37046.4 37061.0 37811.4 38132.4 35765.0 36378.6 37211.4 37302.3 37363.6 32509.0 33161.7 33890.7 33892.2 35223.8 24563.0 24673.3 24695.3 25195.6 25217.6 09:59:48 INFO - PROCESS | 1630 | 09:59:48 INFO - PROCESS | 1630 | [#2] jquery.html Cycles:180 Average:33267.66 Median:25551.67 stddev:93702.50 (366.7%) stddev-sans-first:93932.77 09:59:48 INFO - PROCESS | 1630 | Values: 353.5 370.2 376.4 379.3 402.7 14954.6 15263.9 15876.7 19562.4 20603.8 19707.9 20148.1 20754.3 20844.8 21021.3 4853.9 5925.5 6167.5 6183.1 6190.4 6060.3 6093.0 6134.4 6145.1 6167.0 5865.1 5876.1 5974.3 6057.5 6059.6 5801.9 5934.1 5985.9 6005.6 6008.6 4934.9 5172.5 5228.3 5244.8 5275.4 23215.2 24661.8 25168.5 25287.1 25816.2 2236.3 2238.6 2398.8 2749.3 2807.1 15140.9 15234.8 15405.5 16177.7 16589.0 1857.3 1864.1 1870.6 1879.2 1935.7 99054.0 114673.0 119193.0 122119.0 122121.0 10745.4 10978.7 11462.3 11533.8 11724.7 14344.0 14349.2 14350.2 14358.8 14365.6 8361.5 8562.7 8636.0 8767.0 8802.4 8535.4 8576.1 8577.4 8773.5 8775.5 6658.6 6735.8 6775.2 6778.9 6790.7 1759.9 1846.3 1846.9 1847.7 1850.5 887.8 901.2 974.5 1007.7 1015.4 20735.8 22243.2 22278.9 22339.8 22418.9 771.8 944.6 970.2 999.0 1043.5 11154.1 11787.9 11788.9 11867.6 11977.0 551576.0 561499.2 562372.0 573777.0 574400.0 84274.0 86878.0 90837.5 105141.5 111801.0 932.2 939.8 976.6 978.8 980.0 19494.8 20333.6 20451.3 20544.3 20583.2 46495.6 49308.0 50136.7 51062.2 51366.7 74733.6 75242.6 77162.6 77375.0 78096.6 6734.5 7068.9 8023.2 8255.4 8428.2 12084.3 12361.1 13321.3 13628.6 13830.2 11193.0 11299.5 11745.8 11779.8 11856.9 11137.3 11368.9 11382.8 11445.2 11749.5 11714.5 11714.5 11716.8 11825.5 11900.8 7007.6 7422.9 7481.6 8266.8 11757.9 9506.6 11071.1 11283.8 11711.2 12116.3 09:59:48 INFO - PROCESS | 1630 | 09:59:48 INFO - PROCESS | 1630 | [#3] mootools.html Cycles:180 Average:5465.86 Median:5260.63 stddev:2979.72 (56.6%) stddev-sans-first:2971.88 09:59:48 INFO - PROCESS | 1630 | Values: 1331.4 1459.2 1599.3 1765.4 1835.6 8042.4 8476.4 8478.1 8481.9 8494.6 6909.9 7151.6 7203.2 7881.1 8082.1 2099.6 2298.9 2310.9 2338.7 2348.4 2351.3 2364.2 2406.8 2412.1 2416.0 2070.4 2151.5 2287.4 2349.5 2357.5 2340.2 2361.5 2361.9 2368.9 2369.2 2357.2 2397.7 2403.1 2404.8 2410.2 8262.7 8436.5 8444.8 8457.1 8464.1 5085.5 5253.1 5261.9 5262.4 5266.8 3354.8 3365.5 3582.6 3648.7 3649.6 1072.5 1073.4 1103.2 1116.0 1116.9 10812.9 10916.6 10999.9 11049.0 11066.4 3962.0 4497.6 4586.2 4731.2 4792.7 9224.7 9414.3 9440.1 9443.6 9454.9 5259.5 5261.8 5263.1 5267.0 5273.5 4735.3 4790.8 4818.4 4838.3 4843.7 3754.4 3813.5 3860.3 3872.3 3889.2 1242.3 1250.1 1256.1 1270.7 1278.7 629.5 708.3 880.9 901.9 962.6 5402.3 5554.6 5593.4 5835.9 5852.7 943.2 946.8 966.3 987.0 1012.5 2956.0 3679.8 3891.3 3993.6 4036.9 9864.9 10108.4 10133.3 10184.4 10278.7 9981.3 9986.6 10081.7 10103.2 10254.6 7027.2 7615.7 7825.8 7975.5 7999.5 5449.7 5465.0 5520.5 5748.2 5778.8 9137.2 9502.2 9518.0 9557.5 9653.9 9353.0 9461.4 9534.7 9656.3 9689.3 7176.7 7278.3 7315.2 7337.6 7382.3 6768.8 7484.3 7899.5 7959.3 7969.8 6874.7 7087.5 7186.9 7239.4 7335.4 6954.2 7071.8 7114.4 7187.2 7251.4 6666.1 6674.5 6737.7 6749.6 6750.4 4845.7 4889.7 4907.1 4947.4 4988.7 4842.5 4848.7 4864.1 5017.6 5029.6 09:59:48 INFO - PROCESS | 1630 | 09:59:48 INFO - PROCESS | 1630 | [#4] prototype.html Cycles:180 Average:4298.76 Median:4880.82 stddev:1318.04 (27.0%) stddev-sans-first:1287.12 09:59:48 INFO - PROCESS | 1630 | Values: 300.3 302.1 307.5 313.7 314.2 5129.5 5156.7 5170.5 5211.2 5224.2 4346.9 4736.2 4815.5 4957.3 5108.7 3929.9 3943.5 3957.7 3974.0 3991.7 3921.2 3964.4 3971.5 3996.3 3996.7 3796.8 3928.1 3940.2 3971.7 3972.3 2939.0 3741.8 3901.9 3938.3 3945.1 3443.4 3446.0 3448.8 3451.8 3458.2 5011.6 5027.8 5028.1 5062.4 5066.4 4095.8 4223.3 4456.0 4477.6 4486.9 4681.8 4877.9 4926.5 5160.8 5345.9 3165.9 3498.9 3573.3 3764.4 3797.7 4836.0 5025.3 5608.8 5615.4 5672.7 3150.6 3726.2 3728.7 3735.7 3752.5 3093.5 3703.1 3796.4 3808.6 3817.8 4105.8 4446.9 4469.2 4473.4 4487.6 4799.0 5378.4 5404.3 5425.7 5448.8 1079.7 1132.6 1231.5 1234.6 1240.4 677.7 745.5 767.9 814.0 816.4 4973.9 5194.4 5207.2 5229.7 5234.3 5554.7 5561.8 5567.2 5588.5 5601.3 4162.4 4178.9 4419.6 4656.9 5233.7 2757.0 2757.8 2762.5 2780.6 2792.6 5203.8 5574.4 5583.9 5635.6 5659.7 4805.9 4969.0 5367.6 5377.6 5402.8 4849.9 5085.8 5571.5 5579.9 5628.5 3916.2 4902.1 5084.8 5261.4 5298.6 4397.7 4965.5 5063.9 5147.9 5175.8 4813.7 5220.4 5535.2 5540.6 5599.8 2877.7 3128.0 3340.4 3503.9 3539.9 4610.1 4801.1 4867.0 5167.0 5253.7 4688.5 4979.4 5046.0 5089.6 5089.7 5061.4 5101.3 5131.8 5151.2 5176.2 4619.4 4883.7 5108.5 5326.6 5339.1 5344.4 5362.6 5363.8 5477.9 5504.4 4944.3 4989.3 5361.2 5369.2 5399.8 09:59:48 INFO - PROCESS | 1630 | 09:59:48 INFO - PROCESS | 1630 | [#5] yui.html Cycles:180 Average:19644.62 Median:4533.65 stddev:28267.76 (623.5%) stddev-sans-first:28310.84 09:59:48 INFO - PROCESS | 1630 | Values: 585.9 731.8 746.3 770.4 778.7 11907.9 14136.0 14612.9 14857.7 14998.6 13181.4 13205.0 13340.0 13358.7 13397.0 819.7 824.5 832.2 833.2 837.6 652.8 675.2 809.7 829.5 845.5 840.3 882.8 890.1 892.3 894.2 834.1 835.2 835.6 851.8 883.0 507.0 522.2 625.3 626.5 648.1 13907.7 13936.7 13942.5 13946.3 13968.5 12448.1 12487.5 12559.1 12692.2 13146.3 8994.4 10357.6 10731.1 11017.8 11330.2 4296.6 4334.9 4357.7 4362.7 4520.2 69009.0 91720.0 91761.5 92018.5 93290.1 10147.3 10250.8 10336.2 10411.9 10427.7 19127.1 19197.1 19199.5 19203.2 19242.4 7285.7 7588.1 7673.3 7938.3 7970.9 4240.8 4348.9 4356.3 4366.1 4545.0 3360.4 3473.7 3475.1 3481.4 3673.0 645.3 666.9 695.3 712.9 714.5 540.5 618.5 640.1 677.5 701.6 8890.8 9020.5 9098.0 9154.5 9182.4 545.8 560.5 649.3 655.3 669.6 4305.7 4446.6 4479.8 4522.3 4597.0 78857.2 84096.0 107005.5 111185.4 113288.0 77106.8 78782.0 88438.1 97702.5 101490.5 57249.7 80374.2 83274.6 83313.6 87003.7 9419.0 9622.8 9714.4 9738.6 9747.2 39953.6 51631.7 52748.7 53953.7 53969.9 70224.0 71099.0 72269.3 73142.6 75680.6 11535.4 12044.4 12218.4 12590.2 12611.0 13276.9 13662.1 14419.6 14472.8 15115.5 13192.9 13210.4 13625.7 13799.5 13834.7 12794.7 12845.8 13211.1 13739.5 13836.6 10416.8 12987.2 13117.4 13134.3 13229.1 10211.9 10390.9 10435.0 10795.8 10933.5 9001.4 11207.7 11221.8 11357.4 11485.5 09:59:48 INFO - PROCESS | 1630 | -------- Summary: end -------- 09:59:48 INFO - PROCESS | 1630 | 09:59:49 INFO - TEST-INFO | 1630: exit 0 09:59:49 INFO - TEST-OK | dromaeo_css | took 1119632ms 09:59:49 INFO - TEST-START | kraken 09:59:49 INFO - operating with platform_type : mac_ 09:59:49 INFO - Initialising browser for kraken test... 09:59:49 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/tmpWg460q/profile http://localhost:49236/getInfo.html) 09:59:51 INFO - PROCESS | 1640 | __metrics Screen width/height:1600/1200 09:59:51 INFO - PROCESS | 1640 | colorDepth:24 09:59:51 INFO - PROCESS | 1640 | Browser inner width/height: 1024/689 09:59:51 INFO - PROCESS | 1640 | __metrics 09:59:51 INFO - PROCESS | 1640 | 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" 09:59:51 INFO - PROCESS | 1640 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:59:52 INFO - TEST-INFO | 1640: exit 0 09:59:52 INFO - Browser initialized. 09:59:52 INFO - Running cycle 1/1 for kraken test... 09:59:52 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/tmpWg460q/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:00:05 INFO - PROCESS | 1641 | RSS: Main: 222953472 10:00:05 INFO - PROCESS | 1641 | 10:00:07 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection) 10:00:08 INFO - PROCESS | 1641 | RSS: Main: 272748544 10:00:08 INFO - PROCESS | 1641 | 10:00:11 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-dft) 10:00:12 INFO - PROCESS | 1641 | RSS: Main: 283353088 10:00:12 INFO - PROCESS | 1641 | 10:00:15 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-fft) 10:00:15 INFO - PROCESS | 1641 | RSS: Main: 380985344 10:00:15 INFO - PROCESS | 1641 | 10:00:17 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator) 10:00:18 INFO - PROCESS | 1641 | RSS: Main: 283615232 10:00:18 INFO - PROCESS | 1641 | 10:00:20 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:00:21 INFO - PROCESS | 1641 | RSS: Main: 285552640 10:00:21 INFO - PROCESS | 1641 | 10:00:23 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom) 10:00:24 INFO - PROCESS | 1641 | RSS: Main: 325058560 10:00:24 INFO - PROCESS | 1641 | 10:00:27 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate) 10:00:28 INFO - PROCESS | 1641 | RSS: Main: 326397952 10:00:28 INFO - PROCESS | 1641 | 10:00:31 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial) 10:00:31 INFO - PROCESS | 1641 | RSS: Main: 325120000 10:00:31 INFO - PROCESS | 1641 | 10:00:33 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:00:33 INFO - PROCESS | 1641 | RSS: Main: 300601344 10:00:33 INFO - PROCESS | 1641 | 10:00:35 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:00:36 INFO - PROCESS | 1641 | RSS: Main: 302931968 10:00:36 INFO - PROCESS | 1641 | 10:00:38 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:00:39 INFO - PROCESS | 1641 | RSS: Main: 305500160 10:00:39 INFO - PROCESS | 1641 | 10:00:42 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:00:42 INFO - PROCESS | 1641 | RSS: Main: 314093568 10:00:42 INFO - PROCESS | 1641 | 10:00:45 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:00:46 INFO - PROCESS | 1641 | RSS: Main: 312209408 10:00:46 INFO - PROCESS | 1641 | 10:00:48 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49236/tests/kraken/driver.html?testName=ai-astar) 10:00:48 INFO - PROCESS | 1641 | RSS: Main: 356331520 10:00:48 INFO - PROCESS | 1641 | 10:00:48 INFO - PROCESS | 1641 | __start_tp_report 10:00:48 INFO - PROCESS | 1641 | _x_x_mozilla_page_load 10:00:48 INFO - PROCESS | 1641 | _x_x_mozilla_page_load_details 10:00:48 INFO - PROCESS | 1641 | |i|pagename|runs| 10:00:48 INFO - PROCESS | 1641 | |0;ai-astar;115;115;116;114;115;115;113;115;115;114 10:00:48 INFO - PROCESS | 1641 | |1;audio-beat-detection;145;111;111;144;112;113;107;113;142;110 10:00:48 INFO - PROCESS | 1641 | |2;audio-dft;186;182;183;186;185;185;182;183;184;184 10:00:48 INFO - PROCESS | 1641 | |3;audio-fft;81;62;73;113;88;59;59;58;83;58 10:00:48 INFO - PROCESS | 1641 | |4;audio-oscillator;89;70;92;70;68;70;90;65;65;91 10:00:48 INFO - PROCESS | 1641 | |5;imaging-gaussian-blur;88;85;95;129;111;84;88;85;88;85 10:00:48 INFO - PROCESS | 1641 | |6;imaging-darkroom;95;109;149;148;93;106;143;93;94;94 10:00:48 INFO - PROCESS | 1641 | |7;imaging-desaturate;82;82;80;82;82;80;82;84;82;81 10:00:48 INFO - PROCESS | 1641 | |8;json-parse-financial;54;53;53;52;58;58;57;57;56;57 10:00:48 INFO - PROCESS | 1641 | |9;json-stringify-tinderbox;63;73;72;79;71;72;69;69;70;71 10:00:48 INFO - PROCESS | 1641 | |10;stanford-crypto-aes;84;83;84;84;83;83;82;81;85;83 10:00:48 INFO - PROCESS | 1641 | |11;stanford-crypto-ccm;145;127;144;153;154;157;142;143;229;140 10:00:48 INFO - PROCESS | 1641 | |12;stanford-crypto-pbkdf2;168;164;164;166;162;173;228;165;162;164 10:00:48 INFO - PROCESS | 1641 | |13;stanford-crypto-sha256-iterative;68;67;67;66;67;73;72;88;66;67 10:00:48 INFO - PROCESS | 1641 | __end_tp_report 10:00:48 INFO - PROCESS | 1641 | __start_cc_report 10:00:48 INFO - PROCESS | 1641 | _x_x_mozilla_cycle_collect,1996 10:00:48 INFO - PROCESS | 1641 | __end_cc_report 10:00:48 INFO - PROCESS | 1641 | __startTimestamp1482343248419__endTimestamp 10:00:48 INFO - PROCESS | 1641 | 10:00:48 INFO - PROCESS | 1641 | ------- Summary: start ------- 10:00:48 INFO - PROCESS | 1641 | Number of tests: 14 10:00:48 INFO - PROCESS | 1641 | 10:00:48 INFO - PROCESS | 1641 | [#0] ai-astar Cycles:10 Average:114.70 Median:115.00 stddev:0.82 (0.7%) stddev-sans-first:0.87 10:00:48 INFO - PROCESS | 1641 | Values: 115.0 115.0 116.0 114.0 115.0 115.0 113.0 115.0 115.0 114.0 10:00:48 INFO - PROCESS | 1641 | 10:00:48 INFO - PROCESS | 1641 | [#1] audio-beat-detection Cycles:10 Average:120.80 Median:113.00 stddev:15.89 (14.1%) stddev-sans-first:14.23 10:00:48 INFO - PROCESS | 1641 | Values: 145.0 111.0 111.0 144.0 112.0 113.0 107.0 113.0 142.0 110.0 10:00:48 INFO - PROCESS | 1641 | 10:00:48 INFO - PROCESS | 1641 | [#2] audio-dft Cycles:10 Average:184.00 Median:184.50 stddev:1.49 (0.8%) stddev-sans-first:1.39 10:00:48 INFO - PROCESS | 1641 | Values: 186.0 182.0 183.0 186.0 185.0 185.0 182.0 183.0 184.0 184.0 10:00:48 INFO - PROCESS | 1641 | 10:00:48 INFO - PROCESS | 1641 | [#3] audio-fft Cycles:10 Average:73.40 Median:67.50 stddev:18.11 (26.8%) stddev-sans-first:18.99 10:00:48 INFO - PROCESS | 1641 | Values: 81.0 62.0 73.0 113.0 88.0 59.0 59.0 58.0 83.0 58.0 10:00:48 INFO - PROCESS | 1641 | 10:00:48 INFO - PROCESS | 1641 | [#4] audio-oscillator Cycles:10 Average:77.00 Median:79.50 stddev:11.79 (14.8%) stddev-sans-first:11.67 10:00:48 INFO - PROCESS | 1641 | Values: 89.0 70.0 92.0 70.0 68.0 70.0 90.0 65.0 65.0 91.0 10:00:48 INFO - PROCESS | 1641 | 10:00:48 INFO - PROCESS | 1641 | [#5] imaging-gaussian-blur Cycles:10 Average:93.80 Median:86.50 stddev:14.78 (17.1%) stddev-sans-first:15.53 10:00:48 INFO - PROCESS | 1641 | Values: 88.0 85.0 95.0 129.0 111.0 84.0 88.0 85.0 88.0 85.0 10:00:48 INFO - PROCESS | 1641 | 10:00:48 INFO - PROCESS | 1641 | [#6] imaging-darkroom Cycles:10 Average:112.40 Median:93.00 stddev:24.33 (26.2%) stddev-sans-first:24.98 10:00:48 INFO - PROCESS | 1641 | Values: 95.0 109.0 149.0 148.0 93.0 106.0 143.0 93.0 94.0 94.0 10:00:48 INFO - PROCESS | 1641 | 10:00:48 INFO - PROCESS | 1641 | [#7] imaging-desaturate Cycles:10 Average:81.70 Median:82.00 stddev:1.16 (1.4%) stddev-sans-first:1.22 10:00:48 INFO - PROCESS | 1641 | Values: 82.0 82.0 80.0 82.0 82.0 80.0 82.0 84.0 82.0 81.0 10:00:48 INFO - PROCESS | 1641 | 10:00:48 INFO - PROCESS | 1641 | [#8] json-parse-financial Cycles:10 Average:55.50 Median:57.00 stddev:2.27 (4.0%) stddev-sans-first:2.35 10:00:48 INFO - PROCESS | 1641 | Values: 54.0 53.0 53.0 52.0 58.0 58.0 57.0 57.0 56.0 57.0 10:00:48 INFO - PROCESS | 1641 | 10:00:48 INFO - PROCESS | 1641 | [#9] json-stringify-tinderbox Cycles:10 Average:70.90 Median:71.50 stddev:3.98 (5.6%) stddev-sans-first:3.03 10:00:48 INFO - PROCESS | 1641 | Values: 63.0 73.0 72.0 79.0 71.0 72.0 69.0 69.0 70.0 71.0 10:00:48 INFO - PROCESS | 1641 | 10:00:48 INFO - PROCESS | 1641 | [#10] stanford-crypto-aes Cycles:10 Average:83.20 Median:83.50 stddev:1.14 (1.4%) stddev-sans-first:1.17 10:00:48 INFO - PROCESS | 1641 | Values: 84.0 83.0 84.0 84.0 83.0 83.0 82.0 81.0 85.0 83.0 10:00:48 INFO - PROCESS | 1641 | 10:00:48 INFO - PROCESS | 1641 | [#11] stanford-crypto-ccm Cycles:10 Average:153.40 Median:149.00 stddev:27.89 (18.7%) stddev-sans-first:29.42 10:00:48 INFO - PROCESS | 1641 | Values: 145.0 127.0 144.0 153.0 154.0 157.0 142.0 143.0 229.0 140.0 10:00:48 INFO - PROCESS | 1641 | 10:00:48 INFO - PROCESS | 1641 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.60 Median:165.50 stddev:20.08 (12.1%) stddev-sans-first:21.25 10:00:48 INFO - PROCESS | 1641 | Values: 168.0 164.0 164.0 166.0 162.0 173.0 228.0 165.0 162.0 164.0 10:00:48 INFO - PROCESS | 1641 | 10:00:48 INFO - PROCESS | 1641 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.10 Median:67.50 stddev:6.74 (10.0%) stddev-sans-first:7.11 10:00:48 INFO - PROCESS | 1641 | Values: 68.0 67.0 67.0 66.0 67.0 73.0 72.0 88.0 66.0 67.0 10:00:48 INFO - PROCESS | 1641 | -------- Summary: end -------- 10:00:48 INFO - PROCESS | 1641 | 10:00:49 INFO - TEST-INFO | 1641: exit 0 10:00:49 INFO - TEST-OK | kraken | took 60225ms 10:00:49 INFO - SUITE-END | took 1179s 10:00:49 INFO - Completed test suite (00:19:39) 10:00:49 INFO - javascript benchmark 10:00:49 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3203.19920019995, 3225.4194245035883, 3249.6262929763075, 3263.5477212640353, 3278.5410042594026, 5402.621816472849, 5412.864678383043, 5429.511343979041, 5468.644538105021, 5482.068048431771, 5454.209139674755, 5466.726663666821, 5494.615376923617, 5505.697186654733, 5512.586556008306, 5020.924686129711, 5023.271625614284, 5076.695398276115, 5076.873078173038, 5126.897462050775, 4993.5755460785695, 5006.974965125188, 5014.724190169539, 5095.9745201273945, 5130.333056702623, 5042.218456139304, 5053.519915608012, 5074.264231686422, 5082.339295891529, 5083.262926875601, 5084.720340381277, 5089.949100508985, 5123.538881500645, 5130.384353877558, 5185.0, 4857.368542089541, 5069.188929771219, 5070.974645126751, 5090.516400941905, 5093.770780314895, 4502.2796352583755, 5402.567794576464, 5408.026555220059, 5454.209139674765, 5459.07195776715, 4835.758212089381, 5847.502962248172, 5866.911996320058, 5868.735906884201, 5885.058390657474, 6025.517958563304, 6035.336113027563, 6047.395260473962, 6052.909206361908, 6078.209832721762, 5246.422893481714, 5285.735713214324, 5406.810761623325, 5456.6725996439545, 5567.47109024646, 6076.605020673642, 6084.787032453843, 6105.175801266861, 6111.938880611137, 6118.694065296718, 5735.11105778605, 5736.282964629421, 5737.3115226172295, 5745.511631511285, 5757.568182386254, 5815.534757219412, 5858.8535286617325, 5859.1797148399255, 5863.413658634187, 5927.644341339534, 4853.3447984522345, 5759.452851979056, 5803.912941305884, 5809.0124678804705, 5838.620489668158, 5848.853778655568, 5856.472917437451, 5858.970705146447, 5867.002609556385, 5874.823755287262, 4677.64917631179, 4695.85912422621, 4714.15145273854, 4720.984988227548, 4731.787069581877, 4238.830446782094, 4258.574142585779, 4287.892802679958, 4314.460692413449, 4339.522652508222, 5607.887842243133, 5656.60403771732, 5663.405342438985, 5669.744861481243, 5677.687727175045, 5810.273715785527, 5986.73059712314, 6016.217891674054, 6027.276726792813, 6048.4858787003595, 5539.335279766373, 5615.326160860723, 5616.410276920865, 5621.494065534122, 5624.184493248456, 5180.6891586504935, 5186.040582492251, 5187.740612969412, 5190.870228244248, 5192.350956130484, 6308.936910630927, 6337.96831015842, 6338.049292606146, 6339.778107766206, 6343.270523889812, 6048.032314829607, 6097.146399503984, 6111.449969502766, 6120.479759220338, 6136.325004249618, 5052.797888084436, 6327.240731112296, 6374.171357723466, 6396.0405939109505, 6396.552241343062, 4695.413073365829, 5742.0, 5766.481016708516, 5897.67562784051, 5902.4097590240635, 5894.174815525744, 5898.852528686817, 5917.142014407973, 5949.940500595112, 5964.254468191476, 5809.30288365382, 5818.301803783573, 5834.883302333845, 5835.241418615553, 5859.384764599656, 5800.187973683604, 5809.099589563624, 5823.534117270524, 5825.359210486928, 5838.211841401357, 4728.479867214535, 5383.273258110105, 5537.944620553904, 5642.548596112383, 5702.287214098238, 5795.652260864362, 5820.82537523875, 5837.153612726216, 5861.1501332307425, 5883.705814709162, 5694.288213973254, 5699.116636921284, 5699.202111704448, 5707.372189059283, 5764.855878602901], "value": 5450.990463346139, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [461.20902651951894, 463.52025653448686, 464.51690242148146, 492.29110081482673, 530.4138926486232, 29263.0, 30712.846435767817, 30727.0, 38232.61767382325, 39312.80343598285, 40273.79863100687, 40614.59385406145, 40642.57750978717, 40687.0, 40789.59210407895, 8013.95993020037, 9217.769555761093, 9371.250299975985, 9384.530773461334, 9402.952985235064, 9317.0, 9381.061893810633, 9419.48192849393, 9430.43417394955, 9431.81136377272, 9408.106229908148, 9444.441667124916, 9454.952725236364, 9460.195883349923, 9460.337776355658, 9371.203447706954, 9422.670206542738, 9431.764205894839, 9435.05649435051, 9440.763980900498, 9273.026332235153, 9324.414849475605, 9345.813083738356, 9402.90597094034, 9405.153536181708, 31618.841905790323, 38479.61520384788, 38727.612723872684, 38830.223395532215, 38911.832645020695, 3169.8573564189437, 3256.560364350806, 3282.5404443377947, 3437.4671925851535, 3440.552728145325, 28775.0, 28885.988990385446, 29022.709772902424, 29326.26684332909, 29420.8528957356, 3128.186671465435, 3187.5218717192374, 3227.564278822352, 3261.9184520386943, 3331.900042998714, 437621.0, 439197.0, 439584.0, 441248.0, 441512.79243604216, 27323.863380683368, 58497.0, 58920.99999999914, 58960.82078358409, 58984.0, 33401.83299083489, 34579.827100864335, 34593.82703086468, 34648.30703385918, 34661.48007779886, 14036.017478776388, 14167.433302667983, 14176.0, 14202.28988550589, 14238.28808559568, 11846.289222646496, 11942.761144777056, 11964.64106076819, 11970.40147992597, 11975.041996640248, 7697.0, 7744.612769361509, 7778.8833167502535, 7798.922010779819, 7798.961005195051, 2810.8875644974382, 3207.951880721791, 3271.7424976766433, 3272.443684573628, 3284.441644920369, 753.4123383760675, 895.5253715530779, 923.8521836506127, 961.1829944547079, 1014.2899970020867, 28099.859500702365, 28670.569941450896, 28714.569281460794, 29233.0, 29406.705932940826, 820.3724151024471, 1009.5810238750983, 1012.2104758288547, 1013.5287091502487, 1052.3002203534718, 14486.565403037926, 15027.398904043934, 15118.546443606709, 15448.459303924308, 15477.303521341566, 655811.7209413922, 658040.0, 658471.0, 658711.0, 659162.0, 54623.726881366136, 55341.44658553443, 55388.72305638446, 55458.722706387016, 55516.0, 1227.1593958138526, 1257.3587470390166, 1358.4973559782943, 1367.9589612311647, 1396.0785881318282, 29162.854185729786, 29779.851100744356, 30061.248468788453, 30074.947376841705, 30776.846115769276, 46735.29897051615, 47108.29337560008, 47311.76344118257, 47331.526684732715, 47458.81352966203, 516521.0, 521421.0, 522225.0, 682913.0, 700738.0, 39286.80356598199, 39595.0, 39669.2066158681, 39962.40056399098, 39982.000449988984, 42726.93182670458, 42908.78545607377, 42942.0, 43211.56788432076, 43791.56208438002, 36469.08827279339, 36475.81762091172, 37829.43255851109, 39299.60700393074, 40487.0, 36474.717275445335, 37046.44430333601, 37061.0, 37811.43282850814, 38132.42801358037, 35765.0, 36378.636213637525, 37211.44182837205, 37302.253954920205, 37363.62636373602, 32509.0, 33161.66838331682, 33890.66109338875, 33892.15269618279, 35223.82388088043, 24563.017479300626, 24673.25980220596, 24695.259142225044, 25195.622065668664, 25217.621735674347], "value": 18156.0196839129, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.46097201767316, 370.1523511159445, 376.3526734017491, 379.2585495356577, 402.7221217360021, 14954.626134346634, 15263.923680381597, 15876.682466350667, 19562.41312760616, 20603.793962060376, 19707.916064616442, 20148.093335799887, 20754.27360042399, 20844.791552084476, 21021.26425575105, 4853.878653033667, 5925.525957923366, 6167.4757645600175, 6183.103449999748, 6190.41191086846, 6060.33336333003, 6092.969535152318, 6134.4478996890275, 6145.108959200936, 6166.969165154191, 5865.120231965218, 5876.059830427112, 5974.312954010301, 6057.454829065383, 6059.575829691923, 5801.941980580204, 5934.139549765303, 5985.850353741148, 6005.579609427342, 6008.609440386383, 4934.8766280843265, 5172.5344718975475, 5228.346456692913, 5244.81643142492, 5275.393329767049, 23215.187468438693, 24661.753382466304, 25168.496630067297, 25287.11495097681, 25816.225513234633, 2236.2732112063645, 2238.5746708125566, 2398.844075135128, 2749.2714430675887, 2807.05963502227, 15140.924295378563, 15234.847651523565, 15405.460808871749, 16177.676446471123, 16589.0, 1857.2663797799928, 1864.0586503815487, 1870.6366728325063, 1879.20133943075, 1935.680612698903, 99054.0, 114673.0, 119193.0, 122119.0, 122121.0, 10745.355278683304, 10978.670639880816, 11462.254953428, 11533.76932461346, 11724.70688232801, 14344.0, 14349.210793406462, 14350.21073840928, 14358.784618230526, 14365.569032929028, 8361.498310101291, 8562.657493700306, 8636.0, 8766.956165219133, 8802.427842190365, 8535.359848011423, 8576.142385761374, 8577.442466239674, 8773.473591584525, 8775.473471591726, 6658.600483970978, 6735.764248251386, 6775.1531058617675, 6778.8644227116165, 6790.694418751168, 1759.9296028158985, 1846.3353192850564, 1846.9353572625162, 1847.7228415737748, 1850.4726153046317, 887.8224355129, 901.2384535067857, 974.468914441631, 1007.6725064354114, 1015.3755440404246, 20735.792642073688, 22243.22148724787, 22278.88860555687, 22339.77660223377, 22418.887905560696, 771.8070482379405, 944.631593678466, 970.1705042188968, 999.0159692702682, 1043.4574021508772, 11154.051905587954, 11787.882121178678, 11788.938995490447, 11867.58463453775, 11977.0, 551576.0, 561499.1925040348, 562372.0, 573777.0, 574400.0, 84274.0, 86878.0, 90837.54581227052, 105141.47429262957, 111801.0, 932.1936524905908, 939.8214339275653, 976.6435251133286, 978.8238117138841, 980.0493521284343, 19494.805051949297, 20333.642169516217, 20451.28420505275, 20544.28095016727, 20583.176672933514, 46495.605131846096, 49308.0, 50136.74931625318, 51062.23406648978, 51366.74316628393, 74733.626331868, 75242.62378688072, 77162.61418692871, 77375.0, 78096.60951695206, 6734.528582999143, 7068.9293107070325, 8023.1976802319305, 8255.380846436421, 8428.157184281768, 12084.274943503418, 12361.134720569475, 13321.267330296927, 13628.591142265748, 13830.170189788645, 11193.0, 11299.491522881326, 11745.765084698429, 11779.764404711686, 11856.881431185579, 11137.331760094421, 11368.886311136783, 11382.772344552897, 11445.198836081394, 11749.471273792871, 11714.472848721658, 11714.531418743154, 11716.765664686489, 11825.526978920747, 11900.821487677518, 7007.614581198083, 7422.888656670262, 7481.551106933602, 8266.834663306821, 11757.882421175678, 9506.619735210514, 11071.058959988492, 11283.774324513628, 11711.180217385043, 12116.27302361861], "value": 9733.989297651613, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1331.3942156318876, 1459.2193176650492, 1599.3043026283567, 1765.3644687912358, 1835.6145209505994, 8042.396820238484, 8476.449030812993, 8478.067412584618, 8481.91518084819, 8494.6177422016, 6909.896351554731, 7151.642417879111, 7203.171635261933, 7881.05427348719, 8082.110967793538, 2099.601075795598, 2298.9425264368356, 2310.87290199039, 2338.695969523968, 2348.412896775806, 2351.341624345186, 2364.243442098529, 2406.8194885383577, 2412.0592968742117, 2415.9516809663796, 2070.3995841206033, 2151.4943988162768, 2287.359539328982, 2349.48311371498, 2357.4695693469002, 2340.2277248508035, 2361.480474295652, 2361.8819059046978, 2368.9052437902465, 2369.1707902234252, 2357.233898982837, 2397.676313697646, 2403.07481619576, 2404.7955923746504, 2410.1684918703018, 8262.710805121853, 8436.535990520519, 8444.788880278042, 8457.112003239632, 8464.111268316861, 5085.491450854922, 5253.133233016548, 5261.921071183935, 5262.447443018505, 5266.789328426897, 3354.798712077283, 3365.528825964367, 3582.6059133495296, 3648.72634552407, 3649.59854416014, 1072.4530489450358, 1073.4096247064163, 1103.2056919018294, 1116.0234794554765, 1116.8548088748412, 10812.891871081189, 10916.563337466492, 10999.945000275027, 11049.005589496985, 11066.446677666085, 3961.960380396159, 4497.572730590588, 4586.220342541776, 4731.1720448921305, 4792.688475249098, 9224.723258302261, 9414.29392795543, 9440.055994400505, 9443.622255109718, 9454.90545094554, 5259.47405259471, 5261.789528418821, 5263.105272103751, 5267.0256002639635, 5273.499017593323, 4735.337052812609, 4790.808367665324, 4818.445878723992, 4838.250071238964, 4843.660943733905, 3754.3992961126087, 3813.5423749149722, 3860.3051450738576, 3872.283627528916, 3889.1638297766117, 1242.2918936206277, 1250.0749445410513, 1256.108163204117, 1270.6886812730754, 1278.673938145767, 629.5184184099168, 708.3129364516411, 880.8502554565739, 901.855703087516, 962.5620342744035, 5402.297701298806, 5554.583406244547, 5593.3847276799515, 5835.94164058354, 5852.707364631751, 943.1747221181466, 946.7869729310987, 966.2656381150376, 987.017917172418, 1012.5342342522375, 2955.9801868355385, 3679.816009199529, 3891.3072813326294, 3993.6405723485027, 4036.8587099451966, 9864.901350986542, 10108.444035578113, 10133.2906696532, 10184.439855807854, 10278.691639250685, 9981.251406144569, 9986.550605222783, 10081.747956301151, 10103.19174466041, 10254.58981640741, 7027.15674119109, 7615.65729542183, 7825.843483130306, 7975.481593696507, 7999.520028798291, 5449.6730196188355, 5465.0, 5520.53075488588, 5748.223989761496, 5778.768849246151, 9137.177654011171, 9502.239820814457, 9517.952410237904, 9557.474338911426, 9653.855192172263, 9352.953235233781, 9461.38500997433, 9534.713958581253, 9656.275779316438, 9689.321747477608, 7176.677049532888, 7278.272172782891, 7315.15875674283, 7337.559746415232, 7382.335589796944, 6768.763093291711, 7484.251574842472, 7899.486533375542, 7959.283664470226, 7969.80075498094, 6874.6562671867205, 7087.539309944837, 7186.856262874818, 7239.384652304613, 7335.449841262033, 6954.218312674971, 7071.7878463646175, 7114.430845532242, 7187.1734750504365, 7251.383632391095, 6666.066750655009, 6674.466042716668, 6737.73049078051, 6749.5612785168805, 6750.3924646782025, 4845.685030473008, 4889.706617602955, 4907.067657145274, 4947.356843610308, 4988.650794444499, 4842.4673285939225, 4848.7090774553635, 4864.075825593126, 5017.57350625201, 5029.59763218949], "value": 4439.857053593101, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.28831668944605, 302.11781597734625, 307.4957070404536, 313.74116354007947, 314.15648982482037, 5129.512696293851, 5156.742162891859, 5170.534651881339, 5211.244369566411, 5224.190250511172, 4346.86959391218, 4736.171170045237, 4815.518448155191, 4957.305977163191, 5108.69347839129, 3929.941050884239, 3943.487346644932, 3957.7031722620773, 3974.0, 3991.680665546764, 3921.1961547882615, 3964.3657014877626, 3971.463852379921, 3996.300647386696, 3996.660283875874, 3796.8481260749536, 3928.135810121783, 3940.2316548272956, 3971.6624086952643, 3972.324704800191, 2938.9559156612668, 3741.8316175772156, 3901.9464744519105, 3938.2714197873483, 3945.1123497213184, 3443.4146195146886, 3446.0, 3448.8448019838916, 3451.8274086295582, 3458.239187378773, 5011.646855349072, 5027.7822193796, 5028.139588352296, 5062.392512898439, 5066.392032956032, 4095.795210239476, 4223.282042052828, 4455.977720111411, 4477.6417886569325, 4486.868737349765, 4681.836135735233, 4877.902441951177, 4926.4827193144565, 5160.845174644729, 5345.946540534623, 3165.857536410867, 3498.9475157872653, 3573.285342931398, 3764.4353346997896, 3797.708779015148, 4835.975820120912, 5025.321581586512, 5608.794109266527, 5615.410381909923, 5672.744726487318, 3150.5664922460223, 3726.2361215950664, 3728.7389882708494, 3735.6824669903363, 3752.4746535485056, 3093.474109401362, 3703.055720791181, 3796.411556208737, 3808.647929984871, 3817.7900215488417, 4105.753654780723, 4446.933296000562, 4469.173202957463, 4473.3960915276675, 4487.618552423015, 4799.016201678647, 5378.428335914519, 5404.32445944257, 5425.694080015977, 5448.754806033738, 1079.6868908016745, 1132.5866058888446, 1231.4520038582746, 1234.623439850854, 1240.3922078181627, 677.7153595489906, 745.4818900863957, 767.9155292917775, 813.9540690213048, 816.3510009542427, 4973.875653108701, 5194.4026436959475, 5207.229541955753, 5229.712365819916, 5234.293370395024, 5554.722413844838, 5561.77752889888, 5567.220589117527, 5588.524975377057, 5601.29983752031, 4162.41726158338, 4178.874633760992, 4419.624331931822, 4656.8602941911795, 5233.738313084331, 2757.0350377368163, 2757.7793776497833, 2762.516559602102, 2780.6107144999714, 2792.6369571955515, 5203.7918483261, 5574.414686457944, 5583.888322233533, 5635.633683810539, 5659.745311460994, 4805.927911081337, 4969.0310389474425, 5367.624266301399, 5377.596680248997, 5402.783888644489, 4849.854504364875, 5085.821996230115, 5571.526420254324, 5579.8884022320135, 5628.493435590734, 3916.195583067699, 4902.14212512816, 5084.7966081356335, 5261.394939581844, 5298.602604804578, 4397.691212113666, 4965.4786247444945, 5063.873403164803, 5147.897042059213, 5175.81884634036, 4813.711177329372, 5220.399654039834, 5535.225068490334, 5540.556755459473, 5599.800149887585, 2877.7337971292563, 3127.984360078276, 3340.365330587181, 3503.8948831535095, 3539.876104336439, 4610.118675827608, 4801.063128234814, 4866.95133048665, 5166.974165129301, 5253.658512196695, 4688.507706690884, 4979.377577802775, 5046.038859495278, 5089.643724939367, 5089.694618322913, 5061.417936937152, 5101.336826212569, 5131.79472821083, 5151.175811870083, 5176.221358408307, 4619.422572178478, 4883.731394773179, 5108.514691104339, 5326.627136100436, 5339.102455207798, 5344.35867695879, 5362.570994320523, 5363.785448582169, 5477.890442191214, 5504.403722920309, 4944.283078953604, 4989.25161225819, 5361.24942508057, 5369.221462887937, 5399.811006614907], "value": 3872.4739314418034, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [585.9179714839923, 731.7512045904394, 746.2872956326709, 770.3606007014179, 778.7235531386358, 11907.880921190796, 14136.0, 14612.926935365322, 14857.702845943075, 14998.62503437412, 13181.406836692347, 13205.0, 13340.0, 13358.66603334917, 13397.0, 819.6721311475413, 824.5341382119084, 832.238501770882, 833.2001278772386, 837.6398148796015, 652.7943697735222, 675.2133764164743, 809.6761295481825, 829.4898637338054, 845.4546817302836, 840.2605706977881, 882.7925437522177, 890.1054440287502, 892.2594246775161, 894.239896088323, 834.1408349400098, 835.1606635331483, 835.5905606252892, 851.7700220940374, 882.9889776206272, 507.036630402235, 522.1749635575819, 625.2528228766625, 626.4706323156925, 648.1153225846685, 13907.721845563134, 13936.651583710489, 13942.512012079529, 13946.302684865715, 13968.511102111377, 12448.06639502032, 12487.50049997999, 12559.120861539695, 12692.238465692088, 13146.276954767483, 8994.415363001384, 10357.585696572129, 10731.141508679288, 11017.779644407146, 11330.206885518017, 4296.613304802551, 4334.891627709301, 4357.651387888993, 4362.694611377205, 4520.163769702583, 69009.0, 91720.0, 91761.54119229362, 92018.5399073007, 93290.13419731635, 10147.340422872638, 10250.821155567057, 10336.17310615149, 10411.94794026025, 10427.68716938493, 19127.13927873249, 19197.136128874234, 19199.52001199953, 19203.231870725293, 19242.422727318204, 7285.744998925013, 7588.127365352944, 7673.271039251262, 7938.285554300025, 7970.880436793453, 4240.787960601958, 4348.869533913988, 4356.259435895905, 4366.126774645084, 4545.045540436546, 3360.3615313090436, 3473.7221022318154, 3475.14858859581, 3481.408160612702, 3673.0, 645.3320812958659, 666.8699603577256, 695.2942763095463, 712.9358357747827, 714.4641518860855, 540.497337476151, 618.4681174190193, 640.1038546035587, 677.502036003537, 701.6035939694057, 8890.777730556787, 9020.524142847122, 9098.0, 9154.542272886329, 9182.449053056704, 545.8309806135887, 560.4647561578682, 649.302000349626, 655.3348351423309, 669.5714742564762, 4305.698601097955, 4446.644268458515, 4479.843205487858, 4522.253828118323, 4596.954030459719, 78857.21142788614, 84096.0, 107005.4649726762, 111185.44407277912, 113288.0, 77106.84339734909, 78782.0, 88438.11561884299, 97702.51148744211, 101490.49254753826, 57249.71375143181, 80374.19625803667, 83274.56722245376, 83313.58343208366, 87003.69494457588, 9418.952905235705, 9622.807543848943, 9714.417134971924, 9738.561764720745, 9747.220222382051, 39953.60046399499, 51631.70920727012, 52748.73625631847, 53953.651158721346, 53969.920601588536, 70224.0, 71099.0, 72269.27730722836, 73142.63428682822, 75680.62159689167, 11535.423228838692, 12044.397780111134, 12218.38908054576, 12590.181638193488, 12611.0, 13276.867231327564, 13662.111962722789, 14419.567412977627, 14472.782908256175, 15115.470958516398, 13192.868071319164, 13210.405531751287, 13625.727485450434, 13799.51701690436, 13834.723305533631, 12794.744105118032, 12845.807312890502, 13211.14127581704, 13739.450421983009, 13836.584902452943, 10416.843747343644, 12987.155834870704, 13117.409716562586, 13134.343282836011, 13229.07396482237, 10211.897881021094, 10390.948045259725, 10435.0, 10795.838062429228, 10933.45332733376, 9001.36990410665, 11207.66377008691, 11221.831672525082, 11357.432128393382, 11485.540578377106], "value": 7202.12444358189, "unit": "score"}], "name": "dromaeo_css", "value": 7016.308665011417, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 115.0, 116.0, 114.0, 115.0, 115.0, 113.0, 115.0, 115.0, 114.0], "value": 114.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 111.0, 111.0, 144.0, 112.0, 113.0, 107.0, 113.0, 142.0, 110.0], "value": 120.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 182.0, 183.0, 186.0, 185.0, 185.0, 182.0, 183.0, 184.0, 184.0], "value": 184.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 62.0, 73.0, 113.0, 88.0, 59.0, 59.0, 58.0, 83.0, 58.0], "value": 73.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 70.0, 92.0, 70.0, 68.0, 70.0, 90.0, 65.0, 65.0, 91.0], "value": 77.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 95.0, 129.0, 111.0, 84.0, 88.0, 85.0, 88.0, 85.0], "value": 93.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 109.0, 149.0, 148.0, 93.0, 106.0, 143.0, 93.0, 94.0, 94.0], "value": 112.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 80.0, 82.0, 82.0, 80.0, 82.0, 84.0, 82.0, 81.0], "value": 81.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 53.0, 52.0, 58.0, 58.0, 57.0, 57.0, 56.0, 57.0], "value": 55.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 73.0, 72.0, 79.0, 71.0, 72.0, 69.0, 69.0, 70.0, 71.0], "value": 70.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 84.0, 84.0, 83.0, 83.0, 82.0, 81.0, 85.0, 83.0], "value": 83.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 127.0, 144.0, 153.0, 154.0, 157.0, 142.0, 143.0, 229.0, 140.0], "value": 153.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 164.0, 164.0, 166.0, 162.0, 173.0, 228.0, 165.0, 162.0, 164.0], "value": 171.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 67.0, 66.0, 67.0, 73.0, 72.0, 88.0, 66.0, 67.0], "value": 70.1, "unit": "score"}], "name": "kraken", "value": 1462.4999999999998, "alertThreshold": 2.0}]} 10:00:49 INFO - Return code: 0 10:00:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:00:49 INFO - # TBPL SUCCESS # 10:00:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:00:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:00:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:00:49 INFO - 2016-12-21 10:00:49.779 screenresolution[1645:9660] starting screenresolution argv=screenresolution get 10:00:49 INFO - 2016-12-21 10:00:49.784 screenresolution[1645:9660] Display 0: 1600x1200x32@60 10:00:49 INFO - 2016-12-21 10:00:49.790 screenresolution[1646:9663] starting screenresolution argv=screenresolution list 10:00:49 INFO - Available Modes on Display 0 10:00:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:00:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:00:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:00:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:00:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:00:50 INFO - Intel Iris: 10:00:50 INFO - Chipset Model: Intel Iris 10:00:50 INFO - Type: GPU 10:00:50 INFO - Bus: Built-In 10:00:50 INFO - VRAM (Dynamic, Max): 1536 MB 10:00:50 INFO - Vendor: Intel (0x8086) 10:00:50 INFO - Device ID: 0x0a2e 10:00:50 INFO - Revision ID: 0x0009 10:00:50 INFO - Displays: 10:00:50 INFO - Display: 10:00:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:00:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:00:50 INFO - Display Serial Number: 1600x1200 60 10:00:50 INFO - Main Display: Yes 10:00:50 INFO - Mirror: Off 10:00:50 INFO - Online: Yes 10:00:50 INFO - Rotation: Supported 10:00:50 INFO - Return code: 0 10:00:50 INFO - Running post-action listener: _resource_record_post_action 10:00:50 INFO - [mozharness: 2016-12-21 18:00:50.034165Z] Finished run-tests step (success) 10:00:50 INFO - Running post-run listener: _resource_record_post_run 10:00:50 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 36631552; Write bytes: 652748288; Read time: 234; Write time: 1815 10:00:50 INFO - TinderboxPrint: CPU usage
53.5% 10:00:50 INFO - TinderboxPrint: I/O read bytes / time
36,631,552 / 234 10:00:50 INFO - TinderboxPrint: I/O write bytes / time
652,748,288 / 1,815 10:00:50 INFO - TinderboxPrint: CPU idle
2,251.0 (46.5%) 10:00:50 INFO - TinderboxPrint: CPU system
970.3 (20.0%) 10:00:50 INFO - TinderboxPrint: CPU user
1,624.5 (33.5%) 10:00:50 INFO - TinderboxPrint: Swap in / out
385,175,552 / 0 10:00:50 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 349747712; Write bytes: 352052736; Read time: 26960; Write time: 704 10:00:50 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 34395136; Write bytes: 299372544; Read time: 213; Write time: 1105 10:00:50 INFO - Running post-run listener: _upload_blobber_files 10:00:50 INFO - Blob upload gear active. 10:00:50 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:00:50 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 10:00:50 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'] 10:00:50 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 10:00:50 INFO - (blobuploader) - INFO - Open directory for files ... 10:00:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:00:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:00:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:00:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:00:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:00:51 INFO - (blobuploader) - INFO - Done attempting. 10:00:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:00:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:00:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:00:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:00:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:00:52 INFO - (blobuploader) - INFO - Done attempting. 10:00:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:00:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:00:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:00:54 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:00:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:00:54 INFO - (blobuploader) - INFO - Done attempting. 10:00:54 INFO - (blobuploader) - INFO - Iteration through files over. 10:00:54 INFO - Return code: 0 10:00:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:00:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:00:54 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/582cf2eddc6150e0379ff0985aa6466e92b40fa7d8507d15ca22a288b61bbff962ea5c39df0738ed6cd85fd70f1b89f7d7694c61feb8a7b04c07ec77fa6bb900", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b22dcd9e4de9dfc312a068d157619ed8c2e851970a17eccc456a2fd1c15de7b175dbfd0f66a6879505adca98a141bdf1d67564e1c617d61c7be63132f3c81413", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/20af696deacb4783d0d6c00ff02f2bd72cc70d0f9446c82149330d41a3fe657223087837c4e8fd2753e4935b90427dd819fcc0de5ccf5f48c3f756d947494549"} 10:00:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:00:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:00:54 INFO - Contents: 10:00:54 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/582cf2eddc6150e0379ff0985aa6466e92b40fa7d8507d15ca22a288b61bbff962ea5c39df0738ed6cd85fd70f1b89f7d7694c61feb8a7b04c07ec77fa6bb900", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b22dcd9e4de9dfc312a068d157619ed8c2e851970a17eccc456a2fd1c15de7b175dbfd0f66a6879505adca98a141bdf1d67564e1c617d61c7be63132f3c81413", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/20af696deacb4783d0d6c00ff02f2bd72cc70d0f9446c82149330d41a3fe657223087837c4e8fd2753e4935b90427dd819fcc0de5ccf5f48c3f756d947494549"} 10:00:54 INFO - Running post-run listener: copy_logs_to_upload_dir 10:00:54 INFO - Copying logs to upload dir... 10:00:54 INFO - mkdir: /builds/slave/test/build/upload/logs 10:00:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.848021 ========= master_lag: 0.09 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-12-21 10:00:55.040205) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-21 10:00:55.048190) ========= 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.w2Pr9vPCgP/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.5uUsINHt96/Listeners TMPDIR=/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/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/582cf2eddc6150e0379ff0985aa6466e92b40fa7d8507d15ca22a288b61bbff962ea5c39df0738ed6cd85fd70f1b89f7d7694c61feb8a7b04c07ec77fa6bb900", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b22dcd9e4de9dfc312a068d157619ed8c2e851970a17eccc456a2fd1c15de7b175dbfd0f66a6879505adca98a141bdf1d67564e1c617d61c7be63132f3c81413", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/20af696deacb4783d0d6c00ff02f2bd72cc70d0f9446c82149330d41a3fe657223087837c4e8fd2753e4935b90427dd819fcc0de5ccf5f48c3f756d947494549"} build_url:https://queue.taskcluster.net/v1/task/cha5FFBzTy2P3AtTOnYT8g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010852 build_url: 'https://queue.taskcluster.net/v1/task/cha5FFBzTy2P3AtTOnYT8g/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/582cf2eddc6150e0379ff0985aa6466e92b40fa7d8507d15ca22a288b61bbff962ea5c39df0738ed6cd85fd70f1b89f7d7694c61feb8a7b04c07ec77fa6bb900", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b22dcd9e4de9dfc312a068d157619ed8c2e851970a17eccc456a2fd1c15de7b175dbfd0f66a6879505adca98a141bdf1d67564e1c617d61c7be63132f3c81413", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/20af696deacb4783d0d6c00ff02f2bd72cc70d0f9446c82149330d41a3fe657223087837c4e8fd2753e4935b90427dd819fcc0de5ccf5f48c3f756d947494549"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-21 10:00:55.082924) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 10:00:55.083288) ========= 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.w2Pr9vPCgP/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.5uUsINHt96/Listeners TMPDIR=/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/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.005060 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 10:00:55.131898) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-21 10:00:55.132210) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-21 10:00:55.132547) ========= ========= Total master_lag: 1.02 =========