builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0102 starttime: 1480061649.85 results: success (0) buildid: 20161124215919 builduid: 4bdd6ca568b440bd83e6532cc3c36271 revision: 55b599ca3530c8286f6ad15405d99d2bcaecfbd1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-25 00:14:09.846342) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-25 00:14:09.847129) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-25 00:14:09.847641) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-25 00:14:09.875083) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 00:14:09.875719) ========= 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.Qi62bvemR9/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.70e58qcsqp/Listeners TMPDIR=/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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-11-25 00:14:09-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 16.7M=0.001s 2016-11-25 00:14:10 (16.7 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.702186 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 00:14:10.602915) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 00:14:10.603248) ========= 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.Qi62bvemR9/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.70e58qcsqp/Listeners TMPDIR=/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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.019982 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 00:14:10.657435) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-25 00:14:10.657785) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 55b599ca3530c8286f6ad15405d99d2bcaecfbd1 --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 55b599ca3530c8286f6ad15405d99d2bcaecfbd1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Qi62bvemR9/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.70e58qcsqp/Listeners TMPDIR=/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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-11-25 00:14:10,737 truncating revision to first 12 chars 2016-11-25 00:14:10,737 Setting DEBUG logging. 2016-11-25 00:14:10,737 attempt 1/10 2016-11-25 00:14:10,738 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/55b599ca3530?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-25 00:14:11,464 unpacking tar archive at: mozilla-aurora-55b599ca3530/testing/mozharness/ program finished with exit code 0 elapsedTime=0.984520 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-25 00:14:11.666510) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-25 00:14:11.666820) ========= script_repo_revision: 55b599ca3530c8286f6ad15405d99d2bcaecfbd1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-25 00:14:11.667230) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-25 00:14:11.667492) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-25 00:14:11.688288) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-11-25 00:14:11.688684) ========= /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.Qi62bvemR9/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.70e58qcsqp/Listeners TMPDIR=/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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 00:14:11 INFO - MultiFileLogger online at 20161125 00:14:11 in /builds/slave/test 00:14:11 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 00:14:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 00:14:11 INFO - {'append_to_log': False, 00:14:11 INFO - 'base_work_dir': '/builds/slave/test', 00:14:11 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 00:14:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 00:14:11 INFO - 'branch': 'Mozilla-Aurora', 00:14:11 INFO - 'buildbot_json_path': 'buildprops.json', 00:14:11 INFO - 'config_files': ('talos/mac_config.py',), 00:14:11 INFO - 'default_actions': ('clobber', 00:14:11 INFO - 'read-buildbot-config', 00:14:11 INFO - 'download-and-extract', 00:14:11 INFO - 'populate-webroot', 00:14:11 INFO - 'create-virtualenv', 00:14:11 INFO - 'install', 00:14:11 INFO - 'run-tests'), 00:14:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 00:14:11 INFO - 'download_minidump_stackwalk': True, 00:14:11 INFO - 'download_symbols': 'ondemand', 00:14:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 00:14:11 INFO - 'tooltool.py': '/tools/tooltool.py', 00:14:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 00:14:11 INFO - '/tools/misc-python/virtualenv.py')}, 00:14:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 00:14:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 00:14:11 INFO - 'installer_path': 'installer.exe', 00:14:11 INFO - 'log_level': 'info', 00:14:11 INFO - 'log_name': 'talos', 00:14:11 INFO - 'log_to_console': True, 00:14:11 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 00:14:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 00:14:11 INFO - 'opt_config_files': (), 00:14:11 INFO - 'pip_index': False, 00:14:11 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 00:14:11 INFO - 'cmd': ('bash', 00:14:11 INFO - '-c', 00:14:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 00:14:11 INFO - 'enabled': True, 00:14:11 INFO - 'halt_on_failure': False, 00:14:11 INFO - 'name': 'check_screen_resolution'},), 00:14:11 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 00:14:11 INFO - 'cmd': ('bash', 00:14:11 INFO - '-c', 00:14:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 00:14:11 INFO - 'enabled': True, 00:14:11 INFO - 'halt_on_failure': False, 00:14:11 INFO - 'name': 'check_screen_resolution'},), 00:14:11 INFO - 'run_cmd_checks_enabled': True, 00:14:11 INFO - 'sps_profile': False, 00:14:11 INFO - 'sps_profile_interval': 0, 00:14:11 INFO - 'suite': 'dromaeojs', 00:14:11 INFO - 'system_bits': '32', 00:14:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 00:14:11 INFO - 'title': 't-yosemite-r7-0102', 00:14:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 00:14:11 INFO - 'use_talos_json': True, 00:14:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 00:14:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 00:14:11 INFO - 'work_dir': 'build'} 00:14:11 INFO - [mozharness: 2016-11-25 08:14:11.897061Z] Running clobber step. 00:14:11 INFO - Running pre-action listener: _resource_record_pre_action 00:14:11 INFO - Running main action method: clobber 00:14:11 INFO - rmtree: /builds/slave/test/build 00:14:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 00:14:13 INFO - Running post-action listener: _resource_record_post_action 00:14:13 INFO - [mozharness: 2016-11-25 08:14:13.422827Z] Finished clobber step (success) 00:14:13 INFO - [mozharness: 2016-11-25 08:14:13.422950Z] Running read-buildbot-config step. 00:14:13 INFO - Running pre-action listener: _resource_record_pre_action 00:14:13 INFO - Running main action method: read_buildbot_config 00:14:13 INFO - Using buildbot properties: 00:14:13 INFO - { 00:14:13 INFO - "project": "", 00:14:13 INFO - "product": "firefox", 00:14:13 INFO - "script_repo_revision": "production", 00:14:13 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 00:14:13 INFO - "repository": "", 00:14:13 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 00:14:13 INFO - "buildid": "20161124215919", 00:14:13 INFO - "pgo_build": "False", 00:14:13 INFO - "basedir": "/builds/slave/test", 00:14:13 INFO - "buildnumber": 47, 00:14:13 INFO - "slavename": "t-yosemite-r7-0102", 00:14:13 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 00:14:13 INFO - "platform": "yosemite_r7", 00:14:13 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 00:14:13 INFO - "branch": "mozilla-aurora", 00:14:13 INFO - "repo_path": "releases/mozilla-aurora", 00:14:13 INFO - "revision": "55b599ca3530c8286f6ad15405d99d2bcaecfbd1", 00:14:13 INFO - "stage_platform": "macosx64", 00:14:13 INFO - "builduid": "4bdd6ca568b440bd83e6532cc3c36271", 00:14:13 INFO - "slavebuilddir": "test" 00:14:13 INFO - } 00:14:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/Iv5wHu7GTxyWlrikXGyf1A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 00:14:13 INFO - Running post-action listener: _resource_record_post_action 00:14:13 INFO - [mozharness: 2016-11-25 08:14:13.426489Z] Finished read-buildbot-config step (success) 00:14:13 INFO - [mozharness: 2016-11-25 08:14:13.426620Z] Running download-and-extract step. 00:14:13 INFO - Running pre-action listener: _resource_record_pre_action 00:14:13 INFO - Running main action method: download_and_extract 00:14:13 INFO - mkdir: /builds/slave/test/build/tests 00:14:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:14:13 INFO - trying https://queue.taskcluster.net/v1/task/Iv5wHu7GTxyWlrikXGyf1A/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 00:14:13 INFO - Downloading https://queue.taskcluster.net/v1/task/Iv5wHu7GTxyWlrikXGyf1A/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 00:14:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Iv5wHu7GTxyWlrikXGyf1A/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 00:14:14 INFO - Downloaded 1199 bytes. 00:14:14 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 00:14:14 INFO - Using the following test package requirements: 00:14:14 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 00:14:14 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 00:14:14 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 00:14:14 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 00:14:14 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 00:14:14 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 00:14:14 INFO - u'jsshell-mac.zip'], 00:14:14 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 00:14:14 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 00:14:14 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 00:14:14 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 00:14:14 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 00:14:14 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 00:14:14 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 00:14:14 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 00:14:14 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 00:14:14 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 00:14:14 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 00:14:14 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 00:14:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Iv5wHu7GTxyWlrikXGyf1A/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 00:14:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Iv5wHu7GTxyWlrikXGyf1A/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 00:14:14 INFO - Fetch https://queue.taskcluster.net/v1/task/Iv5wHu7GTxyWlrikXGyf1A/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 00:14:15 INFO - Http code: 200 00:14:15 INFO - via: 1.1 ae162f6796e551002447afd7c07ec67a.cloudfront.net (CloudFront) 00:14:15 INFO - x-amz-cf-id: uoRocbXj5TM2kAGMbyrp94yn9MMXpNbS28pbzVlqXlI3gOc1a269aQ== 00:14:15 INFO - x-amz-version-id: WGFTcdd50Ngj0I.a1uAXIvuVJ7M7KPdo 00:14:16 INFO - Expected file size: 16279024 00:14:16 INFO - Obtained file size: 16279024 00:14:22 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 00:14:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Iv5wHu7GTxyWlrikXGyf1A/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 00:14:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Iv5wHu7GTxyWlrikXGyf1A/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 00:14:22 INFO - Fetch https://queue.taskcluster.net/v1/task/Iv5wHu7GTxyWlrikXGyf1A/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 00:14:23 INFO - Http code: 200 00:14:23 INFO - via: 1.1 37af14ee951b12568cf1cc160d08abe6.cloudfront.net (CloudFront) 00:14:23 INFO - x-amz-cf-id: pz5aF90Azr8RNXsdleqOxgf46dEaCEgx03vN9ZY6rR6YCrDobOdLyw== 00:14:23 INFO - x-amz-version-id: WGFTcdd50Ngj0I.a1uAXIvuVJ7M7KPdo 00:14:23 INFO - Expected file size: 16279024 00:14:23 INFO - Obtained file size: 16279024 00:14:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Iv5wHu7GTxyWlrikXGyf1A/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 00:14:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Iv5wHu7GTxyWlrikXGyf1A/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 00:14:30 INFO - Fetch https://queue.taskcluster.net/v1/task/Iv5wHu7GTxyWlrikXGyf1A/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 00:14:30 INFO - Http code: 200 00:14:30 INFO - via: 1.1 5c2ff4ca1e447265402af29264e83497.cloudfront.net (CloudFront) 00:14:30 INFO - x-amz-cf-id: oE9Oo10Nw2ZFhMFdTB0dhtTloewsIvMsJzIzDA-_im7AZBeKE_Q6mg== 00:14:30 INFO - x-amz-version-id: aEGxYl6N16LXV0QdrxFJ.ii5IZENul6_ 00:14:31 INFO - Expected file size: 14409038 00:14:31 INFO - Obtained file size: 14409038 00:14: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')]} 00:14:31 INFO - trying https://queue.taskcluster.net/v1/task/Iv5wHu7GTxyWlrikXGyf1A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 00:14:31 INFO - Downloading https://queue.taskcluster.net/v1/task/Iv5wHu7GTxyWlrikXGyf1A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 00:14:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Iv5wHu7GTxyWlrikXGyf1A/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 00:14:36 INFO - Downloaded 109693516 bytes. 00:14:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Iv5wHu7GTxyWlrikXGyf1A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 00:14:36 INFO - mkdir: /builds/slave/test/properties 00:14:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 00:14:36 INFO - Writing to file /builds/slave/test/properties/build_url 00:14:36 INFO - Contents: 00:14:36 INFO - build_url:https://queue.taskcluster.net/v1/task/Iv5wHu7GTxyWlrikXGyf1A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 00:14:36 INFO - Running post-action listener: _resource_record_post_action 00:14:36 INFO - Running post-action listener: set_extra_try_arguments 00:14:36 INFO - [mozharness: 2016-11-25 08:14:36.801431Z] Finished download-and-extract step (success) 00:14:36 INFO - [mozharness: 2016-11-25 08:14:36.801554Z] Running populate-webroot step. 00:14:36 INFO - Running pre-action listener: _resource_record_pre_action 00:14:36 INFO - Running main action method: populate_webroot 00:14:36 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 00:14:36 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 00:14:36 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 00:14:36 INFO - '--tppagecycles', 00:14:36 INFO - '7'], 00:14:36 INFO - 'tests': ['tsvgm']}}, 00:14:36 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 00:14:36 INFO - 'tests': ['tresize', 'tcanvasmark']}, 00:14:36 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 00:14:36 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 00:14:36 INFO - 'tests': ['dromaeo_css', 'kraken']}, 00:14:36 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 00:14:36 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:14:36 INFO - 'talos_options': ['--disable-e10s'], 00:14:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 00:14:36 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:14:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 00:14:36 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:14:36 INFO - 'talos_options': ['--disable-e10s'], 00:14:36 INFO - 'tests': ['damp', 'tps']}, 00:14:36 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:14:36 INFO - 'tests': ['damp', 'tps']}, 00:14:36 INFO - 'g3': {'talos_options': ['--disable-e10s'], 00:14:36 INFO - 'tests': ['dromaeo_dom']}, 00:14:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 00:14:36 INFO - 'g4': {'talos_options': ['--disable-e10s'], 00:14:36 INFO - 'tests': ['basic_compositor_video']}, 00:14:36 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 00:14:36 INFO - 'other': {'talos_options': ['--disable-e10s'], 00:14:36 INFO - 'tests': ['a11yr', 00:14:36 INFO - 'ts_paint', 00:14:36 INFO - 'tpaint', 00:14:36 INFO - 'sessionrestore', 00:14:36 INFO - 'sessionrestore_no_auto_restore', 00:14:36 INFO - 'tabpaint']}, 00:14:36 INFO - 'other-e10s': {'tests': ['a11yr', 00:14:36 INFO - 'ts_paint', 00:14:36 INFO - 'tpaint', 00:14:36 INFO - 'sessionrestore', 00:14:36 INFO - 'sessionrestore_no_auto_restore', 00:14:36 INFO - 'tabpaint']}, 00:14:36 INFO - 'other-e10s_l64': {'tests': ['a11yr', 00:14:36 INFO - 'ts_paint', 00:14:36 INFO - 'tpaint', 00:14:36 INFO - 'sessionrestore', 00:14:36 INFO - 'sessionrestore_no_auto_restore', 00:14:36 INFO - 'tabpaint']}, 00:14:36 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 00:14:36 INFO - 'ts_paint', 00:14:36 INFO - 'tpaint', 00:14:36 INFO - 'sessionrestore', 00:14:36 INFO - 'sessionrestore_no_auto_restore', 00:14:36 INFO - 'tabpaint']}, 00:14:36 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 00:14:36 INFO - 'tests': ['a11yr', 00:14:36 INFO - 'ts_paint', 00:14:36 INFO - 'tpaint', 00:14:36 INFO - 'sessionrestore', 00:14:36 INFO - 'sessionrestore_no_auto_restore', 00:14:36 INFO - 'tabpaint']}, 00:14:36 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 00:14:36 INFO - 'tests': ['a11yr', 00:14:36 INFO - 'ts_paint', 00:14:36 INFO - 'tpaint', 00:14:36 INFO - 'sessionrestore', 00:14:36 INFO - 'sessionrestore_no_auto_restore', 00:14:36 INFO - 'tabpaint']}, 00:14:36 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 00:14:36 INFO - 'tests': ['tsvgx', 00:14:36 INFO - 'tsvgr_opacity', 00:14:36 INFO - 'tart', 00:14:36 INFO - 'tscrollx', 00:14:36 INFO - 'cart']}, 00:14:36 INFO - 'svgr-e10s': {'tests': ['tsvgx', 00:14:36 INFO - 'tsvgr_opacity', 00:14:36 INFO - 'tart', 00:14:36 INFO - 'tscrollx', 00:14:36 INFO - 'cart']}, 00:14:36 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:14:36 INFO - 'talos_options': ['--disable-e10s'], 00:14:36 INFO - 'tests': ['tp5o']}, 00:14:36 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:14:36 INFO - 'tests': ['tp5o']}, 00:14:36 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:14:36 INFO - 'talos_options': ['--disable-e10s', 00:14:36 INFO - '--xperf_path', 00:14:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 00:14:36 INFO - 'tests': ['tp5n']}, 00:14:36 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:14:36 INFO - 'talos_options': ['--xperf_path', 00:14:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 00:14:36 INFO - 'tests': ['tp5n']}}, 00:14:36 INFO - 'talos.zip': {'path': '', 00:14:36 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 00:14:36 INFO - Running post-action listener: _resource_record_post_action 00:14:36 INFO - [mozharness: 2016-11-25 08:14:36.820874Z] Finished populate-webroot step (success) 00:14:36 INFO - [mozharness: 2016-11-25 08:14:36.820986Z] Running create-virtualenv step. 00:14:36 INFO - Running pre-action listener: _resource_record_pre_action 00:14:36 INFO - Running main action method: create_virtualenv 00:14:36 INFO - Creating virtualenv /builds/slave/test/build/venv 00:14:36 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 00:14:36 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 00:14:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 00:14:36 INFO - Using real prefix '/tools/python27' 00:14:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 00:14:37 INFO - Installing distribute.............................................................................................................................................................................................done. 00:14:40 INFO - Installing pip.................done. 00:14:40 INFO - Return code: 0 00:14:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 00:14:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 00:14:40 INFO - Reading from file tmpfile_stdout 00:14:40 INFO - Output received: 00:14:40 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) 00:14:40 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 00:14:40 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')]} 00:14:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:14:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:14:40 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')]} 00:14:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:14:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:14:40 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 0x1039ca490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103940570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc4ad64360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037ded30>, '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 0x103644db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1037d6b20>, '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.Qi62bvemR9/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.70e58qcsqp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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 00:14:40 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 00:14:40 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 00:14:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Qi62bvemR9/Render', 00:14:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:14:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:14:40 INFO - 'HOME': '/Users/cltbld', 00:14:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:14:40 INFO - 'LOGNAME': 'cltbld', 00:14:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:14:40 INFO - 'MOZ_NO_REMOTE': '1', 00:14:40 INFO - 'NO_EM_RESTART': '1', 00:14:40 INFO - 'PAGER': '/bin/cat', 00:14:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:14:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:14:40 INFO - 'PWD': '/builds/slave/test', 00:14:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:14:40 INFO - 'SHELL': '/bin/bash', 00:14:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.70e58qcsqp/Listeners', 00:14:40 INFO - 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/', 00:14:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:14:40 INFO - 'USER': 'cltbld', 00:14:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:14:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:14:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:14:40 INFO - 'XPC_FLAGS': '0x0', 00:14:40 INFO - 'XPC_SERVICE_NAME': '0', 00:14:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:14:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:14:41 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 00:14:41 INFO - Cleaning up... 00:14:41 INFO - Return code: 0 00:14:41 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 00:14:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:14:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:14:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:14:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:14:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:14:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:14:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1039ca490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103940570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc4ad64360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037ded30>, '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 0x103644db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1037d6b20>, '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.Qi62bvemR9/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.70e58qcsqp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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 00:14:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 00:14:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 00:14:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Qi62bvemR9/Render', 00:14:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:14:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:14:41 INFO - 'HOME': '/Users/cltbld', 00:14:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:14:41 INFO - 'LOGNAME': 'cltbld', 00:14:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:14:41 INFO - 'MOZ_NO_REMOTE': '1', 00:14:41 INFO - 'NO_EM_RESTART': '1', 00:14:41 INFO - 'PAGER': '/bin/cat', 00:14:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:14:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:14:41 INFO - 'PWD': '/builds/slave/test', 00:14:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:14:41 INFO - 'SHELL': '/bin/bash', 00:14:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.70e58qcsqp/Listeners', 00:14:41 INFO - 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/', 00:14:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:14:41 INFO - 'USER': 'cltbld', 00:14:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:14:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:14:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:14:41 INFO - 'XPC_FLAGS': '0x0', 00:14:41 INFO - 'XPC_SERVICE_NAME': '0', 00:14:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:14:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:14:41 INFO - Downloading/unpacking psutil>=3.1.1 00:14:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:14:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:14:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:14:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:14:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:14:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:14:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 00:14:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 00:14:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 00:14:45 INFO - Installing collected packages: psutil 00:14:45 INFO - Running setup.py install for psutil 00:14:45 INFO - building 'psutil._psutil_osx' extension 00:14:45 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 00:14:45 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 00:14:45 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 00:14:45 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 00:14:45 INFO - building 'psutil._psutil_posix' extension 00:14:45 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 00:14:45 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 00:14:45 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 00:14:45 INFO - ^ 00:14:45 INFO - 1 warning generated. 00:14:45 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 00:14:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 00:14:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 00:14:45 INFO - Successfully installed psutil 00:14:45 INFO - Cleaning up... 00:14:46 INFO - Return code: 0 00:14:46 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 00:14:46 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')]} 00:14:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:14:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:14:46 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')]} 00:14:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:14:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:14:46 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 0x1039ca490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103940570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc4ad64360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037ded30>, '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 0x103644db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1037d6b20>, '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.Qi62bvemR9/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.70e58qcsqp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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 00:14:46 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 00:14:46 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 00:14:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Qi62bvemR9/Render', 00:14:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:14:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:14:46 INFO - 'HOME': '/Users/cltbld', 00:14:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:14:46 INFO - 'LOGNAME': 'cltbld', 00:14:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:14:46 INFO - 'MOZ_NO_REMOTE': '1', 00:14:46 INFO - 'NO_EM_RESTART': '1', 00:14:46 INFO - 'PAGER': '/bin/cat', 00:14:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:14:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:14:46 INFO - 'PWD': '/builds/slave/test', 00:14:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:14:46 INFO - 'SHELL': '/bin/bash', 00:14:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.70e58qcsqp/Listeners', 00:14:46 INFO - 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/', 00:14:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:14:46 INFO - 'USER': 'cltbld', 00:14:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:14:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:14:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:14:46 INFO - 'XPC_FLAGS': '0x0', 00:14:46 INFO - 'XPC_SERVICE_NAME': '0', 00:14:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:14:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:14:46 INFO - Downloading/unpacking mozsystemmonitor==0.3 00:14:46 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 00:14:46 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 00:14:46 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 00:14:46 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 00:14:46 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 00:14:46 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 00:14:48 INFO - Downloading mozsystemmonitor-0.3.tar.gz 00:14:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 00:14:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 00:14:48 INFO - Installing collected packages: mozsystemmonitor 00:14:48 INFO - Running setup.py install for mozsystemmonitor 00:14:49 INFO - Successfully installed mozsystemmonitor 00:14:49 INFO - Cleaning up... 00:14:49 INFO - Return code: 0 00:14:49 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 00:14:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:14:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:14:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:14:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:14:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:14:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:14:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1039ca490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103940570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc4ad64360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037ded30>, '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 0x103644db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1037d6b20>, '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.Qi62bvemR9/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.70e58qcsqp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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 00:14:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 00:14:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 00:14:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Qi62bvemR9/Render', 00:14:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:14:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:14:49 INFO - 'HOME': '/Users/cltbld', 00:14:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:14:49 INFO - 'LOGNAME': 'cltbld', 00:14:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:14:49 INFO - 'MOZ_NO_REMOTE': '1', 00:14:49 INFO - 'NO_EM_RESTART': '1', 00:14:49 INFO - 'PAGER': '/bin/cat', 00:14:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:14:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:14:49 INFO - 'PWD': '/builds/slave/test', 00:14:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:14:49 INFO - 'SHELL': '/bin/bash', 00:14:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.70e58qcsqp/Listeners', 00:14:49 INFO - 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/', 00:14:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:14:49 INFO - 'USER': 'cltbld', 00:14:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:14:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:14:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:14:49 INFO - 'XPC_FLAGS': '0x0', 00:14:49 INFO - 'XPC_SERVICE_NAME': '0', 00:14:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:14:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:14:49 INFO - Downloading/unpacking jsonschema==2.5.1 00:14:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:14:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:14:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:14:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:14:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:14:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:14:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 00:14:52 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 00:14:52 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 00:14:52 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 00:14:52 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 00:14:52 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 00:14:52 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 00:14:52 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 00:14:52 INFO - Downloading functools32-3.2.3-2.tar.gz 00:14:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 00:14:52 INFO - warning: no files found matching '*.txt' 00:14:52 INFO - no previously-included directories found matching 'build' 00:14:52 INFO - no previously-included directories found matching 'dist' 00:14:52 INFO - no previously-included directories found matching '.git*' 00:14:52 INFO - Installing collected packages: jsonschema, functools32 00:14:52 INFO - Running setup.py install for functools32 00:14:52 INFO - warning: no files found matching '*.txt' 00:14:52 INFO - no previously-included directories found matching 'build' 00:14:52 INFO - no previously-included directories found matching 'dist' 00:14:52 INFO - no previously-included directories found matching '.git*' 00:14:52 INFO - Successfully installed jsonschema functools32 00:14:52 INFO - Cleaning up... 00:14:52 INFO - Return code: 0 00:14:52 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 00:14:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:14:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:14:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:14:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:14:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:14:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:14:52 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 0x1039ca490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103940570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc4ad64360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037ded30>, '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 0x103644db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1037d6b20>, '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.Qi62bvemR9/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.70e58qcsqp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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 00:14:52 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 00:14:52 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 00:14:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Qi62bvemR9/Render', 00:14:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:14:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:14:52 INFO - 'HOME': '/Users/cltbld', 00:14:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:14:52 INFO - 'LOGNAME': 'cltbld', 00:14:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:14:52 INFO - 'MOZ_NO_REMOTE': '1', 00:14:52 INFO - 'NO_EM_RESTART': '1', 00:14:52 INFO - 'PAGER': '/bin/cat', 00:14:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:14:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:14:52 INFO - 'PWD': '/builds/slave/test', 00:14:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:14:52 INFO - 'SHELL': '/bin/bash', 00:14:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.70e58qcsqp/Listeners', 00:14:52 INFO - 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/', 00:14:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:14:52 INFO - 'USER': 'cltbld', 00:14:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:14:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:14:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:14:52 INFO - 'XPC_FLAGS': '0x0', 00:14:52 INFO - 'XPC_SERVICE_NAME': '0', 00:14:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:14:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:14:53 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 00:14:53 INFO - Cleaning up... 00:14:53 INFO - Return code: 0 00:14:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 00:14:53 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')]} 00:14:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:14:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:14:53 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')]} 00:14:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:14:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:14:53 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 0x1039ca490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103940570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc4ad64360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037ded30>, '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 0x103644db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1037d6b20>, '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.Qi62bvemR9/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.70e58qcsqp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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 00:14:53 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 00:14:53 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 00:14:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Qi62bvemR9/Render', 00:14:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:14:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:14:53 INFO - 'HOME': '/Users/cltbld', 00:14:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:14:53 INFO - 'LOGNAME': 'cltbld', 00:14:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:14:53 INFO - 'MOZ_NO_REMOTE': '1', 00:14:53 INFO - 'NO_EM_RESTART': '1', 00:14:53 INFO - 'PAGER': '/bin/cat', 00:14:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:14:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:14:53 INFO - 'PWD': '/builds/slave/test', 00:14:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:14:53 INFO - 'SHELL': '/bin/bash', 00:14:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.70e58qcsqp/Listeners', 00:14:53 INFO - 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/', 00:14:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:14:53 INFO - 'USER': 'cltbld', 00:14:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:14:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:14:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:14:53 INFO - 'XPC_FLAGS': '0x0', 00:14:53 INFO - 'XPC_SERVICE_NAME': '0', 00:14:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:14:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:14:53 INFO - Downloading/unpacking blobuploader==1.2.4 00:14:53 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 00:14:53 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 00:14:53 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 00:14:53 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 00:14:53 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 00:14:53 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 00:14:55 INFO - Downloading blobuploader-1.2.4.tar.gz 00:14:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 00:14:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 00:14:55 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 00:14:55 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 00:14:55 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 00:14:55 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 00:14:55 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 00:14:55 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 00:14:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 00:14:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 00:14:56 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 00:14:56 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 00:14:56 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 00:14:56 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 00:14:56 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 00:14:56 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 00:14:57 INFO - Downloading docopt-0.6.1.tar.gz 00:14:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 00:14:57 INFO - Installing collected packages: blobuploader, requests, docopt 00:14:57 INFO - Running setup.py install for blobuploader 00:14:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 00:14:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 00:14:57 INFO - Running setup.py install for requests 00:14:57 INFO - Running setup.py install for docopt 00:14:57 INFO - Successfully installed blobuploader requests docopt 00:14:57 INFO - Cleaning up... 00:14:58 INFO - Return code: 0 00:14:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:14:58 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')]} 00:14:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:14:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:14:58 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')]} 00:14:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:14:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:14:58 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 0x1039ca490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103940570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc4ad64360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037ded30>, '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 0x103644db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1037d6b20>, '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.Qi62bvemR9/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.70e58qcsqp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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 00:14:58 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 00:14:58 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 00:14:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Qi62bvemR9/Render', 00:14:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:14:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:14:58 INFO - 'HOME': '/Users/cltbld', 00:14:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:14:58 INFO - 'LOGNAME': 'cltbld', 00:14:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:14:58 INFO - 'MOZ_NO_REMOTE': '1', 00:14:58 INFO - 'NO_EM_RESTART': '1', 00:14:58 INFO - 'PAGER': '/bin/cat', 00:14:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:14:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:14:58 INFO - 'PWD': '/builds/slave/test', 00:14:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:14:58 INFO - 'SHELL': '/bin/bash', 00:14:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.70e58qcsqp/Listeners', 00:14:58 INFO - 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/', 00:14:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:14:58 INFO - 'USER': 'cltbld', 00:14:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:14:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:14:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:14:58 INFO - 'XPC_FLAGS': '0x0', 00:14:58 INFO - 'XPC_SERVICE_NAME': '0', 00:14:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:14:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:14:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 00:14:58 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-ji9Fm2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 00:14:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 00:14:58 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-xsMxji-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 00:14:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 00:14:58 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-HFB8h7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 00:14:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 00:14:58 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-Cjikr1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 00:14:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 00:14:58 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-jZWcVI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 00:14:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 00:14:58 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-LwpqDJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 00:14:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 00:14:58 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-780fkn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 00:14:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 00:14:59 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-Ci9xXc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 00:14:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 00:14:59 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-cGDnja-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 00:14:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 00:14:59 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-xkUonp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 00:14:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 00:14:59 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-BBeRdJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 00:14:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 00:14:59 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-86wtLi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 00:14:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 00:14:59 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-_sGElB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 00:14:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 00:14:59 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-FK73Go-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 00:14:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 00:14:59 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-jY89lU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 00:14:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 00:14:59 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-sJGp5R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 00:14:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 00:14:59 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-FVjfC_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 00:15:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 00:15:00 INFO - Running setup.py install for manifestparser 00:15:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 00:15:00 INFO - Running setup.py install for mozcrash 00:15:00 INFO - Running setup.py install for mozdebug 00:15:00 INFO - Running setup.py install for mozdevice 00:15:00 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 00:15:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 00:15:00 INFO - Running setup.py install for mozfile 00:15:00 INFO - Running setup.py install for mozhttpd 00:15:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 00:15:00 INFO - Running setup.py install for mozinfo 00:15:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 00:15:01 INFO - Running setup.py install for mozInstall 00:15:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 00:15:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 00:15:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 00:15:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 00:15:01 INFO - Running setup.py install for mozleak 00:15:01 INFO - Running setup.py install for mozlog 00:15:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 00:15:01 INFO - Running setup.py install for moznetwork 00:15:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 00:15:01 INFO - Running setup.py install for mozprocess 00:15:01 INFO - Running setup.py install for mozprofile 00:15:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 00:15:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 00:15:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 00:15:02 INFO - Running setup.py install for mozrunner 00:15:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 00:15:02 INFO - Running setup.py install for mozscreenshot 00:15:02 INFO - Running setup.py install for moztest 00:15:02 INFO - Running setup.py install for mozversion 00:15:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 00:15:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 00:15:02 INFO - Cleaning up... 00:15:02 INFO - Return code: 0 00:15:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:15:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:15:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:15:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:15:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:15:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:15:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:15:02 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 0x1039ca490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103940570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc4ad64360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037ded30>, '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 0x103644db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1037d6b20>, '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.Qi62bvemR9/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.70e58qcsqp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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 00:15:02 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 00:15:02 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 00:15:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Qi62bvemR9/Render', 00:15:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:15:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:15:02 INFO - 'HOME': '/Users/cltbld', 00:15:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:15:02 INFO - 'LOGNAME': 'cltbld', 00:15:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:15:02 INFO - 'MOZ_NO_REMOTE': '1', 00:15:02 INFO - 'NO_EM_RESTART': '1', 00:15:02 INFO - 'PAGER': '/bin/cat', 00:15:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:15:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:15:02 INFO - 'PWD': '/builds/slave/test', 00:15:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:15:02 INFO - 'SHELL': '/bin/bash', 00:15:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.70e58qcsqp/Listeners', 00:15:02 INFO - 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/', 00:15:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:15:02 INFO - 'USER': 'cltbld', 00:15:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:15:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:15:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:15:02 INFO - 'XPC_FLAGS': '0x0', 00:15:02 INFO - 'XPC_SERVICE_NAME': '0', 00:15:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:15:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:15:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 00:15:02 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-YHIjy6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 00:15:03 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)) 00:15:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 00:15:03 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-dUdkAM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 00:15:03 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)) 00:15:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 00:15:03 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-mf4IzU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 00:15:03 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)) 00:15:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 00:15:03 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-Lh3Z_y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 00:15:03 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)) 00:15:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 00:15:03 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-HuztcZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 00:15:03 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)) 00:15:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 00:15:03 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-RNn40n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 00:15:03 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)) 00:15:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 00:15:03 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-sKRa9P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 00:15:03 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)) 00:15:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 00:15:03 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-0xh5xN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 00:15:03 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)) 00:15:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 00:15:03 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-bPeCe2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 00:15:03 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)) 00:15:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 00:15:03 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-J6DqCK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 00:15:04 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)) 00:15:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 00:15:04 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-BQRLrN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 00:15:04 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)) 00:15:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 00:15:04 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-acWnCw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 00:15:04 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)) 00:15:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 00:15:04 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-nmGsUK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 00:15:04 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)) 00:15:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 00:15:04 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-skAf5Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 00:15:04 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)) 00:15:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 00:15:04 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-xggZkK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 00:15:04 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)) 00:15:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 00:15:04 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-5UpYoq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 00:15:04 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)) 00:15:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 00:15:04 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-t6pvte-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 00:15:04 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)) 00:15:04 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)) 00:15:04 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)) 00:15:04 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)) 00:15:04 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)) 00:15:04 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)) 00:15:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 00:15:04 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 00:15:04 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 00:15:04 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 00:15:04 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 00:15:04 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 00:15:04 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 00:15:07 INFO - Downloading blessings-1.6.tar.gz 00:15:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 00:15:07 INFO - Installing collected packages: blessings 00:15:07 INFO - Running setup.py install for blessings 00:15:07 INFO - Successfully installed blessings 00:15:07 INFO - Cleaning up... 00:15:07 INFO - Return code: 0 00:15:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 00:15:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 00:15:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 00:15:07 INFO - Reading from file tmpfile_stdout 00:15:07 INFO - Current package versions: 00:15:07 INFO - blessings == 1.6 00:15:07 INFO - blobuploader == 1.2.4 00:15:07 INFO - docopt == 0.6.1 00:15:07 INFO - functools32 == 3.2.3-2 00:15:07 INFO - jsonschema == 2.5.1 00:15:07 INFO - manifestparser == 1.1 00:15:07 INFO - mozInstall == 1.12 00:15:07 INFO - mozcrash == 1.0 00:15:07 INFO - mozdebug == 0.1 00:15:07 INFO - mozdevice == 0.48 00:15:07 INFO - mozfile == 1.2 00:15:07 INFO - mozhttpd == 0.7 00:15:07 INFO - mozinfo == 0.9 00:15:07 INFO - mozleak == 0.1 00:15:07 INFO - mozlog == 3.4 00:15:07 INFO - moznetwork == 0.27 00:15:07 INFO - mozprocess == 0.23 00:15:07 INFO - mozprofile == 0.28 00:15:07 INFO - mozrunner == 6.13 00:15:07 INFO - mozscreenshot == 0.1 00:15:07 INFO - mozsystemmonitor == 0.3 00:15:07 INFO - moztest == 0.7 00:15:07 INFO - mozversion == 1.4 00:15:07 INFO - psutil == 3.1.1 00:15:07 INFO - requests == 1.2.3 00:15:07 INFO - wsgiref == 0.1.2 00:15:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:15:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:15:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:15:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:15:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:15:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:15:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:15:07 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 0x1039ca490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103940570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc4ad64360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037ded30>, '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 0x103644db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1037d6b20>, '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.Qi62bvemR9/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.70e58qcsqp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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 00:15:07 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 00:15:07 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 00:15:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Qi62bvemR9/Render', 00:15:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:15:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:15:07 INFO - 'HOME': '/Users/cltbld', 00:15:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:15:07 INFO - 'LOGNAME': 'cltbld', 00:15:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:15:07 INFO - 'MOZ_NO_REMOTE': '1', 00:15:07 INFO - 'NO_EM_RESTART': '1', 00:15:07 INFO - 'PAGER': '/bin/cat', 00:15:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:15:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:15:07 INFO - 'PWD': '/builds/slave/test', 00:15:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:15:07 INFO - 'SHELL': '/bin/bash', 00:15:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.70e58qcsqp/Listeners', 00:15:07 INFO - 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/', 00:15:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:15:07 INFO - 'USER': 'cltbld', 00:15:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:15:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:15:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:15:07 INFO - 'XPC_FLAGS': '0x0', 00:15:07 INFO - 'XPC_SERVICE_NAME': '0', 00:15:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:15:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:15:08 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)) 00:15:08 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)) 00:15:08 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)) 00:15:08 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)) 00:15:08 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)) 00:15:08 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)) 00:15:08 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)) 00:15:08 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)) 00:15:08 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)) 00:15:08 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)) 00:15:08 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)) 00:15:08 INFO - Cleaning up... 00:15:08 INFO - Return code: 0 00:15:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 00:15:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:15:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:15:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:15:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:15:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:15:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:15:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1039ca490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103940570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc4ad64360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037ded30>, '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 0x103644db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1037d6b20>, '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.Qi62bvemR9/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.70e58qcsqp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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 00:15:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 00:15:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 00:15:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Qi62bvemR9/Render', 00:15:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:15:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:15:08 INFO - 'HOME': '/Users/cltbld', 00:15:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:15:08 INFO - 'LOGNAME': 'cltbld', 00:15:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:15:08 INFO - 'MOZ_NO_REMOTE': '1', 00:15:08 INFO - 'NO_EM_RESTART': '1', 00:15:08 INFO - 'PAGER': '/bin/cat', 00:15:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:15:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:15:08 INFO - 'PWD': '/builds/slave/test', 00:15:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:15:08 INFO - 'SHELL': '/bin/bash', 00:15:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.70e58qcsqp/Listeners', 00:15:08 INFO - 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/', 00:15:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:15:08 INFO - 'USER': 'cltbld', 00:15:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:15:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:15:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:15:08 INFO - 'XPC_FLAGS': '0x0', 00:15:08 INFO - 'XPC_SERVICE_NAME': '0', 00:15:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:15:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:15:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 00:15:08 INFO - Cleaning up... 00:15:08 INFO - Return code: 0 00:15:08 INFO - Running post-action listener: _resource_record_post_action 00:15:08 INFO - Running post-action listener: _start_resource_monitoring 00:15:08 INFO - Starting resource monitoring. 00:15:08 INFO - [mozharness: 2016-11-25 08:15:08.449571Z] Finished create-virtualenv step (success) 00:15:08 INFO - [mozharness: 2016-11-25 08:15:08.450596Z] Running install step. 00:15:08 INFO - Running pre-action listener: _resource_record_pre_action 00:15:08 INFO - Running main action method: install 00:15:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 00:15:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 00:15:08 INFO - Reading from file tmpfile_stdout 00:15:08 INFO - Detecting whether we're running mozinstall >=1.0... 00:15:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 00:15:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 00:15:08 INFO - Reading from file tmpfile_stdout 00:15:08 INFO - Output received: 00:15:08 INFO - Usage: mozinstall [options] installer 00:15:08 INFO - Options: 00:15:08 INFO - -h, --help show this help message and exit 00:15:08 INFO - -d DEST, --destination=DEST 00:15:08 INFO - Directory to install application into. [default: 00:15:08 INFO - "/builds/slave/test"] 00:15:08 INFO - --app=APP Application being installed. [default: firefox] 00:15:08 INFO - mkdir: /builds/slave/test/build/application 00:15:08 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'] 00:15:08 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 00:15:39 INFO - Reading from file tmpfile_stdout 00:15:39 INFO - Output received: 00:15:39 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 00:15:39 INFO - Running post-action listener: _resource_record_post_action 00:15:39 INFO - [mozharness: 2016-11-25 08:15:39.999877Z] Finished install step (success) 00:15:40 INFO - [mozharness: 2016-11-25 08:15:40.000022Z] Running run-tests step. 00:15:40 INFO - Running pre-action listener: _resource_record_pre_action 00:15:40 INFO - Running main action method: run_tests 00:15:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 00:15:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 00:15:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 00:15:40 INFO - 2016-11-25 00:15:40.014 screenresolution[1907:15555] starting screenresolution argv=screenresolution get 00:15:40 INFO - 2016-11-25 00:15:40.017 screenresolution[1907:15555] Display 0: 1600x1200x32@60 00:15:40 INFO - 2016-11-25 00:15:40.023 screenresolution[1908:15557] starting screenresolution argv=screenresolution list 00:15:40 INFO - Available Modes on Display 0 00:15:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 00:15:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 00:15:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 00:15:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 00:15:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 00:15:40 INFO - Intel Iris: 00:15:40 INFO - Chipset Model: Intel Iris 00:15:40 INFO - Type: GPU 00:15:40 INFO - Bus: Built-In 00:15:40 INFO - VRAM (Dynamic, Max): 1536 MB 00:15:40 INFO - Vendor: Intel (0x8086) 00:15:40 INFO - Device ID: 0x0a2e 00:15:40 INFO - Revision ID: 0x0009 00:15:40 INFO - Displays: 00:15:40 INFO - Display: 00:15:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 00:15:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 00:15:40 INFO - Display Serial Number: 1600x1200 60 00:15:40 INFO - Main Display: Yes 00:15:40 INFO - Mirror: Off 00:15:40 INFO - Online: Yes 00:15:40 INFO - Rotation: Supported 00:15:40 INFO - Return code: 0 00:15:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 00:15:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 00:15:40 INFO - Python 2.7.3 00:15:40 INFO - Return code: 0 00:15:40 INFO - grabbing minidump binary from tooltool 00:15:40 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')]} 00:15:40 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 0x1037ded30>, '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 0x103644db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1037d6b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 00:15:40 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 00:15:40 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 00:15:40 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 00:15:40 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 00:15:40 INFO - Return code: 0 00:15:40 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 00:15:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 00:15:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 00:15:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 00:15:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 00:15:40 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/Iv5wHu7GTxyWlrikXGyf1A/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0102', '--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 00:15:40 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/Iv5wHu7GTxyWlrikXGyf1A/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0102 --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 00:15:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Qi62bvemR9/Render', 00:15:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:15:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:15:40 INFO - 'HOME': '/Users/cltbld', 00:15:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:15:40 INFO - 'LOGNAME': 'cltbld', 00:15:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 00:15:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 00:15:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:15:40 INFO - 'MOZ_NO_REMOTE': '1', 00:15:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 00:15:40 INFO - 'NO_EM_RESTART': '1', 00:15:40 INFO - 'PAGER': '/bin/cat', 00:15:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:15:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:15:40 INFO - 'PWD': '/builds/slave/test', 00:15:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 00:15:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:15:40 INFO - 'SHELL': '/bin/bash', 00:15:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.70e58qcsqp/Listeners', 00:15:40 INFO - 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/', 00:15:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:15:40 INFO - 'USER': 'cltbld', 00:15:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:15:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:15:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:15:40 INFO - 'XPC_FLAGS': '0x0', 00:15:40 INFO - 'XPC_SERVICE_NAME': '0', 00:15:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:15:40 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/Iv5wHu7GTxyWlrikXGyf1A/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0102', '--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 00:15:40 INFO - mozversion application_buildid: 20161124215919 00:15:40 INFO - mozversion application_changeset: 55b599ca3530c8286f6ad15405d99d2bcaecfbd1 00:15:40 INFO - mozversion application_display_name: FirefoxDeveloperEdition 00:15:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 00:15:40 INFO - mozversion application_name: Firefox 00:15:40 INFO - mozversion application_remotingname: firefox-dev 00:15:40 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 00:15:40 INFO - mozversion application_vendor: Mozilla 00:15:40 INFO - mozversion application_version: 52.0a2 00:15:40 INFO - mozversion platform_buildid: 20161124215919 00:15:40 INFO - mozversion platform_changeset: 55b599ca3530c8286f6ad15405d99d2bcaecfbd1 00:15:40 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 00:15:40 INFO - mozversion platform_version: 52.0a2 00:15:40 INFO - using testdate: 1480061740 00:15:40 INFO - actual date: 1480061740 00:15:40 INFO - starting webserver on 'localhost:51557' 00:15:40 INFO - SUITE-START | Running 2 tests 00:15:40 INFO - TEST-START | dromaeo_css 00:15:40 INFO - operating with platform_type : mac_ 00:15:40 INFO - Initialising browser for dromaeo_css test... 00:15:40 INFO - TEST-INFO | started process 1918 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/tmp2WJEwS/profile http://localhost:51557/getInfo.html) 00:15:43 INFO - PROCESS | 1918 | __metrics Screen width/height:1600/1200 00:15:43 INFO - PROCESS | 1918 | colorDepth:24 00:15:43 INFO - PROCESS | 1918 | Browser inner width/height: 1024/689 00:15:43 INFO - PROCESS | 1918 | __metrics 00:15:43 INFO - PROCESS | 1918 | 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" 00:15:43 INFO - PROCESS | 1918 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 00:15:44 INFO - TEST-INFO | 1918: exit 0 00:15:44 INFO - Browser initialized. 00:15:44 INFO - Running cycle 1/1 for dromaeo_css test... 00:15:44 INFO - TEST-INFO | started process 1920 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/tmp2WJEwS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:15:56 INFO - PROCESS | 1920 | RSS: Main: 223051776 00:15:56 INFO - PROCESS | 1920 | 00:16:48 INFO - PROCESS | 1920 | 1480061808295 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] 00:19:01 INFO - PROCESS | 1920 | Cycle 1(1): loaded http://localhost:51557/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51557/tests/dromaeo/cssquery-ext.html) 00:19:03 INFO - PROCESS | 1920 | RSS: Main: 363798528 00:19:03 INFO - PROCESS | 1920 | 00:22:05 INFO - PROCESS | 1920 | Cycle 1(1): loaded http://localhost:51557/tests/dromaeo/cssquery-ext.html (next: http://localhost:51557/tests/dromaeo/cssquery-jquery.html) 00:22:06 INFO - PROCESS | 1920 | RSS: Main: 346714112 00:22:06 INFO - PROCESS | 1920 | 00:25:08 INFO - PROCESS | 1920 | Cycle 1(1): loaded http://localhost:51557/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51557/tests/dromaeo/cssquery-mootools.html) 00:25:09 INFO - PROCESS | 1920 | RSS: Main: 313831424 00:25:09 INFO - PROCESS | 1920 | 00:28:11 INFO - PROCESS | 1920 | Cycle 1(1): loaded http://localhost:51557/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51557/tests/dromaeo/cssquery-prototype.html) 00:28:12 INFO - PROCESS | 1920 | RSS: Main: 322768896 00:28:12 INFO - PROCESS | 1920 | 00:31:15 INFO - PROCESS | 1920 | Cycle 1(1): loaded http://localhost:51557/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51557/tests/dromaeo/cssquery-yui.html) 00:31:16 INFO - PROCESS | 1920 | RSS: Main: 313774080 00:31:16 INFO - PROCESS | 1920 | 00:34:19 INFO - PROCESS | 1920 | Cycle 1(1): loaded http://localhost:51557/tests/dromaeo/cssquery-yui.html (next: http://localhost:51557/tests/dromaeo/cssquery-dojo.html) 00:34:19 INFO - PROCESS | 1920 | RSS: Main: 498814976 00:34:19 INFO - PROCESS | 1920 | 00:34:19 INFO - PROCESS | 1920 | __start_tp_report 00:34:19 INFO - PROCESS | 1920 | _x_x_mozilla_page_load 00:34:19 INFO - PROCESS | 1920 | _x_x_mozilla_page_load_details 00:34:19 INFO - PROCESS | 1920 | |i|pagename|runs| 00:34:19 INFO - PROCESS | 1920 | |0;dojo.html;3350.648181940896;3361.9495707564383;3370.4607262837935;3371.730261579074;3413.573303337083;5673.347565030022;5730.255066841305;5741.913871291928;5798.710064496774;5811.302643682753;5681.914771278434;5782.971085144579;5784.161296611999;5795.1017592273265;5795.623284486505;5219.399769026563;5268.367795864501;5269.8946021079555;5274.235235890794;5291.920621190685;5197.792088316482;5238.188080847455;5241.371035475749;5252.949410117973;5336.706481143536;5279.815206467755;5330.946690533103;5335.573154147659;5338.91991620126;5364.758585863626;5311.256424100629;5317.361916570037;5334.813281535166;5360.437154098822;5388.973055134699;5061.772220250097;5178.197379406198;5239.502247286502;5266.920996185022;5290.92063619046;5687.943120568824;5699.971500142514;5717.771289148429;5721.284839395075;5724.398938111522;6149.631022138641;6193.1639228704;6194.876102477925;6196.783112591038;6225.315215326311;6162.198914141133;6189;6203.751849925998;6231.87536249277;6248.187735594389;5901.996660567714;5917.881642367129;5933.139694744238;5936.406359364029;5939.2575928009;6352.301246862841;6367.235931688135;6376.840578985562;6378.585391949508;6402.903956440658;6094.878102437927;6094.908576371358;6128.105930682418;6129.584095998567;6134.957077261001;6209.223847019122;6257.2804127525005;6270.717817698214;6271.96864015677;6274.6548939808745;6235.968820155961;6236.968815155895;6244.438000579969;6249.7187626556915;6256.468200202943;6124.177795495262;6144.525313924715;6162.845928851814;6202.224721909762;6251.687415629201;4899.608031357483;4913.1893237615395;4924.965757190958;4925.630577706686;4987.476314986874;3694.685951694082;4428.7121337112985;4437.245668236407;4453.19842428366;4494.617957473652;5869.119632055226;5872.0898260769645;5884.558658100659;5885.087811389241;5888;6153.892299386064;6205.627662340274;6232.283287421914;6252.062190671436;6255.374462553708;4862.5380588844;5781.248437703071;5862.765489380377;5910.674912879833;5929.644221346733;5445.319335083114;5454.59090568209;5465.535429488458;5481.862953426196;5485.067538518461;6199.969000154971;6353.110564520971;6425.036244563353;6439.227292724903;6444.9355506444335;6184.072389141665;6253.530985176039;6253.7185826637;6254.687265636791;6262.029385445263;4973.47778483254;6270.090836828543;6284.183056202664;6343.365663433619;6352.777652782131;6198.946179149646;6212.937870621236;6228.844278892882;6235.220597425322;6246.562740608114;6243.937560624336;6252.812415627538;6261;6266.436020758154;6274.968625156845;5859.150423188699;6121.051237058263;6179.28938172116;6194.5973511721595;6214.533909956681;5877.765669209343;6130.938690613037;6134.693265336626;6160.014397696526;6164.013757798731;5417.322834645669;6079.787207447718;6115.205023346936;6120.204373431426;6121.081837724377;5517.896420715953;5729.598928074995;5836.620619659709;6184.5980011299125;6213.596116252429;5901;6004.0693692477735;6071.967765479967;6081.939180608138;6117.705611546597 00:34:19 INFO - PROCESS | 1920 | |1;ext.html;509.3836457885958;510.5532658923442;511.8285374399576;527.6412039812926;546.5354448718592;29074.41851162978;30018;38637.42043869344;39573;39685.007874803145;40114.99712507189;40456;40456;40537.59462405375;40580.79709601448;9179.173874351307;9393.953030234874;9394.62421503139;9396.718098457022;9405.576749046275;9237.584308706091;9318.207952324026;9331.160195582395;9331.206847417978;9341.439513629204;9293;9354.953225233898;9377.906220937806;9380.0619938006;9396.671116510923;9267.675631352904;9311.487868167249;9341.906580934172;9344.719658410258;9355.532223388838;9128.087191280885;9192.494412807293;9196.218321442686;9224.769380765467;9235.769105772342;34291.48562771561;35517.28965420703;37894.43158352627;38227.80886095552;38248;2969.4506516294555;3298.686624770643;3306.9007929762147;3656.524651795276;3767.547894252707;28625.427491450264;29864.805407783664;29967.550486742715;30689;30749;3134.498480243173;3207.759418043656;3208.1498402923244;3208.197950512372;3211.8715251389917;438281;438772;439308;439412;439753.801230995;32238.67761322357;55593.722031390396;55821;55889;55942.44057559453;33634.15914602154;34321.48517772235;34523.13692157716;34622;34670.65329346725;13340.199588024556;13681.452741890413;13821.516246931309;13829.031967762361;13862.584122476343;11332.093432525378;11338.546458141747;11801.881981180079;11882.940585297018;11893.04855611549;7642.426817988673;7665.5017423868385;7719.845603087907;7721.073471183494;7762.262585054411;3130.6086739157604;3150.3856747933933;3195.7123858852815;3202.6797320267788;3233.9029829105166;822.387321445515;959.947202903847;966.9758256043515;1006.5923301063082;1025.108155904368;26141.692915354157;26871.596926046124;27820.16539503818;29299.7070029297;29484.852575736983;866.3415803989013;899.8065415935605;968.5544649461186;1044.587387981743;1093.1801149138146;12503.687407814878;14816.703665926621;14882.627934301729;14949.103053816805;14949.819827868678;659887;661860;662176;662727;664436;48482.75758621185;49320.75339623279;54930;55395.16907246395;55558;1321.6101250131235;1326.3368315842272;1369.452219112343;1369.6096612465399;1375.2092546785839;29207.85396073091;29694.851525742233;29969.850150749106;30412;30662.693373065984;48682.513174867796;52401.737991311325;53764;53929.11248106454;57507.42492575021;606755;611413;613301;817674;839942;39018;39034.21931561296;39381.606183937794;39592;39873.60126398699;43206.783966079965;43268.35097473604;43343.56656433521;43820.12359752851;43945.68162955117;35760.10599735028;36112.81943590265;36259.81870090738;36287;37270.44094338641;36324.09189770277;36411.81794091013;36958.44562331621;37198;37716.62283377131;35642.46536301906;36063.27873442569;36165;36342;36874.262514750095;31036.534451982785;32937;33115.668843312225;33338.499922501665;33399.666003339655;23979.760202397752;24345.878270608533;24360;24833.255002349957;24868.502629947663 00:34:19 INFO - PROCESS | 1920 | |2;jquery.html;321.6220940395035;370.17081736909313;379.7930128080196;392.2958289869684;404.07056306138185;17865.55336116596;19884;20559;20805.583888322242;20899.895500522474;19108.808911910877;19450.319238826643;19651.901740491314;20063.796172229675;20090.497737556532;4882.02359528094;5532.834014979557;5927.110933359987;5998.9700051499685;6000.75996960121;5747.080467125261;5930.673812940287;5936.5250779937505;5953.136795164699;5965.821025369224;4230.598093181143;4741.976290118545;5138.2806407103035;5912.615679980788;5932.644041357532;5697.8005769798065;5721.456461636138;5839.240898683186;5862.355140934494;5870.501007414354;4890.951090489085;5112.130937740593;5156.948430515722;5164.483551644806;5188.948110518884;24739.134130305534;24822.882970266382;24980.750192498024;25354.859031343633;25689;2224.443889027743;2245.9887700561558;2300;2401.6397540369085;3135.216195951012;10364.96350364965;11582.710432239177;12588.181768185039;14361.640958976004;16022.75965860513;1605.5022942887626;1829.9634007319912;1856.3966710819038;1896.6586014517381;1949.0741897598668;123664;124088;129038;130649.04026439611;130880.34559827235;9521.857172142423;11262.211645184758;11603.783885729114;11614.76770464586;11834.171607987359;14726;14752.041117327553;14760.926195368955;14763.926180369244;14799.630009249855;7949.046114466301;8067.919320806717;8348.791280217922;8359.456635318686;8471.195236452528;7333.936579195986;8200.712975045844;8214;8343.207395297437;8348.749537513884;4349.652027837766;4516.8193272268745;4521.525239849834;4528.547145285445;4588;1877.4273846476683;1904.209752952537;1930.5366711989022;1957.7944315846917;1967.3999430173935;688.900109484132;702.7013519254297;749.6738918570439;822.6298165825402;899.7075950316173;20066.598668026556;20587.279445219647;21508.375728998635;21622.783772162078;21831.563368732535;868.5049521772655;880.8766772651834;891.6656253904786;911.9908800911905;930.9441433514011;9374.531273436301;10534.789304213873;11214.495347709373;11323.207375483802;11449.313041217554;546844;547724;572139;593746.0312698409;595513;121368;128609;129999;130882;132599;957.8611101390399;997.7954519323521;1003.2994075042878;1008.2236677758232;1025.9127974122134;18814.717779233597;19079.713804293224;19498.415047548595;19517;19571.70642440336;48116.31592894232;51861.22208166805;52769.47230527645;54748.45251547434;55063.44936550583;72699;78490.6075469619;82368;83645;84346.57826710827;7639.159692433716;7993.6802527898235;8149.877751833608;8340.332773378237;8445.902032735705;9537;12449.128561000643;12656.620301390973;12788.232706037668;13244.735105298032;10270.229732769923;10484.79030419372;10878.836817447585;11394.37330946806;11479.827802583135;11026.834597480884;11037.282576632493;11158.330500170016;11230.382328971986;11422.714432139262;11184.217104802585;11356.318620882774;11478.94260528692;11701.707457313636;11764.235324703866;8021.799455013671;8122.878156827771;8224.506529608243;8228;8348.916510834813;8161.387895907949;10502.632407865687;10797.406142662228;10881.727956801144;11211.047060999907 00:34:19 INFO - PROCESS | 1920 | |3;mootools.html;1482.7182835261297;1503.3310176971247;1566.4125952767713;1654.470569417786;1823.0884557721147;8157.38819588531;8605.569721513923;8607.91392086079;8618.00892897317;8619.78450538736;7556.093268807737;7752.806179845506;8228.341732661387;8422.326213902888;8518.23335899769;2307.849989750665;2357.8821058946983;2362.2676970139228;2363.7399886012527;2372.015613520387;2363.7045369328835;2376.239603326936;2389.593769059264;2407.4462873539096;2413.8793060347;2331.114176612885;2331.3122628824535;2355.528894221154;2360.5986982213067;2367.573836709392;2329.231353653299;2331.300609817065;2351.1065794997876;2355.51711899062;2360.7639236076257;1999.4601457606511;2371.8102551795814;2385.1890357278435;2393.0667039854297;2400.23192578375;8383.16168383163;8571.357148213909;8574.271186949054;8582.87125693115;8595.269402100828;4895.8776030599165;5230.032443997835;5232.92150617741;5239.083160446945;5248.107821670325;3259.38071766366;3407.369636617209;3565.928681426383;3567.090391950036;3583.4087375583;1023.9283250172491;1040.490159821682;1070.689500044986;1076.1552181537456;1088.0207812968392;10898;10989.285696429786;11098.722531936686;11126.332420054823;11144.944275278653;4546.840860569931;4654.371659825948;4756;4758.357621721093;4860.198067318924;9710.514474276259;9715.562799674031;9717.757056073655;9721.027897210364;9750.658726944523;5275.050490911673;5276.366835979707;5277.551408130275;5278.261043453919;5281.577473802086;4266.466691663542;4317.266064768997;4707.411573553306;4758.833440829623;4838.250071238964;3814.5231846019246;3846.788426636506;3905.9688961740303;3951.328274193394;3958.7822669752863;1092.448313601626;1265.0828149591473;1267.645059383374;1270.834791477102;1293.805929110641;740.8740514112505;794.7059587952494;811.8051667599733;854.8418542569644;892.3664198419066;5521.669978149142;5529.834104976857;5730.19777231188;5768.1636162757;5941.168236446901;878.9692360767342;941.1670671455674;945.3618807305041;951.4386511957978;954.4607296877242;3721.7532126737306;3833.156705524836;3915.667168290727;3919.6668283195677;3971.384435412516;9855.457949812686;10297.9485102574;10342.741431464274;10376.221783366278;10398.064174224357;9748.122668959686;10263.692089237185;10282.023207795248;10284.125849302744;10285.02292282232;6854.2117656469145;7775.377969762406;7803;7974.202579741979;7978.082520510099;5814.186013958134;5821.534277257894;5828.251524542705;5866.530677545701;5944.73248703818;9555.856662150212;9614.326997110134;9641.084097010773;9722.805543889224;9767.755806104904;9352.251819854531;9759.072888075621;9766.46284454362;9890.752731181778;9895.406275623485;5598.496135347838;6670.466362691071;6894.8621027580175;6907.34380233877;7449.4412919032375;6667.366600172976;7707.190744971922;7848.843023139391;7884.763457096296;8054;7116.928830711834;7260.818479537843;7289.635518224173;7299.5255308404785;7405.8148546286775;5954.345022047658;7041.753538626123;7075.964620177072;7210.747623833141;7308.049953506223;6684.131062961784;6711.362420570038;6735.158105236846;6816.2502124767225;6850.246472887879;4849.369581954324;4851.32081508582;4892.363992681071;4917.508249175054;4943.728094954812;4872.951270487391;4875.268709693574;4901.21580547125;5018.197088465828;5043.773030213722 00:34:19 INFO - PROCESS | 1920 | |4;prototype.html;248.26018464974376;303.8161912043215;309.4924324108463;313.8775877407811;316.92076980754814;5241.42344342122;5263.710495922723;5297.523222909942;5322.210010990111;5415.404305526389;4464.352668863013;4562.70342427742;4992.076465853819;5086.643934924557;5093.719845408501;3983.422403751454;4008.3386241270155;4029.012891841503;4033.3949907513816;4044.8382064717375;3348.4475061614926;3637.25411802335;3696.1498855263308;4016.8995775105564;4020.4572382728397;3325.7528426839935;3381.292447314103;3720.7395482316247;3989.640932316091;4008.97995510022;3303.7522185836037;3971.609936522223;3976.1113471101285;3993.600639935983;4014.518257809067;2930.662973758024;3451.8274086295582;3460.480927860816;3462.221000274943;3484.9128771780656;4434.556544345572;4880.633952453581;4903.950960490385;5084.211947148198;5091.719955402451;3933.8819835404984;3988.088695252453;4541.523140070278;4546.954530454686;4565.589096981287;4877.3903262092235;5213.471466338822;5364.91952620711;5442.469251797933;5473.907242408027;3558.8255875561003;3851.4993050903204;3869.593692662258;3871.457995880579;3882.223555288925;5120.846374608767;5214.504622060898;5596.1605759136455;5639.2668953036255;5709.942900570941;3886.9028274292805;3899.863504777319;3903.8048097595006;3911.7311959064245;3914.667253283446;3163.9525407118913;3917.138229589486;3974.8012599369918;3980.3132885302307;3982.0841206522664;3640.3993341098953;4428.247197976288;4603.033362993742;4614.630829533629;4615.353850460938;4393.604575588149;4687.874910021638;4857.635677324144;5425.647332923348;5460.7542660579575;1244.8568414632252;1270.5807083662369;1287.3177216075494;1296.12709634579;1304.3869381390732;747.0214019634296;788.251161396664;837.4682076881224;860.7805009722481;882.9028806831243;3941.664958478504;4242.61816436516;4732.455767586703;4738.64460165489;4846.715620360607;5253.133233016586;5284.894302113936;5298.549623281987;5316.979547771822;5399.623096110467;4003.7597744135446;4026.697997650188;4453.710508816981;4454.22051141049;4837.879053023703;2200.4388880835286;2425.9675405742823;2692.165428717065;2766.9723302767115;2817.9714404242304;4647.721136731807;5244.9737751310995;5467.234587157801;5467.6719396836315;5493.972530137404;4268.886900983971;4672.602828759525;4896.755162241873;5128.512791284822;5352.732363381815;4470.865874023784;4849.563539281482;5060.696358218519;5148.3564554430695;5447.645903016291;4237.800460652188;4780.545848144421;5094.617903657167;5250.501202385767;5327.360716714019;4813.387515182313;5163.09645811989;5195.830543368466;5202.959408118316;5247.239150323106;5127.66670166438;5135.768890400016;5415.783368665209;5422.755975981169;5439.456054394529;3028.9100520640686;3185.824779637142;3206.951895721613;3688.6126956670237;3690.8892733218045;4394.682015078595;4661.226570190731;4886.95113048865;5275.702685821361;5401.378841433128;4429.734215947054;4987.351644286219;5095.286659867696;5103.642745007814;5134.229865520202;4360.4767427908855;4833.371661683958;5073.492650734897;5117.667351622133;5167.974160129175;4028.697847661496;4996.525330093636;5017.8243761468175;5025.47232540578;5081.898362032664;5220.895582088413;5371.677699338053;5398.271233383443;5419.268398766116;5560.916586251128;4741.680823423549;5404.973135074347;5416.21464887597;5417.341086665543;5432.782688692408 00:34:19 INFO - PROCESS | 1920 | |5;yui.html;706.269011573022;734.8438456827923;745.0761056290202;755.8299382638907;781.7146741439375;14115.647108822284;14614;14630.195339256337;14648.780268295985;14670.559883203494;12869.935650321735;12920.935395323033;12932.806007909865;12935.482580696784;12936.417861196245;648.935106489352;671.4829581222456;671.6138220523219;804.6821505505322;823.4194892600702;789.6209819286746;806.9273765361116;822.0464261456711;822.6832669422256;826.7395770332326;876.6800117956964;876.7808047988003;880.9867851982225;881.5239770523941;883.7878909061811;689.0869597782907;713.290276175209;872.6596627315353;873.8033942362981;877.9253763430116;547.2693953571986;627.7677259414046;648.2965981909633;654.3227759269181;656.1601150527333;14093.154410735286;14132.22272774997;14136;14141.787873181911;14170.929145354206;12030.097742669335;12686.49254029847;13134.60596182116;13155.342232888317;13225.801612975813;11633.302001879914;11874.881251187464;11896.464659090361;12015.879841201651;12064.45709943046;4287.6141147296585;4290.76400797956;4360.928558934481;4397.802098905557;4447.3773671685985;82772;87380.5630971841;89016.55491722564;90462.54768726115;93947.24211031619;9970.501474926225;10305.227107966932;10378.169746420268;10489.213309001856;10506.947465262625;19213.903930480257;19221.90389048046;19235.615287694167;19741.309054183323;19834.404967850987;7156.606386648784;7320.597367144752;7380.889286660705;7603.809904752426;7647.197044310378;4108.856190033334;4208.789560521962;4269.530351661315;4293.119910418357;4405.229084910128;3132.859021344045;3188.39420510107;3225.5645487859306;3225.8225747601955;3228.0638614801915;590.7459792289357;591.4913174669781;597.6682940967718;598.8203538938388;604.8427408873724;507.72329080651133;516.5660844890235;549.8185598752482;583.107844997155;588.2705445247948;7666.658334791501;8355.119933994045;8359.414840961075;8673.566321683891;8738.956305218433;529.7298377827336;530.4032962916718;571.9685417302088;591.3908674065719;626.0734113511937;4024.8188831502653;4234.259004674232;4320.222359975234;4355.747511604227;4370.082282720664;108130.3780443294;113616;116400;117819.41090294493;122174.38912805557;77015;77278;84425.15574844296;105733;109196.08617612226;61707;80223.9944001393;80437.59781201057;83264.58367708123;84438.57780711057;7425.997490338951;9101.863472048057;9274.304427168125;9608.951955240458;9654.372465789702;37052.25895482129;38152.42771358376;40196.598034019284;48786.51213487962;51802;62412;76953;78786.6060669693;79309;80563;12140.33228172459;12314.692132696755;12340.753184936071;12519.123661343618;12608.74782504363;13560;13886.305684715926;14088.15471071739;14200.644983875072;14246.92876535652;8173.956510872282;9372.297077719335;12090.939545302217;12746.362681866056;13025.577043044292;12286.078544109407;12403.813942791046;12922;13498.46006159782;13499.392527336096;11950.223235489666;12658.746825063632;12748.362581871055;12771.552995645108;12778.105532612628;10503.369797812156;10827.350358978487;11230.66308010761;11444.713882153013;11865.703357416134;9406.38858474197;10096.646617368357;10993.560257589606;11310.717232068935;11393.943030284796 00:34:19 INFO - PROCESS | 1920 | __end_tp_report 00:34:19 INFO - PROCESS | 1920 | __start_cc_report 00:34:19 INFO - PROCESS | 1920 | _x_x_mozilla_cycle_collect,1654 00:34:19 INFO - PROCESS | 1920 | __end_cc_report 00:34:19 INFO - PROCESS | 1920 | __startTimestamp1480062859816__endTimestamp 00:34:19 INFO - PROCESS | 1920 | 00:34:19 INFO - PROCESS | 1920 | ------- Summary: start ------- 00:34:19 INFO - PROCESS | 1920 | Number of tests: 6 00:34:19 INFO - PROCESS | 1920 | 00:34:19 INFO - PROCESS | 1920 | [#0] dojo.html Cycles:165 Average:5764.14 Median:5939.26 stddev:650.18 (10.9%) stddev-sans-first:623.99 00:34:19 INFO - PROCESS | 1920 | Values: 3350.6 3361.9 3370.5 3371.7 3413.6 5673.3 5730.3 5741.9 5798.7 5811.3 5681.9 5783.0 5784.2 5795.1 5795.6 5219.4 5268.4 5269.9 5274.2 5291.9 5197.8 5238.2 5241.4 5252.9 5336.7 5279.8 5330.9 5335.6 5338.9 5364.8 5311.3 5317.4 5334.8 5360.4 5389.0 5061.8 5178.2 5239.5 5266.9 5290.9 5687.9 5700.0 5717.8 5721.3 5724.4 6149.6 6193.2 6194.9 6196.8 6225.3 6162.2 6189.0 6203.8 6231.9 6248.2 5902.0 5917.9 5933.1 5936.4 5939.3 6352.3 6367.2 6376.8 6378.6 6402.9 6094.9 6094.9 6128.1 6129.6 6135.0 6209.2 6257.3 6270.7 6272.0 6274.7 6236.0 6237.0 6244.4 6249.7 6256.5 6124.2 6144.5 6162.8 6202.2 6251.7 4899.6 4913.2 4925.0 4925.6 4987.5 3694.7 4428.7 4437.2 4453.2 4494.6 5869.1 5872.1 5884.6 5885.1 5888.0 6153.9 6205.6 6232.3 6252.1 6255.4 4862.5 5781.2 5862.8 5910.7 5929.6 5445.3 5454.6 5465.5 5481.9 5485.1 6200.0 6353.1 6425.0 6439.2 6444.9 6184.1 6253.5 6253.7 6254.7 6262.0 4973.5 6270.1 6284.2 6343.4 6352.8 6198.9 6212.9 6228.8 6235.2 6246.6 6243.9 6252.8 6261.0 6266.4 6275.0 5859.2 6121.1 6179.3 6194.6 6214.5 5877.8 6130.9 6134.7 6160.0 6164.0 5417.3 6079.8 6115.2 6120.2 6121.1 5517.9 5729.6 5836.6 6184.6 6213.6 5901.0 6004.1 6072.0 6081.9 6117.7 00:34:19 INFO - PROCESS | 1920 | 00:34:19 INFO - PROCESS | 1920 | [#1] ext.html Cycles:180 Average:70787.86 Median:38237.90 stddev:165108.56 (431.8%) stddev-sans-first:165487.42 00:34:19 INFO - PROCESS | 1920 | Values: 509.4 510.6 511.8 527.6 546.5 29074.4 30018.0 38637.4 39573.0 39685.0 40115.0 40456.0 40456.0 40537.6 40580.8 9179.2 9394.0 9394.6 9396.7 9405.6 9237.6 9318.2 9331.2 9331.2 9341.4 9293.0 9355.0 9377.9 9380.1 9396.7 9267.7 9311.5 9341.9 9344.7 9355.5 9128.1 9192.5 9196.2 9224.8 9235.8 34291.5 35517.3 37894.4 38227.8 38248.0 2969.5 3298.7 3306.9 3656.5 3767.5 28625.4 29864.8 29967.6 30689.0 30749.0 3134.5 3207.8 3208.1 3208.2 3211.9 438281.0 438772.0 439308.0 439412.0 439753.8 32238.7 55593.7 55821.0 55889.0 55942.4 33634.2 34321.5 34523.1 34622.0 34670.7 13340.2 13681.5 13821.5 13829.0 13862.6 11332.1 11338.5 11801.9 11882.9 11893.0 7642.4 7665.5 7719.8 7721.1 7762.3 3130.6 3150.4 3195.7 3202.7 3233.9 822.4 959.9 967.0 1006.6 1025.1 26141.7 26871.6 27820.2 29299.7 29484.9 866.3 899.8 968.6 1044.6 1093.2 12503.7 14816.7 14882.6 14949.1 14949.8 659887.0 661860.0 662176.0 662727.0 664436.0 48482.8 49320.8 54930.0 55395.2 55558.0 1321.6 1326.3 1369.5 1369.6 1375.2 29207.9 29694.9 29969.9 30412.0 30662.7 48682.5 52401.7 53764.0 53929.1 57507.4 606755.0 611413.0 613301.0 817674.0 839942.0 39018.0 39034.2 39381.6 39592.0 39873.6 43206.8 43268.4 43343.6 43820.1 43945.7 35760.1 36112.8 36259.8 36287.0 37270.4 36324.1 36411.8 36958.4 37198.0 37716.6 35642.5 36063.3 36165.0 36342.0 36874.3 31036.5 32937.0 33115.7 33338.5 33399.7 23979.8 24345.9 24360.0 24833.3 24868.5 00:34:19 INFO - PROCESS | 1920 | 00:34:19 INFO - PROCESS | 1920 | [#2] jquery.html Cycles:180 Average:34593.07 Median:25167.80 stddev:95967.23 (381.3%) stddev-sans-first:96201.95 00:34:19 INFO - PROCESS | 1920 | Values: 321.6 370.2 379.8 392.3 404.1 17865.6 19884.0 20559.0 20805.6 20899.9 19108.8 19450.3 19651.9 20063.8 20090.5 4882.0 5532.8 5927.1 5999.0 6000.8 5747.1 5930.7 5936.5 5953.1 5965.8 4230.6 4742.0 5138.3 5912.6 5932.6 5697.8 5721.5 5839.2 5862.4 5870.5 4891.0 5112.1 5156.9 5164.5 5188.9 24739.1 24822.9 24980.8 25354.9 25689.0 2224.4 2246.0 2300.0 2401.6 3135.2 10365.0 11582.7 12588.2 14361.6 16022.8 1605.5 1830.0 1856.4 1896.7 1949.1 123664.0 124088.0 129038.0 130649.0 130880.3 9521.9 11262.2 11603.8 11614.8 11834.2 14726.0 14752.0 14760.9 14763.9 14799.6 7949.0 8067.9 8348.8 8359.5 8471.2 7333.9 8200.7 8214.0 8343.2 8348.7 4349.7 4516.8 4521.5 4528.5 4588.0 1877.4 1904.2 1930.5 1957.8 1967.4 688.9 702.7 749.7 822.6 899.7 20066.6 20587.3 21508.4 21622.8 21831.6 868.5 880.9 891.7 912.0 930.9 9374.5 10534.8 11214.5 11323.2 11449.3 546844.0 547724.0 572139.0 593746.0 595513.0 121368.0 128609.0 129999.0 130882.0 132599.0 957.9 997.8 1003.3 1008.2 1025.9 18814.7 19079.7 19498.4 19517.0 19571.7 48116.3 51861.2 52769.5 54748.5 55063.4 72699.0 78490.6 82368.0 83645.0 84346.6 7639.2 7993.7 8149.9 8340.3 8445.9 9537.0 12449.1 12656.6 12788.2 13244.7 10270.2 10484.8 10878.8 11394.4 11479.8 11026.8 11037.3 11158.3 11230.4 11422.7 11184.2 11356.3 11478.9 11701.7 11764.2 8021.8 8122.9 8224.5 8228.0 8348.9 8161.4 10502.6 10797.4 10881.7 11211.0 00:34:19 INFO - PROCESS | 1920 | 00:34:19 INFO - PROCESS | 1920 | [#3] mootools.html Cycles:180 Average:5513.72 Median:5236.00 stddev:3034.70 (58.0%) stddev-sans-first:3028.09 00:34:19 INFO - PROCESS | 1920 | Values: 1482.7 1503.3 1566.4 1654.5 1823.1 8157.4 8605.6 8607.9 8618.0 8619.8 7556.1 7752.8 8228.3 8422.3 8518.2 2307.8 2357.9 2362.3 2363.7 2372.0 2363.7 2376.2 2389.6 2407.4 2413.9 2331.1 2331.3 2355.5 2360.6 2367.6 2329.2 2331.3 2351.1 2355.5 2360.8 1999.5 2371.8 2385.2 2393.1 2400.2 8383.2 8571.4 8574.3 8582.9 8595.3 4895.9 5230.0 5232.9 5239.1 5248.1 3259.4 3407.4 3565.9 3567.1 3583.4 1023.9 1040.5 1070.7 1076.2 1088.0 10898.0 10989.3 11098.7 11126.3 11144.9 4546.8 4654.4 4756.0 4758.4 4860.2 9710.5 9715.6 9717.8 9721.0 9750.7 5275.1 5276.4 5277.6 5278.3 5281.6 4266.5 4317.3 4707.4 4758.8 4838.3 3814.5 3846.8 3906.0 3951.3 3958.8 1092.4 1265.1 1267.6 1270.8 1293.8 740.9 794.7 811.8 854.8 892.4 5521.7 5529.8 5730.2 5768.2 5941.2 879.0 941.2 945.4 951.4 954.5 3721.8 3833.2 3915.7 3919.7 3971.4 9855.5 10297.9 10342.7 10376.2 10398.1 9748.1 10263.7 10282.0 10284.1 10285.0 6854.2 7775.4 7803.0 7974.2 7978.1 5814.2 5821.5 5828.3 5866.5 5944.7 9555.9 9614.3 9641.1 9722.8 9767.8 9352.3 9759.1 9766.5 9890.8 9895.4 5598.5 6670.5 6894.9 6907.3 7449.4 6667.4 7707.2 7848.8 7884.8 8054.0 7116.9 7260.8 7289.6 7299.5 7405.8 5954.3 7041.8 7076.0 7210.7 7308.0 6684.1 6711.4 6735.2 6816.3 6850.2 4849.4 4851.3 4892.4 4917.5 4943.7 4873.0 4875.3 4901.2 5018.2 5043.8 00:34:19 INFO - PROCESS | 1920 | 00:34:19 INFO - PROCESS | 1920 | [#4] prototype.html Cycles:180 Average:4246.85 Median:4710.17 stddev:1282.05 (27.2%) stddev-sans-first:1250.02 00:34:19 INFO - PROCESS | 1920 | Values: 248.3 303.8 309.5 313.9 316.9 5241.4 5263.7 5297.5 5322.2 5415.4 4464.4 4562.7 4992.1 5086.6 5093.7 3983.4 4008.3 4029.0 4033.4 4044.8 3348.4 3637.3 3696.1 4016.9 4020.5 3325.8 3381.3 3720.7 3989.6 4009.0 3303.8 3971.6 3976.1 3993.6 4014.5 2930.7 3451.8 3460.5 3462.2 3484.9 4434.6 4880.6 4904.0 5084.2 5091.7 3933.9 3988.1 4541.5 4547.0 4565.6 4877.4 5213.5 5364.9 5442.5 5473.9 3558.8 3851.5 3869.6 3871.5 3882.2 5120.8 5214.5 5596.2 5639.3 5709.9 3886.9 3899.9 3903.8 3911.7 3914.7 3164.0 3917.1 3974.8 3980.3 3982.1 3640.4 4428.2 4603.0 4614.6 4615.4 4393.6 4687.9 4857.6 5425.6 5460.8 1244.9 1270.6 1287.3 1296.1 1304.4 747.0 788.3 837.5 860.8 882.9 3941.7 4242.6 4732.5 4738.6 4846.7 5253.1 5284.9 5298.5 5317.0 5399.6 4003.8 4026.7 4453.7 4454.2 4837.9 2200.4 2426.0 2692.2 2767.0 2818.0 4647.7 5245.0 5467.2 5467.7 5494.0 4268.9 4672.6 4896.8 5128.5 5352.7 4470.9 4849.6 5060.7 5148.4 5447.6 4237.8 4780.5 5094.6 5250.5 5327.4 4813.4 5163.1 5195.8 5203.0 5247.2 5127.7 5135.8 5415.8 5422.8 5439.5 3028.9 3185.8 3207.0 3688.6 3690.9 4394.7 4661.2 4887.0 5275.7 5401.4 4429.7 4987.4 5095.3 5103.6 5134.2 4360.5 4833.4 5073.5 5117.7 5168.0 4028.7 4996.5 5017.8 5025.5 5081.9 5220.9 5371.7 5398.3 5419.3 5560.9 4741.7 5405.0 5416.2 5417.3 5432.8 00:34:19 INFO - PROCESS | 1920 | 00:34:19 INFO - PROCESS | 1920 | [#5] yui.html Cycles:180 Average:19998.97 Median:4358.34 stddev:29808.17 (683.9%) stddev-sans-first:29856.59 00:34:19 INFO - PROCESS | 1920 | Values: 706.3 734.8 745.1 755.8 781.7 14115.6 14614.0 14630.2 14648.8 14670.6 12869.9 12920.9 12932.8 12935.5 12936.4 648.9 671.5 671.6 804.7 823.4 789.6 806.9 822.0 822.7 826.7 876.7 876.8 881.0 881.5 883.8 689.1 713.3 872.7 873.8 877.9 547.3 627.8 648.3 654.3 656.2 14093.2 14132.2 14136.0 14141.8 14170.9 12030.1 12686.5 13134.6 13155.3 13225.8 11633.3 11874.9 11896.5 12015.9 12064.5 4287.6 4290.8 4360.9 4397.8 4447.4 82772.0 87380.6 89016.6 90462.5 93947.2 9970.5 10305.2 10378.2 10489.2 10506.9 19213.9 19221.9 19235.6 19741.3 19834.4 7156.6 7320.6 7380.9 7603.8 7647.2 4108.9 4208.8 4269.5 4293.1 4405.2 3132.9 3188.4 3225.6 3225.8 3228.1 590.7 591.5 597.7 598.8 604.8 507.7 516.6 549.8 583.1 588.3 7666.7 8355.1 8359.4 8673.6 8739.0 529.7 530.4 572.0 591.4 626.1 4024.8 4234.3 4320.2 4355.7 4370.1 108130.4 113616.0 116400.0 117819.4 122174.4 77015.0 77278.0 84425.2 105733.0 109196.1 61707.0 80224.0 80437.6 83264.6 84438.6 7426.0 9101.9 9274.3 9609.0 9654.4 37052.3 38152.4 40196.6 48786.5 51802.0 62412.0 76953.0 78786.6 79309.0 80563.0 12140.3 12314.7 12340.8 12519.1 12608.7 13560.0 13886.3 14088.2 14200.6 14246.9 8174.0 9372.3 12090.9 12746.4 13025.6 12286.1 12403.8 12922.0 13498.5 13499.4 11950.2 12658.7 12748.4 12771.6 12778.1 10503.4 10827.4 11230.7 11444.7 11865.7 9406.4 10096.6 10993.6 11310.7 11393.9 00:34:19 INFO - PROCESS | 1920 | -------- Summary: end -------- 00:34:19 INFO - PROCESS | 1920 | 00:34:20 INFO - TEST-INFO | 1920: exit 0 00:34:20 INFO - TEST-OK | dromaeo_css | took 1119882ms 00:34:20 INFO - TEST-START | kraken 00:34:20 INFO - operating with platform_type : mac_ 00:34:20 INFO - Initialising browser for kraken test... 00:34:20 INFO - TEST-INFO | started process 1924 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/tmp8Ea4l9/profile http://localhost:51557/getInfo.html) 00:34:23 INFO - PROCESS | 1924 | __metrics Screen width/height:1600/1200 00:34:23 INFO - PROCESS | 1924 | colorDepth:24 00:34:23 INFO - PROCESS | 1924 | Browser inner width/height: 1024/689 00:34:23 INFO - PROCESS | 1924 | __metrics 00:34:23 INFO - PROCESS | 1924 | 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" 00:34:23 INFO - PROCESS | 1924 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 00:34:24 INFO - TEST-INFO | 1924: exit 0 00:34:24 INFO - Browser initialized. 00:34:24 INFO - Running cycle 1/1 for kraken test... 00:34:24 INFO - TEST-INFO | started process 1925 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/tmp8Ea4l9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:34:36 INFO - PROCESS | 1925 | RSS: Main: 223088640 00:34:36 INFO - PROCESS | 1925 | 00:34:39 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:51557/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51557/tests/kraken/driver.html?testName=audio-beat-detection) 00:34:40 INFO - PROCESS | 1925 | RSS: Main: 272404480 00:34:40 INFO - PROCESS | 1925 | 00:34:42 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:51557/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51557/tests/kraken/driver.html?testName=audio-dft) 00:34:43 INFO - PROCESS | 1925 | RSS: Main: 281907200 00:34:43 INFO - PROCESS | 1925 | 00:34:46 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:51557/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51557/tests/kraken/driver.html?testName=audio-fft) 00:34:47 INFO - PROCESS | 1925 | RSS: Main: 378470400 00:34:47 INFO - PROCESS | 1925 | 00:34:49 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:51557/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51557/tests/kraken/driver.html?testName=audio-oscillator) 00:34:49 INFO - PROCESS | 1925 | RSS: Main: 283144192 00:34:49 INFO - PROCESS | 1925 | 00:34:51 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:51557/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51557/tests/kraken/driver.html?testName=imaging-gaussian-blur) 00:34:52 INFO - PROCESS | 1925 | RSS: Main: 284704768 00:34:52 INFO - PROCESS | 1925 | 00:34:55 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:51557/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51557/tests/kraken/driver.html?testName=imaging-darkroom) 00:34:55 INFO - PROCESS | 1925 | RSS: Main: 326225920 00:34:55 INFO - PROCESS | 1925 | 00:34:58 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:51557/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51557/tests/kraken/driver.html?testName=imaging-desaturate) 00:34:59 INFO - PROCESS | 1925 | RSS: Main: 325586944 00:34:59 INFO - PROCESS | 1925 | 00:35:02 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:51557/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51557/tests/kraken/driver.html?testName=json-parse-financial) 00:35:02 INFO - PROCESS | 1925 | RSS: Main: 321798144 00:35:02 INFO - PROCESS | 1925 | 00:35:04 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:51557/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51557/tests/kraken/driver.html?testName=json-stringify-tinderbox) 00:35:04 INFO - PROCESS | 1925 | RSS: Main: 301297664 00:35:04 INFO - PROCESS | 1925 | 00:35:06 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:51557/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51557/tests/kraken/driver.html?testName=stanford-crypto-aes) 00:35:07 INFO - PROCESS | 1925 | RSS: Main: 302751744 00:35:07 INFO - PROCESS | 1925 | 00:35:09 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:51557/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51557/tests/kraken/driver.html?testName=stanford-crypto-ccm) 00:35:10 INFO - PROCESS | 1925 | RSS: Main: 305463296 00:35:10 INFO - PROCESS | 1925 | 00:35:13 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:51557/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51557/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 00:35:14 INFO - PROCESS | 1925 | RSS: Main: 322363392 00:35:14 INFO - PROCESS | 1925 | 00:35:17 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:51557/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51557/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 00:35:17 INFO - PROCESS | 1925 | RSS: Main: 315637760 00:35:17 INFO - PROCESS | 1925 | 00:35:19 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:51557/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51557/tests/kraken/driver.html?testName=ai-astar) 00:35:19 INFO - PROCESS | 1925 | RSS: Main: 359677952 00:35:19 INFO - PROCESS | 1925 | 00:35:19 INFO - PROCESS | 1925 | __start_tp_report 00:35:19 INFO - PROCESS | 1925 | _x_x_mozilla_page_load 00:35:19 INFO - PROCESS | 1925 | _x_x_mozilla_page_load_details 00:35:19 INFO - PROCESS | 1925 | |i|pagename|runs| 00:35:19 INFO - PROCESS | 1925 | |0;ai-astar;160;167;148;115;114;114;114;114;114;114 00:35:19 INFO - PROCESS | 1925 | |1;audio-beat-detection;150;113;132;178;116;114;118;114;146;112 00:35:19 INFO - PROCESS | 1925 | |2;audio-dft;184;181;183;186;182;184;183;184;182;185 00:35:19 INFO - PROCESS | 1925 | |3;audio-fft;82;60;61;90;61;59;60;59;83;59 00:35:19 INFO - PROCESS | 1925 | |4;audio-oscillator;92;69;94;94;98;75;89;68;69;93 00:35:19 INFO - PROCESS | 1925 | |5;imaging-gaussian-blur;87;87;87;85;86;83;87;84;94;101 00:35:19 INFO - PROCESS | 1925 | |6;imaging-darkroom;96;96;95;95;96;96;97;96;96;97 00:35:19 INFO - PROCESS | 1925 | |7;imaging-desaturate;80;81;79;79;83;82;85;83;81;80 00:35:19 INFO - PROCESS | 1925 | |8;json-parse-financial;55;53;52;53;57;57;56;61;56;56 00:35:19 INFO - PROCESS | 1925 | |9;json-stringify-tinderbox;64;73;69;75;72;68;71;69;69;71 00:35:19 INFO - PROCESS | 1925 | |10;stanford-crypto-aes;85;84;96;100;75;92;95;103;104;91 00:35:19 INFO - PROCESS | 1925 | |11;stanford-crypto-ccm;146;165;139;163;153;162;151;153;211;173 00:35:19 INFO - PROCESS | 1925 | |12;stanford-crypto-pbkdf2;170;182;230;203;168;167;163;166;167;169 00:35:19 INFO - PROCESS | 1925 | |13;stanford-crypto-sha256-iterative;67;85;88;84;85;84;66;66;66;67 00:35:19 INFO - PROCESS | 1925 | __end_tp_report 00:35:19 INFO - PROCESS | 1925 | __start_cc_report 00:35:19 INFO - PROCESS | 1925 | _x_x_mozilla_cycle_collect,1988 00:35:19 INFO - PROCESS | 1925 | __end_cc_report 00:35:19 INFO - PROCESS | 1925 | __startTimestamp1480062919914__endTimestamp 00:35:19 INFO - PROCESS | 1925 | 00:35:19 INFO - PROCESS | 1925 | ------- Summary: start ------- 00:35:19 INFO - PROCESS | 1925 | Number of tests: 14 00:35:19 INFO - PROCESS | 1925 | 00:35:19 INFO - PROCESS | 1925 | [#0] ai-astar Cycles:10 Average:127.40 Median:114.50 stddev:21.82 (19.1%) stddev-sans-first:19.70 00:35:19 INFO - PROCESS | 1925 | Values: 160.0 167.0 148.0 115.0 114.0 114.0 114.0 114.0 114.0 114.0 00:35:19 INFO - PROCESS | 1925 | 00:35:19 INFO - PROCESS | 1925 | [#1] audio-beat-detection Cycles:10 Average:129.30 Median:125.00 stddev:22.17 (17.7%) stddev-sans-first:22.21 00:35:19 INFO - PROCESS | 1925 | Values: 150.0 113.0 132.0 178.0 116.0 114.0 118.0 114.0 146.0 112.0 00:35:19 INFO - PROCESS | 1925 | 00:35:19 INFO - PROCESS | 1925 | [#2] audio-dft Cycles:10 Average:183.40 Median:184.00 stddev:1.51 (0.8%) stddev-sans-first:1.58 00:35:19 INFO - PROCESS | 1925 | Values: 184.0 181.0 183.0 186.0 182.0 184.0 183.0 184.0 182.0 185.0 00:35:19 INFO - PROCESS | 1925 | 00:35:19 INFO - PROCESS | 1925 | [#3] audio-fft Cycles:10 Average:67.40 Median:61.00 stddev:12.34 (20.2%) stddev-sans-first:11.90 00:35:19 INFO - PROCESS | 1925 | Values: 82.0 60.0 61.0 90.0 61.0 59.0 60.0 59.0 83.0 59.0 00:35:19 INFO - PROCESS | 1925 | 00:35:19 INFO - PROCESS | 1925 | [#4] audio-oscillator Cycles:10 Average:84.10 Median:92.50 stddev:12.26 (13.3%) stddev-sans-first:12.67 00:35:19 INFO - PROCESS | 1925 | Values: 92.0 69.0 94.0 94.0 98.0 75.0 89.0 68.0 69.0 93.0 00:35:19 INFO - PROCESS | 1925 | 00:35:19 INFO - PROCESS | 1925 | [#5] imaging-gaussian-blur Cycles:10 Average:88.10 Median:87.00 stddev:5.40 (6.2%) stddev-sans-first:5.72 00:35:19 INFO - PROCESS | 1925 | Values: 87.0 87.0 87.0 85.0 86.0 83.0 87.0 84.0 94.0 101.0 00:35:19 INFO - PROCESS | 1925 | 00:35:19 INFO - PROCESS | 1925 | [#6] imaging-darkroom Cycles:10 Average:96.00 Median:96.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 00:35:19 INFO - PROCESS | 1925 | Values: 96.0 96.0 95.0 95.0 96.0 96.0 97.0 96.0 96.0 97.0 00:35:19 INFO - PROCESS | 1925 | 00:35:19 INFO - PROCESS | 1925 | [#7] imaging-desaturate Cycles:10 Average:81.30 Median:81.50 stddev:1.95 (2.4%) stddev-sans-first:2.01 00:35:19 INFO - PROCESS | 1925 | Values: 80.0 81.0 79.0 79.0 83.0 82.0 85.0 83.0 81.0 80.0 00:35:19 INFO - PROCESS | 1925 | 00:35:19 INFO - PROCESS | 1925 | [#8] json-parse-financial Cycles:10 Average:55.60 Median:56.00 stddev:2.59 (4.6%) stddev-sans-first:2.74 00:35:19 INFO - PROCESS | 1925 | Values: 55.0 53.0 52.0 53.0 57.0 57.0 56.0 61.0 56.0 56.0 00:35:19 INFO - PROCESS | 1925 | 00:35:19 INFO - PROCESS | 1925 | [#9] json-stringify-tinderbox Cycles:10 Average:70.10 Median:71.00 stddev:3.03 (4.3%) stddev-sans-first:2.28 00:35:19 INFO - PROCESS | 1925 | Values: 64.0 73.0 69.0 75.0 72.0 68.0 71.0 69.0 69.0 71.0 00:35:19 INFO - PROCESS | 1925 | 00:35:19 INFO - PROCESS | 1925 | [#10] stanford-crypto-aes Cycles:10 Average:92.50 Median:88.00 stddev:9.16 (10.4%) stddev-sans-first:9.30 00:35:19 INFO - PROCESS | 1925 | Values: 85.0 84.0 96.0 100.0 75.0 92.0 95.0 103.0 104.0 91.0 00:35:19 INFO - PROCESS | 1925 | 00:35:19 INFO - PROCESS | 1925 | [#11] stanford-crypto-ccm Cycles:10 Average:161.60 Median:162.50 stddev:20.00 (12.3%) stddev-sans-first:20.40 00:35:19 INFO - PROCESS | 1925 | Values: 146.0 165.0 139.0 163.0 153.0 162.0 151.0 153.0 211.0 173.0 00:35:19 INFO - PROCESS | 1925 | 00:35:19 INFO - PROCESS | 1925 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.50 Median:169.50 stddev:21.60 (12.7%) stddev-sans-first:22.69 00:35:19 INFO - PROCESS | 1925 | Values: 170.0 182.0 230.0 203.0 168.0 167.0 163.0 166.0 167.0 169.0 00:35:19 INFO - PROCESS | 1925 | 00:35:19 INFO - PROCESS | 1925 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.80 Median:84.00 stddev:9.98 (11.9%) stddev-sans-first:10.06 00:35:19 INFO - PROCESS | 1925 | Values: 67.0 85.0 88.0 84.0 85.0 84.0 66.0 66.0 66.0 67.0 00:35:19 INFO - PROCESS | 1925 | -------- Summary: end -------- 00:35:19 INFO - PROCESS | 1925 | 00:35:20 INFO - TEST-INFO | 1925: exit 0 00:35:20 INFO - TEST-OK | kraken | took 60491ms 00:35:20 INFO - SUITE-END | took 1180s 00:35:21 INFO - Completed test suite (00:19:40) 00:35:21 INFO - javascript benchmark 00:35:21 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3350.648181940896, 3361.9495707564383, 3370.4607262837935, 3371.730261579074, 3413.573303337083, 5673.347565030022, 5730.255066841305, 5741.913871291928, 5798.710064496774, 5811.302643682753, 5681.914771278434, 5782.971085144579, 5784.161296611999, 5795.1017592273265, 5795.623284486505, 5219.399769026563, 5268.367795864501, 5269.8946021079555, 5274.235235890794, 5291.920621190685, 5197.792088316482, 5238.188080847455, 5241.371035475749, 5252.949410117973, 5336.706481143536, 5279.815206467755, 5330.946690533103, 5335.573154147659, 5338.91991620126, 5364.758585863626, 5311.256424100629, 5317.361916570037, 5334.813281535166, 5360.437154098822, 5388.973055134699, 5061.772220250097, 5178.197379406198, 5239.502247286502, 5266.920996185022, 5290.92063619046, 5687.943120568824, 5699.971500142514, 5717.771289148429, 5721.284839395075, 5724.398938111522, 6149.631022138641, 6193.1639228704, 6194.876102477925, 6196.783112591038, 6225.315215326311, 6162.198914141133, 6189.0, 6203.751849925998, 6231.87536249277, 6248.187735594389, 5901.996660567714, 5917.881642367129, 5933.139694744238, 5936.406359364029, 5939.2575928009, 6352.301246862841, 6367.235931688135, 6376.840578985562, 6378.585391949508, 6402.903956440658, 6094.878102437927, 6094.908576371358, 6128.105930682418, 6129.584095998567, 6134.957077261001, 6209.223847019122, 6257.2804127525005, 6270.717817698214, 6271.96864015677, 6274.6548939808745, 6235.968820155961, 6236.968815155895, 6244.438000579969, 6249.7187626556915, 6256.468200202943, 6124.177795495262, 6144.525313924715, 6162.845928851814, 6202.224721909762, 6251.687415629201, 4899.608031357483, 4913.1893237615395, 4924.965757190958, 4925.630577706686, 4987.476314986874, 3694.685951694082, 4428.7121337112985, 4437.245668236407, 4453.19842428366, 4494.617957473652, 5869.119632055226, 5872.0898260769645, 5884.558658100659, 5885.087811389241, 5888.0, 6153.892299386064, 6205.627662340274, 6232.283287421914, 6252.062190671436, 6255.374462553708, 4862.5380588844, 5781.248437703071, 5862.765489380377, 5910.674912879833, 5929.644221346733, 5445.319335083114, 5454.59090568209, 5465.535429488458, 5481.862953426196, 5485.067538518461, 6199.969000154971, 6353.110564520971, 6425.036244563353, 6439.227292724903, 6444.9355506444335, 6184.072389141665, 6253.530985176039, 6253.7185826637, 6254.687265636791, 6262.029385445263, 4973.47778483254, 6270.090836828543, 6284.183056202664, 6343.365663433619, 6352.777652782131, 6198.946179149646, 6212.937870621236, 6228.844278892882, 6235.220597425322, 6246.562740608114, 6243.937560624336, 6252.812415627538, 6261.0, 6266.436020758154, 6274.968625156845, 5859.150423188699, 6121.051237058263, 6179.28938172116, 6194.5973511721595, 6214.533909956681, 5877.765669209343, 6130.938690613037, 6134.693265336626, 6160.014397696526, 6164.013757798731, 5417.322834645669, 6079.787207447718, 6115.205023346936, 6120.204373431426, 6121.081837724377, 5517.896420715953, 5729.598928074995, 5836.620619659709, 6184.5980011299125, 6213.596116252429, 5901.0, 6004.0693692477735, 6071.967765479967, 6081.939180608138, 6117.705611546597], "value": 5722.617273741188, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [509.3836457885958, 510.5532658923442, 511.8285374399576, 527.6412039812926, 546.5354448718592, 29074.41851162978, 30018.0, 38637.42043869344, 39573.0, 39685.007874803145, 40114.99712507189, 40456.0, 40456.0, 40537.59462405375, 40580.79709601448, 9179.173874351307, 9393.953030234874, 9394.62421503139, 9396.718098457022, 9405.576749046275, 9237.584308706091, 9318.207952324026, 9331.160195582395, 9331.206847417978, 9341.439513629204, 9293.0, 9354.953225233898, 9377.906220937806, 9380.0619938006, 9396.671116510923, 9267.675631352904, 9311.487868167249, 9341.906580934172, 9344.719658410258, 9355.532223388838, 9128.087191280885, 9192.494412807293, 9196.218321442686, 9224.769380765467, 9235.769105772342, 34291.48562771561, 35517.28965420703, 37894.43158352627, 38227.80886095552, 38248.0, 2969.4506516294555, 3298.686624770643, 3306.9007929762147, 3656.524651795276, 3767.547894252707, 28625.427491450264, 29864.805407783664, 29967.550486742715, 30689.0, 30749.0, 3134.498480243173, 3207.759418043656, 3208.1498402923244, 3208.197950512372, 3211.8715251389917, 438281.0, 438772.0, 439308.0, 439412.0, 439753.801230995, 32238.67761322357, 55593.722031390396, 55821.0, 55889.0, 55942.44057559453, 33634.15914602154, 34321.48517772235, 34523.13692157716, 34622.0, 34670.65329346725, 13340.199588024556, 13681.452741890413, 13821.516246931309, 13829.031967762361, 13862.584122476343, 11332.093432525378, 11338.546458141747, 11801.881981180079, 11882.940585297018, 11893.04855611549, 7642.426817988673, 7665.5017423868385, 7719.845603087907, 7721.073471183494, 7762.262585054411, 3130.6086739157604, 3150.3856747933933, 3195.7123858852815, 3202.6797320267788, 3233.9029829105166, 822.387321445515, 959.947202903847, 966.9758256043515, 1006.5923301063082, 1025.108155904368, 26141.692915354157, 26871.596926046124, 27820.16539503818, 29299.7070029297, 29484.852575736983, 866.3415803989013, 899.8065415935605, 968.5544649461186, 1044.587387981743, 1093.1801149138146, 12503.687407814878, 14816.703665926621, 14882.627934301729, 14949.103053816805, 14949.819827868678, 659887.0, 661860.0, 662176.0, 662727.0, 664436.0, 48482.75758621185, 49320.75339623279, 54930.0, 55395.16907246395, 55558.0, 1321.6101250131235, 1326.3368315842272, 1369.452219112343, 1369.6096612465399, 1375.2092546785839, 29207.85396073091, 29694.851525742233, 29969.850150749106, 30412.0, 30662.693373065984, 48682.513174867796, 52401.737991311325, 53764.0, 53929.11248106454, 57507.42492575021, 606755.0, 611413.0, 613301.0, 817674.0, 839942.0, 39018.0, 39034.21931561296, 39381.606183937794, 39592.0, 39873.60126398699, 43206.783966079965, 43268.35097473604, 43343.56656433521, 43820.12359752851, 43945.68162955117, 35760.10599735028, 36112.81943590265, 36259.81870090738, 36287.0, 37270.44094338641, 36324.09189770277, 36411.81794091013, 36958.44562331621, 37198.0, 37716.62283377131, 35642.46536301906, 36063.27873442569, 36165.0, 36342.0, 36874.262514750095, 31036.534451982785, 32937.0, 33115.668843312225, 33338.499922501665, 33399.666003339655, 23979.760202397752, 24345.878270608533, 24360.0, 24833.255002349957, 24868.502629947663], "value": 18241.474382043136, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [321.6220940395035, 370.17081736909313, 379.7930128080196, 392.2958289869684, 404.07056306138185, 17865.55336116596, 19884.0, 20559.0, 20805.583888322242, 20899.895500522474, 19108.808911910877, 19450.319238826643, 19651.901740491314, 20063.796172229675, 20090.497737556532, 4882.02359528094, 5532.834014979557, 5927.110933359987, 5998.9700051499685, 6000.75996960121, 5747.080467125261, 5930.673812940287, 5936.5250779937505, 5953.136795164699, 5965.821025369224, 4230.598093181143, 4741.976290118545, 5138.2806407103035, 5912.615679980788, 5932.644041357532, 5697.8005769798065, 5721.456461636138, 5839.240898683186, 5862.355140934494, 5870.501007414354, 4890.951090489085, 5112.130937740593, 5156.948430515722, 5164.483551644806, 5188.948110518884, 24739.134130305534, 24822.882970266382, 24980.750192498024, 25354.859031343633, 25689.0, 2224.443889027743, 2245.9887700561558, 2300.0, 2401.6397540369085, 3135.216195951012, 10364.96350364965, 11582.710432239177, 12588.181768185039, 14361.640958976004, 16022.75965860513, 1605.5022942887626, 1829.9634007319912, 1856.3966710819038, 1896.6586014517381, 1949.0741897598668, 123664.0, 124088.0, 129038.0, 130649.04026439611, 130880.34559827235, 9521.857172142423, 11262.211645184758, 11603.783885729114, 11614.76770464586, 11834.171607987359, 14726.0, 14752.041117327553, 14760.926195368955, 14763.926180369244, 14799.630009249855, 7949.046114466301, 8067.919320806717, 8348.791280217922, 8359.456635318686, 8471.195236452528, 7333.936579195986, 8200.712975045844, 8214.0, 8343.207395297437, 8348.749537513884, 4349.652027837766, 4516.8193272268745, 4521.525239849834, 4528.547145285445, 4588.0, 1877.4273846476683, 1904.209752952537, 1930.5366711989022, 1957.7944315846917, 1967.3999430173935, 688.900109484132, 702.7013519254297, 749.6738918570439, 822.6298165825402, 899.7075950316173, 20066.598668026556, 20587.279445219647, 21508.375728998635, 21622.783772162078, 21831.563368732535, 868.5049521772655, 880.8766772651834, 891.6656253904786, 911.9908800911905, 930.9441433514011, 9374.531273436301, 10534.789304213873, 11214.495347709373, 11323.207375483802, 11449.313041217554, 546844.0, 547724.0, 572139.0, 593746.0312698409, 595513.0, 121368.0, 128609.0, 129999.0, 130882.0, 132599.0, 957.8611101390399, 997.7954519323521, 1003.2994075042878, 1008.2236677758232, 1025.9127974122134, 18814.717779233597, 19079.713804293224, 19498.415047548595, 19517.0, 19571.70642440336, 48116.31592894232, 51861.22208166805, 52769.47230527645, 54748.45251547434, 55063.44936550583, 72699.0, 78490.6075469619, 82368.0, 83645.0, 84346.57826710827, 7639.159692433716, 7993.6802527898235, 8149.877751833608, 8340.332773378237, 8445.902032735705, 9537.0, 12449.128561000643, 12656.620301390973, 12788.232706037668, 13244.735105298032, 10270.229732769923, 10484.79030419372, 10878.836817447585, 11394.37330946806, 11479.827802583135, 11026.834597480884, 11037.282576632493, 11158.330500170016, 11230.382328971986, 11422.714432139262, 11184.217104802585, 11356.318620882774, 11478.94260528692, 11701.707457313636, 11764.235324703866, 8021.799455013671, 8122.878156827771, 8224.506529608243, 8228.0, 8348.916510834813, 8161.387895907949, 10502.632407865687, 10797.406142662228, 10881.727956801144, 11211.047060999907], "value": 9484.997562721537, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1482.7182835261297, 1503.3310176971247, 1566.4125952767713, 1654.470569417786, 1823.0884557721147, 8157.38819588531, 8605.569721513923, 8607.91392086079, 8618.00892897317, 8619.78450538736, 7556.093268807737, 7752.806179845506, 8228.341732661387, 8422.326213902888, 8518.23335899769, 2307.849989750665, 2357.8821058946983, 2362.2676970139228, 2363.7399886012527, 2372.015613520387, 2363.7045369328835, 2376.239603326936, 2389.593769059264, 2407.4462873539096, 2413.8793060347, 2331.114176612885, 2331.3122628824535, 2355.528894221154, 2360.5986982213067, 2367.573836709392, 2329.231353653299, 2331.300609817065, 2351.1065794997876, 2355.51711899062, 2360.7639236076257, 1999.4601457606511, 2371.8102551795814, 2385.1890357278435, 2393.0667039854297, 2400.23192578375, 8383.16168383163, 8571.357148213909, 8574.271186949054, 8582.87125693115, 8595.269402100828, 4895.8776030599165, 5230.032443997835, 5232.92150617741, 5239.083160446945, 5248.107821670325, 3259.38071766366, 3407.369636617209, 3565.928681426383, 3567.090391950036, 3583.4087375583, 1023.9283250172491, 1040.490159821682, 1070.689500044986, 1076.1552181537456, 1088.0207812968392, 10898.0, 10989.285696429786, 11098.722531936686, 11126.332420054823, 11144.944275278653, 4546.840860569931, 4654.371659825948, 4756.0, 4758.357621721093, 4860.198067318924, 9710.514474276259, 9715.562799674031, 9717.757056073655, 9721.027897210364, 9750.658726944523, 5275.050490911673, 5276.366835979707, 5277.551408130275, 5278.261043453919, 5281.577473802086, 4266.466691663542, 4317.266064768997, 4707.411573553306, 4758.833440829623, 4838.250071238964, 3814.5231846019246, 3846.788426636506, 3905.9688961740303, 3951.328274193394, 3958.7822669752863, 1092.448313601626, 1265.0828149591473, 1267.645059383374, 1270.834791477102, 1293.805929110641, 740.8740514112505, 794.7059587952494, 811.8051667599733, 854.8418542569644, 892.3664198419066, 5521.669978149142, 5529.834104976857, 5730.19777231188, 5768.1636162757, 5941.168236446901, 878.9692360767342, 941.1670671455674, 945.3618807305041, 951.4386511957978, 954.4607296877242, 3721.7532126737306, 3833.156705524836, 3915.667168290727, 3919.6668283195677, 3971.384435412516, 9855.457949812686, 10297.9485102574, 10342.741431464274, 10376.221783366278, 10398.064174224357, 9748.122668959686, 10263.692089237185, 10282.023207795248, 10284.125849302744, 10285.02292282232, 6854.2117656469145, 7775.377969762406, 7803.0, 7974.202579741979, 7978.082520510099, 5814.186013958134, 5821.534277257894, 5828.251524542705, 5866.530677545701, 5944.73248703818, 9555.856662150212, 9614.326997110134, 9641.084097010773, 9722.805543889224, 9767.755806104904, 9352.251819854531, 9759.072888075621, 9766.46284454362, 9890.752731181778, 9895.406275623485, 5598.496135347838, 6670.466362691071, 6894.8621027580175, 6907.34380233877, 7449.4412919032375, 6667.366600172976, 7707.190744971922, 7848.843023139391, 7884.763457096296, 8054.0, 7116.928830711834, 7260.818479537843, 7289.635518224173, 7299.5255308404785, 7405.8148546286775, 5954.345022047658, 7041.753538626123, 7075.964620177072, 7210.747623833141, 7308.049953506223, 6684.131062961784, 6711.362420570038, 6735.158105236846, 6816.2502124767225, 6850.246472887879, 4849.369581954324, 4851.32081508582, 4892.363992681071, 4917.508249175054, 4943.728094954812, 4872.951270487391, 4875.268709693574, 4901.21580547125, 5018.197088465828, 5043.773030213722], "value": 4460.763093245775, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [248.26018464974376, 303.8161912043215, 309.4924324108463, 313.8775877407811, 316.92076980754814, 5241.42344342122, 5263.710495922723, 5297.523222909942, 5322.210010990111, 5415.404305526389, 4464.352668863013, 4562.70342427742, 4992.076465853819, 5086.643934924557, 5093.719845408501, 3983.422403751454, 4008.3386241270155, 4029.012891841503, 4033.3949907513816, 4044.8382064717375, 3348.4475061614926, 3637.25411802335, 3696.1498855263308, 4016.8995775105564, 4020.4572382728397, 3325.7528426839935, 3381.292447314103, 3720.7395482316247, 3989.640932316091, 4008.97995510022, 3303.7522185836037, 3971.609936522223, 3976.1113471101285, 3993.600639935983, 4014.518257809067, 2930.662973758024, 3451.8274086295582, 3460.480927860816, 3462.221000274943, 3484.9128771780656, 4434.556544345572, 4880.633952453581, 4903.950960490385, 5084.211947148198, 5091.719955402451, 3933.8819835404984, 3988.088695252453, 4541.523140070278, 4546.954530454686, 4565.589096981287, 4877.3903262092235, 5213.471466338822, 5364.91952620711, 5442.469251797933, 5473.907242408027, 3558.8255875561003, 3851.4993050903204, 3869.593692662258, 3871.457995880579, 3882.223555288925, 5120.846374608767, 5214.504622060898, 5596.1605759136455, 5639.2668953036255, 5709.942900570941, 3886.9028274292805, 3899.863504777319, 3903.8048097595006, 3911.7311959064245, 3914.667253283446, 3163.9525407118913, 3917.138229589486, 3974.8012599369918, 3980.3132885302307, 3982.0841206522664, 3640.3993341098953, 4428.247197976288, 4603.033362993742, 4614.630829533629, 4615.353850460938, 4393.604575588149, 4687.874910021638, 4857.635677324144, 5425.647332923348, 5460.7542660579575, 1244.8568414632252, 1270.5807083662369, 1287.3177216075494, 1296.12709634579, 1304.3869381390732, 747.0214019634296, 788.251161396664, 837.4682076881224, 860.7805009722481, 882.9028806831243, 3941.664958478504, 4242.61816436516, 4732.455767586703, 4738.64460165489, 4846.715620360607, 5253.133233016586, 5284.894302113936, 5298.549623281987, 5316.979547771822, 5399.623096110467, 4003.7597744135446, 4026.697997650188, 4453.710508816981, 4454.22051141049, 4837.879053023703, 2200.4388880835286, 2425.9675405742823, 2692.165428717065, 2766.9723302767115, 2817.9714404242304, 4647.721136731807, 5244.9737751310995, 5467.234587157801, 5467.6719396836315, 5493.972530137404, 4268.886900983971, 4672.602828759525, 4896.755162241873, 5128.512791284822, 5352.732363381815, 4470.865874023784, 4849.563539281482, 5060.696358218519, 5148.3564554430695, 5447.645903016291, 4237.800460652188, 4780.545848144421, 5094.617903657167, 5250.501202385767, 5327.360716714019, 4813.387515182313, 5163.09645811989, 5195.830543368466, 5202.959408118316, 5247.239150323106, 5127.66670166438, 5135.768890400016, 5415.783368665209, 5422.755975981169, 5439.456054394529, 3028.9100520640686, 3185.824779637142, 3206.951895721613, 3688.6126956670237, 3690.8892733218045, 4394.682015078595, 4661.226570190731, 4886.95113048865, 5275.702685821361, 5401.378841433128, 4429.734215947054, 4987.351644286219, 5095.286659867696, 5103.642745007814, 5134.229865520202, 4360.4767427908855, 4833.371661683958, 5073.492650734897, 5117.667351622133, 5167.974160129175, 4028.697847661496, 4996.525330093636, 5017.8243761468175, 5025.47232540578, 5081.898362032664, 5220.895582088413, 5371.677699338053, 5398.271233383443, 5419.268398766116, 5560.916586251128, 4741.680823423549, 5404.973135074347, 5416.21464887597, 5417.341086665543, 5432.782688692408], "value": 3842.059877354041, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [706.269011573022, 734.8438456827923, 745.0761056290202, 755.8299382638907, 781.7146741439375, 14115.647108822284, 14614.0, 14630.195339256337, 14648.780268295985, 14670.559883203494, 12869.935650321735, 12920.935395323033, 12932.806007909865, 12935.482580696784, 12936.417861196245, 648.935106489352, 671.4829581222456, 671.6138220523219, 804.6821505505322, 823.4194892600702, 789.6209819286746, 806.9273765361116, 822.0464261456711, 822.6832669422256, 826.7395770332326, 876.6800117956964, 876.7808047988003, 880.9867851982225, 881.5239770523941, 883.7878909061811, 689.0869597782907, 713.290276175209, 872.6596627315353, 873.8033942362981, 877.9253763430116, 547.2693953571986, 627.7677259414046, 648.2965981909633, 654.3227759269181, 656.1601150527333, 14093.154410735286, 14132.22272774997, 14136.0, 14141.787873181911, 14170.929145354206, 12030.097742669335, 12686.49254029847, 13134.60596182116, 13155.342232888317, 13225.801612975813, 11633.302001879914, 11874.881251187464, 11896.464659090361, 12015.879841201651, 12064.45709943046, 4287.6141147296585, 4290.76400797956, 4360.928558934481, 4397.802098905557, 4447.3773671685985, 82772.0, 87380.5630971841, 89016.55491722564, 90462.54768726115, 93947.24211031619, 9970.501474926225, 10305.227107966932, 10378.169746420268, 10489.213309001856, 10506.947465262625, 19213.903930480257, 19221.90389048046, 19235.615287694167, 19741.309054183323, 19834.404967850987, 7156.606386648784, 7320.597367144752, 7380.889286660705, 7603.809904752426, 7647.197044310378, 4108.856190033334, 4208.789560521962, 4269.530351661315, 4293.119910418357, 4405.229084910128, 3132.859021344045, 3188.39420510107, 3225.5645487859306, 3225.8225747601955, 3228.0638614801915, 590.7459792289357, 591.4913174669781, 597.6682940967718, 598.8203538938388, 604.8427408873724, 507.72329080651133, 516.5660844890235, 549.8185598752482, 583.107844997155, 588.2705445247948, 7666.658334791501, 8355.119933994045, 8359.414840961075, 8673.566321683891, 8738.956305218433, 529.7298377827336, 530.4032962916718, 571.9685417302088, 591.3908674065719, 626.0734113511937, 4024.8188831502653, 4234.259004674232, 4320.222359975234, 4355.747511604227, 4370.082282720664, 108130.3780443294, 113616.0, 116400.0, 117819.41090294493, 122174.38912805557, 77015.0, 77278.0, 84425.15574844296, 105733.0, 109196.08617612226, 61707.0, 80223.9944001393, 80437.59781201057, 83264.58367708123, 84438.57780711057, 7425.997490338951, 9101.863472048057, 9274.304427168125, 9608.951955240458, 9654.372465789702, 37052.25895482129, 38152.42771358376, 40196.598034019284, 48786.51213487962, 51802.0, 62412.0, 76953.0, 78786.6060669693, 79309.0, 80563.0, 12140.33228172459, 12314.692132696755, 12340.753184936071, 12519.123661343618, 12608.74782504363, 13560.0, 13886.305684715926, 14088.15471071739, 14200.644983875072, 14246.92876535652, 8173.956510872282, 9372.297077719335, 12090.939545302217, 12746.362681866056, 13025.577043044292, 12286.078544109407, 12403.813942791046, 12922.0, 13498.46006159782, 13499.392527336096, 11950.223235489666, 12658.746825063632, 12748.362581871055, 12771.552995645108, 12778.105532612628, 10503.369797812156, 10827.350358978487, 11230.66308010761, 11444.713882153013, 11865.703357416134, 9406.38858474197, 10096.646617368357, 10993.560257589606, 11310.717232068935, 11393.943030284796], "value": 7066.793936915172, "unit": "score"}], "name": "dromaeo_css", "value": 7022.455138112169, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 167.0, 148.0, 115.0, 114.0, 114.0, 114.0, 114.0, 114.0, 114.0], "value": 127.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 113.0, 132.0, 178.0, 116.0, 114.0, 118.0, 114.0, 146.0, 112.0], "value": 129.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 181.0, 183.0, 186.0, 182.0, 184.0, 183.0, 184.0, 182.0, 185.0], "value": 183.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 60.0, 61.0, 90.0, 61.0, 59.0, 60.0, 59.0, 83.0, 59.0], "value": 67.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 69.0, 94.0, 94.0, 98.0, 75.0, 89.0, 68.0, 69.0, 93.0], "value": 84.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 87.0, 85.0, 86.0, 83.0, 87.0, 84.0, 94.0, 101.0], "value": 88.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 95.0, 95.0, 96.0, 96.0, 97.0, 96.0, 96.0, 97.0], "value": 96.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 81.0, 79.0, 79.0, 83.0, 82.0, 85.0, 83.0, 81.0, 80.0], "value": 81.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 53.0, 52.0, 53.0, 57.0, 57.0, 56.0, 61.0, 56.0, 56.0], "value": 55.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 73.0, 69.0, 75.0, 72.0, 68.0, 71.0, 69.0, 69.0, 71.0], "value": 70.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 96.0, 100.0, 75.0, 92.0, 95.0, 103.0, 104.0, 91.0], "value": 92.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 165.0, 139.0, 163.0, 153.0, 162.0, 151.0, 153.0, 211.0, 173.0], "value": 161.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 182.0, 230.0, 203.0, 168.0, 167.0, 163.0, 166.0, 167.0, 169.0], "value": 178.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 85.0, 88.0, 84.0, 85.0, 84.0, 66.0, 66.0, 66.0, 67.0], "value": 75.8, "unit": "score"}], "name": "kraken", "value": 1491.1, "alertThreshold": 2.0}]} 00:35:21 INFO - Return code: 0 00:35:21 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 00:35:21 INFO - # TBPL SUCCESS # 00:35:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 00:35:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 00:35:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 00:35:21 INFO - 2016-11-25 00:35:21.214 screenresolution[1929:17608] starting screenresolution argv=screenresolution get 00:35:21 INFO - 2016-11-25 00:35:21.216 screenresolution[1929:17608] Display 0: 1600x1200x32@60 00:35:21 INFO - 2016-11-25 00:35:21.223 screenresolution[1930:17611] starting screenresolution argv=screenresolution list 00:35:21 INFO - Available Modes on Display 0 00:35:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 00:35:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 00:35:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 00:35:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 00:35:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 00:35:21 INFO - Intel Iris: 00:35:21 INFO - Chipset Model: Intel Iris 00:35:21 INFO - Type: GPU 00:35:21 INFO - Bus: Built-In 00:35:21 INFO - VRAM (Dynamic, Max): 1536 MB 00:35:21 INFO - Vendor: Intel (0x8086) 00:35:21 INFO - Device ID: 0x0a2e 00:35:21 INFO - Revision ID: 0x0009 00:35:21 INFO - Displays: 00:35:21 INFO - Display: 00:35:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 00:35:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 00:35:21 INFO - Display Serial Number: 1600x1200 60 00:35:21 INFO - Main Display: Yes 00:35:21 INFO - Mirror: Off 00:35:21 INFO - Online: Yes 00:35:21 INFO - Rotation: Supported 00:35:21 INFO - Return code: 0 00:35:21 INFO - Running post-action listener: _resource_record_post_action 00:35:21 INFO - [mozharness: 2016-11-25 08:35:21.466737Z] Finished run-tests step (success) 00:35:21 INFO - Running post-run listener: _resource_record_post_run 00:35:21 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 811008; Write bytes: 657332224; Read time: 10; Write time: 2109 00:35:21 INFO - TinderboxPrint: CPU usage
53.3% 00:35:21 INFO - TinderboxPrint: I/O read bytes / time
811,008 / 10 00:35:21 INFO - TinderboxPrint: I/O write bytes / time
657,332,224 / 2,109 00:35:21 INFO - TinderboxPrint: CPU idle
2,262.6 (46.7%) 00:35:21 INFO - TinderboxPrint: CPU system
971.6 (20.0%) 00:35:21 INFO - TinderboxPrint: CPU user
1,615.5 (33.3%) 00:35:21 INFO - TinderboxPrint: Swap in / out
348,786,688 / 0 00:35:21 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 344291840; Write bytes: 345179648; Read time: 26805; Write time: 745 00:35:21 INFO - run-tests - Wall time: 1181s; CPU: 53.0%; Read bytes: 811008; Write bytes: 305246720; Read time: 10; Write time: 1335 00:35:21 INFO - Running post-run listener: _upload_blobber_files 00:35:21 INFO - Blob upload gear active. 00:35:21 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 00:35:21 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 00:35:21 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'] 00:35:21 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 00:35:22 INFO - (blobuploader) - INFO - Open directory for files ... 00:35:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 00:35:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:35:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:35:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 00:35:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:35:23 INFO - (blobuploader) - INFO - Done attempting. 00:35:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 00:35:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:35:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:35:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 00:35:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:35:24 INFO - (blobuploader) - INFO - Done attempting. 00:35:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 00:35:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:35:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:35:26 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 00:35:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:35:26 INFO - (blobuploader) - INFO - Done attempting. 00:35:26 INFO - (blobuploader) - INFO - Iteration through files over. 00:35:26 INFO - Return code: 0 00:35:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 00:35:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 00:35:26 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1dad7cd5fc60edb2f1da89da32b6a1eb1a99a3a792a0da50d897745b7451e88b99cb05773bcefbcfbd2acdf3dbf13b72a5c467cbef68e4c6853b6cd34a2f7fa1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b7f1e1ff7ca0e0742ec6c4abb2b69e63b171abbe1bda7b4f909396cb265cb13a14637193e53cd24b5b0fde3f5d25b95b837bbf7aa6574191e5857afb7c5213b8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/579f76691c105e7149b0c16bc76c89add0c659458ef8a2d24b274ebf300f5ac2eaab5ecfc64e5de88305dc93a7e79f714bd2b4a02eed82fadaf00c479da5c1c3"} 00:35:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 00:35:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 00:35:26 INFO - Contents: 00:35:26 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1dad7cd5fc60edb2f1da89da32b6a1eb1a99a3a792a0da50d897745b7451e88b99cb05773bcefbcfbd2acdf3dbf13b72a5c467cbef68e4c6853b6cd34a2f7fa1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b7f1e1ff7ca0e0742ec6c4abb2b69e63b171abbe1bda7b4f909396cb265cb13a14637193e53cd24b5b0fde3f5d25b95b837bbf7aa6574191e5857afb7c5213b8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/579f76691c105e7149b0c16bc76c89add0c659458ef8a2d24b274ebf300f5ac2eaab5ecfc64e5de88305dc93a7e79f714bd2b4a02eed82fadaf00c479da5c1c3"} 00:35:26 INFO - Running post-run listener: copy_logs_to_upload_dir 00:35:26 INFO - Copying logs to upload dir... 00:35:26 INFO - mkdir: /builds/slave/test/build/upload/logs 00:35:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.312840 ========= master_lag: 0.21 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-11-25 00:35:27.206814) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-25 00:35:27.211365) ========= 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.Qi62bvemR9/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.70e58qcsqp/Listeners TMPDIR=/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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/1dad7cd5fc60edb2f1da89da32b6a1eb1a99a3a792a0da50d897745b7451e88b99cb05773bcefbcfbd2acdf3dbf13b72a5c467cbef68e4c6853b6cd34a2f7fa1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b7f1e1ff7ca0e0742ec6c4abb2b69e63b171abbe1bda7b4f909396cb265cb13a14637193e53cd24b5b0fde3f5d25b95b837bbf7aa6574191e5857afb7c5213b8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/579f76691c105e7149b0c16bc76c89add0c659458ef8a2d24b274ebf300f5ac2eaab5ecfc64e5de88305dc93a7e79f714bd2b4a02eed82fadaf00c479da5c1c3"} build_url:https://queue.taskcluster.net/v1/task/Iv5wHu7GTxyWlrikXGyf1A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010882 build_url: 'https://queue.taskcluster.net/v1/task/Iv5wHu7GTxyWlrikXGyf1A/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/1dad7cd5fc60edb2f1da89da32b6a1eb1a99a3a792a0da50d897745b7451e88b99cb05773bcefbcfbd2acdf3dbf13b72a5c467cbef68e4c6853b6cd34a2f7fa1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b7f1e1ff7ca0e0742ec6c4abb2b69e63b171abbe1bda7b4f909396cb265cb13a14637193e53cd24b5b0fde3f5d25b95b837bbf7aa6574191e5857afb7c5213b8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/579f76691c105e7149b0c16bc76c89add0c659458ef8a2d24b274ebf300f5ac2eaab5ecfc64e5de88305dc93a7e79f714bd2b4a02eed82fadaf00c479da5c1c3"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-25 00:35:27.253730) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2016-11-25 00:35:27.254106) ========= 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.Qi62bvemR9/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.70e58qcsqp/Listeners TMPDIR=/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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.005245 ========= master_lag: 2.45 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2016-11-25 00:35:29.707841) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-25 00:35:29.708189) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-25 00:35:29.709245) ========= ========= Total master_lag: 2.77 =========