builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0220 starttime: 1479195978.02 results: success (0) buildid: 20161114212412 builduid: 48ac1ae133494e02a0895a36968e62f0 revision: 401aa89aeb11b0cb29419438bfc4d27061c3c3e7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-14 23:46:18.021311) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-14 23:46:18.021727) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-14 23:46:18.021999) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-14 23:46:18.046389) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 23:46:18.046648) ========= 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.GHL4deNbT5/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.zqXleoD2lj/Listeners TMPDIR=/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/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-14 23:46:18-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 26.2M=0s 2016-11-14 23:46:18 (26.2 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.340702 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 23:46:18.409647) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 23:46:18.409962) ========= 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.GHL4deNbT5/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.zqXleoD2lj/Listeners TMPDIR=/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/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.024631 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 23:46:18.462120) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 23:46:18.465510) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 401aa89aeb11b0cb29419438bfc4d27061c3c3e7 --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 401aa89aeb11b0cb29419438bfc4d27061c3c3e7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.GHL4deNbT5/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.zqXleoD2lj/Listeners TMPDIR=/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/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-14 23:46:18,566 truncating revision to first 12 chars 2016-11-14 23:46:18,566 Setting DEBUG logging. 2016-11-14 23:46:18,567 attempt 1/10 2016-11-14 23:46:18,567 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/401aa89aeb11?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-14 23:46:19,034 unpacking tar archive at: mozilla-aurora-401aa89aeb11/testing/mozharness/ program finished with exit code 0 elapsedTime=0.745229 ========= master_lag: 0.15 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 23:46:19.362128) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-14 23:46:19.362488) ========= script_repo_revision: 401aa89aeb11b0cb29419438bfc4d27061c3c3e7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-14 23:46:19.362903) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-14 23:46:19.363234) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-14 23:46:19.383184) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-11-14 23:46:19.383543) ========= /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.GHL4deNbT5/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.zqXleoD2lj/Listeners TMPDIR=/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/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 23:46:19 INFO - MultiFileLogger online at 20161114 23:46:19 in /builds/slave/test 23:46:19 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 23:46:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:46:19 INFO - {'append_to_log': False, 23:46:19 INFO - 'base_work_dir': '/builds/slave/test', 23:46:19 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 23:46:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:46:19 INFO - 'branch': 'Mozilla-Aurora', 23:46:19 INFO - 'buildbot_json_path': 'buildprops.json', 23:46:19 INFO - 'config_files': ('talos/mac_config.py',), 23:46:19 INFO - 'default_actions': ('clobber', 23:46:19 INFO - 'read-buildbot-config', 23:46:19 INFO - 'download-and-extract', 23:46:19 INFO - 'populate-webroot', 23:46:19 INFO - 'create-virtualenv', 23:46:19 INFO - 'install', 23:46:19 INFO - 'run-tests'), 23:46:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:46:19 INFO - 'download_minidump_stackwalk': True, 23:46:19 INFO - 'download_symbols': 'ondemand', 23:46:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:46:19 INFO - 'tooltool.py': '/tools/tooltool.py', 23:46:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:46:19 INFO - '/tools/misc-python/virtualenv.py')}, 23:46:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:46:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:46:19 INFO - 'installer_path': 'installer.exe', 23:46:19 INFO - 'log_level': 'info', 23:46:19 INFO - 'log_name': 'talos', 23:46:19 INFO - 'log_to_console': True, 23:46:19 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 23:46:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 23:46:19 INFO - 'opt_config_files': (), 23:46:19 INFO - 'pip_index': False, 23:46:19 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:46:19 INFO - 'cmd': ('bash', 23:46:19 INFO - '-c', 23:46:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:46:19 INFO - 'enabled': True, 23:46:19 INFO - 'halt_on_failure': False, 23:46:19 INFO - 'name': 'check_screen_resolution'},), 23:46:19 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:46:19 INFO - 'cmd': ('bash', 23:46:19 INFO - '-c', 23:46:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:46:19 INFO - 'enabled': True, 23:46:19 INFO - 'halt_on_failure': False, 23:46:19 INFO - 'name': 'check_screen_resolution'},), 23:46:19 INFO - 'run_cmd_checks_enabled': True, 23:46:19 INFO - 'sps_profile': False, 23:46:19 INFO - 'sps_profile_interval': 0, 23:46:19 INFO - 'suite': 'dromaeojs', 23:46:19 INFO - 'system_bits': '32', 23:46:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:46:19 INFO - 'title': 't-yosemite-r7-0220', 23:46:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:46:19 INFO - 'use_talos_json': True, 23:46:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:46:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:46:19 INFO - 'work_dir': 'build'} 23:46:19 INFO - [mozharness: 2016-11-15 07:46:19.586210Z] Running clobber step. 23:46:19 INFO - Running pre-action listener: _resource_record_pre_action 23:46:19 INFO - Running main action method: clobber 23:46:19 INFO - rmtree: /builds/slave/test/build 23:46:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:46:21 INFO - Running post-action listener: _resource_record_post_action 23:46:21 INFO - [mozharness: 2016-11-15 07:46:21.872460Z] Finished clobber step (success) 23:46:21 INFO - [mozharness: 2016-11-15 07:46:21.872656Z] Running read-buildbot-config step. 23:46:21 INFO - Running pre-action listener: _resource_record_pre_action 23:46:21 INFO - Running main action method: read_buildbot_config 23:46:21 INFO - Using buildbot properties: 23:46:21 INFO - { 23:46:21 INFO - "project": "", 23:46:21 INFO - "product": "firefox", 23:46:21 INFO - "script_repo_revision": "production", 23:46:21 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 23:46:21 INFO - "repository": "", 23:46:21 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 23:46:21 INFO - "buildid": "20161114212412", 23:46:21 INFO - "pgo_build": "False", 23:46:21 INFO - "basedir": "/builds/slave/test", 23:46:21 INFO - "buildnumber": 130, 23:46:21 INFO - "slavename": "t-yosemite-r7-0220", 23:46:21 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 23:46:21 INFO - "platform": "yosemite_r7", 23:46:21 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 23:46:21 INFO - "branch": "mozilla-aurora", 23:46:21 INFO - "repo_path": "releases/mozilla-aurora", 23:46:21 INFO - "revision": "401aa89aeb11b0cb29419438bfc4d27061c3c3e7", 23:46:21 INFO - "stage_platform": "macosx64", 23:46:21 INFO - "builduid": "48ac1ae133494e02a0895a36968e62f0", 23:46:21 INFO - "slavebuilddir": "test" 23:46:21 INFO - } 23:46:21 INFO - Found installer url https://queue.taskcluster.net/v1/task/aa_w99o9QCueGkcOxSdlag/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 23:46:21 INFO - Running post-action listener: _resource_record_post_action 23:46:21 INFO - [mozharness: 2016-11-15 07:46:21.878311Z] Finished read-buildbot-config step (success) 23:46:21 INFO - [mozharness: 2016-11-15 07:46:21.878535Z] Running download-and-extract step. 23:46:21 INFO - Running pre-action listener: _resource_record_pre_action 23:46:21 INFO - Running main action method: download_and_extract 23:46:21 INFO - mkdir: /builds/slave/test/build/tests 23:46:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:46:21 INFO - trying https://queue.taskcluster.net/v1/task/aa_w99o9QCueGkcOxSdlag/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 23:46:21 INFO - Downloading https://queue.taskcluster.net/v1/task/aa_w99o9QCueGkcOxSdlag/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 23:46:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aa_w99o9QCueGkcOxSdlag/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 23:46:23 INFO - Downloaded 1199 bytes. 23:46:23 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 23:46:23 INFO - Using the following test package requirements: 23:46:23 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 23:46:23 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 23:46:23 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 23:46:23 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 23:46:23 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 23:46:23 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 23:46:23 INFO - u'jsshell-mac.zip'], 23:46:23 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 23:46:23 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 23:46:23 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 23:46:23 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 23:46:23 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 23:46:23 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 23:46:23 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 23:46:23 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 23:46:23 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 23:46:23 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 23:46:23 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 23:46:23 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 23:46:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aa_w99o9QCueGkcOxSdlag/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 23:46:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aa_w99o9QCueGkcOxSdlag/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 23:46:23 INFO - Fetch https://queue.taskcluster.net/v1/task/aa_w99o9QCueGkcOxSdlag/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 23:46:23 INFO - Http code: 200 23:46:23 INFO - via: 1.1 b9a8ce441329f20cea895e08f19f1ec8.cloudfront.net (CloudFront) 23:46:23 INFO - x-amz-cf-id: dd5SVgB2nUEct47Dli9LeJBO_MXOHk6lzHr__HovrlS5lfJOOjpltw== 23:46:23 INFO - x-amz-version-id: ei_F3lRTHkaBNEdAP_PEzpyrV.FL4h36 23:46:24 INFO - Expected file size: 16327496 23:46:24 INFO - Obtained file size: 16327496 23:46:31 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 23:46:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aa_w99o9QCueGkcOxSdlag/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 23:46:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aa_w99o9QCueGkcOxSdlag/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 23:46:31 INFO - Fetch https://queue.taskcluster.net/v1/task/aa_w99o9QCueGkcOxSdlag/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 23:46:31 INFO - Http code: 200 23:46:31 INFO - via: 1.1 ba26a6eef2785fa2e20e601890b882b2.cloudfront.net (CloudFront) 23:46:31 INFO - x-amz-cf-id: -SQzUjnViqN0_CAEYOH1s6H2XNK_k1OL4MnUtelamcO3VMKLKs4OpQ== 23:46:31 INFO - x-amz-version-id: ei_F3lRTHkaBNEdAP_PEzpyrV.FL4h36 23:46:32 INFO - Expected file size: 16327496 23:46:32 INFO - Obtained file size: 16327496 23:46:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aa_w99o9QCueGkcOxSdlag/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 23:46:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aa_w99o9QCueGkcOxSdlag/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 23:46:38 INFO - Fetch https://queue.taskcluster.net/v1/task/aa_w99o9QCueGkcOxSdlag/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 23:46:38 INFO - Http code: 200 23:46:38 INFO - via: 1.1 e1eb7447ab46f530f6009f1d93917c54.cloudfront.net (CloudFront) 23:46:38 INFO - x-amz-cf-id: QakVV7P75YDf8wl4f9fHRCpH3b2zPKhaYP1WIQ6eoS-VhF6lU7ZnQA== 23:46:38 INFO - x-amz-version-id: 9N9RDiqdFo6934PDmF7Ub34LELrYjFtb 23:46:39 INFO - Expected file size: 14409038 23:46:39 INFO - Obtained file size: 14409038 23:46:39 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')]} 23:46:39 INFO - trying https://queue.taskcluster.net/v1/task/aa_w99o9QCueGkcOxSdlag/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 23:46:39 INFO - Downloading https://queue.taskcluster.net/v1/task/aa_w99o9QCueGkcOxSdlag/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 23:46:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aa_w99o9QCueGkcOxSdlag/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 23:46:42 INFO - Downloaded 114361282 bytes. 23:46:42 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/aa_w99o9QCueGkcOxSdlag/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 23:46:42 INFO - mkdir: /builds/slave/test/properties 23:46:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:46:42 INFO - Writing to file /builds/slave/test/properties/build_url 23:46:42 INFO - Contents: 23:46:42 INFO - build_url:https://queue.taskcluster.net/v1/task/aa_w99o9QCueGkcOxSdlag/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 23:46:43 INFO - Running post-action listener: _resource_record_post_action 23:46:43 INFO - Running post-action listener: set_extra_try_arguments 23:46:43 INFO - [mozharness: 2016-11-15 07:46:43.217371Z] Finished download-and-extract step (success) 23:46:43 INFO - [mozharness: 2016-11-15 07:46:43.217488Z] Running populate-webroot step. 23:46:43 INFO - Running pre-action listener: _resource_record_pre_action 23:46:43 INFO - Running main action method: populate_webroot 23:46:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:46:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:46:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:46:43 INFO - '--tppagecycles', 23:46:43 INFO - '7'], 23:46:43 INFO - 'tests': ['tsvgm']}}, 23:46:43 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:46:43 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:46:43 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:46:43 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:46:43 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:46:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:46:43 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:46:43 INFO - 'talos_options': ['--disable-e10s'], 23:46:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:46:43 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:46:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:46:43 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:46:43 INFO - 'talos_options': ['--disable-e10s'], 23:46:43 INFO - 'tests': ['damp', 'tps']}, 23:46:43 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:46:43 INFO - 'tests': ['damp', 'tps']}, 23:46:43 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:46:43 INFO - 'tests': ['dromaeo_dom']}, 23:46:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:46:43 INFO - 'g4': {'talos_options': ['--disable-e10s'], 23:46:43 INFO - 'tests': ['basic_compositor_video']}, 23:46:43 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 23:46:43 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:46:43 INFO - 'tests': ['a11yr', 23:46:43 INFO - 'ts_paint', 23:46:43 INFO - 'tpaint', 23:46:43 INFO - 'sessionrestore', 23:46:43 INFO - 'sessionrestore_no_auto_restore', 23:46:43 INFO - 'tabpaint']}, 23:46:43 INFO - 'other-e10s': {'tests': ['a11yr', 23:46:43 INFO - 'ts_paint', 23:46:43 INFO - 'tpaint', 23:46:43 INFO - 'sessionrestore', 23:46:43 INFO - 'sessionrestore_no_auto_restore', 23:46:43 INFO - 'tabpaint']}, 23:46:43 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:46:43 INFO - 'ts_paint', 23:46:43 INFO - 'tpaint', 23:46:43 INFO - 'sessionrestore', 23:46:43 INFO - 'sessionrestore_no_auto_restore', 23:46:43 INFO - 'tabpaint']}, 23:46:43 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:46:43 INFO - 'ts_paint', 23:46:43 INFO - 'tpaint', 23:46:43 INFO - 'sessionrestore', 23:46:43 INFO - 'sessionrestore_no_auto_restore', 23:46:43 INFO - 'tabpaint']}, 23:46:43 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:46:43 INFO - 'tests': ['a11yr', 23:46:43 INFO - 'ts_paint', 23:46:43 INFO - 'tpaint', 23:46:43 INFO - 'sessionrestore', 23:46:43 INFO - 'sessionrestore_no_auto_restore', 23:46:43 INFO - 'tabpaint']}, 23:46:43 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:46:43 INFO - 'tests': ['a11yr', 23:46:43 INFO - 'ts_paint', 23:46:43 INFO - 'tpaint', 23:46:43 INFO - 'sessionrestore', 23:46:43 INFO - 'sessionrestore_no_auto_restore', 23:46:43 INFO - 'tabpaint']}, 23:46:43 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:46:43 INFO - 'tests': ['tsvgx', 23:46:43 INFO - 'tsvgr_opacity', 23:46:43 INFO - 'tart', 23:46:43 INFO - 'tscrollx', 23:46:43 INFO - 'cart']}, 23:46:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:46:43 INFO - 'tsvgr_opacity', 23:46:43 INFO - 'tart', 23:46:43 INFO - 'tscrollx', 23:46:43 INFO - 'cart']}, 23:46:43 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:46:43 INFO - 'talos_options': ['--disable-e10s'], 23:46:43 INFO - 'tests': ['tp5o']}, 23:46:43 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:46:43 INFO - 'tests': ['tp5o']}, 23:46:43 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:46:43 INFO - 'talos_options': ['--disable-e10s', 23:46:43 INFO - '--xperf_path', 23:46:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:46:43 INFO - 'tests': ['tp5n']}, 23:46:43 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:46:43 INFO - 'talos_options': ['--xperf_path', 23:46:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:46:43 INFO - 'tests': ['tp5n']}}, 23:46:43 INFO - 'talos.zip': {'path': '', 23:46:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:46:43 INFO - Running post-action listener: _resource_record_post_action 23:46:43 INFO - [mozharness: 2016-11-15 07:46:43.236262Z] Finished populate-webroot step (success) 23:46:43 INFO - [mozharness: 2016-11-15 07:46:43.236389Z] Running create-virtualenv step. 23:46:43 INFO - Running pre-action listener: _resource_record_pre_action 23:46:43 INFO - Running main action method: create_virtualenv 23:46:43 INFO - Creating virtualenv /builds/slave/test/build/venv 23:46:43 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 23:46:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:46:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:46:43 INFO - Using real prefix '/tools/python27' 23:46:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:46:44 INFO - Installing distribute.............................................................................................................................................................................................done. 23:46:47 INFO - Installing pip.................done. 23:46:47 INFO - Return code: 0 23:46:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 23:46:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 23:46:47 INFO - Reading from file tmpfile_stdout 23:46:47 INFO - Output received: 23:46:47 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) 23:46:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:46:47 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')]} 23:46:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:46:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:46:47 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')]} 23:46:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:46:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:46:47 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 0x102257490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021cd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabb0d82bd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10206bd30>, '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 0x101ed1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102063b20>, '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.GHL4deNbT5/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.zqXleoD2lj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/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 23:46:47 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 23:46:47 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 23:46:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GHL4deNbT5/Render', 23:46:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:46:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:46:47 INFO - 'HOME': '/Users/cltbld', 23:46:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:46:47 INFO - 'LOGNAME': 'cltbld', 23:46:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:46:47 INFO - 'MOZ_NO_REMOTE': '1', 23:46:47 INFO - 'NO_EM_RESTART': '1', 23:46:47 INFO - 'PAGER': '/bin/cat', 23:46:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:46:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:46:47 INFO - 'PWD': '/builds/slave/test', 23:46:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:46:47 INFO - 'SHELL': '/bin/bash', 23:46:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zqXleoD2lj/Listeners', 23:46:47 INFO - 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/', 23:46:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:46:47 INFO - 'USER': 'cltbld', 23:46:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:46:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:46:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:46:47 INFO - 'XPC_FLAGS': '0x0', 23:46:47 INFO - 'XPC_SERVICE_NAME': '0', 23:46:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:46:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:46:47 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 23:46:47 INFO - Cleaning up... 23:46:47 INFO - Return code: 0 23:46:47 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:46:47 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')]} 23:46:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:46:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:46:47 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')]} 23:46:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:46:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:46:47 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 0x102257490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021cd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabb0d82bd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10206bd30>, '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 0x101ed1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102063b20>, '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.GHL4deNbT5/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.zqXleoD2lj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/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 23:46:47 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 23:46:47 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 23:46:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GHL4deNbT5/Render', 23:46:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:46:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:46:47 INFO - 'HOME': '/Users/cltbld', 23:46:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:46:47 INFO - 'LOGNAME': 'cltbld', 23:46:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:46:47 INFO - 'MOZ_NO_REMOTE': '1', 23:46:47 INFO - 'NO_EM_RESTART': '1', 23:46:47 INFO - 'PAGER': '/bin/cat', 23:46:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:46:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:46:47 INFO - 'PWD': '/builds/slave/test', 23:46:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:46:47 INFO - 'SHELL': '/bin/bash', 23:46:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zqXleoD2lj/Listeners', 23:46:47 INFO - 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/', 23:46:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:46:47 INFO - 'USER': 'cltbld', 23:46:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:46:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:46:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:46:47 INFO - 'XPC_FLAGS': '0x0', 23:46:47 INFO - 'XPC_SERVICE_NAME': '0', 23:46:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:46:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:46:47 INFO - Downloading/unpacking psutil>=3.1.1 23:46:47 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 23:46:47 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 23:46:47 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 23:46:47 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 23:46:47 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 23:46:47 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 23:46:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:46:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:46:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:46:51 INFO - Installing collected packages: psutil 23:46:51 INFO - Running setup.py install for psutil 23:46:51 INFO - building 'psutil._psutil_osx' extension 23:46:51 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 23:46:51 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 23:46:51 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 23:46:52 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 23:46:52 INFO - building 'psutil._psutil_posix' extension 23:46:52 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 23:46:52 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 23:46:52 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 23:46:52 INFO - ^ 23:46:52 INFO - 1 warning generated. 23:46:52 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 23:46:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:46:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:46:52 INFO - Successfully installed psutil 23:46:52 INFO - Cleaning up... 23:46:52 INFO - Return code: 0 23:46:52 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:46: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')]} 23:46:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:46:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:46: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')]} 23:46:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:46:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:46: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102257490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021cd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabb0d82bd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10206bd30>, '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 0x101ed1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102063b20>, '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.GHL4deNbT5/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.zqXleoD2lj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/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 23:46: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 23:46: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 mozsystemmonitor==0.3 23:46:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GHL4deNbT5/Render', 23:46:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:46:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:46:52 INFO - 'HOME': '/Users/cltbld', 23:46:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:46:52 INFO - 'LOGNAME': 'cltbld', 23:46:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:46:52 INFO - 'MOZ_NO_REMOTE': '1', 23:46:52 INFO - 'NO_EM_RESTART': '1', 23:46:52 INFO - 'PAGER': '/bin/cat', 23:46:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:46:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:46:52 INFO - 'PWD': '/builds/slave/test', 23:46:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:46:52 INFO - 'SHELL': '/bin/bash', 23:46:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zqXleoD2lj/Listeners', 23:46:52 INFO - 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/', 23:46:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:46:52 INFO - 'USER': 'cltbld', 23:46:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:46:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:46:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:46:52 INFO - 'XPC_FLAGS': '0x0', 23:46:52 INFO - 'XPC_SERVICE_NAME': '0', 23:46:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:46:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:46:52 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:46: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 23:46: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 23:46: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 23:46: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 23:46: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 23:46: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 23:46:55 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:46:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:46:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:46:55 INFO - Installing collected packages: mozsystemmonitor 23:46:55 INFO - Running setup.py install for mozsystemmonitor 23:46:55 INFO - Successfully installed mozsystemmonitor 23:46:55 INFO - Cleaning up... 23:46:55 INFO - Return code: 0 23:46:55 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:46:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:46:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:46:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:46:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:46:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:46:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:46:55 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 0x102257490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021cd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabb0d82bd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10206bd30>, '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 0x101ed1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102063b20>, '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.GHL4deNbT5/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.zqXleoD2lj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/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 23:46:55 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 23:46:55 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 23:46:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GHL4deNbT5/Render', 23:46:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:46:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:46:55 INFO - 'HOME': '/Users/cltbld', 23:46:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:46:55 INFO - 'LOGNAME': 'cltbld', 23:46:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:46:55 INFO - 'MOZ_NO_REMOTE': '1', 23:46:55 INFO - 'NO_EM_RESTART': '1', 23:46:55 INFO - 'PAGER': '/bin/cat', 23:46:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:46:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:46:55 INFO - 'PWD': '/builds/slave/test', 23:46:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:46:55 INFO - 'SHELL': '/bin/bash', 23:46:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zqXleoD2lj/Listeners', 23:46:55 INFO - 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/', 23:46:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:46:55 INFO - 'USER': 'cltbld', 23:46:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:46:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:46:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:46:55 INFO - 'XPC_FLAGS': '0x0', 23:46:55 INFO - 'XPC_SERVICE_NAME': '0', 23:46:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:46:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:46:55 INFO - Downloading/unpacking jsonschema==2.5.1 23:46: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 23:46: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 23:46: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 23:46: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 23:46: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 23:46: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 23:46:58 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:46:58 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:46:58 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 23:46:58 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 23:46:58 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 23:46:58 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 23:46:58 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 23:46:58 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 23:46:58 INFO - Downloading functools32-3.2.3-2.tar.gz 23:46:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:46:58 INFO - warning: no files found matching '*.txt' 23:46:58 INFO - no previously-included directories found matching 'build' 23:46:58 INFO - no previously-included directories found matching 'dist' 23:46:58 INFO - no previously-included directories found matching '.git*' 23:46:58 INFO - Installing collected packages: jsonschema, functools32 23:46:58 INFO - Running setup.py install for functools32 23:46:58 INFO - warning: no files found matching '*.txt' 23:46:58 INFO - no previously-included directories found matching 'build' 23:46:58 INFO - no previously-included directories found matching 'dist' 23:46:58 INFO - no previously-included directories found matching '.git*' 23:46:58 INFO - Successfully installed jsonschema functools32 23:46:58 INFO - Cleaning up... 23:46:58 INFO - Return code: 0 23:46:58 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 23:46: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')]} 23:46:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:46:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:46: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')]} 23:46:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:46:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:46:58 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 0x102257490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021cd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabb0d82bd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10206bd30>, '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 0x101ed1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102063b20>, '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.GHL4deNbT5/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.zqXleoD2lj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/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 23:46:58 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 23:46:58 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 23:46:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GHL4deNbT5/Render', 23:46:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:46:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:46:58 INFO - 'HOME': '/Users/cltbld', 23:46:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:46:58 INFO - 'LOGNAME': 'cltbld', 23:46:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:46:58 INFO - 'MOZ_NO_REMOTE': '1', 23:46:58 INFO - 'NO_EM_RESTART': '1', 23:46:58 INFO - 'PAGER': '/bin/cat', 23:46:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:46:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:46:58 INFO - 'PWD': '/builds/slave/test', 23:46:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:46:58 INFO - 'SHELL': '/bin/bash', 23:46:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zqXleoD2lj/Listeners', 23:46:58 INFO - 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/', 23:46:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:46:58 INFO - 'USER': 'cltbld', 23:46:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:46:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:46:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:46:58 INFO - 'XPC_FLAGS': '0x0', 23:46:58 INFO - 'XPC_SERVICE_NAME': '0', 23:46:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:46:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:46:59 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 23:46:59 INFO - Cleaning up... 23:46:59 INFO - Return code: 0 23:46:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:46:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:46:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:46:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:46:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:46:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:46:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:46:59 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 0x102257490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021cd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabb0d82bd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10206bd30>, '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 0x101ed1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102063b20>, '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.GHL4deNbT5/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.zqXleoD2lj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/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 23:46:59 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 23:46:59 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 23:46:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GHL4deNbT5/Render', 23:46:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:46:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:46:59 INFO - 'HOME': '/Users/cltbld', 23:46:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:46:59 INFO - 'LOGNAME': 'cltbld', 23:46:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:46:59 INFO - 'MOZ_NO_REMOTE': '1', 23:46:59 INFO - 'NO_EM_RESTART': '1', 23:46:59 INFO - 'PAGER': '/bin/cat', 23:46:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:46:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:46:59 INFO - 'PWD': '/builds/slave/test', 23:46:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:46:59 INFO - 'SHELL': '/bin/bash', 23:46:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zqXleoD2lj/Listeners', 23:46:59 INFO - 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/', 23:46:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:46:59 INFO - 'USER': 'cltbld', 23:46:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:46:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:46:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:46:59 INFO - 'XPC_FLAGS': '0x0', 23:46:59 INFO - 'XPC_SERVICE_NAME': '0', 23:46:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:46:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:46:59 INFO - Downloading/unpacking blobuploader==1.2.4 23:46:59 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 23:46:59 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 23:46:59 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 23:46:59 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 23:46:59 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 23:46:59 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 23:47:01 INFO - Downloading blobuploader-1.2.4.tar.gz 23:47:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:47:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:47:01 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 23:47:01 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 23:47:01 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 23:47:01 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 23:47:01 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 23:47:01 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 23:47:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:47:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:47:02 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 23:47:02 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 23:47:02 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 23:47:02 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 23:47:02 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 23:47:02 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 23:47:03 INFO - Downloading docopt-0.6.1.tar.gz 23:47:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:47:03 INFO - Installing collected packages: blobuploader, requests, docopt 23:47:03 INFO - Running setup.py install for blobuploader 23:47:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:47:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:47:03 INFO - Running setup.py install for requests 23:47:03 INFO - Running setup.py install for docopt 23:47:03 INFO - Successfully installed blobuploader requests docopt 23:47:03 INFO - Cleaning up... 23:47:04 INFO - Return code: 0 23:47:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:47:04 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')]} 23:47:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:47:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:47:04 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')]} 23:47:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:47:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:47:04 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 0x102257490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021cd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabb0d82bd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10206bd30>, '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 0x101ed1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102063b20>, '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.GHL4deNbT5/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.zqXleoD2lj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/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 23:47:04 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 23:47:04 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 23:47:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GHL4deNbT5/Render', 23:47:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:47:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:47:04 INFO - 'HOME': '/Users/cltbld', 23:47:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:47:04 INFO - 'LOGNAME': 'cltbld', 23:47:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:47:04 INFO - 'MOZ_NO_REMOTE': '1', 23:47:04 INFO - 'NO_EM_RESTART': '1', 23:47:04 INFO - 'PAGER': '/bin/cat', 23:47:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:47:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:47:04 INFO - 'PWD': '/builds/slave/test', 23:47:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:47:04 INFO - 'SHELL': '/bin/bash', 23:47:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zqXleoD2lj/Listeners', 23:47:04 INFO - 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/', 23:47:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:47:04 INFO - 'USER': 'cltbld', 23:47:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:47:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:47:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:47:04 INFO - 'XPC_FLAGS': '0x0', 23:47:04 INFO - 'XPC_SERVICE_NAME': '0', 23:47:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:47:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:47:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:47:04 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-Sy_2aO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:47:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:47:04 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-kCu_Yl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:47:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:47:04 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-n4eNrH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:47:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:47:04 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-lCF0O4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:47:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:47:04 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-HTBiBC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:47:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:47:04 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-Z_oB9d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:47:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:47:04 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-ropW6M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:47:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:47:05 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-2OL55_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:47:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:47:05 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-s6EzZW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:47:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:47:05 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-lKIH2m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:47:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:47:05 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-oQwAdH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:47:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:47:05 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-2WPcxp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:47:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:47:05 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-QMZanC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:47:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:47:05 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-1PlOW0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:47:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:47:05 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-Qor5SW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:47:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:47:05 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-30mHzC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:47:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:47:05 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-UwbDKd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:47:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:47:06 INFO - Running setup.py install for manifestparser 23:47:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:47:06 INFO - Running setup.py install for mozcrash 23:47:06 INFO - Running setup.py install for mozdebug 23:47:06 INFO - Running setup.py install for mozdevice 23:47:06 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:47:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:47:06 INFO - Running setup.py install for mozfile 23:47:06 INFO - Running setup.py install for mozhttpd 23:47:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:47:06 INFO - Running setup.py install for mozinfo 23:47:07 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:47:07 INFO - Running setup.py install for mozInstall 23:47:07 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:47:07 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:47:07 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:47:07 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:47:07 INFO - Running setup.py install for mozleak 23:47:07 INFO - Running setup.py install for mozlog 23:47:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:47:07 INFO - Running setup.py install for moznetwork 23:47:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:47:07 INFO - Running setup.py install for mozprocess 23:47:07 INFO - Running setup.py install for mozprofile 23:47:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:47:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:47:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:47:08 INFO - Running setup.py install for mozrunner 23:47:08 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:47:08 INFO - Running setup.py install for mozscreenshot 23:47:08 INFO - Running setup.py install for moztest 23:47:08 INFO - Running setup.py install for mozversion 23:47:08 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:47:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:47:08 INFO - Cleaning up... 23:47:08 INFO - Return code: 0 23:47:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:47: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')]} 23:47:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:47:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:47: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')]} 23:47:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:47:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:47:08 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 0x102257490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021cd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabb0d82bd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10206bd30>, '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 0x101ed1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102063b20>, '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.GHL4deNbT5/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.zqXleoD2lj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/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 23:47:08 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 23:47:08 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 23:47:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GHL4deNbT5/Render', 23:47:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:47:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:47:08 INFO - 'HOME': '/Users/cltbld', 23:47:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:47:08 INFO - 'LOGNAME': 'cltbld', 23:47:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:47:08 INFO - 'MOZ_NO_REMOTE': '1', 23:47:08 INFO - 'NO_EM_RESTART': '1', 23:47:08 INFO - 'PAGER': '/bin/cat', 23:47:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:47:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:47:08 INFO - 'PWD': '/builds/slave/test', 23:47:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:47:08 INFO - 'SHELL': '/bin/bash', 23:47:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zqXleoD2lj/Listeners', 23:47:08 INFO - 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/', 23:47:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:47:08 INFO - 'USER': 'cltbld', 23:47:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:47:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:47:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:47:08 INFO - 'XPC_FLAGS': '0x0', 23:47:08 INFO - 'XPC_SERVICE_NAME': '0', 23:47:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:47:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:47:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:47:08 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-Et00CN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:47:09 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)) 23:47:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:47:09 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-1q79Bu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:47:09 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)) 23:47:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:47:09 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-bUVOsy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:47:09 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)) 23:47:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:47:09 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-hkDlz5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:47:09 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)) 23:47:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:47:09 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-pnjiK2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:47:09 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)) 23:47:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:47:09 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-rZPJeX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:47:09 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)) 23:47:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:47:09 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-3tuQUW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:47:09 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)) 23:47:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:47:09 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-Wtf5p6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:47:09 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)) 23:47:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:47:09 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-e9o5ak-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:47:09 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)) 23:47:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:47:09 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-YlD72w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:47:10 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)) 23:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:47:10 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-y9abSP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:47:10 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)) 23:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:47:10 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-puAS3o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:47:10 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)) 23:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:47:10 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-R8qzUg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:47:10 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)) 23:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:47:10 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-LJ7r8p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:47:10 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)) 23:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:47:10 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-qahq9h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:47:10 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)) 23:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:47:10 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-28kcgM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:47:10 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)) 23:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:47:10 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-FfZW6T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:47:10 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)) 23:47:10 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)) 23:47:10 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)) 23:47:10 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)) 23:47:10 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)) 23:47:10 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)) 23:47:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:47:10 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 23:47:10 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 23:47:10 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 23:47:10 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 23:47:10 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 23:47:10 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 23:47:13 INFO - Downloading blessings-1.6.tar.gz 23:47:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:47:13 INFO - Installing collected packages: blessings 23:47:13 INFO - Running setup.py install for blessings 23:47:13 INFO - Successfully installed blessings 23:47:13 INFO - Cleaning up... 23:47:14 INFO - Return code: 0 23:47:14 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:47:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:47:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:47:14 INFO - Reading from file tmpfile_stdout 23:47:14 INFO - Current package versions: 23:47:14 INFO - blessings == 1.6 23:47:14 INFO - blobuploader == 1.2.4 23:47:14 INFO - docopt == 0.6.1 23:47:14 INFO - functools32 == 3.2.3-2 23:47:14 INFO - jsonschema == 2.5.1 23:47:14 INFO - manifestparser == 1.1 23:47:14 INFO - mozInstall == 1.12 23:47:14 INFO - mozcrash == 1.0 23:47:14 INFO - mozdebug == 0.1 23:47:14 INFO - mozdevice == 0.48 23:47:14 INFO - mozfile == 1.2 23:47:14 INFO - mozhttpd == 0.7 23:47:14 INFO - mozinfo == 0.9 23:47:14 INFO - mozleak == 0.1 23:47:14 INFO - mozlog == 3.4 23:47:14 INFO - moznetwork == 0.27 23:47:14 INFO - mozprocess == 0.23 23:47:14 INFO - mozprofile == 0.28 23:47:14 INFO - mozrunner == 6.13 23:47:14 INFO - mozscreenshot == 0.1 23:47:14 INFO - mozsystemmonitor == 0.3 23:47:14 INFO - moztest == 0.7 23:47:14 INFO - mozversion == 1.4 23:47:14 INFO - psutil == 3.1.1 23:47:14 INFO - requests == 1.2.3 23:47:14 INFO - wsgiref == 0.1.2 23:47:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:47:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:47:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:47:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:47:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:47:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:47:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:47:14 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 0x102257490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021cd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabb0d82bd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10206bd30>, '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 0x101ed1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102063b20>, '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.GHL4deNbT5/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.zqXleoD2lj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/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 23:47:14 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 23:47:14 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 23:47:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GHL4deNbT5/Render', 23:47:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:47:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:47:14 INFO - 'HOME': '/Users/cltbld', 23:47:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:47:14 INFO - 'LOGNAME': 'cltbld', 23:47:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:47:14 INFO - 'MOZ_NO_REMOTE': '1', 23:47:14 INFO - 'NO_EM_RESTART': '1', 23:47:14 INFO - 'PAGER': '/bin/cat', 23:47:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:47:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:47:14 INFO - 'PWD': '/builds/slave/test', 23:47:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:47:14 INFO - 'SHELL': '/bin/bash', 23:47:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zqXleoD2lj/Listeners', 23:47:14 INFO - 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/', 23:47:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:47:14 INFO - 'USER': 'cltbld', 23:47:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:47:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:47:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:47:14 INFO - 'XPC_FLAGS': '0x0', 23:47:14 INFO - 'XPC_SERVICE_NAME': '0', 23:47:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:47:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:47:14 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)) 23:47:14 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)) 23:47:14 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)) 23:47:14 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)) 23:47:14 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)) 23:47:14 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)) 23:47:14 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)) 23:47:14 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)) 23:47:14 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)) 23:47:14 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)) 23:47:14 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)) 23:47:14 INFO - Cleaning up... 23:47:15 INFO - Return code: 0 23:47:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:47:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:47:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:47:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:47:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:47:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:47:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:47:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102257490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021cd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabb0d82bd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10206bd30>, '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 0x101ed1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102063b20>, '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.GHL4deNbT5/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.zqXleoD2lj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/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 23:47:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 23:47:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 23:47:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GHL4deNbT5/Render', 23:47:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:47:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:47:15 INFO - 'HOME': '/Users/cltbld', 23:47:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:47:15 INFO - 'LOGNAME': 'cltbld', 23:47:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:47:15 INFO - 'MOZ_NO_REMOTE': '1', 23:47:15 INFO - 'NO_EM_RESTART': '1', 23:47:15 INFO - 'PAGER': '/bin/cat', 23:47:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:47:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:47:15 INFO - 'PWD': '/builds/slave/test', 23:47:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:47:15 INFO - 'SHELL': '/bin/bash', 23:47:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zqXleoD2lj/Listeners', 23:47:15 INFO - 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/', 23:47:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:47:15 INFO - 'USER': 'cltbld', 23:47:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:47:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:47:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:47:15 INFO - 'XPC_FLAGS': '0x0', 23:47:15 INFO - 'XPC_SERVICE_NAME': '0', 23:47:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:47:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:47:15 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:47:15 INFO - Cleaning up... 23:47:15 INFO - Return code: 0 23:47:15 INFO - Running post-action listener: _resource_record_post_action 23:47:15 INFO - Running post-action listener: _start_resource_monitoring 23:47:15 INFO - Starting resource monitoring. 23:47:15 INFO - [mozharness: 2016-11-15 07:47:15.304493Z] Finished create-virtualenv step (success) 23:47:15 INFO - [mozharness: 2016-11-15 07:47:15.305562Z] Running install step. 23:47:15 INFO - Running pre-action listener: _resource_record_pre_action 23:47:15 INFO - Running main action method: install 23:47:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:47:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:47:15 INFO - Reading from file tmpfile_stdout 23:47:15 INFO - Detecting whether we're running mozinstall >=1.0... 23:47:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:47:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:47:15 INFO - Reading from file tmpfile_stdout 23:47:15 INFO - Output received: 23:47:15 INFO - Usage: mozinstall [options] installer 23:47:15 INFO - Options: 23:47:15 INFO - -h, --help show this help message and exit 23:47:15 INFO - -d DEST, --destination=DEST 23:47:15 INFO - Directory to install application into. [default: 23:47:15 INFO - "/builds/slave/test"] 23:47:15 INFO - --app=APP Application being installed. [default: firefox] 23:47:15 INFO - mkdir: /builds/slave/test/build/application 23:47:15 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'] 23:47:15 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 23:47:47 INFO - Reading from file tmpfile_stdout 23:47:47 INFO - Output received: 23:47:47 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 23:47:47 INFO - Running post-action listener: _resource_record_post_action 23:47:47 INFO - [mozharness: 2016-11-15 07:47:47.940942Z] Finished install step (success) 23:47:47 INFO - [mozharness: 2016-11-15 07:47:47.941105Z] Running run-tests step. 23:47:47 INFO - Running pre-action listener: _resource_record_pre_action 23:47:47 INFO - Running main action method: run_tests 23:47:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:47:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:47:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:47:47 INFO - 2016-11-14 23:47:47.955 screenresolution[1631:7722] starting screenresolution argv=screenresolution get 23:47:47 INFO - 2016-11-14 23:47:47.958 screenresolution[1631:7722] Display 0: 1600x1200x32@60 23:47:47 INFO - 2016-11-14 23:47:47.964 screenresolution[1632:7726] starting screenresolution argv=screenresolution list 23:47:47 INFO - Available Modes on Display 0 23:47:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:47:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:47:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:47:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:47:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:47:48 INFO - Intel Iris: 23:47:48 INFO - Chipset Model: Intel Iris 23:47:48 INFO - Type: GPU 23:47:48 INFO - Bus: Built-In 23:47:48 INFO - VRAM (Dynamic, Max): 1536 MB 23:47:48 INFO - Vendor: Intel (0x8086) 23:47:48 INFO - Device ID: 0x0a2e 23:47:48 INFO - Revision ID: 0x0009 23:47:48 INFO - Displays: 23:47:48 INFO - Display: 23:47:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:47:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:47:48 INFO - Display Serial Number: 1600x1200 60 23:47:48 INFO - Main Display: Yes 23:47:48 INFO - Mirror: Off 23:47:48 INFO - Online: Yes 23:47:48 INFO - Rotation: Supported 23:47:48 INFO - Return code: 0 23:47:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:47:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:47:48 INFO - Python 2.7.3 23:47:48 INFO - Return code: 0 23:47:48 INFO - grabbing minidump binary from tooltool 23:47:48 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')]} 23:47:48 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 0x10206bd30>, '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 0x101ed1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102063b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:47:48 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 23:47:48 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 23:47:48 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 23:47:48 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:47:48 INFO - Return code: 0 23:47:48 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 23:47:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:47:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:47:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 23:47:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:47:48 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/aa_w99o9QCueGkcOxSdlag/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0220', '--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 23:47:48 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/aa_w99o9QCueGkcOxSdlag/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0220 --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 23:47:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GHL4deNbT5/Render', 23:47:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:47:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:47:48 INFO - 'HOME': '/Users/cltbld', 23:47:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:47:48 INFO - 'LOGNAME': 'cltbld', 23:47:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:47:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 23:47:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:47:48 INFO - 'MOZ_NO_REMOTE': '1', 23:47:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:47:48 INFO - 'NO_EM_RESTART': '1', 23:47:48 INFO - 'PAGER': '/bin/cat', 23:47:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:47:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:47:48 INFO - 'PWD': '/builds/slave/test', 23:47:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:47:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:47:48 INFO - 'SHELL': '/bin/bash', 23:47:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zqXleoD2lj/Listeners', 23:47:48 INFO - 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/', 23:47:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:47:48 INFO - 'USER': 'cltbld', 23:47:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:47:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:47:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:47:48 INFO - 'XPC_FLAGS': '0x0', 23:47:48 INFO - 'XPC_SERVICE_NAME': '0', 23:47:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:47:48 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/aa_w99o9QCueGkcOxSdlag/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0220', '--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 23:47:48 INFO - mozversion application_buildid: 20161114212412 23:47:48 INFO - mozversion application_changeset: 401aa89aeb11b0cb29419438bfc4d27061c3c3e7 23:47:48 INFO - mozversion application_display_name: FirefoxDeveloperEdition 23:47:48 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:47:48 INFO - mozversion application_name: Firefox 23:47:48 INFO - mozversion application_remotingname: firefox-dev 23:47:48 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 23:47:48 INFO - mozversion application_vendor: Mozilla 23:47:48 INFO - mozversion application_version: 52.0a2 23:47:48 INFO - mozversion platform_buildid: 20161114212412 23:47:48 INFO - mozversion platform_changeset: 401aa89aeb11b0cb29419438bfc4d27061c3c3e7 23:47:48 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 23:47:48 INFO - mozversion platform_version: 52.0a2 23:47:48 INFO - using testdate: 1479196068 23:47:48 INFO - actual date: 1479196068 23:47:48 INFO - starting webserver on 'localhost:49241' 23:47:48 INFO - SUITE-START | Running 2 tests 23:47:48 INFO - TEST-START | dromaeo_css 23:47:48 INFO - operating with platform_type : mac_ 23:47:48 INFO - Initialising browser for dromaeo_css test... 23:47:48 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/tmpn8ZeKY/profile http://localhost:49241/getInfo.html) 23:47:50 ERROR - PROCESS | 1642 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5376: TypeError: parent.firstChild is null 23:47:51 INFO - PROCESS | 1642 | __metrics Screen width/height:1600/1200 23:47:51 INFO - PROCESS | 1642 | colorDepth:24 23:47:51 INFO - PROCESS | 1642 | Browser inner width/height: 1024/689 23:47:51 INFO - PROCESS | 1642 | __metrics 23:47:51 INFO - PROCESS | 1642 | 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" 23:47:51 INFO - PROCESS | 1642 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:47:51 INFO - TEST-INFO | 1642: exit 0 23:47:51 INFO - Browser initialized. 23:47:51 INFO - Running cycle 1/1 for dromaeo_css test... 23:47:52 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/tmpn8ZeKY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:48:04 INFO - PROCESS | 1644 | RSS: Main: 217714688 23:48:04 INFO - PROCESS | 1644 | 23:48:54 INFO - PROCESS | 1644 | 1479196134940 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] 23:51:08 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 23:51:10 INFO - PROCESS | 1644 | RSS: Main: 362516480 23:51:10 INFO - PROCESS | 1644 | 23:54:12 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 23:54:13 INFO - PROCESS | 1644 | RSS: Main: 354271232 23:54:13 INFO - PROCESS | 1644 | 23:57:15 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 23:57:16 INFO - PROCESS | 1644 | RSS: Main: 322789376 23:57:16 INFO - PROCESS | 1644 | 00:00:18 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 00:00:19 INFO - PROCESS | 1644 | RSS: Main: 332873728 00:00:19 INFO - PROCESS | 1644 | 00:03:22 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 00:03:23 INFO - PROCESS | 1644 | RSS: Main: 313937920 00:03:23 INFO - PROCESS | 1644 | 00:06:26 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 00:06:26 INFO - PROCESS | 1644 | RSS: Main: 458907648 00:06:26 INFO - PROCESS | 1644 | 00:06:26 INFO - PROCESS | 1644 | __start_tp_report 00:06:26 INFO - PROCESS | 1644 | _x_x_mozilla_page_load 00:06:26 INFO - PROCESS | 1644 | _x_x_mozilla_page_load_details 00:06:26 INFO - PROCESS | 1644 | |i|pagename|runs| 00:06:26 INFO - PROCESS | 1644 | |0;dojo.html;3350.246194606213;3366.0238530826045;3368.208471009313;3382.053025152955;3428.6057103433095;4538.614217791487;5746.626469279504;5804.564657650677;5816.040353341694;5877.059670452729;5813.651180929137;5867.501262392691;5887.175795388649;5916.733746981386;5957.31490878548;5223.242629818684;5256.553192978601;5267.315249017623;5335.839924802262;5353.839384818461;5184.53339199472;5236.21456781482;5237.188235823453;5248.291480650101;5369.5972802039805;5283.947160528384;5333.786648534054;5355.464453554652;5364.597655175858;5380.408155102936;5319;5331.866703332409;5333;5359.517643412092;5387.0841956867425;4346.239408103569;5056.74716264189;5168.3539557555305;5254.7635356409055;5259.8422047338645;5725.770969161229;5749.965006298865;5752;5845.473907348359;5887.411258874121;6209.5342849286035;6236;6258.374162583706;6288.779892703779;6295;6198.845028874269;6253.312135665074;6255.718492667841;6269.62382257066;6270.65511396873;5679.659220446745;5807.332156801937;5854.941450585525;5878.147668588031;5896.941030589725;6267.7806276781;6286.968565157236;6309.211348581427;6317.810465686037;6321.683915804191;6219.502439804895;6225.439710426083;6234.251889773256;6235.3452887446165;6239.719212635351;5308.893822123536;6134.344744984565;6226.283977342664;6227.09707092469;6260.46786023184;6162.895025421159;6221.564490485713;6227.657478838707;6232.345603711636;6246;6175.104609831549;6252.531060170505;6260.906086408708;6261.686915654199;6316.336784637639;4973.502649734997;4976.427710813303;4983.725895075733;4988.102141614544;5002.324686167393;4376.562343765598;4502.144592527409;4504.729716217037;4511.007578332761;4549.294859296814;5781.884362312814;5832.358440571534;5842;5873.060310350409;5939.46544810969;6213.161223234805;6220.657863817443;6227.408396202353;6244.812655620339;6255.593386429868;5899.380565040609;5942.851428714317;5955;5964.985952388104;5986.700664966735;5328.946710532845;5377.489138531833;5378.489043540857;5484.808031718871;5493.423190565013;5240.292560504283;6181.783637572664;6183.659898705434;6237.781677641352;6244.469220116341;6119.204503414528;6243.625382477066;6246.312905580474;6247.2815626201755;6253;6195.16365290698;6201.410865967726;6255.374462553708;6263.843403914756;6267.435930766253;6066.908996365147;6124.136831511461;6141.731496609813;6162.537809664383;6209.441150296495;6241.438270555672;6245.156903817927;6253.37466253371;6262.718177681924;6268.404501572343;6197.690115494297;6216.7824126157175;6239.5008399327035;6253.187085678833;6266.498680105672;5140.640155189101;6028.457438830526;6136.8772624548155;6150.60021098645;6166.229221347332;6063.363346848518;6067.908981365372;6075.1191077294425;6085.57400981927;6101.450869421596;6184.443400094013;6198.969005154945;6200.193974783429;6211.09939058843;6231.096491008869;5904.763809447746;5965.194698715792;5971.611845230046;6034.668093254913;6048.758049677964 00:06:26 INFO - PROCESS | 1644 | |1;ext.html;487.2472030712549;515.3017661069251;525.4797750227276;539.6761942834298;552.5551930695793;29985.850070749642;31951.520727189083;39530.40704389437;40830.77507674767;40957.795211023906;41241;41617.16765664685;41770.582294177046;41830.58169418305;41863.79068104655;9161.496117713523;9342.299327550461;9347.485888276142;9348.158665720084;9357.017513161121;9258.074192580756;9345.859812102824;9348.766280842965;9355.812883742321;9362.25101991839;9307;9376.343655944087;9377.906220937772;9413.670521531747;9417.434953902753;9297.256219502424;9345.579448924815;9354.06459354066;9358.344915855825;9372.109649583314;9268.304877134175;9348.345615806897;9351.953240233823;9353.625854965792;9355.251579873593;38889.41665875014;39302;39385.21229575421;39385.60614393877;39471;3212.213007813077;3212.7429805615493;3327.4177019021577;3401.5237866698435;3716.1267102230954;28925;29782.33340977766;29880.40239195204;29913.25216869574;30044.699553004408;3156.2582793043616;3208.7593430492807;3209.871605135792;3220.114468521152;3235.6117265928237;593188;595410;596594;597257;597918;61124.69437652872;61706.69146654238;61906;61910.69044654748;62081.689591552655;34566;34792.65207347945;65207.347926520124;65802.67098664476;65853.67073164604;15902.76145857813;16099.356025759073;16101.114438705983;16102.838971610368;16118.67762644764;10357.585696572203;12348.753024939451;12355.444005019795;12365.38173091342;12381.195222310522;15812.36750529989;18684.439466815744;18686.345977890982;18686.532836678918;18772.812271877105;7564.735234266775;7596.734114305973;7667.539947603162;7683.692652293846;7883.344497655557;841.9663213471515;929.5770424456856;991.861139440479;1059.2267644619471;1075.0754351257913;29125.41749165005;29189.41621167607;29399.85300073486;29829.701702983126;29936.952206673097;1020.6172685243034;1020.9030142136634;1052.5053224984244;1083.842842787807;1106.8893110688866;14832.555023349316;14915.701685966436;15102.622434439007;15392.211626700875;15595.284518702934;682886;683913;686127;686333.5683321551;687573;57402.85194296091;57915.262542123804;57977.710111449174;58078.70960645254;58345.70827145922;1228.766534358469;1259.2444533279959;1340.8122862799216;1366.2349084512705;1383.280694039094;30041.773090959334;30154.84922575373;30213.885028047825;30501.389972199988;30782;48889;49481.752591236815;49633.50366496289;49647.007059857875;49791.26148061747;465182;468500;468509;468718;468849;22930.770692292863;23353.299401017997;25137.24588262355;25338.493230135664;26154.86922565375;45246.77376613096;45257;45692.31461528146;45697.54302456933;45733.77133114313;31935.520967185046;37121.44317835181;37516.81241593775;39090.41364379593;39454.40818387784;37297.440538391405;37647;37865.242695146495;37885.242295154494;38275;37308.440373393874;38066.238675226894;38106.61893381141;38210.0447488815;38393.4240986391;31417.8429107853;34069.807556735395;34077.48883766696;34150.82924585361;34262.31475370529;24783.628245576692;24903.377415564755;25114.830131028386;25208.873955630104;25624.743752562237 00:06:26 INFO - PROCESS | 1644 | |2;jquery.html;304.82320254252534;347.8052290717198;373.645037214646;383.93856982882744;402.28995822373497;14536.927315363422;14730.631734206638;15380.307886145123;19217.23131074759;19834.504137396572;19262.71105933412;19525.316613918516;19769.81381117134;20002.89998550005;20025.399238022845;5591.74837132328;5941.970290148543;5949.494292985101;5954.196183515235;5955.821325360224;5506.972465137689;5760.36635970043;5819.359870414251;5820.505257053155;5821.8835623287505;5751.7986870459545;5764.769409223626;5769.278840144982;5770.249867517238;5777.855553611173;5108.616853735966;5340.519353258206;5787.13193021046;5789.65262084272;5794.304683438015;5019.627329192546;5063.670861393997;5063.797448102072;5082.1360368737405;5085.059264036166;24151;24760.50478990428;24800.883960221832;24842.751572484405;25143.87428062866;2333.97666023341;2425.4300239443714;2435.8051355891484;2487.2702681199858;2793.52510073288;14622.780658290134;15182.772258416133;15198.088114713153;15309.234538273042;15728;1798.9910050449794;1807.53907753522;1859.9721004184948;1885.9528511787178;1902.5529000684828;83462;85965;122652.77347226502;124794;130685;10568.735781605521;10587.682369528926;10882.945585272182;10884.836727449094;11069.944650276697;11512;13474;13535.18788872651;13541.322933853267;13563.186208827305;8371.748847534584;8491.363147763943;8492;8515.957420212859;8549.444286121383;8579.198368342719;8590.1839325264;8600.698975535826;8741.781455463664;8795.428297160664;6040.939590604125;6372.139761132281;6429.260635026938;6450.6774661266745;6474.6762661866715;1785.7589225454658;1811.619559892411;1812.5559237986513;1833.9358122465649;1853.5458812590732;732.4030914804417;895.1048951048951;947.6493697331894;1018.4398580780542;1019.7246743379246;20579.07394167266;21547.67678484824;21595.568088638454;21653.891730541247;21673.24143654996;879.4151888993875;930.6789157740569;961.9759506012266;968.1238479176357;982.8034393121404;11044.061254793272;11519.146108065688;11565.190436669352;11581.768364632828;11612.187146899805;598264;600147;605812;610970.8902911003;626786;113905;115347.42326288483;119218;120017;122455;970.1414248390225;980.1032055669126;996.9900300997042;1011.3022014809758;1012.4988130875264;18522.79601825877;18528.907355463136;19119.426417207505;19246;19282.035898205315;50903.236451453995;51631;53737.73131134476;53759.19361209507;53986.73006634942;73068.63465682637;73121;73169.6341518289;73275.26724732897;74392.14019649551;7700;7753.883691744515;7859.331956783737;7863.292303692695;8010.919890801018;12241.143119981873;12434.75130497403;12809.231446113263;12995;13096.083274170716;10361.533730981973;10443.686689399026;10825.10560651867;11022.393768342818;11036.337819730841;10599.841002384816;10665.306755060896;10703.94648026786;11010.46759245362;11165.274257173258;11384.715382115182;11391.829122563335;11581.015613672933;11589;11691.298522088702;7663.233676632292;7839.746505200506;7843.254890785366;8061.113277539349;8130.552819594979;8849.4247873888;9632.036796320312;10672.146228301563;10798.892011079788;11219.158563107636 00:06:26 INFO - PROCESS | 1644 | |3;mootools.html;1468.698916722072;1483.1471903655397;1640.5242479680894;1758.8329108734665;1810.3392261824429;8016;8633.309335253189;8638.438501497398;8646.870296945552;8650.221480066793;7562.432817538679;7766.611669416535;7772.922270777291;8139.145389734082;8434.662613495468;2279.908803647852;2344.531093781242;2344.6951896253463;2345.718513778349;2346.6128088865316;2154.859934104286;2232.977670223301;2342.9882850585723;2389.116027069978;2392.473655795722;2282.315305408371;2283.8743869087193;2312.953740925189;2316.6061769499142;2317.4669825940045;2299.908003679859;2336.6378211377264;2338.4504641409253;2339.993802664862;2340.6606042123967;2281.1103669568884;2309.0648287443614;2309.988450057756;2310.145246258895;2310.699609050813;8366.748997530083;8398.286145677625;8496.490210587323;8507.361947853935;8516.020657624329;4770.45139808923;5232.529072383504;5238.05714971305;5239.240310155006;5263.289455923478;3218.742500599946;3326.1518312830317;3581.015220814271;3596.406592912166;3597.8740744074094;973.4500007495742;1073.3881687438163;1102.2064113838023;1120.4621781544904;1121.6466812953893;11056.060234880046;11184.049355804766;11246.718832029264;11251.931066548665;11265.549378024869;4546.136234115507;4587.449506059227;4732.479427263067;4755.167845627001;4770.42754869411;9511;9539.236861051098;9549.665761698307;9553.283503737248;9553.522323883779;5191.766370513336;5192.4547922468355;5197.038547868657;5202;5204.5576126028955;4032.495938007749;4218.8523401680795;4679.4618618858585;4740.5733483986605;4783;3520.4895290183294;3645.8906232813056;3815.7328986970647;3870;3917.3928041153667;1258.8300579421843;1268.2834198677717;1269.292801182408;1285.4858056777364;1286.1832736212573;800.8598495263423;848.9023762267295;873.0178549132227;894.1550235027967;900.0639335091562;5441.537469315073;5543.420125264281;5568.805091821767;5778.27771528559;5975.850603734942;974.2595627323144;988.7725823060737;997.2221667099528;1005.934614250086;1029.531563138785;3563.6080031196548;3568.6252943441086;3688.151725103188;3754.5869954305003;3771.6982641389172;9957.850632240523;10286.2799604027;10287.897121028844;10351.741206469898;10367.844482332772;9605.807883842284;9808;9814.2148628111;10161.695149145537;10233.283670143168;7805.297523222937;8030.23712747288;8035.919640803634;8036.276735093869;8039.839203215903;5463.918041229305;5477.945220547744;5503.862403439946;5758.049921762862;5785;9496.335256531976;9496.762580935532;9504.904950950402;9512.09635084666;9520.047995200424;9264.44413335202;9498.85751713711;9583.281253906125;9661.3237073407;9675.854862176931;6679.165104361955;6751.628660423724;6751.797446076625;7174.282571742784;7346.816329591803;7507.986421832983;7611.124720657195;7619.885701714368;7665.348445382205;7745.496542724705;7045.11936007999;7282.599457029914;7321.304476074764;7324.560526368435;7419.07261592301;5929.525637948867;6905.343992320722;6933.375996160168;7131.786046418616;7187.820304492429;6661.766838160641;6840.213375461885;6865.588064716133;6881.174259088942;6882.449404047564;4769.213079841926;4794.017226468496;4815.518448155157;4857.53853383928;4930.975345123252;4365.236083685406;4825.131476334153;4830.372051633265;5007.173816320412;5032.6225533084435 00:06:26 INFO - PROCESS | 1644 | |4;prototype.html;298.9596404485394;301.1612658745394;306.6243851282179;308.06809401560275;313.66437911434775;4606.631469482441;5175.715335656538;5356.223347614603;5372.677639341638;5453.91819122713;4304.7632380219075;4805.567498925096;5200.973995130029;5247.842564723064;5313.1233346497875;3454.9309013819707;4085.55058943516;4094.9181016379653;4104.619320793947;4117.176564687052;3453.3783918894587;3982.7411218270863;3985.3822657488136;4118.299889018859;4138.8344466221315;3461.9480707789403;4071.6131967463043;4079.224947260026;4104.240715467633;4135.0902801383645;3838.692904567628;3887.319719049155;4074.7555146691293;4105.97947010263;4110.77390743509;3137.9686203137903;3521.5421995140723;3557.982210088933;3577.2130131371055;3593.4969104325414;4652.208674795096;4996.650234483587;5183.948160518385;5255.921161182585;5303.310569625963;3946.842126314944;4216.662666986634;4676.5089665584965;4689.788959496831;4702.882427939329;4604.194266003436;5069.645124841262;5305.286299590302;5644.5484361251;5647.717614119319;3576.016595436276;3699.7965111918834;3868.825902834379;3887.9805600971813;3941.3890846918775;5298.947010529884;5502.889942201134;5871.266091738533;5898.351181370046;5904.940950590482;3565.983694647013;3838.788866612363;3970.900727481778;3978.3688687637928;3993.560708322082;3595.9280814383565;3713.7771733696063;4034.9307433529552;4061.882982179924;4074.696097248911;4635.6523260755575;4647.34937108805;4662.836800711959;4668.276417155346;4676.19966312972;5688.8862222755315;5699.430056994267;5717.969476177633;5740.052891272893;5746.482816546447;1213.872543382932;1236.839210902595;1240.2248594628356;1240.815021654318;1274.387899307377;790.3519114326198;804.1596531624466;862.7834752998242;874.8687696845396;880.7401816464159;4289.227938971015;4423.20382331177;4741.35991641124;5011.644527072271;5054;4441.845704833153;4804.855854324376;5150.922736158961;5187.559057480081;5470.261514695545;4279.465066866642;5006.047641419849;5024.924626130611;5042.39491261051;5060.974695126501;2552.515022145786;2608.5826267797443;2724.9046283380385;2826;2869.26833657416;4702.388689470347;5207.661502002434;5384.40771515133;5567.552436366493;5611.803586874522;4813;6050.492383174475;6082.60463069899;6102.359252278536;6105.877882442415;6067.635941843503;6074.574779765374;6110.991686371699;6113.29697084841;6136.6011209271255;4618.56123668251;5839.445252700986;5886.38192989747;5894.911576326444;5907.675077870758;5810.128480727924;5813.273340832396;5838.766449341979;5854.356020837789;5861.706914654335;5361.731913404392;5551.694656793915;6007.369226231182;6103.016519631049;6104.20645316106;3392.508086327518;3923.725339226227;3951.732129300406;3976.4432979383487;3987.641112299791;5100.591952643706;5516.255305533701;5915.37888521699;5934.406559344204;5952.761889524544;5213.973930130325;5349.491798279158;5485.259489968803;5722.656640601577;5735.082386818256;5734;5740.708340623493;5740.712964351849;5753;5762.6830524319885;4948.960718249208;5273.709945953009;5462.890742185214;5489.560835133259;5635.647444613345;5272.235525848807;5655.660660360391;5657.773689052392;5685.374608793112;5691.430856914441;4528.524504927067;5318.627696061239;5600.4959553640365;5619.859503512282;5622.3815380307315 00:06:26 INFO - PROCESS | 1644 | |5;yui.html;672.2840175213395;687.4775170870139;714.3749219432996;717.1322699533564;737.811857976216;13365.198088114696;13957.232352220624;14017;14654.413823447063;14687.971841971053;13019.869801301984;13228.93385533071;13954.79067813981;14535;14756.335964881579;786.6617354537532;808.5552945879742;809.4576633655465;811.9878201826978;812.1147948735877;653.807126897564;670.071950348768;672.495628278791;816.0859836982588;817.9918200817975;703.707961196103;705.016501979738;705.3968856627587;844.3920377328313;870.0299166429418;860.746079906426;870.1776820904216;870.5168631409565;871.751550808023;871.7646235516376;643.0996604753336;644.3813938618931;644.6196743921062;645.4932877691037;647.1683886206202;11661.941690291495;11799.705007374798;12112.757744845054;14202.147871127765;14274.429022839075;11126.61056863014;12261.570845020382;13506.797398039038;13651.317434128354;13684.47870198777;9549.522523873778;11231.382273974928;11233;11341.319520828776;11531.538738450452;3422.691957723817;3494.4408894576745;3607.3867442534834;3672.9081772955624;3753.0804952786743;90923.54538227333;91577;95469;95528.52235738777;97461.53807692819;9989.700308990741;10298.639547615798;10381.53283102247;10408.635697750542;10450.999999999847;20009.699854502192;20142.798572014093;20185.899070504845;20393.898030509754;20432.387028389174;7611.086669599573;7721.575313357707;7839.05931288249;7840.294373506412;7851.293383595504;4148.8133034013545;4176.394422808676;4259.9787001065415;4303.33298338758;4395.922998865234;3262.8531716072835;3369.0735047862036;3369.612494563157;3371.763976521669;3372.4772660237704;587.9206307148565;608.1030480041903;608.6986941463915;661.7253839656489;687.8383579858729;520.6121439527576;566.3713278261126;567.3503838105346;596.8478038100257;658.1969996604136;8510.361722870686;9038.593263303035;9122.33100744649;9154.40496367734;9484.525773711286;513.496773162303;615.8152554233712;636.6816591704148;672.7409947170374;684.5105749389211;4270.743755374688;4455.2648812946145;4510.8421205257655;4597.987322421744;4635.513271106553;100845.49577252066;107455;108198.45900770446;108945.91054089516;110270.44864775785;85240;86124.56937715271;87905.1209487897;91710.54144729233;93441;66714.66570668656;79150.27222686021;79701.40597188023;86666;88461.55769221112;8865.335099867665;8911.55442227899;9803.117719405287;9887.30788844774;9930.702078937644;50811;51871.74064129655;54347.64130896759;55213.89572208453;57338.42661573498;65336.67331663311;69370.65314673394;69775.30224697688;77580;79817.60091199506;11696.70758231051;12017;12052.457639406422;12255;12569.245845248948;12135.514579416724;13909.58271251864;14143.292835358397;14211.50259740904;14761.92619036898;12507.868626412635;13166.670833228864;13221.40503677356;13232.404541795835;13404.865951340751;12298.877011230132;13226.801597976231;13231.933840330737;13618.18290902549;13734;12131.45408456604;12455.252684838939;12684.87315126837;12863.742725145632;13027.869721303045;10531.842022369518;10667.89332106669;10881.673549793519;11700.70748231267;11889.405529723306;9530.428174309563;9583.137517623447;10892.891071089505;10944.413923987478;11123.27698699582 00:06:26 INFO - PROCESS | 1644 | __end_tp_report 00:06:26 INFO - PROCESS | 1644 | __start_cc_report 00:06:26 INFO - PROCESS | 1644 | _x_x_mozilla_cycle_collect,1422 00:06:26 INFO - PROCESS | 1644 | __end_cc_report 00:06:26 INFO - PROCESS | 1644 | __startTimestamp1479197186639__endTimestamp 00:06:26 INFO - PROCESS | 1644 | 00:06:26 INFO - PROCESS | 1644 | ------- Summary: start ------- 00:06:26 INFO - PROCESS | 1644 | Number of tests: 6 00:06:26 INFO - PROCESS | 1644 | 00:06:26 INFO - PROCESS | 1644 | [#0] dojo.html Cycles:165 Average:5771.28 Median:6034.67 stddev:643.95 (10.7%) stddev-sans-first:617.28 00:06:26 INFO - PROCESS | 1644 | Values: 3350.2 3366.0 3368.2 3382.1 3428.6 4538.6 5746.6 5804.6 5816.0 5877.1 5813.7 5867.5 5887.2 5916.7 5957.3 5223.2 5256.6 5267.3 5335.8 5353.8 5184.5 5236.2 5237.2 5248.3 5369.6 5283.9 5333.8 5355.5 5364.6 5380.4 5319.0 5331.9 5333.0 5359.5 5387.1 4346.2 5056.7 5168.4 5254.8 5259.8 5725.8 5750.0 5752.0 5845.5 5887.4 6209.5 6236.0 6258.4 6288.8 6295.0 6198.8 6253.3 6255.7 6269.6 6270.7 5679.7 5807.3 5854.9 5878.1 5896.9 6267.8 6287.0 6309.2 6317.8 6321.7 6219.5 6225.4 6234.3 6235.3 6239.7 5308.9 6134.3 6226.3 6227.1 6260.5 6162.9 6221.6 6227.7 6232.3 6246.0 6175.1 6252.5 6260.9 6261.7 6316.3 4973.5 4976.4 4983.7 4988.1 5002.3 4376.6 4502.1 4504.7 4511.0 4549.3 5781.9 5832.4 5842.0 5873.1 5939.5 6213.2 6220.7 6227.4 6244.8 6255.6 5899.4 5942.9 5955.0 5965.0 5986.7 5328.9 5377.5 5378.5 5484.8 5493.4 5240.3 6181.8 6183.7 6237.8 6244.5 6119.2 6243.6 6246.3 6247.3 6253.0 6195.2 6201.4 6255.4 6263.8 6267.4 6066.9 6124.1 6141.7 6162.5 6209.4 6241.4 6245.2 6253.4 6262.7 6268.4 6197.7 6216.8 6239.5 6253.2 6266.5 5140.6 6028.5 6136.9 6150.6 6166.2 6063.4 6067.9 6075.1 6085.6 6101.5 6184.4 6199.0 6200.2 6211.1 6231.1 5904.8 5965.2 5971.6 6034.7 6048.8 00:06:26 INFO - PROCESS | 1644 | 00:06:26 INFO - PROCESS | 1644 | [#1] ext.html Cycles:180 Average:70744.17 Median:39196.21 stddev:158027.64 (403.2%) stddev-sans-first:158382.91 00:06:26 INFO - PROCESS | 1644 | Values: 487.2 515.3 525.5 539.7 552.6 29985.9 31951.5 39530.4 40830.8 40957.8 41241.0 41617.2 41770.6 41830.6 41863.8 9161.5 9342.3 9347.5 9348.2 9357.0 9258.1 9345.9 9348.8 9355.8 9362.3 9307.0 9376.3 9377.9 9413.7 9417.4 9297.3 9345.6 9354.1 9358.3 9372.1 9268.3 9348.3 9352.0 9353.6 9355.3 38889.4 39302.0 39385.2 39385.6 39471.0 3212.2 3212.7 3327.4 3401.5 3716.1 28925.0 29782.3 29880.4 29913.3 30044.7 3156.3 3208.8 3209.9 3220.1 3235.6 593188.0 595410.0 596594.0 597257.0 597918.0 61124.7 61706.7 61906.0 61910.7 62081.7 34566.0 34792.7 65207.3 65802.7 65853.7 15902.8 16099.4 16101.1 16102.8 16118.7 10357.6 12348.8 12355.4 12365.4 12381.2 15812.4 18684.4 18686.3 18686.5 18772.8 7564.7 7596.7 7667.5 7683.7 7883.3 842.0 929.6 991.9 1059.2 1075.1 29125.4 29189.4 29399.9 29829.7 29937.0 1020.6 1020.9 1052.5 1083.8 1106.9 14832.6 14915.7 15102.6 15392.2 15595.3 682886.0 683913.0 686127.0 686333.6 687573.0 57402.9 57915.3 57977.7 58078.7 58345.7 1228.8 1259.2 1340.8 1366.2 1383.3 30041.8 30154.8 30213.9 30501.4 30782.0 48889.0 49481.8 49633.5 49647.0 49791.3 465182.0 468500.0 468509.0 468718.0 468849.0 22930.8 23353.3 25137.2 25338.5 26154.9 45246.8 45257.0 45692.3 45697.5 45733.8 31935.5 37121.4 37516.8 39090.4 39454.4 37297.4 37647.0 37865.2 37885.2 38275.0 37308.4 38066.2 38106.6 38210.0 38393.4 31417.8 34069.8 34077.5 34150.8 34262.3 24783.6 24903.4 25114.8 25208.9 25624.7 00:06:26 INFO - PROCESS | 1644 | 00:06:26 INFO - PROCESS | 1644 | [#2] jquery.html Cycles:180 Average:34642.90 Median:24821.82 stddev:101040.20 (407.1%) stddev-sans-first:101290.75 00:06:26 INFO - PROCESS | 1644 | Values: 304.8 347.8 373.6 383.9 402.3 14536.9 14730.6 15380.3 19217.2 19834.5 19262.7 19525.3 19769.8 20002.9 20025.4 5591.7 5942.0 5949.5 5954.2 5955.8 5507.0 5760.4 5819.4 5820.5 5821.9 5751.8 5764.8 5769.3 5770.2 5777.9 5108.6 5340.5 5787.1 5789.7 5794.3 5019.6 5063.7 5063.8 5082.1 5085.1 24151.0 24760.5 24800.9 24842.8 25143.9 2334.0 2425.4 2435.8 2487.3 2793.5 14622.8 15182.8 15198.1 15309.2 15728.0 1799.0 1807.5 1860.0 1886.0 1902.6 83462.0 85965.0 122652.8 124794.0 130685.0 10568.7 10587.7 10882.9 10884.8 11069.9 11512.0 13474.0 13535.2 13541.3 13563.2 8371.7 8491.4 8492.0 8516.0 8549.4 8579.2 8590.2 8600.7 8741.8 8795.4 6040.9 6372.1 6429.3 6450.7 6474.7 1785.8 1811.6 1812.6 1833.9 1853.5 732.4 895.1 947.6 1018.4 1019.7 20579.1 21547.7 21595.6 21653.9 21673.2 879.4 930.7 962.0 968.1 982.8 11044.1 11519.1 11565.2 11581.8 11612.2 598264.0 600147.0 605812.0 610970.9 626786.0 113905.0 115347.4 119218.0 120017.0 122455.0 970.1 980.1 997.0 1011.3 1012.5 18522.8 18528.9 19119.4 19246.0 19282.0 50903.2 51631.0 53737.7 53759.2 53986.7 73068.6 73121.0 73169.6 73275.3 74392.1 7700.0 7753.9 7859.3 7863.3 8010.9 12241.1 12434.8 12809.2 12995.0 13096.1 10361.5 10443.7 10825.1 11022.4 11036.3 10599.8 10665.3 10703.9 11010.5 11165.3 11384.7 11391.8 11581.0 11589.0 11691.3 7663.2 7839.7 7843.3 8061.1 8130.6 8849.4 9632.0 10672.1 10798.9 11219.2 00:06:26 INFO - PROCESS | 1644 | 00:06:26 INFO - PROCESS | 1644 | [#3] mootools.html Cycles:180 Average:5474.41 Median:5199.52 stddev:3016.46 (58.0%) stddev-sans-first:3009.90 00:06:26 INFO - PROCESS | 1644 | Values: 1468.7 1483.1 1640.5 1758.8 1810.3 8016.0 8633.3 8638.4 8646.9 8650.2 7562.4 7766.6 7772.9 8139.1 8434.7 2279.9 2344.5 2344.7 2345.7 2346.6 2154.9 2233.0 2343.0 2389.1 2392.5 2282.3 2283.9 2313.0 2316.6 2317.5 2299.9 2336.6 2338.5 2340.0 2340.7 2281.1 2309.1 2310.0 2310.1 2310.7 8366.7 8398.3 8496.5 8507.4 8516.0 4770.5 5232.5 5238.1 5239.2 5263.3 3218.7 3326.2 3581.0 3596.4 3597.9 973.5 1073.4 1102.2 1120.5 1121.6 11056.1 11184.0 11246.7 11251.9 11265.5 4546.1 4587.4 4732.5 4755.2 4770.4 9511.0 9539.2 9549.7 9553.3 9553.5 5191.8 5192.5 5197.0 5202.0 5204.6 4032.5 4218.9 4679.5 4740.6 4783.0 3520.5 3645.9 3815.7 3870.0 3917.4 1258.8 1268.3 1269.3 1285.5 1286.2 800.9 848.9 873.0 894.2 900.1 5441.5 5543.4 5568.8 5778.3 5975.9 974.3 988.8 997.2 1005.9 1029.5 3563.6 3568.6 3688.2 3754.6 3771.7 9957.9 10286.3 10287.9 10351.7 10367.8 9605.8 9808.0 9814.2 10161.7 10233.3 7805.3 8030.2 8035.9 8036.3 8039.8 5463.9 5477.9 5503.9 5758.0 5785.0 9496.3 9496.8 9504.9 9512.1 9520.0 9264.4 9498.9 9583.3 9661.3 9675.9 6679.2 6751.6 6751.8 7174.3 7346.8 7508.0 7611.1 7619.9 7665.3 7745.5 7045.1 7282.6 7321.3 7324.6 7419.1 5929.5 6905.3 6933.4 7131.8 7187.8 6661.8 6840.2 6865.6 6881.2 6882.4 4769.2 4794.0 4815.5 4857.5 4931.0 4365.2 4825.1 4830.4 5007.2 5032.6 00:06:26 INFO - PROCESS | 1644 | 00:06:26 INFO - PROCESS | 1644 | [#4] prototype.html Cycles:180 Average:4534.13 Median:5048.20 stddev:1422.12 (28.2%) stddev-sans-first:1390.13 00:06:26 INFO - PROCESS | 1644 | Values: 299.0 301.2 306.6 308.1 313.7 4606.6 5175.7 5356.2 5372.7 5453.9 4304.8 4805.6 5201.0 5247.8 5313.1 3454.9 4085.6 4094.9 4104.6 4117.2 3453.4 3982.7 3985.4 4118.3 4138.8 3461.9 4071.6 4079.2 4104.2 4135.1 3838.7 3887.3 4074.8 4106.0 4110.8 3138.0 3521.5 3558.0 3577.2 3593.5 4652.2 4996.7 5183.9 5255.9 5303.3 3946.8 4216.7 4676.5 4689.8 4702.9 4604.2 5069.6 5305.3 5644.5 5647.7 3576.0 3699.8 3868.8 3888.0 3941.4 5298.9 5502.9 5871.3 5898.4 5904.9 3566.0 3838.8 3970.9 3978.4 3993.6 3595.9 3713.8 4034.9 4061.9 4074.7 4635.7 4647.3 4662.8 4668.3 4676.2 5688.9 5699.4 5718.0 5740.1 5746.5 1213.9 1236.8 1240.2 1240.8 1274.4 790.4 804.2 862.8 874.9 880.7 4289.2 4423.2 4741.4 5011.6 5054.0 4441.8 4804.9 5150.9 5187.6 5470.3 4279.5 5006.0 5024.9 5042.4 5061.0 2552.5 2608.6 2724.9 2826.0 2869.3 4702.4 5207.7 5384.4 5567.6 5611.8 4813.0 6050.5 6082.6 6102.4 6105.9 6067.6 6074.6 6111.0 6113.3 6136.6 4618.6 5839.4 5886.4 5894.9 5907.7 5810.1 5813.3 5838.8 5854.4 5861.7 5361.7 5551.7 6007.4 6103.0 6104.2 3392.5 3923.7 3951.7 3976.4 3987.6 5100.6 5516.3 5915.4 5934.4 5952.8 5214.0 5349.5 5485.3 5722.7 5735.1 5734.0 5740.7 5740.7 5753.0 5762.7 4949.0 5273.7 5462.9 5489.6 5635.6 5272.2 5655.7 5657.8 5685.4 5691.4 4528.5 5318.6 5600.5 5619.9 5622.4 00:06:26 INFO - PROCESS | 1644 | 00:06:26 INFO - PROCESS | 1644 | [#5] yui.html Cycles:180 Average:20185.51 Median:27723.26 stddev:29432.65 (106.2%) stddev-sans-first:29478.75 00:06:26 INFO - PROCESS | 1644 | Values: 672.3 687.5 714.4 717.1 737.8 13365.2 13957.2 14017.0 14654.4 14688.0 13019.9 13228.9 13954.8 14535.0 14756.3 786.7 808.6 809.5 812.0 812.1 653.8 670.1 672.5 816.1 818.0 703.7 705.0 705.4 844.4 870.0 860.7 870.2 870.5 871.8 871.8 643.1 644.4 644.6 645.5 647.2 11661.9 11799.7 12112.8 14202.1 14274.4 11126.6 12261.6 13506.8 13651.3 13684.5 9549.5 11231.4 11233.0 11341.3 11531.5 3422.7 3494.4 3607.4 3672.9 3753.1 90923.5 91577.0 95469.0 95528.5 97461.5 9989.7 10298.6 10381.5 10408.6 10451.0 20009.7 20142.8 20185.9 20393.9 20432.4 7611.1 7721.6 7839.1 7840.3 7851.3 4148.8 4176.4 4260.0 4303.3 4395.9 3262.9 3369.1 3369.6 3371.8 3372.5 587.9 608.1 608.7 661.7 687.8 520.6 566.4 567.4 596.8 658.2 8510.4 9038.6 9122.3 9154.4 9484.5 513.5 615.8 636.7 672.7 684.5 4270.7 4455.3 4510.8 4598.0 4635.5 100845.5 107455.0 108198.5 108945.9 110270.4 85240.0 86124.6 87905.1 91710.5 93441.0 66714.7 79150.3 79701.4 86666.0 88461.6 8865.3 8911.6 9803.1 9887.3 9930.7 50811.0 51871.7 54347.6 55213.9 57338.4 65336.7 69370.7 69775.3 77580.0 79817.6 11696.7 12017.0 12052.5 12255.0 12569.2 12135.5 13909.6 14143.3 14211.5 14761.9 12507.9 13166.7 13221.4 13232.4 13404.9 12298.9 13226.8 13231.9 13618.2 13734.0 12131.5 12455.3 12684.9 12863.7 13027.9 10531.8 10667.9 10881.7 11700.7 11889.4 9530.4 9583.1 10892.9 10944.4 11123.3 00:06:26 INFO - PROCESS | 1644 | -------- Summary: end -------- 00:06:26 INFO - PROCESS | 1644 | 00:06:27 INFO - TEST-INFO | 1644: exit 0 00:06:27 INFO - TEST-OK | dromaeo_css | took 1118740ms 00:06:27 INFO - TEST-START | kraken 00:06:27 INFO - operating with platform_type : mac_ 00:06:27 INFO - Initialising browser for kraken test... 00:06:27 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/tmpoduaYi/profile http://localhost:49241/getInfo.html) 00:06:28 ERROR - PROCESS | 1654 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5376: TypeError: parent.firstChild is null 00:06:29 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 00:06:29 INFO - PROCESS | 1654 | colorDepth:24 00:06:29 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 00:06:29 INFO - PROCESS | 1654 | __metrics 00:06:29 INFO - PROCESS | 1654 | 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:06:29 INFO - PROCESS | 1654 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 00:06:30 INFO - TEST-INFO | 1654: exit 0 00:06:30 INFO - Browser initialized. 00:06:30 INFO - Running cycle 1/1 for kraken test... 00:06:30 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/tmpoduaYi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:06:42 INFO - PROCESS | 1655 | RSS: Main: 217202688 00:06:42 INFO - PROCESS | 1655 | 00:06:45 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 00:06:46 INFO - PROCESS | 1655 | RSS: Main: 267403264 00:06:46 INFO - PROCESS | 1655 | 00:06:48 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 00:06:49 INFO - PROCESS | 1655 | RSS: Main: 277270528 00:06:49 INFO - PROCESS | 1655 | 00:06:52 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 00:06:53 INFO - PROCESS | 1655 | RSS: Main: 376602624 00:06:53 INFO - PROCESS | 1655 | 00:06:54 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 00:06:55 INFO - PROCESS | 1655 | RSS: Main: 278294528 00:06:55 INFO - PROCESS | 1655 | 00:06:57 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 00:06:58 INFO - PROCESS | 1655 | RSS: Main: 280031232 00:06:58 INFO - PROCESS | 1655 | 00:07:00 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 00:07:01 INFO - PROCESS | 1655 | RSS: Main: 321155072 00:07:01 INFO - PROCESS | 1655 | 00:07:04 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 00:07:05 INFO - PROCESS | 1655 | RSS: Main: 320602112 00:07:05 INFO - PROCESS | 1655 | 00:07:07 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 00:07:08 INFO - PROCESS | 1655 | RSS: Main: 319401984 00:07:08 INFO - PROCESS | 1655 | 00:07:10 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 00:07:10 INFO - PROCESS | 1655 | RSS: Main: 296620032 00:07:10 INFO - PROCESS | 1655 | 00:07:12 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 00:07:13 INFO - PROCESS | 1655 | RSS: Main: 297684992 00:07:13 INFO - PROCESS | 1655 | 00:07:15 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 00:07:16 INFO - PROCESS | 1655 | RSS: Main: 300449792 00:07:16 INFO - PROCESS | 1655 | 00:07:19 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 00:07:19 INFO - PROCESS | 1655 | RSS: Main: 309198848 00:07:19 INFO - PROCESS | 1655 | 00:07:22 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 00:07:23 INFO - PROCESS | 1655 | RSS: Main: 307142656 00:07:23 INFO - PROCESS | 1655 | 00:07:25 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 00:07:25 INFO - PROCESS | 1655 | RSS: Main: 350937088 00:07:25 INFO - PROCESS | 1655 | 00:07:25 INFO - PROCESS | 1655 | __start_tp_report 00:07:25 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 00:07:25 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 00:07:25 INFO - PROCESS | 1655 | |i|pagename|runs| 00:07:25 INFO - PROCESS | 1655 | |0;ai-astar;116;115;117;112;115;116;115;115;116;114 00:07:25 INFO - PROCESS | 1655 | |1;audio-beat-detection;142;114;111;143;117;115;133;97;161;134 00:07:25 INFO - PROCESS | 1655 | |2;audio-dft;185;184;182;185;183;182;183;183;181;186 00:07:25 INFO - PROCESS | 1655 | |3;audio-fft;82;62;59;88;59;59;59;58;84;58 00:07:25 INFO - PROCESS | 1655 | |4;audio-oscillator;90;69;95;69;67;69;88;67;68;91 00:07:25 INFO - PROCESS | 1655 | |5;imaging-gaussian-blur;89;85;90;88;89;86;90;87;89;88 00:07:25 INFO - PROCESS | 1655 | |6;imaging-darkroom;95;94;94;95;96;95;95;95;95;95 00:07:25 INFO - PROCESS | 1655 | |7;imaging-desaturate;81;81;79;81;83;83;85;85;81;80 00:07:25 INFO - PROCESS | 1655 | |8;json-parse-financial;58;56;53;54;54;54;64;79;81;83 00:07:25 INFO - PROCESS | 1655 | |9;json-stringify-tinderbox;64;72;69;75;72;68;70;71;71;71 00:07:25 INFO - PROCESS | 1655 | |10;stanford-crypto-aes;84;84;83;87;82;82;81;82;88;83 00:07:25 INFO - PROCESS | 1655 | |11;stanford-crypto-ccm;147;147;145;146;148;154;153;157;264;170 00:07:25 INFO - PROCESS | 1655 | |12;stanford-crypto-pbkdf2;160;167;166;165;164;165;166;164;166;169 00:07:25 INFO - PROCESS | 1655 | |13;stanford-crypto-sha256-iterative;66;67;66;67;68;83;84;82;83;70 00:07:25 INFO - PROCESS | 1655 | __end_tp_report 00:07:25 INFO - PROCESS | 1655 | __start_cc_report 00:07:25 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,2068 00:07:25 INFO - PROCESS | 1655 | __end_cc_report 00:07:25 INFO - PROCESS | 1655 | __startTimestamp1479197245402__endTimestamp 00:07:25 INFO - PROCESS | 1655 | 00:07:25 INFO - PROCESS | 1655 | ------- Summary: start ------- 00:07:25 INFO - PROCESS | 1655 | Number of tests: 14 00:07:25 INFO - PROCESS | 1655 | 00:07:25 INFO - PROCESS | 1655 | [#0] ai-astar Cycles:10 Average:115.10 Median:115.50 stddev:1.37 (1.2%) stddev-sans-first:1.41 00:07:25 INFO - PROCESS | 1655 | Values: 116.0 115.0 117.0 112.0 115.0 116.0 115.0 115.0 116.0 114.0 00:07:25 INFO - PROCESS | 1655 | 00:07:25 INFO - PROCESS | 1655 | [#1] audio-beat-detection Cycles:10 Average:126.70 Median:138.00 stddev:19.12 (13.9%) stddev-sans-first:19.46 00:07:25 INFO - PROCESS | 1655 | Values: 142.0 114.0 111.0 143.0 117.0 115.0 133.0 97.0 161.0 134.0 00:07:25 INFO - PROCESS | 1655 | 00:07:25 INFO - PROCESS | 1655 | [#2] audio-dft Cycles:10 Average:183.40 Median:183.50 stddev:1.58 (0.9%) stddev-sans-first:1.56 00:07:25 INFO - PROCESS | 1655 | Values: 185.0 184.0 182.0 185.0 183.0 182.0 183.0 183.0 181.0 186.0 00:07:25 INFO - PROCESS | 1655 | 00:07:25 INFO - PROCESS | 1655 | [#3] audio-fft Cycles:10 Average:66.80 Median:60.50 stddev:12.46 (20.6%) stddev-sans-first:11.94 00:07:25 INFO - PROCESS | 1655 | Values: 82.0 62.0 59.0 88.0 59.0 59.0 59.0 58.0 84.0 58.0 00:07:25 INFO - PROCESS | 1655 | 00:07:25 INFO - PROCESS | 1655 | [#4] audio-oscillator Cycles:10 Average:77.30 Median:78.50 stddev:11.94 (15.2%) stddev-sans-first:11.74 00:07:25 INFO - PROCESS | 1655 | Values: 90.0 69.0 95.0 69.0 67.0 69.0 88.0 67.0 68.0 91.0 00:07:25 INFO - PROCESS | 1655 | 00:07:25 INFO - PROCESS | 1655 | [#5] imaging-gaussian-blur Cycles:10 Average:88.10 Median:89.00 stddev:1.66 (1.9%) stddev-sans-first:1.73 00:07:25 INFO - PROCESS | 1655 | Values: 89.0 85.0 90.0 88.0 89.0 86.0 90.0 87.0 89.0 88.0 00:07:25 INFO - PROCESS | 1655 | 00:07:25 INFO - PROCESS | 1655 | [#6] imaging-darkroom Cycles:10 Average:94.90 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 00:07:25 INFO - PROCESS | 1655 | Values: 95.0 94.0 94.0 95.0 96.0 95.0 95.0 95.0 95.0 95.0 00:07:25 INFO - PROCESS | 1655 | 00:07:25 INFO - PROCESS | 1655 | [#7] imaging-desaturate Cycles:10 Average:81.90 Median:82.00 stddev:2.02 (2.5%) stddev-sans-first:2.12 00:07:25 INFO - PROCESS | 1655 | Values: 81.0 81.0 79.0 81.0 83.0 83.0 85.0 85.0 81.0 80.0 00:07:25 INFO - PROCESS | 1655 | 00:07:25 INFO - PROCESS | 1655 | [#8] json-parse-financial Cycles:10 Average:63.60 Median:61.00 stddev:12.45 (20.4%) stddev-sans-first:13.04 00:07:25 INFO - PROCESS | 1655 | Values: 58.0 56.0 53.0 54.0 54.0 54.0 64.0 79.0 81.0 83.0 00:07:25 INFO - PROCESS | 1655 | 00:07:25 INFO - PROCESS | 1655 | [#9] json-stringify-tinderbox Cycles:10 Average:70.30 Median:71.00 stddev:2.91 (4.1%) stddev-sans-first:2.00 00:07:25 INFO - PROCESS | 1655 | Values: 64.0 72.0 69.0 75.0 72.0 68.0 70.0 71.0 71.0 71.0 00:07:25 INFO - PROCESS | 1655 | 00:07:25 INFO - PROCESS | 1655 | [#10] stanford-crypto-aes Cycles:10 Average:83.60 Median:83.50 stddev:2.27 (2.7%) stddev-sans-first:2.40 00:07:25 INFO - PROCESS | 1655 | Values: 84.0 84.0 83.0 87.0 82.0 82.0 81.0 82.0 88.0 83.0 00:07:25 INFO - PROCESS | 1655 | 00:07:25 INFO - PROCESS | 1655 | [#11] stanford-crypto-ccm Cycles:10 Average:163.10 Median:153.50 stddev:36.24 (23.6%) stddev-sans-first:37.96 00:07:25 INFO - PROCESS | 1655 | Values: 147.0 147.0 145.0 146.0 148.0 154.0 153.0 157.0 264.0 170.0 00:07:25 INFO - PROCESS | 1655 | 00:07:25 INFO - PROCESS | 1655 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.20 Median:166.00 stddev:2.35 (1.4%) stddev-sans-first:1.56 00:07:25 INFO - PROCESS | 1655 | Values: 160.0 167.0 166.0 165.0 164.0 165.0 166.0 164.0 166.0 169.0 00:07:25 INFO - PROCESS | 1655 | 00:07:25 INFO - PROCESS | 1655 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.60 Median:76.00 stddev:8.18 (10.8%) stddev-sans-first:8.20 00:07:25 INFO - PROCESS | 1655 | Values: 66.0 67.0 66.0 67.0 68.0 83.0 84.0 82.0 83.0 70.0 00:07:25 INFO - PROCESS | 1655 | -------- Summary: end -------- 00:07:25 INFO - PROCESS | 1655 | 00:07:26 INFO - TEST-INFO | 1655: exit 0 00:07:26 INFO - TEST-OK | kraken | took 58995ms 00:07:26 INFO - SUITE-END | took 1177s 00:07:26 INFO - Completed test suite (00:19:38) 00:07:26 INFO - javascript benchmark 00:07:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3350.246194606213, 3366.0238530826045, 3368.208471009313, 3382.053025152955, 3428.6057103433095, 4538.614217791487, 5746.626469279504, 5804.564657650677, 5816.040353341694, 5877.059670452729, 5813.651180929137, 5867.501262392691, 5887.175795388649, 5916.733746981386, 5957.31490878548, 5223.242629818684, 5256.553192978601, 5267.315249017623, 5335.839924802262, 5353.839384818461, 5184.53339199472, 5236.21456781482, 5237.188235823453, 5248.291480650101, 5369.5972802039805, 5283.947160528384, 5333.786648534054, 5355.464453554652, 5364.597655175858, 5380.408155102936, 5319.0, 5331.866703332409, 5333.0, 5359.517643412092, 5387.0841956867425, 4346.239408103569, 5056.74716264189, 5168.3539557555305, 5254.7635356409055, 5259.8422047338645, 5725.770969161229, 5749.965006298865, 5752.0, 5845.473907348359, 5887.411258874121, 6209.5342849286035, 6236.0, 6258.374162583706, 6288.779892703779, 6295.0, 6198.845028874269, 6253.312135665074, 6255.718492667841, 6269.62382257066, 6270.65511396873, 5679.659220446745, 5807.332156801937, 5854.941450585525, 5878.147668588031, 5896.941030589725, 6267.7806276781, 6286.968565157236, 6309.211348581427, 6317.810465686037, 6321.683915804191, 6219.502439804895, 6225.439710426083, 6234.251889773256, 6235.3452887446165, 6239.719212635351, 5308.893822123536, 6134.344744984565, 6226.283977342664, 6227.09707092469, 6260.46786023184, 6162.895025421159, 6221.564490485713, 6227.657478838707, 6232.345603711636, 6246.0, 6175.104609831549, 6252.531060170505, 6260.906086408708, 6261.686915654199, 6316.336784637639, 4973.502649734997, 4976.427710813303, 4983.725895075733, 4988.102141614544, 5002.324686167393, 4376.562343765598, 4502.144592527409, 4504.729716217037, 4511.007578332761, 4549.294859296814, 5781.884362312814, 5832.358440571534, 5842.0, 5873.060310350409, 5939.46544810969, 6213.161223234805, 6220.657863817443, 6227.408396202353, 6244.812655620339, 6255.593386429868, 5899.380565040609, 5942.851428714317, 5955.0, 5964.985952388104, 5986.700664966735, 5328.946710532845, 5377.489138531833, 5378.489043540857, 5484.808031718871, 5493.423190565013, 5240.292560504283, 6181.783637572664, 6183.659898705434, 6237.781677641352, 6244.469220116341, 6119.204503414528, 6243.625382477066, 6246.312905580474, 6247.2815626201755, 6253.0, 6195.16365290698, 6201.410865967726, 6255.374462553708, 6263.843403914756, 6267.435930766253, 6066.908996365147, 6124.136831511461, 6141.731496609813, 6162.537809664383, 6209.441150296495, 6241.438270555672, 6245.156903817927, 6253.37466253371, 6262.718177681924, 6268.404501572343, 6197.690115494297, 6216.7824126157175, 6239.5008399327035, 6253.187085678833, 6266.498680105672, 5140.640155189101, 6028.457438830526, 6136.8772624548155, 6150.60021098645, 6166.229221347332, 6063.363346848518, 6067.908981365372, 6075.1191077294425, 6085.57400981927, 6101.450869421596, 6184.443400094013, 6198.969005154945, 6200.193974783429, 6211.09939058843, 6231.096491008869, 5904.763809447746, 5965.194698715792, 5971.611845230046, 6034.668093254913, 6048.758049677964], "value": 5731.059549314034, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [487.2472030712549, 515.3017661069251, 525.4797750227276, 539.6761942834298, 552.5551930695793, 29985.850070749642, 31951.520727189083, 39530.40704389437, 40830.77507674767, 40957.795211023906, 41241.0, 41617.16765664685, 41770.582294177046, 41830.58169418305, 41863.79068104655, 9161.496117713523, 9342.299327550461, 9347.485888276142, 9348.158665720084, 9357.017513161121, 9258.074192580756, 9345.859812102824, 9348.766280842965, 9355.812883742321, 9362.25101991839, 9307.0, 9376.343655944087, 9377.906220937772, 9413.670521531747, 9417.434953902753, 9297.256219502424, 9345.579448924815, 9354.06459354066, 9358.344915855825, 9372.109649583314, 9268.304877134175, 9348.345615806897, 9351.953240233823, 9353.625854965792, 9355.251579873593, 38889.41665875014, 39302.0, 39385.21229575421, 39385.60614393877, 39471.0, 3212.213007813077, 3212.7429805615493, 3327.4177019021577, 3401.5237866698435, 3716.1267102230954, 28925.0, 29782.33340977766, 29880.40239195204, 29913.25216869574, 30044.699553004408, 3156.2582793043616, 3208.7593430492807, 3209.871605135792, 3220.114468521152, 3235.6117265928237, 593188.0, 595410.0, 596594.0, 597257.0, 597918.0, 61124.69437652872, 61706.69146654238, 61906.0, 61910.69044654748, 62081.689591552655, 34566.0, 34792.65207347945, 65207.347926520124, 65802.67098664476, 65853.67073164604, 15902.76145857813, 16099.356025759073, 16101.114438705983, 16102.838971610368, 16118.67762644764, 10357.585696572203, 12348.753024939451, 12355.444005019795, 12365.38173091342, 12381.195222310522, 15812.36750529989, 18684.439466815744, 18686.345977890982, 18686.532836678918, 18772.812271877105, 7564.735234266775, 7596.734114305973, 7667.539947603162, 7683.692652293846, 7883.344497655557, 841.9663213471515, 929.5770424456856, 991.861139440479, 1059.2267644619471, 1075.0754351257913, 29125.41749165005, 29189.41621167607, 29399.85300073486, 29829.701702983126, 29936.952206673097, 1020.6172685243034, 1020.9030142136634, 1052.5053224984244, 1083.842842787807, 1106.8893110688866, 14832.555023349316, 14915.701685966436, 15102.622434439007, 15392.211626700875, 15595.284518702934, 682886.0, 683913.0, 686127.0, 686333.5683321551, 687573.0, 57402.85194296091, 57915.262542123804, 57977.710111449174, 58078.70960645254, 58345.70827145922, 1228.766534358469, 1259.2444533279959, 1340.8122862799216, 1366.2349084512705, 1383.280694039094, 30041.773090959334, 30154.84922575373, 30213.885028047825, 30501.389972199988, 30782.0, 48889.0, 49481.752591236815, 49633.50366496289, 49647.007059857875, 49791.26148061747, 465182.0, 468500.0, 468509.0, 468718.0, 468849.0, 22930.770692292863, 23353.299401017997, 25137.24588262355, 25338.493230135664, 26154.86922565375, 45246.77376613096, 45257.0, 45692.31461528146, 45697.54302456933, 45733.77133114313, 31935.520967185046, 37121.44317835181, 37516.81241593775, 39090.41364379593, 39454.40818387784, 37297.440538391405, 37647.0, 37865.242695146495, 37885.242295154494, 38275.0, 37308.440373393874, 38066.238675226894, 38106.61893381141, 38210.0447488815, 38393.4240986391, 31417.8429107853, 34069.807556735395, 34077.48883766696, 34150.82924585361, 34262.31475370529, 24783.628245576692, 24903.377415564755, 25114.830131028386, 25208.873955630104, 25624.743752562237], "value": 19585.69549756364, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [304.82320254252534, 347.8052290717198, 373.645037214646, 383.93856982882744, 402.28995822373497, 14536.927315363422, 14730.631734206638, 15380.307886145123, 19217.23131074759, 19834.504137396572, 19262.71105933412, 19525.316613918516, 19769.81381117134, 20002.89998550005, 20025.399238022845, 5591.74837132328, 5941.970290148543, 5949.494292985101, 5954.196183515235, 5955.821325360224, 5506.972465137689, 5760.36635970043, 5819.359870414251, 5820.505257053155, 5821.8835623287505, 5751.7986870459545, 5764.769409223626, 5769.278840144982, 5770.249867517238, 5777.855553611173, 5108.616853735966, 5340.519353258206, 5787.13193021046, 5789.65262084272, 5794.304683438015, 5019.627329192546, 5063.670861393997, 5063.797448102072, 5082.1360368737405, 5085.059264036166, 24151.0, 24760.50478990428, 24800.883960221832, 24842.751572484405, 25143.87428062866, 2333.97666023341, 2425.4300239443714, 2435.8051355891484, 2487.2702681199858, 2793.52510073288, 14622.780658290134, 15182.772258416133, 15198.088114713153, 15309.234538273042, 15728.0, 1798.9910050449794, 1807.53907753522, 1859.9721004184948, 1885.9528511787178, 1902.5529000684828, 83462.0, 85965.0, 122652.77347226502, 124794.0, 130685.0, 10568.735781605521, 10587.682369528926, 10882.945585272182, 10884.836727449094, 11069.944650276697, 11512.0, 13474.0, 13535.18788872651, 13541.322933853267, 13563.186208827305, 8371.748847534584, 8491.363147763943, 8492.0, 8515.957420212859, 8549.444286121383, 8579.198368342719, 8590.1839325264, 8600.698975535826, 8741.781455463664, 8795.428297160664, 6040.939590604125, 6372.139761132281, 6429.260635026938, 6450.6774661266745, 6474.6762661866715, 1785.7589225454658, 1811.619559892411, 1812.5559237986513, 1833.9358122465649, 1853.5458812590732, 732.4030914804417, 895.1048951048951, 947.6493697331894, 1018.4398580780542, 1019.7246743379246, 20579.07394167266, 21547.67678484824, 21595.568088638454, 21653.891730541247, 21673.24143654996, 879.4151888993875, 930.6789157740569, 961.9759506012266, 968.1238479176357, 982.8034393121404, 11044.061254793272, 11519.146108065688, 11565.190436669352, 11581.768364632828, 11612.187146899805, 598264.0, 600147.0, 605812.0, 610970.8902911003, 626786.0, 113905.0, 115347.42326288483, 119218.0, 120017.0, 122455.0, 970.1414248390225, 980.1032055669126, 996.9900300997042, 1011.3022014809758, 1012.4988130875264, 18522.79601825877, 18528.907355463136, 19119.426417207505, 19246.0, 19282.035898205315, 50903.236451453995, 51631.0, 53737.73131134476, 53759.19361209507, 53986.73006634942, 73068.63465682637, 73121.0, 73169.6341518289, 73275.26724732897, 74392.14019649551, 7700.0, 7753.883691744515, 7859.331956783737, 7863.292303692695, 8010.919890801018, 12241.143119981873, 12434.75130497403, 12809.231446113263, 12995.0, 13096.083274170716, 10361.533730981973, 10443.686689399026, 10825.10560651867, 11022.393768342818, 11036.337819730841, 10599.841002384816, 10665.306755060896, 10703.94648026786, 11010.46759245362, 11165.274257173258, 11384.715382115182, 11391.829122563335, 11581.015613672933, 11589.0, 11691.298522088702, 7663.233676632292, 7839.746505200506, 7843.254890785366, 8061.113277539349, 8130.552819594979, 8849.4247873888, 9632.036796320312, 10672.146228301563, 10798.892011079788, 11219.158563107636], "value": 9540.25631009113, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1468.698916722072, 1483.1471903655397, 1640.5242479680894, 1758.8329108734665, 1810.3392261824429, 8016.0, 8633.309335253189, 8638.438501497398, 8646.870296945552, 8650.221480066793, 7562.432817538679, 7766.611669416535, 7772.922270777291, 8139.145389734082, 8434.662613495468, 2279.908803647852, 2344.531093781242, 2344.6951896253463, 2345.718513778349, 2346.6128088865316, 2154.859934104286, 2232.977670223301, 2342.9882850585723, 2389.116027069978, 2392.473655795722, 2282.315305408371, 2283.8743869087193, 2312.953740925189, 2316.6061769499142, 2317.4669825940045, 2299.908003679859, 2336.6378211377264, 2338.4504641409253, 2339.993802664862, 2340.6606042123967, 2281.1103669568884, 2309.0648287443614, 2309.988450057756, 2310.145246258895, 2310.699609050813, 8366.748997530083, 8398.286145677625, 8496.490210587323, 8507.361947853935, 8516.020657624329, 4770.45139808923, 5232.529072383504, 5238.05714971305, 5239.240310155006, 5263.289455923478, 3218.742500599946, 3326.1518312830317, 3581.015220814271, 3596.406592912166, 3597.8740744074094, 973.4500007495742, 1073.3881687438163, 1102.2064113838023, 1120.4621781544904, 1121.6466812953893, 11056.060234880046, 11184.049355804766, 11246.718832029264, 11251.931066548665, 11265.549378024869, 4546.136234115507, 4587.449506059227, 4732.479427263067, 4755.167845627001, 4770.42754869411, 9511.0, 9539.236861051098, 9549.665761698307, 9553.283503737248, 9553.522323883779, 5191.766370513336, 5192.4547922468355, 5197.038547868657, 5202.0, 5204.5576126028955, 4032.495938007749, 4218.8523401680795, 4679.4618618858585, 4740.5733483986605, 4783.0, 3520.4895290183294, 3645.8906232813056, 3815.7328986970647, 3870.0, 3917.3928041153667, 1258.8300579421843, 1268.2834198677717, 1269.292801182408, 1285.4858056777364, 1286.1832736212573, 800.8598495263423, 848.9023762267295, 873.0178549132227, 894.1550235027967, 900.0639335091562, 5441.537469315073, 5543.420125264281, 5568.805091821767, 5778.27771528559, 5975.850603734942, 974.2595627323144, 988.7725823060737, 997.2221667099528, 1005.934614250086, 1029.531563138785, 3563.6080031196548, 3568.6252943441086, 3688.151725103188, 3754.5869954305003, 3771.6982641389172, 9957.850632240523, 10286.2799604027, 10287.897121028844, 10351.741206469898, 10367.844482332772, 9605.807883842284, 9808.0, 9814.2148628111, 10161.695149145537, 10233.283670143168, 7805.297523222937, 8030.23712747288, 8035.919640803634, 8036.276735093869, 8039.839203215903, 5463.918041229305, 5477.945220547744, 5503.862403439946, 5758.049921762862, 5785.0, 9496.335256531976, 9496.762580935532, 9504.904950950402, 9512.09635084666, 9520.047995200424, 9264.44413335202, 9498.85751713711, 9583.281253906125, 9661.3237073407, 9675.854862176931, 6679.165104361955, 6751.628660423724, 6751.797446076625, 7174.282571742784, 7346.816329591803, 7507.986421832983, 7611.124720657195, 7619.885701714368, 7665.348445382205, 7745.496542724705, 7045.11936007999, 7282.599457029914, 7321.304476074764, 7324.560526368435, 7419.07261592301, 5929.525637948867, 6905.343992320722, 6933.375996160168, 7131.786046418616, 7187.820304492429, 6661.766838160641, 6840.213375461885, 6865.588064716133, 6881.174259088942, 6882.449404047564, 4769.213079841926, 4794.017226468496, 4815.518448155157, 4857.53853383928, 4930.975345123252, 4365.236083685406, 4825.131476334153, 4830.372051633265, 5007.173816320412, 5032.6225533084435], "value": 4442.068655580871, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.9596404485394, 301.1612658745394, 306.6243851282179, 308.06809401560275, 313.66437911434775, 4606.631469482441, 5175.715335656538, 5356.223347614603, 5372.677639341638, 5453.91819122713, 4304.7632380219075, 4805.567498925096, 5200.973995130029, 5247.842564723064, 5313.1233346497875, 3454.9309013819707, 4085.55058943516, 4094.9181016379653, 4104.619320793947, 4117.176564687052, 3453.3783918894587, 3982.7411218270863, 3985.3822657488136, 4118.299889018859, 4138.8344466221315, 3461.9480707789403, 4071.6131967463043, 4079.224947260026, 4104.240715467633, 4135.0902801383645, 3838.692904567628, 3887.319719049155, 4074.7555146691293, 4105.97947010263, 4110.77390743509, 3137.9686203137903, 3521.5421995140723, 3557.982210088933, 3577.2130131371055, 3593.4969104325414, 4652.208674795096, 4996.650234483587, 5183.948160518385, 5255.921161182585, 5303.310569625963, 3946.842126314944, 4216.662666986634, 4676.5089665584965, 4689.788959496831, 4702.882427939329, 4604.194266003436, 5069.645124841262, 5305.286299590302, 5644.5484361251, 5647.717614119319, 3576.016595436276, 3699.7965111918834, 3868.825902834379, 3887.9805600971813, 3941.3890846918775, 5298.947010529884, 5502.889942201134, 5871.266091738533, 5898.351181370046, 5904.940950590482, 3565.983694647013, 3838.788866612363, 3970.900727481778, 3978.3688687637928, 3993.560708322082, 3595.9280814383565, 3713.7771733696063, 4034.9307433529552, 4061.882982179924, 4074.696097248911, 4635.6523260755575, 4647.34937108805, 4662.836800711959, 4668.276417155346, 4676.19966312972, 5688.8862222755315, 5699.430056994267, 5717.969476177633, 5740.052891272893, 5746.482816546447, 1213.872543382932, 1236.839210902595, 1240.2248594628356, 1240.815021654318, 1274.387899307377, 790.3519114326198, 804.1596531624466, 862.7834752998242, 874.8687696845396, 880.7401816464159, 4289.227938971015, 4423.20382331177, 4741.35991641124, 5011.644527072271, 5054.0, 4441.845704833153, 4804.855854324376, 5150.922736158961, 5187.559057480081, 5470.261514695545, 4279.465066866642, 5006.047641419849, 5024.924626130611, 5042.39491261051, 5060.974695126501, 2552.515022145786, 2608.5826267797443, 2724.9046283380385, 2826.0, 2869.26833657416, 4702.388689470347, 5207.661502002434, 5384.40771515133, 5567.552436366493, 5611.803586874522, 4813.0, 6050.492383174475, 6082.60463069899, 6102.359252278536, 6105.877882442415, 6067.635941843503, 6074.574779765374, 6110.991686371699, 6113.29697084841, 6136.6011209271255, 4618.56123668251, 5839.445252700986, 5886.38192989747, 5894.911576326444, 5907.675077870758, 5810.128480727924, 5813.273340832396, 5838.766449341979, 5854.356020837789, 5861.706914654335, 5361.731913404392, 5551.694656793915, 6007.369226231182, 6103.016519631049, 6104.20645316106, 3392.508086327518, 3923.725339226227, 3951.732129300406, 3976.4432979383487, 3987.641112299791, 5100.591952643706, 5516.255305533701, 5915.37888521699, 5934.406559344204, 5952.761889524544, 5213.973930130325, 5349.491798279158, 5485.259489968803, 5722.656640601577, 5735.082386818256, 5734.0, 5740.708340623493, 5740.712964351849, 5753.0, 5762.6830524319885, 4948.960718249208, 5273.709945953009, 5462.890742185214, 5489.560835133259, 5635.647444613345, 5272.235525848807, 5655.660660360391, 5657.773689052392, 5685.374608793112, 5691.430856914441, 4528.524504927067, 5318.627696061239, 5600.4959553640365, 5619.859503512282, 5622.3815380307315], "value": 4074.7017184915644, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [672.2840175213395, 687.4775170870139, 714.3749219432996, 717.1322699533564, 737.811857976216, 13365.198088114696, 13957.232352220624, 14017.0, 14654.413823447063, 14687.971841971053, 13019.869801301984, 13228.93385533071, 13954.79067813981, 14535.0, 14756.335964881579, 786.6617354537532, 808.5552945879742, 809.4576633655465, 811.9878201826978, 812.1147948735877, 653.807126897564, 670.071950348768, 672.495628278791, 816.0859836982588, 817.9918200817975, 703.707961196103, 705.016501979738, 705.3968856627587, 844.3920377328313, 870.0299166429418, 860.746079906426, 870.1776820904216, 870.5168631409565, 871.751550808023, 871.7646235516376, 643.0996604753336, 644.3813938618931, 644.6196743921062, 645.4932877691037, 647.1683886206202, 11661.941690291495, 11799.705007374798, 12112.757744845054, 14202.147871127765, 14274.429022839075, 11126.61056863014, 12261.570845020382, 13506.797398039038, 13651.317434128354, 13684.47870198777, 9549.522523873778, 11231.382273974928, 11233.0, 11341.319520828776, 11531.538738450452, 3422.691957723817, 3494.4408894576745, 3607.3867442534834, 3672.9081772955624, 3753.0804952786743, 90923.54538227333, 91577.0, 95469.0, 95528.52235738777, 97461.53807692819, 9989.700308990741, 10298.639547615798, 10381.53283102247, 10408.635697750542, 10450.999999999847, 20009.699854502192, 20142.798572014093, 20185.899070504845, 20393.898030509754, 20432.387028389174, 7611.086669599573, 7721.575313357707, 7839.05931288249, 7840.294373506412, 7851.293383595504, 4148.8133034013545, 4176.394422808676, 4259.9787001065415, 4303.33298338758, 4395.922998865234, 3262.8531716072835, 3369.0735047862036, 3369.612494563157, 3371.763976521669, 3372.4772660237704, 587.9206307148565, 608.1030480041903, 608.6986941463915, 661.7253839656489, 687.8383579858729, 520.6121439527576, 566.3713278261126, 567.3503838105346, 596.8478038100257, 658.1969996604136, 8510.361722870686, 9038.593263303035, 9122.33100744649, 9154.40496367734, 9484.525773711286, 513.496773162303, 615.8152554233712, 636.6816591704148, 672.7409947170374, 684.5105749389211, 4270.743755374688, 4455.2648812946145, 4510.8421205257655, 4597.987322421744, 4635.513271106553, 100845.49577252066, 107455.0, 108198.45900770446, 108945.91054089516, 110270.44864775785, 85240.0, 86124.56937715271, 87905.1209487897, 91710.54144729233, 93441.0, 66714.66570668656, 79150.27222686021, 79701.40597188023, 86666.0, 88461.55769221112, 8865.335099867665, 8911.55442227899, 9803.117719405287, 9887.30788844774, 9930.702078937644, 50811.0, 51871.74064129655, 54347.64130896759, 55213.89572208453, 57338.42661573498, 65336.67331663311, 69370.65314673394, 69775.30224697688, 77580.0, 79817.60091199506, 11696.70758231051, 12017.0, 12052.457639406422, 12255.0, 12569.245845248948, 12135.514579416724, 13909.58271251864, 14143.292835358397, 14211.50259740904, 14761.92619036898, 12507.868626412635, 13166.670833228864, 13221.40503677356, 13232.404541795835, 13404.865951340751, 12298.877011230132, 13226.801597976231, 13231.933840330737, 13618.18290902549, 13734.0, 12131.45408456604, 12455.252684838939, 12684.87315126837, 12863.742725145632, 13027.869721303045, 10531.842022369518, 10667.89332106669, 10881.673549793519, 11700.70748231267, 11889.405529723306, 9530.428174309563, 9583.137517623447, 10892.891071089505, 10944.413923987478, 11123.27698699582], "value": 7145.86601917328, "unit": "score"}], "name": "dromaeo_css", "value": 7193.15428655565, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 115.0, 117.0, 112.0, 115.0, 116.0, 115.0, 115.0, 116.0, 114.0], "value": 115.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 114.0, 111.0, 143.0, 117.0, 115.0, 133.0, 97.0, 161.0, 134.0], "value": 126.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 184.0, 182.0, 185.0, 183.0, 182.0, 183.0, 183.0, 181.0, 186.0], "value": 183.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 62.0, 59.0, 88.0, 59.0, 59.0, 59.0, 58.0, 84.0, 58.0], "value": 66.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 69.0, 95.0, 69.0, 67.0, 69.0, 88.0, 67.0, 68.0, 91.0], "value": 77.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 90.0, 88.0, 89.0, 86.0, 90.0, 87.0, 89.0, 88.0], "value": 88.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 79.0, 81.0, 83.0, 83.0, 85.0, 85.0, 81.0, 80.0], "value": 81.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 56.0, 53.0, 54.0, 54.0, 54.0, 64.0, 79.0, 81.0, 83.0], "value": 63.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 72.0, 69.0, 75.0, 72.0, 68.0, 70.0, 71.0, 71.0, 71.0], "value": 70.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 83.0, 87.0, 82.0, 82.0, 81.0, 82.0, 88.0, 83.0], "value": 83.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 145.0, 146.0, 148.0, 154.0, 153.0, 157.0, 264.0, 170.0], "value": 163.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 167.0, 166.0, 165.0, 164.0, 165.0, 166.0, 164.0, 166.0, 169.0], "value": 165.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 66.0, 67.0, 68.0, 83.0, 84.0, 82.0, 83.0, 70.0], "value": 73.6, "unit": "score"}], "name": "kraken", "value": 1453.6, "alertThreshold": 2.0}]} 00:07:26 INFO - Return code: 0 00:07:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 00:07:26 ERROR - # TBPL SUCCESS # 00:07:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 00:07:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 00:07:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 00:07:26 INFO - 2016-11-15 00:07:26.742 screenresolution[1659:10288] starting screenresolution argv=screenresolution get 00:07:26 INFO - 2016-11-15 00:07:26.745 screenresolution[1659:10288] Display 0: 1600x1200x32@60 00:07:26 INFO - 2016-11-15 00:07:26.751 screenresolution[1660:10291] starting screenresolution argv=screenresolution list 00:07:26 INFO - Available Modes on Display 0 00:07:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 00:07:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 00:07:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 00:07:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 00:07:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 00:07:26 INFO - Intel Iris: 00:07:26 INFO - Chipset Model: Intel Iris 00:07:26 INFO - Type: GPU 00:07:26 INFO - Bus: Built-In 00:07:26 INFO - VRAM (Dynamic, Max): 1536 MB 00:07:26 INFO - Vendor: Intel (0x8086) 00:07:26 INFO - Device ID: 0x0a2e 00:07:26 INFO - Revision ID: 0x0009 00:07:26 INFO - Displays: 00:07:26 INFO - Display: 00:07:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 00:07:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 00:07:26 INFO - Display Serial Number: 1600x1200 60 00:07:26 INFO - Main Display: Yes 00:07:26 INFO - Mirror: Off 00:07:26 INFO - Online: Yes 00:07:26 INFO - Rotation: Supported 00:07:26 INFO - Return code: 0 00:07:26 INFO - Running post-action listener: _resource_record_post_action 00:07:26 INFO - [mozharness: 2016-11-15 08:07:26.985369Z] Finished run-tests step (success) 00:07:26 INFO - Running post-run listener: _resource_record_post_run 00:07:27 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 42492928; Write bytes: 664363520; Read time: 340; Write time: 1934 00:07:27 INFO - TinderboxPrint: CPU usage
53.8% 00:07:27 INFO - TinderboxPrint: I/O read bytes / time
42,492,928 / 340 00:07:27 INFO - TinderboxPrint: I/O write bytes / time
664,363,520 / 1,934 00:07:27 INFO - TinderboxPrint: CPU idle
2,237.4 (46.2%) 00:07:27 INFO - TinderboxPrint: CPU system
978.6 (20.2%) 00:07:27 INFO - TinderboxPrint: CPU user
1,629.7 (33.6%) 00:07:27 INFO - TinderboxPrint: Swap in / out
392,114,176 / 0 00:07:27 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 346362368; Write bytes: 345532416; Read time: 26978; Write time: 736 00:07:27 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 40698880; Write bytes: 307135488; Read time: 327; Write time: 1160 00:07:27 INFO - Running post-run listener: _upload_blobber_files 00:07:27 INFO - Blob upload gear active. 00:07:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 00:07:27 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:07:27 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:07:27 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:07:27 INFO - (blobuploader) - INFO - Open directory for files ... 00:07:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 00:07:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:07:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:07:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 00:07:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:07:28 INFO - (blobuploader) - INFO - Done attempting. 00:07:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 00:07:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:07:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:07:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 00:07:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:07:29 INFO - (blobuploader) - INFO - Done attempting. 00:07:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 00:07:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:07:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:07:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 00:07:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:07:32 INFO - (blobuploader) - INFO - Done attempting. 00:07:32 INFO - (blobuploader) - INFO - Iteration through files over. 00:07:32 INFO - Return code: 0 00:07:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 00:07:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 00:07:32 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/099c214af3556fc15dd93becc377122d878f363d852f470155151bd404afa78ef8a429893b17b1e9f34d1fa43d44db2b2e0329f9be1bf43aea79c0a009ebf5cd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3bd230d0b4a291728fa45b491439038445caf50eb4b9e30575376148a6392da88971ae620de268bf27d1260ba408281c68a3b35c28abd8512e35779b9e0b591e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/89f2d29fa1a94571e09a32d4e88bd469339ca3cd0cd8f2ca328bcef12de0d2b1caa284f8e36aa23f7f4f953e3bdf15e704f0eafe9a25853e5b1e96670c2f0d34"} 00:07:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 00:07:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 00:07:32 INFO - Contents: 00:07:32 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/099c214af3556fc15dd93becc377122d878f363d852f470155151bd404afa78ef8a429893b17b1e9f34d1fa43d44db2b2e0329f9be1bf43aea79c0a009ebf5cd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3bd230d0b4a291728fa45b491439038445caf50eb4b9e30575376148a6392da88971ae620de268bf27d1260ba408281c68a3b35c28abd8512e35779b9e0b591e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/89f2d29fa1a94571e09a32d4e88bd469339ca3cd0cd8f2ca328bcef12de0d2b1caa284f8e36aa23f7f4f953e3bdf15e704f0eafe9a25853e5b1e96670c2f0d34"} 00:07:32 INFO - Running post-run listener: copy_logs_to_upload_dir 00:07:32 INFO - Copying logs to upload dir... 00:07:32 INFO - mkdir: /builds/slave/test/build/upload/logs 00:07:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.664148 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-11-15 00:07:32.101567) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-15 00:07:32.106552) ========= 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.GHL4deNbT5/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.zqXleoD2lj/Listeners TMPDIR=/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/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/099c214af3556fc15dd93becc377122d878f363d852f470155151bd404afa78ef8a429893b17b1e9f34d1fa43d44db2b2e0329f9be1bf43aea79c0a009ebf5cd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3bd230d0b4a291728fa45b491439038445caf50eb4b9e30575376148a6392da88971ae620de268bf27d1260ba408281c68a3b35c28abd8512e35779b9e0b591e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/89f2d29fa1a94571e09a32d4e88bd469339ca3cd0cd8f2ca328bcef12de0d2b1caa284f8e36aa23f7f4f953e3bdf15e704f0eafe9a25853e5b1e96670c2f0d34"} build_url:https://queue.taskcluster.net/v1/task/aa_w99o9QCueGkcOxSdlag/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011312 build_url: 'https://queue.taskcluster.net/v1/task/aa_w99o9QCueGkcOxSdlag/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/099c214af3556fc15dd93becc377122d878f363d852f470155151bd404afa78ef8a429893b17b1e9f34d1fa43d44db2b2e0329f9be1bf43aea79c0a009ebf5cd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3bd230d0b4a291728fa45b491439038445caf50eb4b9e30575376148a6392da88971ae620de268bf27d1260ba408281c68a3b35c28abd8512e35779b9e0b591e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/89f2d29fa1a94571e09a32d4e88bd469339ca3cd0cd8f2ca328bcef12de0d2b1caa284f8e36aa23f7f4f953e3bdf15e704f0eafe9a25853e5b1e96670c2f0d34"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-15 00:07:32.139937) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-15 00:07:32.140251) ========= 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.GHL4deNbT5/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.zqXleoD2lj/Listeners TMPDIR=/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/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.005040 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-15 00:07:32.192010) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-15 00:07:32.195372) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-15 00:07:32.195724) ========= ========= Total master_lag: 0.32 =========