builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0296 starttime: 1483403306.22 results: success (0) buildid: 20170102144820 builduid: 13781368df9e41ce8ea8aaba7b3de297 revision: 7368fa4424f5a4340ed6d7818f99279e46978751 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-02 16:28:26.217424) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-02 16:28:26.217912) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-02 16:28:26.218188) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-02 16:28:26.243115) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-02 16:28:26.243468) ========= 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.Ek4g7fephP/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.ZMIsonx6cb/Listeners TMPDIR=/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-01-02 16:28:26-- 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% 176M=0s 2017-01-02 16:28:26 (176 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.107374 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-02 16:28:26.376227) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-02 16:28:26.376779) ========= 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.Ek4g7fephP/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.ZMIsonx6cb/Listeners TMPDIR=/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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.019878 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-02 16:28:26.428186) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-02 16:28:26.428776) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 7368fa4424f5a4340ed6d7818f99279e46978751 --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 7368fa4424f5a4340ed6d7818f99279e46978751 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Ek4g7fephP/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.ZMIsonx6cb/Listeners TMPDIR=/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-01-02 16:28:26,505 truncating revision to first 12 chars 2017-01-02 16:28:26,505 Setting DEBUG logging. 2017-01-02 16:28:26,506 attempt 1/10 2017-01-02 16:28:26,506 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/7368fa4424f5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-02 16:28:27,374 unpacking tar archive at: mozilla-aurora-7368fa4424f5/testing/mozharness/ program finished with exit code 0 elapsedTime=1.131911 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-02 16:28:27.585075) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-02 16:28:27.585396) ========= script_repo_revision: 7368fa4424f5a4340ed6d7818f99279e46978751 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-02 16:28:27.585817) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-02 16:28:27.586088) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-02 16:28:27.605690) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-01-02 16:28:27.606030) ========= /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.Ek4g7fephP/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.ZMIsonx6cb/Listeners TMPDIR=/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 16:28:27 INFO - MultiFileLogger online at 20170102 16:28:27 in /builds/slave/test 16:28:27 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 16:28:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:28:27 INFO - {'append_to_log': False, 16:28:27 INFO - 'base_work_dir': '/builds/slave/test', 16:28:27 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 16:28:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:28:27 INFO - 'branch': 'Mozilla-Aurora', 16:28:27 INFO - 'buildbot_json_path': 'buildprops.json', 16:28:27 INFO - 'config_files': ('talos/mac_config.py',), 16:28:27 INFO - 'default_actions': ('clobber', 16:28:27 INFO - 'read-buildbot-config', 16:28:27 INFO - 'download-and-extract', 16:28:27 INFO - 'populate-webroot', 16:28:27 INFO - 'create-virtualenv', 16:28:27 INFO - 'install', 16:28:27 INFO - 'run-tests'), 16:28:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:28:27 INFO - 'download_minidump_stackwalk': True, 16:28:27 INFO - 'download_symbols': 'ondemand', 16:28:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:28:27 INFO - 'tooltool.py': '/tools/tooltool.py', 16:28:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:28:27 INFO - '/tools/misc-python/virtualenv.py')}, 16:28:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:28:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:28:27 INFO - 'installer_path': 'installer.exe', 16:28:27 INFO - 'log_level': 'info', 16:28:27 INFO - 'log_name': 'talos', 16:28:27 INFO - 'log_to_console': True, 16:28:27 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:28:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:28:27 INFO - 'opt_config_files': (), 16:28:27 INFO - 'pip_index': False, 16:28:27 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:28:27 INFO - 'cmd': ('bash', 16:28:27 INFO - '-c', 16:28:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:28:27 INFO - 'enabled': True, 16:28:27 INFO - 'halt_on_failure': False, 16:28:27 INFO - 'name': 'check_screen_resolution'},), 16:28:27 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:28:27 INFO - 'cmd': ('bash', 16:28:27 INFO - '-c', 16:28:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:28:27 INFO - 'enabled': True, 16:28:27 INFO - 'halt_on_failure': False, 16:28:27 INFO - 'name': 'check_screen_resolution'},), 16:28:27 INFO - 'run_cmd_checks_enabled': True, 16:28:27 INFO - 'sps_profile': False, 16:28:27 INFO - 'sps_profile_interval': 0, 16:28:27 INFO - 'suite': 'dromaeojs', 16:28:27 INFO - 'system_bits': '32', 16:28:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:28:27 INFO - 'title': 't-yosemite-r7-0296', 16:28:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:28:27 INFO - 'use_talos_json': True, 16:28:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:28:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:28:27 INFO - 'work_dir': 'build'} 16:28:27 INFO - [mozharness: 2017-01-03 00:28:27.796838Z] Running clobber step. 16:28:27 INFO - Running pre-action listener: _resource_record_pre_action 16:28:27 INFO - Running main action method: clobber 16:28:27 INFO - rmtree: /builds/slave/test/build 16:28:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:28:29 INFO - Running post-action listener: _resource_record_post_action 16:28:29 INFO - [mozharness: 2017-01-03 00:28:29.279292Z] Finished clobber step (success) 16:28:29 INFO - [mozharness: 2017-01-03 00:28:29.279439Z] Running read-buildbot-config step. 16:28:29 INFO - Running pre-action listener: _resource_record_pre_action 16:28:29 INFO - Running main action method: read_buildbot_config 16:28:29 INFO - Using buildbot properties: 16:28:29 INFO - { 16:28:29 INFO - "project": "", 16:28:29 INFO - "product": "firefox", 16:28:29 INFO - "script_repo_revision": "production", 16:28:29 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 16:28:29 INFO - "repository": "", 16:28:29 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 16:28:29 INFO - "buildid": "20170102144820", 16:28:29 INFO - "pgo_build": "False", 16:28:29 INFO - "basedir": "/builds/slave/test", 16:28:29 INFO - "buildnumber": 29, 16:28:29 INFO - "slavename": "t-yosemite-r7-0296", 16:28:29 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 16:28:29 INFO - "platform": "yosemite_r7", 16:28:29 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 16:28:29 INFO - "branch": "mozilla-aurora", 16:28:29 INFO - "repo_path": "releases/mozilla-aurora", 16:28:29 INFO - "revision": "7368fa4424f5a4340ed6d7818f99279e46978751", 16:28:29 INFO - "stage_platform": "macosx64", 16:28:29 INFO - "builduid": "13781368df9e41ce8ea8aaba7b3de297", 16:28:29 INFO - "slavebuilddir": "test" 16:28:29 INFO - } 16:28:29 INFO - Found installer url https://queue.taskcluster.net/v1/task/K9_jxa-JRp-Bnbxsw8D-SQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 16:28:29 INFO - Running post-action listener: _resource_record_post_action 16:28:29 INFO - [mozharness: 2017-01-03 00:28:29.283487Z] Finished read-buildbot-config step (success) 16:28:29 INFO - [mozharness: 2017-01-03 00:28:29.283658Z] Running download-and-extract step. 16:28:29 INFO - Running pre-action listener: _resource_record_pre_action 16:28:29 INFO - Running main action method: download_and_extract 16:28:29 INFO - mkdir: /builds/slave/test/build/tests 16:28:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:28:29 INFO - trying https://queue.taskcluster.net/v1/task/K9_jxa-JRp-Bnbxsw8D-SQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 16:28:29 INFO - Downloading https://queue.taskcluster.net/v1/task/K9_jxa-JRp-Bnbxsw8D-SQ/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 16:28:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/K9_jxa-JRp-Bnbxsw8D-SQ/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 16:28:30 INFO - Downloaded 1199 bytes. 16:28:30 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 16:28:30 INFO - Using the following test package requirements: 16:28:30 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 16:28:30 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 16:28:30 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 16:28:30 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 16:28:30 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 16:28:30 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 16:28:30 INFO - u'jsshell-mac.zip'], 16:28:30 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 16:28:30 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 16:28:30 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 16:28:30 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 16:28:30 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 16:28:30 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 16:28:30 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 16:28:30 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 16:28:30 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 16:28:30 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 16:28:30 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 16:28:30 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 16:28:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/K9_jxa-JRp-Bnbxsw8D-SQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 16:28:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/K9_jxa-JRp-Bnbxsw8D-SQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 16:28:30 INFO - Fetch https://queue.taskcluster.net/v1/task/K9_jxa-JRp-Bnbxsw8D-SQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 16:28:31 INFO - Http code: 200 16:28:31 INFO - via: 1.1 ba26a6eef2785fa2e20e601890b882b2.cloudfront.net (CloudFront) 16:28:31 INFO - x-amz-cf-id: Ad-0FNom3hjgZdndBD86epjWLHAK_z08WUgs5g3LW1hmqNBNxupTVA== 16:28:31 INFO - x-amz-version-id: l4x2Y_ZPptXRrlDe4CN1QQDmhcBGHRQH 16:28:31 INFO - Expected file size: 16268431 16:28:31 INFO - Obtained file size: 16268431 16:28:38 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 16:28:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/K9_jxa-JRp-Bnbxsw8D-SQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 16:28:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/K9_jxa-JRp-Bnbxsw8D-SQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 16:28:38 INFO - Fetch https://queue.taskcluster.net/v1/task/K9_jxa-JRp-Bnbxsw8D-SQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 16:28:38 INFO - Http code: 200 16:28:38 INFO - via: 1.1 f1a40337a32137e1c23ceffead6a50d5.cloudfront.net (CloudFront) 16:28:38 INFO - x-amz-cf-id: AAYx4aEHF1ZA3nafg--rYEFjKyv0IKWoHjzX2EY5mWWXlPZRde7o6w== 16:28:38 INFO - x-amz-version-id: l4x2Y_ZPptXRrlDe4CN1QQDmhcBGHRQH 16:28:39 INFO - Expected file size: 16268431 16:28:39 INFO - Obtained file size: 16268431 16:28:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/K9_jxa-JRp-Bnbxsw8D-SQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 16:28:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/K9_jxa-JRp-Bnbxsw8D-SQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 16:28:45 INFO - Fetch https://queue.taskcluster.net/v1/task/K9_jxa-JRp-Bnbxsw8D-SQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 16:28:46 INFO - Http code: 200 16:28:46 INFO - via: 1.1 73fa5a1ee49cf827e596b502b5927eab.cloudfront.net (CloudFront) 16:28:46 INFO - x-amz-cf-id: tgw1_zArAFhtgfZ-DkivWe4eZ956k_H6eTlmzPwSiJscIsCa0O_uVA== 16:28:46 INFO - x-amz-version-id: cRLWUyXa9DvzEMMsjbq_i63o8sWmw1Ro 16:28:46 INFO - Expected file size: 14409038 16:28:46 INFO - Obtained file size: 14409038 16:28: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')]} 16:28:47 INFO - trying https://queue.taskcluster.net/v1/task/K9_jxa-JRp-Bnbxsw8D-SQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 16:28:47 INFO - Downloading https://queue.taskcluster.net/v1/task/K9_jxa-JRp-Bnbxsw8D-SQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 16:28:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/K9_jxa-JRp-Bnbxsw8D-SQ/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 16:28:49 INFO - Downloaded 109828877 bytes. 16:28:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/K9_jxa-JRp-Bnbxsw8D-SQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 16:28:49 INFO - mkdir: /builds/slave/test/properties 16:28:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:28:49 INFO - Writing to file /builds/slave/test/properties/build_url 16:28:49 INFO - Contents: 16:28:49 INFO - build_url:https://queue.taskcluster.net/v1/task/K9_jxa-JRp-Bnbxsw8D-SQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 16:28:50 INFO - Running post-action listener: _resource_record_post_action 16:28:50 INFO - Running post-action listener: set_extra_try_arguments 16:28:50 INFO - [mozharness: 2017-01-03 00:28:50.517150Z] Finished download-and-extract step (success) 16:28:50 INFO - [mozharness: 2017-01-03 00:28:50.517267Z] Running populate-webroot step. 16:28:50 INFO - Running pre-action listener: _resource_record_pre_action 16:28:50 INFO - Running main action method: populate_webroot 16:28:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:28:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:28:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:28:50 INFO - '--tppagecycles', 16:28:50 INFO - '7'], 16:28:50 INFO - 'tests': ['tsvgm']}}, 16:28:50 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:28:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:28:50 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:28:50 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:28:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:28:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:28:50 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:28:50 INFO - 'talos_options': ['--disable-e10s'], 16:28:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:28:50 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:28:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:28:50 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:28:50 INFO - 'talos_options': ['--disable-e10s'], 16:28:50 INFO - 'tests': ['damp', 'tps']}, 16:28:50 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:28:50 INFO - 'tests': ['damp', 'tps']}, 16:28:50 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:28:50 INFO - 'tests': ['dromaeo_dom']}, 16:28:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:28:50 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:28:50 INFO - 'tests': ['basic_compositor_video']}, 16:28:50 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:28:50 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:28:50 INFO - 'tests': ['a11yr', 16:28:50 INFO - 'ts_paint', 16:28:50 INFO - 'tpaint', 16:28:50 INFO - 'sessionrestore', 16:28:50 INFO - 'sessionrestore_no_auto_restore', 16:28:50 INFO - 'tabpaint']}, 16:28:50 INFO - 'other-e10s': {'tests': ['a11yr', 16:28:50 INFO - 'ts_paint', 16:28:50 INFO - 'tpaint', 16:28:50 INFO - 'sessionrestore', 16:28:50 INFO - 'sessionrestore_no_auto_restore', 16:28:50 INFO - 'tabpaint']}, 16:28:50 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:28:50 INFO - 'ts_paint', 16:28:50 INFO - 'tpaint', 16:28:50 INFO - 'sessionrestore', 16:28:50 INFO - 'sessionrestore_no_auto_restore', 16:28:50 INFO - 'tabpaint']}, 16:28:50 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:28:50 INFO - 'ts_paint', 16:28:50 INFO - 'tpaint', 16:28:50 INFO - 'sessionrestore', 16:28:50 INFO - 'sessionrestore_no_auto_restore', 16:28:50 INFO - 'tabpaint']}, 16:28:50 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:28:50 INFO - 'tests': ['a11yr', 16:28:50 INFO - 'ts_paint', 16:28:50 INFO - 'tpaint', 16:28:50 INFO - 'sessionrestore', 16:28:50 INFO - 'sessionrestore_no_auto_restore', 16:28:50 INFO - 'tabpaint']}, 16:28:50 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:28:50 INFO - 'tests': ['a11yr', 16:28:50 INFO - 'ts_paint', 16:28:50 INFO - 'tpaint', 16:28:50 INFO - 'sessionrestore', 16:28:50 INFO - 'sessionrestore_no_auto_restore', 16:28:50 INFO - 'tabpaint']}, 16:28:50 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:28:50 INFO - 'tests': ['tsvgx', 16:28:50 INFO - 'tsvgr_opacity', 16:28:50 INFO - 'tart', 16:28:50 INFO - 'tscrollx', 16:28:50 INFO - 'cart']}, 16:28:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:28:50 INFO - 'tsvgr_opacity', 16:28:50 INFO - 'tart', 16:28:50 INFO - 'tscrollx', 16:28:50 INFO - 'cart']}, 16:28:50 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:28:50 INFO - 'talos_options': ['--disable-e10s'], 16:28:50 INFO - 'tests': ['tp5o']}, 16:28:50 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:28:50 INFO - 'tests': ['tp5o']}, 16:28:50 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:28:50 INFO - 'talos_options': ['--disable-e10s', 16:28:50 INFO - '--xperf_path', 16:28:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:28:50 INFO - 'tests': ['tp5n']}, 16:28:50 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:28:50 INFO - 'talos_options': ['--xperf_path', 16:28:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:28:50 INFO - 'tests': ['tp5n']}}, 16:28:50 INFO - 'talos.zip': {'path': '', 16:28:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:28:50 INFO - Running post-action listener: _resource_record_post_action 16:28:50 INFO - [mozharness: 2017-01-03 00:28:50.536358Z] Finished populate-webroot step (success) 16:28:50 INFO - [mozharness: 2017-01-03 00:28:50.536496Z] Running create-virtualenv step. 16:28:50 INFO - Running pre-action listener: _resource_record_pre_action 16:28:50 INFO - Running main action method: create_virtualenv 16:28:50 INFO - Creating virtualenv /builds/slave/test/build/venv 16:28:50 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 16:28:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:28:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:28:50 INFO - Using real prefix '/tools/python27' 16:28:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:28:51 INFO - Installing distribute.............................................................................................................................................................................................done. 16:28:54 INFO - Installing pip.................done. 16:28:54 INFO - Return code: 0 16:28:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:28:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:28:54 INFO - Reading from file tmpfile_stdout 16:28:54 INFO - Output received: 16:28:54 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) 16:28:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:28:54 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')]} 16:28:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:28:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:54 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')]} 16:28:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:28:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:54 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 0x10d4b9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d42f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc249d75c30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2cdd30>, '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 0x10d133db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2c5b20>, '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.Ek4g7fephP/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.ZMIsonx6cb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 16:28:54 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 16:28:54 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 16:28:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ek4g7fephP/Render', 16:28:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:28:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:28:54 INFO - 'HOME': '/Users/cltbld', 16:28:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:28:54 INFO - 'LOGNAME': 'cltbld', 16:28:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:28:54 INFO - 'MOZ_NO_REMOTE': '1', 16:28:54 INFO - 'NO_EM_RESTART': '1', 16:28:54 INFO - 'PAGER': '/bin/cat', 16:28:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:28:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:28:54 INFO - 'PWD': '/builds/slave/test', 16:28:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:28:54 INFO - 'SHELL': '/bin/bash', 16:28:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZMIsonx6cb/Listeners', 16:28:54 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 16:28:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:28:54 INFO - 'USER': 'cltbld', 16:28:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:28:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:28:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:28:54 INFO - 'XPC_FLAGS': '0x0', 16:28:54 INFO - 'XPC_SERVICE_NAME': '0', 16:28:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:28:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:28:54 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 16:28:54 INFO - Cleaning up... 16:28:54 INFO - Return code: 0 16:28:54 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:28:54 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')]} 16:28:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:28:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:54 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')]} 16:28:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:28:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:54 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 0x10d4b9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d42f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc249d75c30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2cdd30>, '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 0x10d133db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2c5b20>, '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.Ek4g7fephP/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.ZMIsonx6cb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 16:28:54 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 16:28:54 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 16:28:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ek4g7fephP/Render', 16:28:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:28:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:28:54 INFO - 'HOME': '/Users/cltbld', 16:28:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:28:54 INFO - 'LOGNAME': 'cltbld', 16:28:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:28:54 INFO - 'MOZ_NO_REMOTE': '1', 16:28:54 INFO - 'NO_EM_RESTART': '1', 16:28:54 INFO - 'PAGER': '/bin/cat', 16:28:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:28:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:28:54 INFO - 'PWD': '/builds/slave/test', 16:28:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:28:54 INFO - 'SHELL': '/bin/bash', 16:28:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZMIsonx6cb/Listeners', 16:28:54 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 16:28:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:28:54 INFO - 'USER': 'cltbld', 16:28:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:28:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:28:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:28:54 INFO - 'XPC_FLAGS': '0x0', 16:28:54 INFO - 'XPC_SERVICE_NAME': '0', 16:28:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:28:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:28:54 INFO - Downloading/unpacking psutil>=3.1.1 16:28:54 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 16:28:54 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 16:28:54 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 16:28:54 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 16:28:54 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 16:28:54 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 16:28:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:28:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:28:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:28:58 INFO - Installing collected packages: psutil 16:28:58 INFO - Running setup.py install for psutil 16:28:58 INFO - building 'psutil._psutil_osx' extension 16:28:58 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 16:28:58 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 16:28:58 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 16:28:58 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 16:28:59 INFO - building 'psutil._psutil_posix' extension 16:28:59 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 16:28:59 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:28:59 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:28:59 INFO - ^ 16:28:59 INFO - 1 warning generated. 16:28:59 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 16:28:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:28:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:28:59 INFO - Successfully installed psutil 16:28:59 INFO - Cleaning up... 16:28:59 INFO - Return code: 0 16:28:59 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:28: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')]} 16:28:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:28:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28: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')]} 16:28:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:28:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d4b9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d42f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc249d75c30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2cdd30>, '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 0x10d133db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2c5b20>, '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.Ek4g7fephP/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.ZMIsonx6cb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 16:28: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:28: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 mozsystemmonitor==0.3 16:28:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ek4g7fephP/Render', 16:28:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:28:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:28:59 INFO - 'HOME': '/Users/cltbld', 16:28:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:28:59 INFO - 'LOGNAME': 'cltbld', 16:28:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:28:59 INFO - 'MOZ_NO_REMOTE': '1', 16:28:59 INFO - 'NO_EM_RESTART': '1', 16:28:59 INFO - 'PAGER': '/bin/cat', 16:28:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:28:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:28:59 INFO - 'PWD': '/builds/slave/test', 16:28:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:28:59 INFO - 'SHELL': '/bin/bash', 16:28:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZMIsonx6cb/Listeners', 16:28:59 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 16:28:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:28:59 INFO - 'USER': 'cltbld', 16:28:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:28:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:28:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:28:59 INFO - 'XPC_FLAGS': '0x0', 16:28:59 INFO - 'XPC_SERVICE_NAME': '0', 16:28:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:28:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:28:59 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:28: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 16:28: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 16:28: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 16:28: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 16:28: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 16:28: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 16:29:02 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:29:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:29:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:29:02 INFO - Installing collected packages: mozsystemmonitor 16:29:02 INFO - Running setup.py install for mozsystemmonitor 16:29:02 INFO - Successfully installed mozsystemmonitor 16:29:02 INFO - Cleaning up... 16:29:02 INFO - Return code: 0 16:29:02 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:29:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:02 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 0x10d4b9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d42f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc249d75c30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2cdd30>, '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 0x10d133db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2c5b20>, '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.Ek4g7fephP/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.ZMIsonx6cb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 16:29:02 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 16:29:02 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 16:29:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ek4g7fephP/Render', 16:29:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:02 INFO - 'HOME': '/Users/cltbld', 16:29:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:02 INFO - 'LOGNAME': 'cltbld', 16:29:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:02 INFO - 'MOZ_NO_REMOTE': '1', 16:29:02 INFO - 'NO_EM_RESTART': '1', 16:29:02 INFO - 'PAGER': '/bin/cat', 16:29:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:02 INFO - 'PWD': '/builds/slave/test', 16:29:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:02 INFO - 'SHELL': '/bin/bash', 16:29:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZMIsonx6cb/Listeners', 16:29:02 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 16:29:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:02 INFO - 'USER': 'cltbld', 16:29:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:02 INFO - 'XPC_FLAGS': '0x0', 16:29:02 INFO - 'XPC_SERVICE_NAME': '0', 16:29:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:02 INFO - Downloading/unpacking jsonschema==2.5.1 16:29: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 16:29: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 16:29: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 16:29: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 16:29: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 16:29: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 16:29:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:29:05 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:29:05 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 16:29:05 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 16:29:05 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 16:29:05 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 16:29:05 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 16:29:05 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 16:29:05 INFO - Downloading functools32-3.2.3-2.tar.gz 16:29:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:29:05 INFO - warning: no files found matching '*.txt' 16:29:05 INFO - no previously-included directories found matching 'build' 16:29:05 INFO - no previously-included directories found matching 'dist' 16:29:05 INFO - no previously-included directories found matching '.git*' 16:29:05 INFO - Installing collected packages: jsonschema, functools32 16:29:05 INFO - Running setup.py install for functools32 16:29:05 INFO - warning: no files found matching '*.txt' 16:29:05 INFO - no previously-included directories found matching 'build' 16:29:05 INFO - no previously-included directories found matching 'dist' 16:29:05 INFO - no previously-included directories found matching '.git*' 16:29:05 INFO - Successfully installed jsonschema functools32 16:29:05 INFO - Cleaning up... 16:29:06 INFO - Return code: 0 16:29:06 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:29:06 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')]} 16:29:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:06 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')]} 16:29:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:06 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 0x10d4b9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d42f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc249d75c30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2cdd30>, '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 0x10d133db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2c5b20>, '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.Ek4g7fephP/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.ZMIsonx6cb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 16:29:06 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 16:29:06 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 16:29:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ek4g7fephP/Render', 16:29:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:06 INFO - 'HOME': '/Users/cltbld', 16:29:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:06 INFO - 'LOGNAME': 'cltbld', 16:29:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:06 INFO - 'MOZ_NO_REMOTE': '1', 16:29:06 INFO - 'NO_EM_RESTART': '1', 16:29:06 INFO - 'PAGER': '/bin/cat', 16:29:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:06 INFO - 'PWD': '/builds/slave/test', 16:29:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:06 INFO - 'SHELL': '/bin/bash', 16:29:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZMIsonx6cb/Listeners', 16:29:06 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 16:29:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:06 INFO - 'USER': 'cltbld', 16:29:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:06 INFO - 'XPC_FLAGS': '0x0', 16:29:06 INFO - 'XPC_SERVICE_NAME': '0', 16:29:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:06 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:29:06 INFO - Cleaning up... 16:29:06 INFO - Return code: 0 16:29:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:29:06 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')]} 16:29:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:06 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')]} 16:29:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:06 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 0x10d4b9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d42f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc249d75c30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2cdd30>, '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 0x10d133db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2c5b20>, '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.Ek4g7fephP/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.ZMIsonx6cb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 16:29:06 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 16:29:06 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 16:29:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ek4g7fephP/Render', 16:29:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:06 INFO - 'HOME': '/Users/cltbld', 16:29:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:06 INFO - 'LOGNAME': 'cltbld', 16:29:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:06 INFO - 'MOZ_NO_REMOTE': '1', 16:29:06 INFO - 'NO_EM_RESTART': '1', 16:29:06 INFO - 'PAGER': '/bin/cat', 16:29:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:06 INFO - 'PWD': '/builds/slave/test', 16:29:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:06 INFO - 'SHELL': '/bin/bash', 16:29:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZMIsonx6cb/Listeners', 16:29:06 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 16:29:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:06 INFO - 'USER': 'cltbld', 16:29:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:06 INFO - 'XPC_FLAGS': '0x0', 16:29:06 INFO - 'XPC_SERVICE_NAME': '0', 16:29:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:06 INFO - Downloading/unpacking blobuploader==1.2.4 16:29:06 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 16:29:06 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 16:29:06 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 16:29:06 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 16:29:06 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 16:29:06 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 16:29:09 INFO - Downloading blobuploader-1.2.4.tar.gz 16:29:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:29:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:29:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:29:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:29:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:10 INFO - Downloading docopt-0.6.1.tar.gz 16:29:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:29:10 INFO - Installing collected packages: blobuploader, requests, docopt 16:29:10 INFO - Running setup.py install for blobuploader 16:29:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:29:10 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:29:10 INFO - Running setup.py install for requests 16:29:11 INFO - Running setup.py install for docopt 16:29:11 INFO - Successfully installed blobuploader requests docopt 16:29:11 INFO - Cleaning up... 16:29:11 INFO - Return code: 0 16:29:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:29:11 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')]} 16:29:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:11 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')]} 16:29:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:11 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 0x10d4b9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d42f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc249d75c30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2cdd30>, '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 0x10d133db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2c5b20>, '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.Ek4g7fephP/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.ZMIsonx6cb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 16:29:11 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 16:29:11 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 16:29:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ek4g7fephP/Render', 16:29:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:11 INFO - 'HOME': '/Users/cltbld', 16:29:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:11 INFO - 'LOGNAME': 'cltbld', 16:29:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:11 INFO - 'MOZ_NO_REMOTE': '1', 16:29:11 INFO - 'NO_EM_RESTART': '1', 16:29:11 INFO - 'PAGER': '/bin/cat', 16:29:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:11 INFO - 'PWD': '/builds/slave/test', 16:29:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:11 INFO - 'SHELL': '/bin/bash', 16:29:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZMIsonx6cb/Listeners', 16:29:11 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 16:29:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:11 INFO - 'USER': 'cltbld', 16:29:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:11 INFO - 'XPC_FLAGS': '0x0', 16:29:11 INFO - 'XPC_SERVICE_NAME': '0', 16:29:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:29:11 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-D6kZSN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:29:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:29:11 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-Q5i8cJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:29:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:29:11 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-S0aNRK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:29:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:29:11 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-e5uENS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:29:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:29:12 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-NYFrR2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:29:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:29:12 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-56_QZ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:29:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:29:12 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-KJKDVW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:29:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:29:12 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-2sXKeI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:29:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:29:12 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-2yUY_0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:29:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:29:12 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-uqYeo4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:29:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:29:12 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-KQMzvE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:29:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:29:12 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-FIm1Jn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:29:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:29:12 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-TQSmY_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:29:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:29:12 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-XjGhaW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:29:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:29:13 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-HxPWAQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:29:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:29:13 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-FpvENB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:29:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:29:13 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-8lbbUD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:29:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:29:13 INFO - Running setup.py install for manifestparser 16:29:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:29:13 INFO - Running setup.py install for mozcrash 16:29:13 INFO - Running setup.py install for mozdebug 16:29:13 INFO - Running setup.py install for mozdevice 16:29:13 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:29:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:29:13 INFO - Running setup.py install for mozfile 16:29:14 INFO - Running setup.py install for mozhttpd 16:29:14 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:29:14 INFO - Running setup.py install for mozinfo 16:29:14 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:29:14 INFO - Running setup.py install for mozInstall 16:29:14 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:29:14 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:29:14 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:29:14 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:29:14 INFO - Running setup.py install for mozleak 16:29:14 INFO - Running setup.py install for mozlog 16:29:14 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:29:14 INFO - Running setup.py install for moznetwork 16:29:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:29:14 INFO - Running setup.py install for mozprocess 16:29:15 INFO - Running setup.py install for mozprofile 16:29:15 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:29:15 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:29:15 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:29:15 INFO - Running setup.py install for mozrunner 16:29:15 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:29:15 INFO - Running setup.py install for mozscreenshot 16:29:15 INFO - Running setup.py install for moztest 16:29:15 INFO - Running setup.py install for mozversion 16:29:15 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:29:15 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:29:15 INFO - Cleaning up... 16:29:15 INFO - Return code: 0 16:29:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:29: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')]} 16:29:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29: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')]} 16:29:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:15 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 0x10d4b9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d42f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc249d75c30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2cdd30>, '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 0x10d133db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2c5b20>, '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.Ek4g7fephP/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.ZMIsonx6cb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 16:29:15 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 16:29:15 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 16:29:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ek4g7fephP/Render', 16:29:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:15 INFO - 'HOME': '/Users/cltbld', 16:29:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:15 INFO - 'LOGNAME': 'cltbld', 16:29:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:15 INFO - 'MOZ_NO_REMOTE': '1', 16:29:15 INFO - 'NO_EM_RESTART': '1', 16:29:15 INFO - 'PAGER': '/bin/cat', 16:29:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:15 INFO - 'PWD': '/builds/slave/test', 16:29:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:15 INFO - 'SHELL': '/bin/bash', 16:29:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZMIsonx6cb/Listeners', 16:29:15 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 16:29:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:15 INFO - 'USER': 'cltbld', 16:29:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:15 INFO - 'XPC_FLAGS': '0x0', 16:29:15 INFO - 'XPC_SERVICE_NAME': '0', 16:29:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:29:16 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-fZv8my-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:29:16 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)) 16:29:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:29:16 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-xwML0H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:29:16 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)) 16:29:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:29:16 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-VZe38Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:29:16 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)) 16:29:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:29:16 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-amTWZu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:29:16 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)) 16:29:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:29:16 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-y8Ctqs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:29:16 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)) 16:29:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:29:16 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-shKely-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:29:16 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)) 16:29:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:29:16 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-BYlHnI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:29:16 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)) 16:29:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:29:16 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-6Vlf3M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:29:17 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)) 16:29:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:29:17 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-BBtoER-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:29:17 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)) 16:29:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:29:17 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-nFwCph-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:29:17 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)) 16:29:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:29:17 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-Y81aoT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:29:17 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)) 16:29:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:29:17 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-kJzKLq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:29:17 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)) 16:29:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:29:17 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-AWr75R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:29:17 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)) 16:29:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:29:17 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-K3_ysm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:29:17 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)) 16:29:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:29:17 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-GkLroK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:29:17 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)) 16:29:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:29:17 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-crH4j1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:29:17 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)) 16:29:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:29:17 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-otSfGI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:29:18 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)) 16:29:18 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)) 16:29:18 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)) 16:29:18 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)) 16:29:18 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)) 16:29:18 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)) 16:29:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:29:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:20 INFO - Downloading blessings-1.6.tar.gz 16:29:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:29:20 INFO - Installing collected packages: blessings 16:29:20 INFO - Running setup.py install for blessings 16:29:20 INFO - Successfully installed blessings 16:29:20 INFO - Cleaning up... 16:29:21 INFO - Return code: 0 16:29:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:29:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:29:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:29:21 INFO - Reading from file tmpfile_stdout 16:29:21 INFO - Current package versions: 16:29:21 INFO - blessings == 1.6 16:29:21 INFO - blobuploader == 1.2.4 16:29:21 INFO - docopt == 0.6.1 16:29:21 INFO - functools32 == 3.2.3-2 16:29:21 INFO - jsonschema == 2.5.1 16:29:21 INFO - manifestparser == 1.1 16:29:21 INFO - mozInstall == 1.12 16:29:21 INFO - mozcrash == 1.0 16:29:21 INFO - mozdebug == 0.1 16:29:21 INFO - mozdevice == 0.48 16:29:21 INFO - mozfile == 1.2 16:29:21 INFO - mozhttpd == 0.7 16:29:21 INFO - mozinfo == 0.9 16:29:21 INFO - mozleak == 0.1 16:29:21 INFO - mozlog == 3.4 16:29:21 INFO - moznetwork == 0.27 16:29:21 INFO - mozprocess == 0.23 16:29:21 INFO - mozprofile == 0.28 16:29:21 INFO - mozrunner == 6.13 16:29:21 INFO - mozscreenshot == 0.1 16:29:21 INFO - mozsystemmonitor == 0.3 16:29:21 INFO - moztest == 0.7 16:29:21 INFO - mozversion == 1.4 16:29:21 INFO - psutil == 3.1.1 16:29:21 INFO - requests == 1.2.3 16:29:21 INFO - wsgiref == 0.1.2 16:29:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:29: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')]} 16:29:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29: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')]} 16:29:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:21 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 0x10d4b9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d42f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc249d75c30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2cdd30>, '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 0x10d133db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2c5b20>, '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.Ek4g7fephP/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.ZMIsonx6cb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 16:29:21 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 16:29:21 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 16:29:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ek4g7fephP/Render', 16:29:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:21 INFO - 'HOME': '/Users/cltbld', 16:29:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:21 INFO - 'LOGNAME': 'cltbld', 16:29:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:21 INFO - 'MOZ_NO_REMOTE': '1', 16:29:21 INFO - 'NO_EM_RESTART': '1', 16:29:21 INFO - 'PAGER': '/bin/cat', 16:29:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:21 INFO - 'PWD': '/builds/slave/test', 16:29:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:21 INFO - 'SHELL': '/bin/bash', 16:29:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZMIsonx6cb/Listeners', 16:29:21 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 16:29:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:21 INFO - 'USER': 'cltbld', 16:29:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:21 INFO - 'XPC_FLAGS': '0x0', 16:29:21 INFO - 'XPC_SERVICE_NAME': '0', 16:29:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:21 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)) 16:29:21 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)) 16:29:21 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)) 16:29:21 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)) 16:29:21 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)) 16:29:21 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)) 16:29:21 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)) 16:29:21 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)) 16:29:21 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)) 16:29:21 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)) 16:29:21 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)) 16:29:21 INFO - Cleaning up... 16:29:21 INFO - Return code: 0 16:29:21 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:29: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')]} 16:29:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29: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')]} 16:29:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d4b9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d42f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc249d75c30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2cdd30>, '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 0x10d133db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2c5b20>, '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.Ek4g7fephP/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.ZMIsonx6cb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 16:29:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:29:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:29:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ek4g7fephP/Render', 16:29:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:21 INFO - 'HOME': '/Users/cltbld', 16:29:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:21 INFO - 'LOGNAME': 'cltbld', 16:29:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:21 INFO - 'MOZ_NO_REMOTE': '1', 16:29:21 INFO - 'NO_EM_RESTART': '1', 16:29:21 INFO - 'PAGER': '/bin/cat', 16:29:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:21 INFO - 'PWD': '/builds/slave/test', 16:29:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:21 INFO - 'SHELL': '/bin/bash', 16:29:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZMIsonx6cb/Listeners', 16:29:21 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 16:29:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:21 INFO - 'USER': 'cltbld', 16:29:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:21 INFO - 'XPC_FLAGS': '0x0', 16:29:21 INFO - 'XPC_SERVICE_NAME': '0', 16:29:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:21 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:29:21 INFO - Cleaning up... 16:29:21 INFO - Return code: 0 16:29:21 INFO - Running post-action listener: _resource_record_post_action 16:29:21 INFO - Running post-action listener: _start_resource_monitoring 16:29:21 INFO - Starting resource monitoring. 16:29:21 INFO - [mozharness: 2017-01-03 00:29:21.892143Z] Finished create-virtualenv step (success) 16:29:21 INFO - [mozharness: 2017-01-03 00:29:21.893193Z] Running install step. 16:29:21 INFO - Running pre-action listener: _resource_record_pre_action 16:29:21 INFO - Running main action method: install 16:29:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:29:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:29:22 INFO - Reading from file tmpfile_stdout 16:29:22 INFO - Detecting whether we're running mozinstall >=1.0... 16:29:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:29:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:29:22 INFO - Reading from file tmpfile_stdout 16:29:22 INFO - Output received: 16:29:22 INFO - Usage: mozinstall [options] installer 16:29:22 INFO - Options: 16:29:22 INFO - -h, --help show this help message and exit 16:29:22 INFO - -d DEST, --destination=DEST 16:29:22 INFO - Directory to install application into. [default: 16:29:22 INFO - "/builds/slave/test"] 16:29:22 INFO - --app=APP Application being installed. [default: firefox] 16:29:22 INFO - mkdir: /builds/slave/test/build/application 16:29:22 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'] 16:29:22 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 16:29:53 INFO - Reading from file tmpfile_stdout 16:29:53 INFO - Output received: 16:29:53 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 16:29:53 INFO - Running post-action listener: _resource_record_post_action 16:29:53 INFO - [mozharness: 2017-01-03 00:29:53.307838Z] Finished install step (success) 16:29:53 INFO - [mozharness: 2017-01-03 00:29:53.307995Z] Running run-tests step. 16:29:53 INFO - Running pre-action listener: _resource_record_pre_action 16:29:53 INFO - Running main action method: run_tests 16:29:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:29:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:29:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:29:53 INFO - 2017-01-02 16:29:53.322 screenresolution[1895:20659] starting screenresolution argv=screenresolution get 16:29:53 INFO - 2017-01-02 16:29:53.325 screenresolution[1895:20659] Display 0: 1600x1200x32@60 16:29:53 INFO - 2017-01-02 16:29:53.330 screenresolution[1896:20661] starting screenresolution argv=screenresolution list 16:29:53 INFO - Available Modes on Display 0 16:29:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:29:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:29:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:29:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:29:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:29:53 INFO - Intel Iris: 16:29:53 INFO - Chipset Model: Intel Iris 16:29:53 INFO - Type: GPU 16:29:53 INFO - Bus: Built-In 16:29:53 INFO - VRAM (Dynamic, Max): 1536 MB 16:29:53 INFO - Vendor: Intel (0x8086) 16:29:53 INFO - Device ID: 0x0a2e 16:29:53 INFO - Revision ID: 0x0009 16:29:53 INFO - Displays: 16:29:53 INFO - Display: 16:29:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:29:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:29:53 INFO - Display Serial Number: 1600x1200 60 16:29:53 INFO - Main Display: Yes 16:29:53 INFO - Mirror: Off 16:29:53 INFO - Online: Yes 16:29:53 INFO - Rotation: Supported 16:29:53 INFO - Return code: 0 16:29:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:29:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:29:53 INFO - Python 2.7.3 16:29:53 INFO - Return code: 0 16:29:53 INFO - grabbing minidump binary from tooltool 16:29:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:53 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 0x10d2cdd30>, '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 0x10d133db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2c5b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:29:53 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 16:29:53 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 16:29:53 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 16:29:53 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:29:53 INFO - Return code: 0 16:29:53 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:29:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:29:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:29:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:29:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:29:53 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/K9_jxa-JRp-Bnbxsw8D-SQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0296', '--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 16:29:53 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/K9_jxa-JRp-Bnbxsw8D-SQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0296 --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 16:29:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ek4g7fephP/Render', 16:29:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:53 INFO - 'HOME': '/Users/cltbld', 16:29:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:53 INFO - 'LOGNAME': 'cltbld', 16:29:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:29:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:29:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:53 INFO - 'MOZ_NO_REMOTE': '1', 16:29:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:29:53 INFO - 'NO_EM_RESTART': '1', 16:29:53 INFO - 'PAGER': '/bin/cat', 16:29:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:53 INFO - 'PWD': '/builds/slave/test', 16:29:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:29:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:53 INFO - 'SHELL': '/bin/bash', 16:29:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZMIsonx6cb/Listeners', 16:29:53 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 16:29:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:53 INFO - 'USER': 'cltbld', 16:29:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:53 INFO - 'XPC_FLAGS': '0x0', 16:29:53 INFO - 'XPC_SERVICE_NAME': '0', 16:29:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:53 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/K9_jxa-JRp-Bnbxsw8D-SQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0296', '--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 16:29:53 INFO - mozversion application_buildid: 20170102144820 16:29:53 INFO - mozversion application_changeset: 7368fa4424f5a4340ed6d7818f99279e46978751 16:29:53 INFO - mozversion application_display_name: FirefoxDeveloperEdition 16:29:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:29:53 INFO - mozversion application_name: Firefox 16:29:53 INFO - mozversion application_remotingname: firefox-dev 16:29:53 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 16:29:53 INFO - mozversion application_vendor: Mozilla 16:29:53 INFO - mozversion application_version: 52.0a2 16:29:53 INFO - mozversion platform_buildid: 20170102144820 16:29:53 INFO - mozversion platform_changeset: 7368fa4424f5a4340ed6d7818f99279e46978751 16:29:53 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 16:29:53 INFO - mozversion platform_version: 52.0a2 16:29:53 INFO - using testdate: 1483403393 16:29:53 INFO - actual date: 1483403393 16:29:53 INFO - starting webserver on 'localhost:56183' 16:29:53 INFO - SUITE-START | Running 2 tests 16:29:53 INFO - TEST-START | dromaeo_css 16:29:53 INFO - operating with platform_type : mac_ 16:29:53 INFO - Initialising browser for dromaeo_css test... 16:29:53 INFO - TEST-INFO | started process 1906 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/tmpGi9Zdh/profile http://localhost:56183/getInfo.html) 16:29:56 INFO - PROCESS | 1906 | __metrics Screen width/height:1600/1200 16:29:56 INFO - PROCESS | 1906 | colorDepth:24 16:29:56 INFO - PROCESS | 1906 | Browser inner width/height: 1024/689 16:29:56 INFO - PROCESS | 1906 | __metrics 16:29:56 INFO - PROCESS | 1906 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:29:56 INFO - PROCESS | 1906 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:29:57 INFO - TEST-INFO | 1906: exit 0 16:29:57 INFO - Browser initialized. 16:29:57 INFO - Running cycle 1/1 for dromaeo_css test... 16:29:57 INFO - TEST-INFO | started process 1908 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/tmpGi9Zdh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:30:09 INFO - PROCESS | 1908 | RSS: Main: 222715904 16:30:09 INFO - PROCESS | 1908 | 16:31:00 INFO - PROCESS | 1908 | 1483403460666 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] 16:33:13 INFO - PROCESS | 1908 | Cycle 1(1): loaded http://localhost:56183/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56183/tests/dromaeo/cssquery-ext.html) 16:33:17 INFO - PROCESS | 1908 | RSS: Main: 515940352 16:33:17 INFO - PROCESS | 1908 | 16:36:20 INFO - PROCESS | 1908 | Cycle 1(1): loaded http://localhost:56183/tests/dromaeo/cssquery-ext.html (next: http://localhost:56183/tests/dromaeo/cssquery-jquery.html) 16:36:20 INFO - PROCESS | 1908 | RSS: Main: 347803648 16:36:20 INFO - PROCESS | 1908 | 16:39:23 INFO - PROCESS | 1908 | Cycle 1(1): loaded http://localhost:56183/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56183/tests/dromaeo/cssquery-mootools.html) 16:39:23 INFO - PROCESS | 1908 | RSS: Main: 321200128 16:39:23 INFO - PROCESS | 1908 | 16:42:26 INFO - PROCESS | 1908 | Cycle 1(1): loaded http://localhost:56183/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56183/tests/dromaeo/cssquery-prototype.html) 16:42:26 INFO - PROCESS | 1908 | RSS: Main: 325636096 16:42:26 INFO - PROCESS | 1908 | 16:45:30 INFO - PROCESS | 1908 | Cycle 1(1): loaded http://localhost:56183/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56183/tests/dromaeo/cssquery-yui.html) 16:45:30 INFO - PROCESS | 1908 | RSS: Main: 313266176 16:45:30 INFO - PROCESS | 1908 | 16:48:33 INFO - PROCESS | 1908 | Cycle 1(1): loaded http://localhost:56183/tests/dromaeo/cssquery-yui.html (next: http://localhost:56183/tests/dromaeo/cssquery-dojo.html) 16:48:34 INFO - PROCESS | 1908 | RSS: Main: 429756416 16:48:34 INFO - PROCESS | 1908 | 16:48:34 INFO - PROCESS | 1908 | __start_tp_report 16:48:34 INFO - PROCESS | 1908 | _x_x_mozilla_page_load 16:48:34 INFO - PROCESS | 1908 | _x_x_mozilla_page_load_details 16:48:34 INFO - PROCESS | 1908 | |i|pagename|runs| 16:48:34 INFO - PROCESS | 1908 | |0;dojo.html;3025.4554180247555;3329.850156742946;3352.0111567087706;3359.664033596642;3399.524066630672;5710.7430165642545;5786.392428794979;5817.592768506206;5830.892130495208;5850;5636.520895723858;5669.177969194464;5764.7982320618785;5778.566607504433;5795.246617939672;5241.947580524203;5256.553192978601;5270.868228294285;5272.947270527303;5317.574594032488;5161.17421212606;5209.895802083956;5217.165253559431;5358.2766326546;5371.274877891493;5271.9472805272035;5295.761690723926;5299.125644268691;5307.469253074681;5316.282301889253;5299.072662284104;5299.867503312429;5300.787968481295;5306.097963346202;5312.681239125664;5168.173092305213;5251.159814429711;5264.6051546134195;5268.841934741964;5286.207068939651;5787.305523337184;5798.449147330997;5807.709614519257;5808.390119037483;5810.244668193108;5141.331626888518;6173.074038894157;6200.62796232223;6203.751849925998;6217.658028808413;5207.244949482304;6232.781852635136;6273.654948977804;6283.622982621012;6318.968405157991;5754.395788442237;5805.651660900359;5814.156947242668;5816;5817.621854579481;6271.184745983084;6283;6288.993760998264;6297.4962003039645;6303.527235457359;6076.422739839708;6125.6017461851925;6135.140800782589;6141.1791743482045;6145.139680444741;6285.371462853769;6298.1812364392335;6308.495320374359;6341.651209183539;6359;6278.811635650938;6306.8423289417215;6310.432061114522;6311.779087731908;6312.558120931582;6119.07606531266;6164.3835616438;6185.226846644169;6217.129601855744;6231.781887633911;4952.108620448282;4952.603791696655;4954.281629163823;4971.304017437562;4982;4437.001674623184;4452;4469.687121901436;4472.038511719996;4501.887452813706;4514.164879497303;5456.61803673747;5705.62913410627;5717.971410143005;5733.0540460823495;6272.6236425814595;6311.873762524724;6312.968435157794;6331.556791024676;6342.397472240222;5820.447057529528;5838.182654428383;5848;5850.23946886902;5866.178734977107;5322.175062865262;5375.301210842644;5433.592480563894;5444.183372494158;5457.590680698963;5303.655262407931;6222.937770622417;6255.96872015637;6257.9687101564205;6272.247330320391;6126.693665316806;6306.842328941813;6311.305756366888;6328.841778955563;6334.018227174795;6168.414000669929;6279.843003924756;6295.527835412271;6327.683615819283;6430.710618022106;6128.4790792783115;6144.341027922346;6154.692265386623;6207.193064901534;6260.74957001733;4818.638602104786;5154.762856914389;6193.690315484298;6259.061140828912;6261.74953001893;6061.636301821904;6083.66539840313;6095.1771510845465;6102.786402475892;6110.236220472441;6114.6636934969;6124.14262003311;6124.418180272867;6125.234345706786;6129.785457508966;5918.496927761188;5922.2597175353085;5923.200367950444;5928.407159284037;5941;6015.218021657157;6093.9390606093375;6103.511719062553;6108.144859719731;6132.8773424532155;5804.1293805929445;5949.196858424058;5953.76184952597;5961.135635332939;5987.850303742442 16:48:34 INFO - PROCESS | 1908 | |1;ext.html;500.69707826764807;510.61877621035103;513.6276199755175;516.5944733384295;548.914918187681;37982.34123611348;39530.80234598827;39619.80190099045;39738.60261397385;39933.40099898504;33183.502247466306;40919;40926.7953660232;40981.59018409815;41061.58938410615;8375.539345336005;9172.2203612693;9349.485778282191;9362.485063321514;9363.204127649124;8530.957345213264;8627.827443451128;8628.784280393009;8630.093840146788;8633.13668633138;11299.943500282487;11550.018248448892;11576.47905844239;11583.826242606368;11585.826212606818;11213.046891014274;11306.830397544043;11313.981741643292;11336.149788765835;11341.149413793999;8345.95827020861;8576.871346929802;8593.14068593142;11125;11230.876912308704;31101.688983110333;31389.843050784828;38068.80965595182;38288.80855595704;38485;3252.4796032634663;3457.7060949819297;3776.066128123256;3814.0464883779055;3868.2843673920415;27776.444471110466;27904.02335918233;29226;29257.268568285963;30646.69353306461;3148.7795854290207;3190.122716253026;3198.4562624353684;3261.869525218988;3284.310294838087;507308;509182;510220;510925.4453727745;511244;32544;33471.3305733884;33478.83260583682;56889.29332120043;57626.7118664404;34488.48267275993;34684;34752.30495390078;34860.9541713749;34956;13860.792088118686;14021.018528703096;14033.578992630237;14042.789358159636;14072.155670659793;11408.429578521042;11613.6515904523;11706.239094458833;11724.88275117238;11731.120165987584;7176.7488137914925;7357.668904899292;7365.079365079365;7551.169371369145;7707.499012564165;3174.53969174473;3178.888738894174;3196.1530194498478;3203.1831882870183;3235.4499735045097;881.9029906710257;897.7351681253915;984.601236499219;1005.4419797012582;1008.7478130467383;25605.61591576128;28096.29759256035;28682.856585716938;29354.412911741645;29432.11703648894;978.7650963768644;1001.5643195210107;1004.9748756281006;1031.7678522332415;1038.89611038897;12345.876541234653;14512.056716313617;14931.328090235966;14987.32557034936;15369.538913832379;708054;711397;712123;713589;715055;47888.042239155024;48995.755021224664;55269.44730552723;55684;56409.7179514108;1331.1414137881013;1338.110156745753;1366.7881478370869;1367.2001878900955;1369.000629540441;29278.82884684589;30065.69934300629;30264.394712105193;30423.54364684487;31142.377152457277;52385.47614523958;53887.73056134851;54163.72918135384;57186.7140664294;57237.42762572488;678562;697979;884446;885454;889568;38307.425388618634;38609;38922.2215555693;39020.82937511879;39302.60697393104;42197;42794;42862.57137428586;43312.91717707083;43546.564534355515;36171.45742813807;36334;36505.90482285536;36633.26733465263;36696.449553256185;30071.248218794706;36118.09704757402;36241.45637815488;36443.63556364403;36462.63537364592;35435.64564354427;35826;35952.64047359493;36259.818700906326;36323.45514817227;30867.845660771553;32665;32730.67269327276;32898.67101328956;32931.67068329286;20077.899610501852;24357.51284974326;24559.631605525574;24843;25026.874865625556 16:48:34 INFO - PROCESS | 1908 | |2;jquery.html;314.6633102580239;350.5985646434831;374.9475073489712;377.47719408619065;401.7569370530829;14572;14867.40530378785;16754.910930789505;19091.331803386885;20651.69022464664;20044.298449554233;20632.793672063275;20823.16707331705;20827.375178744624;21047;4548.385967894334;5719.914201286984;5947.643141411508;5984.401559844003;6011.428914253139;5658.632188907728;5869.7652093916195;5965.582409231356;5982.700864956757;5984.311804142514;5783.190353350513;5825.533957283428;5892.558058145656;5908.468237858592;5916.083007133901;5659.688717120577;5874.060150375919;5883.117532370136;5892.793752218673;5899.705014749266;5022.4726403727445;5185.325907631984;5190.480951904817;5194.844154675366;5216.400113986902;12649.620511384674;13233.735325293537;13243.20540767557;13251.933740331237;14539.927300363537;2179.182806447582;2180.618391781432;2294.5640328337563;2319.3158018384615;2725.9454810903867;9709.805803883954;9898.10917017472;10213.234007449397;10318.484075796181;14522.128672279592;1779.3060706324543;1792.4353828543967;1858.7118996555555;1860.5348662834292;1867.0384751852807;88718.11281887228;111208;114898;124680;128180;10638.893611063944;10802.189835762349;10804.027637512661;10924.563017479371;11492;10515;13365.39855706476;13404.329783510786;13759.518416855362;13788.862111378758;6976.790696279119;7478.700851965969;7505.737299194502;7650.4644674872225;8020.759377218693;8170.632321545545;8278.461899976483;8309.127541608044;8455.323574114056;8474.491530508189;4442.333649952533;4521.982553925341;4546.158960592301;4587.655925805511;4642.883927901829;1740.7562941188244;1783.1351794379716;1838.8253115953962;1840.6226723521645;1864.850811935042;816.6243527977197;908.68650315641;938.5870217104589;951.5860600638745;965.8068386322764;19446.80553194478;20241.59516809685;20308;20318.390448286576;20674.689879651818;832.5587438657521;860.7891066688576;897.6274845938863;921.4839689773748;939.9201067909304;10313.690589282334;10343.896561034444;10802.567897284021;10934.07060399859;10946.397948112772;565767;567593;581784;591788;594949;128747;130664;133337;135369;137584;990.5740531571494;997.5660587644398;1041.234692501011;1046.1055797293204;1050.24907191357;16407.917960410123;16874.818762686493;17552.56118597045;17999;18319.175637096097;43703.562964371224;52874.73562632316;53257.402277931724;53520;56426.717866412044;82617.58691206506;83759.5812020936;84400.57799710962;85766;86502.13497865193;6909.378155965987;7305.488615796843;7308.488405811542;7541.660625271986;7811.140774514913;10844;12448.502059917864;12491.063170262085;12787.61637150887;12943.870561294267;8774.692885748967;10827.945860270649;10996.505157268102;11213.271137376045;11248.831267531157;10847.566097356017;10964.451777411257;10966.616168434066;11222.046126079049;11263.774724505627;10931.83602245951;10958.068564172134;11060.225784195029;11078;11516;7188.964055179691;7266.200717920919;7415.369693576106;7481.326680598772;7587.2412758723685;10249.436281004617;10801.567937282422;10947.397893115636;11017.898210178919;11099.944500277446 16:48:34 INFO - PROCESS | 1908 | |3;mootools.html;1441.2361448432334;1472.7201831651987;1604.8475394837494;1788.079139243289;1791.6685413198559;8192.467489613171;8535.487870727746;8570.700025499102;8572.971243450795;8573.971123465186;7449.66476508557;7727.4204434667345;7730.342920851735;8038.196180381959;8257.587120643973;2296.8851557422145;2330.0912644068826;2334;2336.8714720690355;2337.4506990857135;2261.9886900565475;2280.3728974532055;2309.6189128793812;2310.90756369745;2315.0045480443405;2047.8361731061555;2129.1376992318137;2129.2015494189677;2188.4857058591297;2291.9083236670513;2245.6070187717164;2291.472961218912;2294.804941579951;2295.747467778535;2301.8158547316175;2309.9422514437106;2357.2456813819585;2366.2546297916274;2373.2524254859836;2374.608189648706;7044.612546309951;8165.67337306507;8347.582620868992;8348.165183481664;8356.791080222982;5040.823571174991;5116.437191908887;5120.6927584344685;5188.92216616751;5195.064888320101;3294.5387645729616;3366.175287054663;3454.1709989602564;3516.3494753470436;3524.8942531724088;1059.311447559085;1077.8975997280245;1083.6911480228175;1090.4547726136932;1097.4786976186329;10455.634052808115;10741.677749667522;10919.890801091968;11065.225434219608;11085.445727713583;4482.125985432875;4545.204644165536;4652.651051171176;4670.182718024332;4676.836310729176;9491.38306010107;9491.525423728786;9500.287478439144;9516.095970882756;9519.857202141973;5224.50367215114;5237.188235823453;5237.73811309433;5238.109521381374;5242.082635538765;4596.4714057883775;4798.5441383068555;4804.183288840905;4816.20532612115;4834.347363105936;3711.7030637548933;3726.5341832270965;3757.3612485877466;3796.3925771876366;3828.406596977473;1211.5820042085643;1227.9508819647115;1229.065909908476;1241.975160496785;1245.3212998915612;611.7675283392282;750.9586972716444;768.5696010234286;771.5524995502595;829.3033851564594;5488.91766623501;5497.8075767347955;5555.77776888928;5569.693666848281;5804.9419505805245;879.560219890055;889.7775556110972;919.7424721078108;940.1397721085131;949.928755343352;2973.3755911258445;3730.4963829883163;3806.4861243731743;3846.4038074098557;3916.6475017248586;9036.096390360912;9710.75723106928;10030.498475076218;10114.949425252827;10266.435346056038;10024.298299119138;10236.232282578836;10245.282830201815;10254.487275636338;10257.846132308021;7663.655135518804;7993.400494962901;7994.5602991836;8007.439479236397;8016.198380161937;5546.417626149197;5581.6930068846605;5612.298462692163;5824.5631577631;5897.026990546512;9327.486988215713;9357.812843743222;9467.053294670477;9490.762730931505;9493.24054075686;9179;9479.47862867549;9487.525623718924;9530.61877524919;9726.076022777825;7125.643717813985;7193.992841002159;7241.782746517613;7264.273572642694;7291.197968223385;7767.805804854924;7778.338841198562;7791;7791.104023037196;7850.921490785247;7048.436125110082;7111.893321600283;7130.268142168331;7232.963835180791;7312.415006799337;6923.115351951003;6982.476314276348;7009.439244860501;7057.36483716468;7068.929310706827;6537.673116344069;6681.164854393201;6689.364510371507;6689.49828762835;6699.229588597179;4841.588464980516;4874.512548745097;4883.121038213156;4924.63065270099;4950.8267210647455;4877.756112194305;4883.145449546244;4906.0433215522635;5006.299118123538;5009.599232061353 16:48:34 INFO - PROCESS | 1908 | |4;prototype.html;296.83377308707117;297.73055384876693;303.7682198684335;309.16525381470035;311.268518980396;3933.3903244997;4971.825986090487;5028.597712183026;5045.243213517974;5101.872453188672;4108.650764685005;4435.201663700533;4826;4937.950620493794;5000.824971126011;3393.915152121192;3887.7667339959553;3893.9610603894025;3898.512685914254;3912.1784425270585;3030.6060212172497;3277.475603903376;3827.10063135163;3858.459815625815;3890.474785903895;3149.16603014373;3374.7300215982787;3825.713071519647;3841.2125514269646;3889.961100388988;3832.310184166849;3842.346801043829;3856.3058649443087;3863.632954869283;3874.593167717377;3367.8821241256437;3383.2387712764676;3392.4741665041956;3397.8980630581123;3405.3189362127673;4344.391785150082;4432.734035957853;4742.691725037862;4860.416749990024;4907.460179380265;4199.622034016923;4361.520232774392;4385.276429389147;4401.163778882003;4404.625606823424;4088.795560221977;4296.5059018213;4457.465104187486;5237.423883372826;5246;3277.6394596594355;3563.3585954528176;3596.6403359663823;3716.8513259469587;3726.441033844918;5449.04641687703;5456.7544460498975;5485.6982865942355;5495.279836480928;5567.860803479945;3170.25499007733;3761.247750449921;3761.3229618668356;3763.5860055393887;3786.807155745931;3343.9665603343656;3378;3433.8881587823807;3810.599887011879;3834.581204954129;3573.749837511349;3679.9448008279896;4219.409282700424;4423.712458690175;4437.534058923831;4458.4426946631675;5167.767450464738;5230.895382092337;5237.109691352479;5257.684538927677;1038.3354653022072;1167.9708007299885;1221.3893053473264;1223.7980733179104;1224.2287358963795;749.865024295632;806.5443024691031;808.7989335836278;841.4993079117914;855.7078810995441;4455.710378825366;4608.654350923627;4615.23848564983;4671.416072990876;4693.976530117396;4707.552782485658;4718.9292160617615;4950.628702847301;5036.79852805891;5071.4928507149725;4130.463039804806;4490.2142125127975;4634.096351211549;4993.750312484434;5051.348042277834;2291.9541609167723;2332.4518738096535;2660.414708764069;2661.8136730429073;2673.9598906016427;4587.72473651582;5146.382434107931;5217.921731173959;5252.5535329497425;5286.074936886106;4306.224879521654;4857.70093467284;4969.08690154286;5189.351331083614;5271.128749294022;4743.407074115736;5258.949009886148;5269.892043182835;5279.762410691451;5292;4237.050956560017;5042.798288068436;5067.315912351785;5087.694738315713;5118.978003688597;4098.508179018418;4184.958150418457;5007.899842003212;5389.461053894579;5489.533389661764;5157.8452646420665;5560.944390556205;5567.164925261243;5671.319441666861;5693.4875861172695;3281.3765384576855;3598.208394153282;3712.4431335299923;3742.8877133686033;3743.0242956848824;4655.86032419028;5224.843254702365;5392.757325920422;5483.643563168382;5545.72271386421;4428.977855110704;5285.894282114413;5345.812896548602;5386.757595908115;5387.353517577916;5365.624406291522;5368.355797304349;5373.220882972026;5374.951884382508;5391.32608423947;4082.693797965105;4286.5070516890955;4977.751112444435;5065;5257.39539952895;4783.736894470699;4971.1797553403285;5243.6329456939175;5248.60635452335;5371.206972374265;5227.477252274894;5235.476452354734;5250.0574787327405;5252.711100889488;5256.135406203552 16:48:34 INFO - PROCESS | 1908 | |5;yui.html;687.7902239816854;702.982425439364;704.0882057735236;731.6159016516332;746.5035751225433;12288.692782680426;14019.299035048245;14673.853261467382;14675.559733207994;14694.485693000746;12866.742665146714;12895.548655797049;12936.417861196245;12944.546940857072;12945.870541294584;651.2413038809805;671.6205343980636;800.7957970717459;815.1033862750971;833.9166083391673;828.8922440082781;829.0341751859107;833.4374297349295;837.3845223760532;838.5220424358118;884.1158841158841;884.1953822021961;886.4149661223594;889.715291106846;891.8974317953454;686.5674624986227;709.1844378964181;878.8242351529719;880.9427387219811;882.2016075451727;625.3184029407967;646.9870602587922;650.4471199480452;651.1925212736173;651.989416404571;14057.016008879382;14114.294285285694;14115.858841411557;14120.940929430231;14122.929385353007;12971.610851674464;13047.282399468026;13273.20811690419;13319.334033298393;13378.331083445788;11523.366214858179;11743.882561174365;12006.579769708018;12091.45588448522;12263.448144833505;4182.707210495267;4544.818207271738;4547.18150732868;4562.315652652095;4592.47186573545;94727.52636236843;94915;95715.52142239314;95869.9999999993;95936.52031739797;10482.895171048192;10544.894551054545;10601.57593696259;10646.254762166729;10768.56925722978;20114.39656810298;20290.898545507178;20358.898205508878;20368.388948331572;20385.796142038686;7208.459365547605;7339.1926888043345;7356.816079597946;7450.739224127129;7584.8483030340185;4116.75299482032;4154.439150714615;4237.449131612871;4287.721298115613;4347.456567929009;3247.4966380211104;3286.194882253863;3332.8333583320737;3359.983200083984;3372.0389688939026;537.7096367961335;622.7540121652013;627.4980015987192;640.1617250673884;652.3378770547897;524.8373004368658;540.7917951588588;573.6357413042665;609.065085094378;609.3084349263529;7120.323569260912;8163.67345306193;8695.826083478294;8719.346049046348;8739.43193692408;495.95784358329945;565.8443927919773;606.2936678769273;610.8167549735061;641.8106658535743;3842.9807850961124;3924.7448915820382;3981.900452488711;4003.5657686212708;4059.4113853491667;79822;89365.87219447287;115393.26910096355;118354;120238.79761202451;84854;96361.5545766814;99582;102885.97114028914;103433.48283258536;70235;84063.5796821012;86544.56727716321;88785.55607221923;88865.55567222252;6655.866882662416;7772.805679857823;7794.1816109309975;7951.960240198762;7968.920310796818;55850.44149558615;56175.876482470936;56639.43360566341;57353.713231433576;59206.11190832227;67321.66339168469;79604.80592790997;79651.60174199092;80800.59599702198;82526;10240.48797560134;10595.205359597907;10736.087432568007;10820.917908209116;10832.566697332333;9293;10456.47717614101;11683.824742628698;11833.52665893389;11953.282803031845;8774.868376974478;10706.89293107059;11373.54505819758;12267.509299628271;12523.56167534132;12789.8081528779;12916.22502649844;13173.341332933507;13184.208947463183;13492.59522214335;10227.335223210468;10716.785664286515;12074.335911524951;12169.330686811956;12437.93781031089;10353.63762268317;10636.295180722904;10704.839427408751;10819.18856085781;10946.890531094587;10617.150627949592;10935.890641093487;10941.398223097487;10955.452227388758;11036.944815276194 16:48:34 INFO - PROCESS | 1908 | __end_tp_report 16:48:34 INFO - PROCESS | 1908 | __start_cc_report 16:48:34 INFO - PROCESS | 1908 | _x_x_mozilla_cycle_collect,3652 16:48:34 INFO - PROCESS | 1908 | __end_cc_report 16:48:34 INFO - PROCESS | 1908 | __startTimestamp1483404514234__endTimestamp 16:48:34 INFO - PROCESS | 1908 | 16:48:34 INFO - PROCESS | 1908 | ------- Summary: start ------- 16:48:34 INFO - PROCESS | 1908 | Number of tests: 6 16:48:34 INFO - PROCESS | 1908 | 16:48:34 INFO - PROCESS | 1908 | [#0] dojo.html Cycles:165 Average:5734.08 Median:5922.26 stddev:650.01 (11.0%) stddev-sans-first:616.29 16:48:34 INFO - PROCESS | 1908 | Values: 3025.5 3329.9 3352.0 3359.7 3399.5 5710.7 5786.4 5817.6 5830.9 5850.0 5636.5 5669.2 5764.8 5778.6 5795.2 5241.9 5256.6 5270.9 5272.9 5317.6 5161.2 5209.9 5217.2 5358.3 5371.3 5271.9 5295.8 5299.1 5307.5 5316.3 5299.1 5299.9 5300.8 5306.1 5312.7 5168.2 5251.2 5264.6 5268.8 5286.2 5787.3 5798.4 5807.7 5808.4 5810.2 5141.3 6173.1 6200.6 6203.8 6217.7 5207.2 6232.8 6273.7 6283.6 6319.0 5754.4 5805.7 5814.2 5816.0 5817.6 6271.2 6283.0 6289.0 6297.5 6303.5 6076.4 6125.6 6135.1 6141.2 6145.1 6285.4 6298.2 6308.5 6341.7 6359.0 6278.8 6306.8 6310.4 6311.8 6312.6 6119.1 6164.4 6185.2 6217.1 6231.8 4952.1 4952.6 4954.3 4971.3 4982.0 4437.0 4452.0 4469.7 4472.0 4501.9 4514.2 5456.6 5705.6 5718.0 5733.1 6272.6 6311.9 6313.0 6331.6 6342.4 5820.4 5838.2 5848.0 5850.2 5866.2 5322.2 5375.3 5433.6 5444.2 5457.6 5303.7 6222.9 6256.0 6258.0 6272.2 6126.7 6306.8 6311.3 6328.8 6334.0 6168.4 6279.8 6295.5 6327.7 6430.7 6128.5 6144.3 6154.7 6207.2 6260.7 4818.6 5154.8 6193.7 6259.1 6261.7 6061.6 6083.7 6095.2 6102.8 6110.2 6114.7 6124.1 6124.4 6125.2 6129.8 5918.5 5922.3 5923.2 5928.4 5941.0 6015.2 6093.9 6103.5 6108.1 6132.9 5804.1 5949.2 5953.8 5961.1 5987.9 16:48:34 INFO - PROCESS | 1908 | 16:48:34 INFO - PROCESS | 1908 | [#1] ext.html Cycles:180 Average:77029.19 Median:36144.78 stddev:186128.51 (515.0%) stddev-sans-first:186561.96 16:48:34 INFO - PROCESS | 1908 | Values: 500.7 510.6 513.6 516.6 548.9 37982.3 39530.8 39619.8 39738.6 39933.4 33183.5 40919.0 40926.8 40981.6 41061.6 8375.5 9172.2 9349.5 9362.5 9363.2 8531.0 8627.8 8628.8 8630.1 8633.1 11299.9 11550.0 11576.5 11583.8 11585.8 11213.0 11306.8 11314.0 11336.1 11341.1 8346.0 8576.9 8593.1 11125.0 11230.9 31101.7 31389.8 38068.8 38288.8 38485.0 3252.5 3457.7 3776.1 3814.0 3868.3 27776.4 27904.0 29226.0 29257.3 30646.7 3148.8 3190.1 3198.5 3261.9 3284.3 507308.0 509182.0 510220.0 510925.4 511244.0 32544.0 33471.3 33478.8 56889.3 57626.7 34488.5 34684.0 34752.3 34861.0 34956.0 13860.8 14021.0 14033.6 14042.8 14072.2 11408.4 11613.7 11706.2 11724.9 11731.1 7176.7 7357.7 7365.1 7551.2 7707.5 3174.5 3178.9 3196.2 3203.2 3235.4 881.9 897.7 984.6 1005.4 1008.7 25605.6 28096.3 28682.9 29354.4 29432.1 978.8 1001.6 1005.0 1031.8 1038.9 12345.9 14512.1 14931.3 14987.3 15369.5 708054.0 711397.0 712123.0 713589.0 715055.0 47888.0 48995.8 55269.4 55684.0 56409.7 1331.1 1338.1 1366.8 1367.2 1369.0 29278.8 30065.7 30264.4 30423.5 31142.4 52385.5 53887.7 54163.7 57186.7 57237.4 678562.0 697979.0 884446.0 885454.0 889568.0 38307.4 38609.0 38922.2 39020.8 39302.6 42197.0 42794.0 42862.6 43312.9 43546.6 36171.5 36334.0 36505.9 36633.3 36696.4 30071.2 36118.1 36241.5 36443.6 36462.6 35435.6 35826.0 35952.6 36259.8 36323.5 30867.8 32665.0 32730.7 32898.7 32931.7 20077.9 24357.5 24559.6 24843.0 25026.9 16:48:34 INFO - PROCESS | 1908 | 16:48:34 INFO - PROCESS | 1908 | [#2] jquery.html Cycles:180 Average:34073.69 Median:2237.59 stddev:97323.13 (4349.5%) stddev-sans-first:97563.14 16:48:34 INFO - PROCESS | 1908 | Values: 314.7 350.6 374.9 377.5 401.8 14572.0 14867.4 16754.9 19091.3 20651.7 20044.3 20632.8 20823.2 20827.4 21047.0 4548.4 5719.9 5947.6 5984.4 6011.4 5658.6 5869.8 5965.6 5982.7 5984.3 5783.2 5825.5 5892.6 5908.5 5916.1 5659.7 5874.1 5883.1 5892.8 5899.7 5022.5 5185.3 5190.5 5194.8 5216.4 12649.6 13233.7 13243.2 13251.9 14539.9 2179.2 2180.6 2294.6 2319.3 2725.9 9709.8 9898.1 10213.2 10318.5 14522.1 1779.3 1792.4 1858.7 1860.5 1867.0 88718.1 111208.0 114898.0 124680.0 128180.0 10638.9 10802.2 10804.0 10924.6 11492.0 10515.0 13365.4 13404.3 13759.5 13788.9 6976.8 7478.7 7505.7 7650.5 8020.8 8170.6 8278.5 8309.1 8455.3 8474.5 4442.3 4522.0 4546.2 4587.7 4642.9 1740.8 1783.1 1838.8 1840.6 1864.9 816.6 908.7 938.6 951.6 965.8 19446.8 20241.6 20308.0 20318.4 20674.7 832.6 860.8 897.6 921.5 939.9 10313.7 10343.9 10802.6 10934.1 10946.4 565767.0 567593.0 581784.0 591788.0 594949.0 128747.0 130664.0 133337.0 135369.0 137584.0 990.6 997.6 1041.2 1046.1 1050.2 16407.9 16874.8 17552.6 17999.0 18319.2 43703.6 52874.7 53257.4 53520.0 56426.7 82617.6 83759.6 84400.6 85766.0 86502.1 6909.4 7305.5 7308.5 7541.7 7811.1 10844.0 12448.5 12491.1 12787.6 12943.9 8774.7 10827.9 10996.5 11213.3 11248.8 10847.6 10964.5 10966.6 11222.0 11263.8 10931.8 10958.1 11060.2 11078.0 11516.0 7189.0 7266.2 7415.4 7481.3 7587.2 10249.4 10801.6 10947.4 11017.9 11099.9 16:48:34 INFO - PROCESS | 1908 | 16:48:34 INFO - PROCESS | 1908 | [#3] mootools.html Cycles:180 Average:5449.72 Median:5154.81 stddev:2990.96 (58.0%) stddev-sans-first:2984.18 16:48:34 INFO - PROCESS | 1908 | Values: 1441.2 1472.7 1604.8 1788.1 1791.7 8192.5 8535.5 8570.7 8573.0 8574.0 7449.7 7727.4 7730.3 8038.2 8257.6 2296.9 2330.1 2334.0 2336.9 2337.5 2262.0 2280.4 2309.6 2310.9 2315.0 2047.8 2129.1 2129.2 2188.5 2291.9 2245.6 2291.5 2294.8 2295.7 2301.8 2309.9 2357.2 2366.3 2373.3 2374.6 7044.6 8165.7 8347.6 8348.2 8356.8 5040.8 5116.4 5120.7 5188.9 5195.1 3294.5 3366.2 3454.2 3516.3 3524.9 1059.3 1077.9 1083.7 1090.5 1097.5 10455.6 10741.7 10919.9 11065.2 11085.4 4482.1 4545.2 4652.7 4670.2 4676.8 9491.4 9491.5 9500.3 9516.1 9519.9 5224.5 5237.2 5237.7 5238.1 5242.1 4596.5 4798.5 4804.2 4816.2 4834.3 3711.7 3726.5 3757.4 3796.4 3828.4 1211.6 1228.0 1229.1 1242.0 1245.3 611.8 751.0 768.6 771.6 829.3 5488.9 5497.8 5555.8 5569.7 5804.9 879.6 889.8 919.7 940.1 949.9 2973.4 3730.5 3806.5 3846.4 3916.6 9036.1 9710.8 10030.5 10114.9 10266.4 10024.3 10236.2 10245.3 10254.5 10257.8 7663.7 7993.4 7994.6 8007.4 8016.2 5546.4 5581.7 5612.3 5824.6 5897.0 9327.5 9357.8 9467.1 9490.8 9493.2 9179.0 9479.5 9487.5 9530.6 9726.1 7125.6 7194.0 7241.8 7264.3 7291.2 7767.8 7778.3 7791.0 7791.1 7850.9 7048.4 7111.9 7130.3 7233.0 7312.4 6923.1 6982.5 7009.4 7057.4 7068.9 6537.7 6681.2 6689.4 6689.5 6699.2 4841.6 4874.5 4883.1 4924.6 4950.8 4877.8 4883.1 4906.0 5006.3 5009.6 16:48:34 INFO - PROCESS | 1908 | 16:48:34 INFO - PROCESS | 1908 | [#4] prototype.html Cycles:180 Average:4187.77 Median:4611.95 stddev:1287.45 (27.9%) stddev-sans-first:1257.50 16:48:34 INFO - PROCESS | 1908 | Values: 296.8 297.7 303.8 309.2 311.3 3933.4 4971.8 5028.6 5045.2 5101.9 4108.7 4435.2 4826.0 4938.0 5000.8 3393.9 3887.8 3894.0 3898.5 3912.2 3030.6 3277.5 3827.1 3858.5 3890.5 3149.2 3374.7 3825.7 3841.2 3890.0 3832.3 3842.3 3856.3 3863.6 3874.6 3367.9 3383.2 3392.5 3397.9 3405.3 4344.4 4432.7 4742.7 4860.4 4907.5 4199.6 4361.5 4385.3 4401.2 4404.6 4088.8 4296.5 4457.5 5237.4 5246.0 3277.6 3563.4 3596.6 3716.9 3726.4 5449.0 5456.8 5485.7 5495.3 5567.9 3170.3 3761.2 3761.3 3763.6 3786.8 3344.0 3378.0 3433.9 3810.6 3834.6 3573.7 3679.9 4219.4 4423.7 4437.5 4458.4 5167.8 5230.9 5237.1 5257.7 1038.3 1168.0 1221.4 1223.8 1224.2 749.9 806.5 808.8 841.5 855.7 4455.7 4608.7 4615.2 4671.4 4694.0 4707.6 4718.9 4950.6 5036.8 5071.5 4130.5 4490.2 4634.1 4993.8 5051.3 2292.0 2332.5 2660.4 2661.8 2674.0 4587.7 5146.4 5217.9 5252.6 5286.1 4306.2 4857.7 4969.1 5189.4 5271.1 4743.4 5258.9 5269.9 5279.8 5292.0 4237.1 5042.8 5067.3 5087.7 5119.0 4098.5 4185.0 5007.9 5389.5 5489.5 5157.8 5560.9 5567.2 5671.3 5693.5 3281.4 3598.2 3712.4 3742.9 3743.0 4655.9 5224.8 5392.8 5483.6 5545.7 4429.0 5285.9 5345.8 5386.8 5387.4 5365.6 5368.4 5373.2 5375.0 5391.3 4082.7 4286.5 4977.8 5065.0 5257.4 4783.7 4971.2 5243.6 5248.6 5371.2 5227.5 5235.5 5250.1 5252.7 5256.1 16:48:34 INFO - PROCESS | 1908 | 16:48:34 INFO - PROCESS | 1908 | [#5] yui.html Cycles:180 Average:20502.96 Median:4554.75 stddev:30689.32 (673.8%) stddev-sans-first:30739.34 16:48:34 INFO - PROCESS | 1908 | Values: 687.8 703.0 704.1 731.6 746.5 12288.7 14019.3 14673.9 14675.6 14694.5 12866.7 12895.5 12936.4 12944.5 12945.9 651.2 671.6 800.8 815.1 833.9 828.9 829.0 833.4 837.4 838.5 884.1 884.2 886.4 889.7 891.9 686.6 709.2 878.8 880.9 882.2 625.3 647.0 650.4 651.2 652.0 14057.0 14114.3 14115.9 14120.9 14122.9 12971.6 13047.3 13273.2 13319.3 13378.3 11523.4 11743.9 12006.6 12091.5 12263.4 4182.7 4544.8 4547.2 4562.3 4592.5 94727.5 94915.0 95715.5 95870.0 95936.5 10482.9 10544.9 10601.6 10646.3 10768.6 20114.4 20290.9 20358.9 20368.4 20385.8 7208.5 7339.2 7356.8 7450.7 7584.8 4116.8 4154.4 4237.4 4287.7 4347.5 3247.5 3286.2 3332.8 3360.0 3372.0 537.7 622.8 627.5 640.2 652.3 524.8 540.8 573.6 609.1 609.3 7120.3 8163.7 8695.8 8719.3 8739.4 496.0 565.8 606.3 610.8 641.8 3843.0 3924.7 3981.9 4003.6 4059.4 79822.0 89365.9 115393.3 118354.0 120238.8 84854.0 96361.6 99582.0 102886.0 103433.5 70235.0 84063.6 86544.6 88785.6 88865.6 6655.9 7772.8 7794.2 7952.0 7968.9 55850.4 56175.9 56639.4 57353.7 59206.1 67321.7 79604.8 79651.6 80800.6 82526.0 10240.5 10595.2 10736.1 10820.9 10832.6 9293.0 10456.5 11683.8 11833.5 11953.3 8774.9 10706.9 11373.5 12267.5 12523.6 12789.8 12916.2 13173.3 13184.2 13492.6 10227.3 10716.8 12074.3 12169.3 12437.9 10353.6 10636.3 10704.8 10819.2 10946.9 10617.2 10935.9 10941.4 10955.5 11036.9 16:48:34 INFO - PROCESS | 1908 | -------- Summary: end -------- 16:48:34 INFO - PROCESS | 1908 | 16:48:34 INFO - TEST-INFO | 1908: exit 0 16:48:34 INFO - TEST-OK | dromaeo_css | took 1120987ms 16:48:34 INFO - TEST-START | kraken 16:48:34 INFO - operating with platform_type : mac_ 16:48:34 INFO - Initialising browser for kraken test... 16:48:34 INFO - TEST-INFO | started process 1911 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/tmpFXhmbX/profile http://localhost:56183/getInfo.html) 16:48:37 INFO - PROCESS | 1911 | __metrics Screen width/height:1600/1200 16:48:37 INFO - PROCESS | 1911 | colorDepth:24 16:48:37 INFO - PROCESS | 1911 | Browser inner width/height: 1024/689 16:48:37 INFO - PROCESS | 1911 | __metrics 16:48:37 INFO - PROCESS | 1911 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:48:37 INFO - PROCESS | 1911 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:48:38 INFO - TEST-INFO | 1911: exit 0 16:48:38 INFO - Browser initialized. 16:48:38 INFO - Running cycle 1/1 for kraken test... 16:48:38 INFO - TEST-INFO | started process 1912 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/tmpFXhmbX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:48:50 INFO - PROCESS | 1912 | RSS: Main: 223059968 16:48:50 INFO - PROCESS | 1912 | 16:48:53 INFO - PROCESS | 1912 | Cycle 1(1): loaded http://localhost:56183/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56183/tests/kraken/driver.html?testName=audio-beat-detection) 16:48:54 INFO - PROCESS | 1912 | RSS: Main: 272809984 16:48:54 INFO - PROCESS | 1912 | 16:48:57 INFO - PROCESS | 1912 | Cycle 1(1): loaded http://localhost:56183/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56183/tests/kraken/driver.html?testName=audio-dft) 16:48:57 INFO - PROCESS | 1912 | RSS: Main: 283201536 16:48:57 INFO - PROCESS | 1912 | 16:49:01 INFO - PROCESS | 1912 | Cycle 1(1): loaded http://localhost:56183/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56183/tests/kraken/driver.html?testName=audio-fft) 16:49:01 INFO - PROCESS | 1912 | RSS: Main: 379383808 16:49:01 INFO - PROCESS | 1912 | 16:49:03 INFO - PROCESS | 1912 | Cycle 1(1): loaded http://localhost:56183/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56183/tests/kraken/driver.html?testName=audio-oscillator) 16:49:04 INFO - PROCESS | 1912 | RSS: Main: 282632192 16:49:04 INFO - PROCESS | 1912 | 16:49:06 INFO - PROCESS | 1912 | Cycle 1(1): loaded http://localhost:56183/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56183/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:49:07 INFO - PROCESS | 1912 | RSS: Main: 283586560 16:49:07 INFO - PROCESS | 1912 | 16:49:09 INFO - PROCESS | 1912 | Cycle 1(1): loaded http://localhost:56183/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56183/tests/kraken/driver.html?testName=imaging-darkroom) 16:49:10 INFO - PROCESS | 1912 | RSS: Main: 326291456 16:49:10 INFO - PROCESS | 1912 | 16:49:13 INFO - PROCESS | 1912 | Cycle 1(1): loaded http://localhost:56183/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56183/tests/kraken/driver.html?testName=imaging-desaturate) 16:49:13 INFO - PROCESS | 1912 | RSS: Main: 327454720 16:49:13 INFO - PROCESS | 1912 | 16:49:16 INFO - PROCESS | 1912 | Cycle 1(1): loaded http://localhost:56183/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56183/tests/kraken/driver.html?testName=json-parse-financial) 16:49:17 INFO - PROCESS | 1912 | RSS: Main: 321679360 16:49:17 INFO - PROCESS | 1912 | 16:49:19 INFO - PROCESS | 1912 | Cycle 1(1): loaded http://localhost:56183/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56183/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:49:19 INFO - PROCESS | 1912 | RSS: Main: 300486656 16:49:19 INFO - PROCESS | 1912 | 16:49:21 INFO - PROCESS | 1912 | Cycle 1(1): loaded http://localhost:56183/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56183/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:49:22 INFO - PROCESS | 1912 | RSS: Main: 302845952 16:49:22 INFO - PROCESS | 1912 | 16:49:24 INFO - PROCESS | 1912 | Cycle 1(1): loaded http://localhost:56183/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56183/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:49:25 INFO - PROCESS | 1912 | RSS: Main: 304803840 16:49:25 INFO - PROCESS | 1912 | 16:49:28 INFO - PROCESS | 1912 | Cycle 1(1): loaded http://localhost:56183/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56183/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:49:28 INFO - PROCESS | 1912 | RSS: Main: 314269696 16:49:28 INFO - PROCESS | 1912 | 16:49:31 INFO - PROCESS | 1912 | Cycle 1(1): loaded http://localhost:56183/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56183/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:49:32 INFO - PROCESS | 1912 | RSS: Main: 314339328 16:49:32 INFO - PROCESS | 1912 | 16:49:34 INFO - PROCESS | 1912 | Cycle 1(1): loaded http://localhost:56183/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56183/tests/kraken/driver.html?testName=ai-astar) 16:49:34 INFO - PROCESS | 1912 | RSS: Main: 357588992 16:49:34 INFO - PROCESS | 1912 | 16:49:34 INFO - PROCESS | 1912 | __start_tp_report 16:49:34 INFO - PROCESS | 1912 | _x_x_mozilla_page_load 16:49:34 INFO - PROCESS | 1912 | _x_x_mozilla_page_load_details 16:49:34 INFO - PROCESS | 1912 | |i|pagename|runs| 16:49:34 INFO - PROCESS | 1912 | |0;ai-astar;115;114;114;112;112;115;113;114;113;116 16:49:34 INFO - PROCESS | 1912 | |1;audio-beat-detection;146;110;110;143;111;109;109;115;174;138 16:49:34 INFO - PROCESS | 1912 | |2;audio-dft;181;182;183;186;185;184;183;183;184;185 16:49:34 INFO - PROCESS | 1912 | |3;audio-fft;84;78;83;110;63;58;59;57;84;57 16:49:34 INFO - PROCESS | 1912 | |4;audio-oscillator;87;72;94;69;67;70;89;68;69;97 16:49:34 INFO - PROCESS | 1912 | |5;imaging-gaussian-blur;88;86;90;84;87;85;87;85;88;84 16:49:34 INFO - PROCESS | 1912 | |6;imaging-darkroom;94;93;94;93;93;93;104;147;118;93 16:49:34 INFO - PROCESS | 1912 | |7;imaging-desaturate;98;107;123;130;84;84;84;85;84;83 16:49:34 INFO - PROCESS | 1912 | |8;json-parse-financial;57;54;54;55;58;58;58;57;57;56 16:49:34 INFO - PROCESS | 1912 | |9;json-stringify-tinderbox;67;77;82;76;74;71;72;72;98;103 16:49:34 INFO - PROCESS | 1912 | |10;stanford-crypto-aes;84;82;84;84;84;83;83;81;81;103 16:49:34 INFO - PROCESS | 1912 | |11;stanford-crypto-ccm;151;153;155;127;145;156;148;141;183;140 16:49:34 INFO - PROCESS | 1912 | |12;stanford-crypto-pbkdf2;165;165;165;165;164;164;170;164;164;164 16:49:34 INFO - PROCESS | 1912 | |13;stanford-crypto-sha256-iterative;69;67;68;69;72;85;83;86;82;83 16:49:34 INFO - PROCESS | 1912 | __end_tp_report 16:49:34 INFO - PROCESS | 1912 | __start_cc_report 16:49:34 INFO - PROCESS | 1912 | _x_x_mozilla_cycle_collect,2171 16:49:34 INFO - PROCESS | 1912 | __end_cc_report 16:49:34 INFO - PROCESS | 1912 | __startTimestamp1483404574381__endTimestamp 16:49:34 INFO - PROCESS | 1912 | 16:49:34 INFO - PROCESS | 1912 | ------- Summary: start ------- 16:49:34 INFO - PROCESS | 1912 | Number of tests: 14 16:49:34 INFO - PROCESS | 1912 | 16:49:34 INFO - PROCESS | 1912 | [#0] ai-astar Cycles:10 Average:113.80 Median:114.00 stddev:1.32 (1.2%) stddev-sans-first:1.32 16:49:34 INFO - PROCESS | 1912 | Values: 115.0 114.0 114.0 112.0 112.0 115.0 113.0 114.0 113.0 116.0 16:49:34 INFO - PROCESS | 1912 | 16:49:34 INFO - PROCESS | 1912 | [#1] audio-beat-detection Cycles:10 Average:126.50 Median:126.50 stddev:22.54 (17.8%) stddev-sans-first:22.77 16:49:34 INFO - PROCESS | 1912 | Values: 146.0 110.0 110.0 143.0 111.0 109.0 109.0 115.0 174.0 138.0 16:49:34 INFO - PROCESS | 1912 | 16:49:34 INFO - PROCESS | 1912 | [#2] audio-dft Cycles:10 Average:183.60 Median:184.00 stddev:1.51 (0.8%) stddev-sans-first:1.27 16:49:34 INFO - PROCESS | 1912 | Values: 181.0 182.0 183.0 186.0 185.0 184.0 183.0 183.0 184.0 185.0 16:49:34 INFO - PROCESS | 1912 | 16:49:34 INFO - PROCESS | 1912 | [#3] audio-fft Cycles:10 Average:73.30 Median:70.50 stddev:17.54 (24.9%) stddev-sans-first:18.17 16:49:34 INFO - PROCESS | 1912 | Values: 84.0 78.0 83.0 110.0 63.0 58.0 59.0 57.0 84.0 57.0 16:49:34 INFO - PROCESS | 1912 | 16:49:34 INFO - PROCESS | 1912 | [#4] audio-oscillator Cycles:10 Average:78.20 Median:79.50 stddev:12.03 (15.1%) stddev-sans-first:12.33 16:49:34 INFO - PROCESS | 1912 | Values: 87.0 72.0 94.0 69.0 67.0 70.0 89.0 68.0 69.0 97.0 16:49:34 INFO - PROCESS | 1912 | 16:49:34 INFO - PROCESS | 1912 | [#5] imaging-gaussian-blur Cycles:10 Average:86.40 Median:87.00 stddev:1.96 (2.2%) stddev-sans-first:1.99 16:49:34 INFO - PROCESS | 1912 | Values: 88.0 86.0 90.0 84.0 87.0 85.0 87.0 85.0 88.0 84.0 16:49:34 INFO - PROCESS | 1912 | 16:49:34 INFO - PROCESS | 1912 | [#6] imaging-darkroom Cycles:10 Average:102.20 Median:93.00 stddev:17.69 (19.0%) stddev-sans-first:18.52 16:49:34 INFO - PROCESS | 1912 | Values: 94.0 93.0 94.0 93.0 93.0 93.0 104.0 147.0 118.0 93.0 16:49:34 INFO - PROCESS | 1912 | 16:49:34 INFO - PROCESS | 1912 | [#7] imaging-desaturate Cycles:10 Average:96.20 Median:84.00 stddev:17.87 (21.3%) stddev-sans-first:18.95 16:49:34 INFO - PROCESS | 1912 | Values: 98.0 107.0 123.0 130.0 84.0 84.0 84.0 85.0 84.0 83.0 16:49:34 INFO - PROCESS | 1912 | 16:49:34 INFO - PROCESS | 1912 | [#8] json-parse-financial Cycles:10 Average:56.40 Median:57.00 stddev:1.58 (2.8%) stddev-sans-first:1.66 16:49:34 INFO - PROCESS | 1912 | Values: 57.0 54.0 54.0 55.0 58.0 58.0 58.0 57.0 57.0 56.0 16:49:34 INFO - PROCESS | 1912 | 16:49:34 INFO - PROCESS | 1912 | [#9] json-stringify-tinderbox Cycles:10 Average:79.20 Median:75.00 stddev:11.97 (16.0%) stddev-sans-first:11.85 16:49:34 INFO - PROCESS | 1912 | Values: 67.0 77.0 82.0 76.0 74.0 71.0 72.0 72.0 98.0 103.0 16:49:34 INFO - PROCESS | 1912 | 16:49:34 INFO - PROCESS | 1912 | [#10] stanford-crypto-aes Cycles:10 Average:84.90 Median:83.50 stddev:6.47 (7.8%) stddev-sans-first:6.86 16:49:34 INFO - PROCESS | 1912 | Values: 84.0 82.0 84.0 84.0 84.0 83.0 83.0 81.0 81.0 103.0 16:49:34 INFO - PROCESS | 1912 | 16:49:34 INFO - PROCESS | 1912 | [#11] stanford-crypto-ccm Cycles:10 Average:149.90 Median:152.00 stddev:14.53 (9.6%) stddev-sans-first:15.40 16:49:34 INFO - PROCESS | 1912 | Values: 151.0 153.0 155.0 127.0 145.0 156.0 148.0 141.0 183.0 140.0 16:49:34 INFO - PROCESS | 1912 | 16:49:34 INFO - PROCESS | 1912 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.00 Median:165.00 stddev:1.83 (1.1%) stddev-sans-first:1.94 16:49:34 INFO - PROCESS | 1912 | Values: 165.0 165.0 165.0 165.0 164.0 164.0 170.0 164.0 164.0 164.0 16:49:34 INFO - PROCESS | 1912 | 16:49:34 INFO - PROCESS | 1912 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.40 Median:82.50 stddev:7.97 (9.7%) stddev-sans-first:8.00 16:49:34 INFO - PROCESS | 1912 | Values: 69.0 67.0 68.0 69.0 72.0 85.0 83.0 86.0 82.0 83.0 16:49:34 INFO - PROCESS | 1912 | -------- Summary: end -------- 16:49:34 INFO - PROCESS | 1912 | 16:49:35 INFO - TEST-INFO | 1912: exit 0 16:49:35 INFO - TEST-OK | kraken | took 60554ms 16:49:35 INFO - SUITE-END | took 1181s 16:49:35 INFO - Completed test suite (00:19:41) 16:49:35 INFO - javascript benchmark 16:49:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3025.4554180247555, 3329.850156742946, 3352.0111567087706, 3359.664033596642, 3399.524066630672, 5710.7430165642545, 5786.392428794979, 5817.592768506206, 5830.892130495208, 5850.0, 5636.520895723858, 5669.177969194464, 5764.7982320618785, 5778.566607504433, 5795.246617939672, 5241.947580524203, 5256.553192978601, 5270.868228294285, 5272.947270527303, 5317.574594032488, 5161.17421212606, 5209.895802083956, 5217.165253559431, 5358.2766326546, 5371.274877891493, 5271.9472805272035, 5295.761690723926, 5299.125644268691, 5307.469253074681, 5316.282301889253, 5299.072662284104, 5299.867503312429, 5300.787968481295, 5306.097963346202, 5312.681239125664, 5168.173092305213, 5251.159814429711, 5264.6051546134195, 5268.841934741964, 5286.207068939651, 5787.305523337184, 5798.449147330997, 5807.709614519257, 5808.390119037483, 5810.244668193108, 5141.331626888518, 6173.074038894157, 6200.62796232223, 6203.751849925998, 6217.658028808413, 5207.244949482304, 6232.781852635136, 6273.654948977804, 6283.622982621012, 6318.968405157991, 5754.395788442237, 5805.651660900359, 5814.156947242668, 5816.0, 5817.621854579481, 6271.184745983084, 6283.0, 6288.993760998264, 6297.4962003039645, 6303.527235457359, 6076.422739839708, 6125.6017461851925, 6135.140800782589, 6141.1791743482045, 6145.139680444741, 6285.371462853769, 6298.1812364392335, 6308.495320374359, 6341.651209183539, 6359.0, 6278.811635650938, 6306.8423289417215, 6310.432061114522, 6311.779087731908, 6312.558120931582, 6119.07606531266, 6164.3835616438, 6185.226846644169, 6217.129601855744, 6231.781887633911, 4952.108620448282, 4952.603791696655, 4954.281629163823, 4971.304017437562, 4982.0, 4437.001674623184, 4452.0, 4469.687121901436, 4472.038511719996, 4501.887452813706, 4514.164879497303, 5456.61803673747, 5705.62913410627, 5717.971410143005, 5733.0540460823495, 6272.6236425814595, 6311.873762524724, 6312.968435157794, 6331.556791024676, 6342.397472240222, 5820.447057529528, 5838.182654428383, 5848.0, 5850.23946886902, 5866.178734977107, 5322.175062865262, 5375.301210842644, 5433.592480563894, 5444.183372494158, 5457.590680698963, 5303.655262407931, 6222.937770622417, 6255.96872015637, 6257.9687101564205, 6272.247330320391, 6126.693665316806, 6306.842328941813, 6311.305756366888, 6328.841778955563, 6334.018227174795, 6168.414000669929, 6279.843003924756, 6295.527835412271, 6327.683615819283, 6430.710618022106, 6128.4790792783115, 6144.341027922346, 6154.692265386623, 6207.193064901534, 6260.74957001733, 4818.638602104786, 5154.762856914389, 6193.690315484298, 6259.061140828912, 6261.74953001893, 6061.636301821904, 6083.66539840313, 6095.1771510845465, 6102.786402475892, 6110.236220472441, 6114.6636934969, 6124.14262003311, 6124.418180272867, 6125.234345706786, 6129.785457508966, 5918.496927761188, 5922.2597175353085, 5923.200367950444, 5928.407159284037, 5941.0, 6015.218021657157, 6093.9390606093375, 6103.511719062553, 6108.144859719731, 6132.8773424532155, 5804.1293805929445, 5949.196858424058, 5953.76184952597, 5961.135635332939, 5987.850303742442], "value": 5693.44920738633, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [500.69707826764807, 510.61877621035103, 513.6276199755175, 516.5944733384295, 548.914918187681, 37982.34123611348, 39530.80234598827, 39619.80190099045, 39738.60261397385, 39933.40099898504, 33183.502247466306, 40919.0, 40926.7953660232, 40981.59018409815, 41061.58938410615, 8375.539345336005, 9172.2203612693, 9349.485778282191, 9362.485063321514, 9363.204127649124, 8530.957345213264, 8627.827443451128, 8628.784280393009, 8630.093840146788, 8633.13668633138, 11299.943500282487, 11550.018248448892, 11576.47905844239, 11583.826242606368, 11585.826212606818, 11213.046891014274, 11306.830397544043, 11313.981741643292, 11336.149788765835, 11341.149413793999, 8345.95827020861, 8576.871346929802, 8593.14068593142, 11125.0, 11230.876912308704, 31101.688983110333, 31389.843050784828, 38068.80965595182, 38288.80855595704, 38485.0, 3252.4796032634663, 3457.7060949819297, 3776.066128123256, 3814.0464883779055, 3868.2843673920415, 27776.444471110466, 27904.02335918233, 29226.0, 29257.268568285963, 30646.69353306461, 3148.7795854290207, 3190.122716253026, 3198.4562624353684, 3261.869525218988, 3284.310294838087, 507308.0, 509182.0, 510220.0, 510925.4453727745, 511244.0, 32544.0, 33471.3305733884, 33478.83260583682, 56889.29332120043, 57626.7118664404, 34488.48267275993, 34684.0, 34752.30495390078, 34860.9541713749, 34956.0, 13860.792088118686, 14021.018528703096, 14033.578992630237, 14042.789358159636, 14072.155670659793, 11408.429578521042, 11613.6515904523, 11706.239094458833, 11724.88275117238, 11731.120165987584, 7176.7488137914925, 7357.668904899292, 7365.079365079365, 7551.169371369145, 7707.499012564165, 3174.53969174473, 3178.888738894174, 3196.1530194498478, 3203.1831882870183, 3235.4499735045097, 881.9029906710257, 897.7351681253915, 984.601236499219, 1005.4419797012582, 1008.7478130467383, 25605.61591576128, 28096.29759256035, 28682.856585716938, 29354.412911741645, 29432.11703648894, 978.7650963768644, 1001.5643195210107, 1004.9748756281006, 1031.7678522332415, 1038.89611038897, 12345.876541234653, 14512.056716313617, 14931.328090235966, 14987.32557034936, 15369.538913832379, 708054.0, 711397.0, 712123.0, 713589.0, 715055.0, 47888.042239155024, 48995.755021224664, 55269.44730552723, 55684.0, 56409.7179514108, 1331.1414137881013, 1338.110156745753, 1366.7881478370869, 1367.2001878900955, 1369.000629540441, 29278.82884684589, 30065.69934300629, 30264.394712105193, 30423.54364684487, 31142.377152457277, 52385.47614523958, 53887.73056134851, 54163.72918135384, 57186.7140664294, 57237.42762572488, 678562.0, 697979.0, 884446.0, 885454.0, 889568.0, 38307.425388618634, 38609.0, 38922.2215555693, 39020.82937511879, 39302.60697393104, 42197.0, 42794.0, 42862.57137428586, 43312.91717707083, 43546.564534355515, 36171.45742813807, 36334.0, 36505.90482285536, 36633.26733465263, 36696.449553256185, 30071.248218794706, 36118.09704757402, 36241.45637815488, 36443.63556364403, 36462.63537364592, 35435.64564354427, 35826.0, 35952.64047359493, 36259.818700906326, 36323.45514817227, 30867.845660771553, 32665.0, 32730.67269327276, 32898.67101328956, 32931.67068329286, 20077.899610501852, 24357.51284974326, 24559.631605525574, 24843.0, 25026.874865625556], "value": 18534.356284892187, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [314.6633102580239, 350.5985646434831, 374.9475073489712, 377.47719408619065, 401.7569370530829, 14572.0, 14867.40530378785, 16754.910930789505, 19091.331803386885, 20651.69022464664, 20044.298449554233, 20632.793672063275, 20823.16707331705, 20827.375178744624, 21047.0, 4548.385967894334, 5719.914201286984, 5947.643141411508, 5984.401559844003, 6011.428914253139, 5658.632188907728, 5869.7652093916195, 5965.582409231356, 5982.700864956757, 5984.311804142514, 5783.190353350513, 5825.533957283428, 5892.558058145656, 5908.468237858592, 5916.083007133901, 5659.688717120577, 5874.060150375919, 5883.117532370136, 5892.793752218673, 5899.705014749266, 5022.4726403727445, 5185.325907631984, 5190.480951904817, 5194.844154675366, 5216.400113986902, 12649.620511384674, 13233.735325293537, 13243.20540767557, 13251.933740331237, 14539.927300363537, 2179.182806447582, 2180.618391781432, 2294.5640328337563, 2319.3158018384615, 2725.9454810903867, 9709.805803883954, 9898.10917017472, 10213.234007449397, 10318.484075796181, 14522.128672279592, 1779.3060706324543, 1792.4353828543967, 1858.7118996555555, 1860.5348662834292, 1867.0384751852807, 88718.11281887228, 111208.0, 114898.0, 124680.0, 128180.0, 10638.893611063944, 10802.189835762349, 10804.027637512661, 10924.563017479371, 11492.0, 10515.0, 13365.39855706476, 13404.329783510786, 13759.518416855362, 13788.862111378758, 6976.790696279119, 7478.700851965969, 7505.737299194502, 7650.4644674872225, 8020.759377218693, 8170.632321545545, 8278.461899976483, 8309.127541608044, 8455.323574114056, 8474.491530508189, 4442.333649952533, 4521.982553925341, 4546.158960592301, 4587.655925805511, 4642.883927901829, 1740.7562941188244, 1783.1351794379716, 1838.8253115953962, 1840.6226723521645, 1864.850811935042, 816.6243527977197, 908.68650315641, 938.5870217104589, 951.5860600638745, 965.8068386322764, 19446.80553194478, 20241.59516809685, 20308.0, 20318.390448286576, 20674.689879651818, 832.5587438657521, 860.7891066688576, 897.6274845938863, 921.4839689773748, 939.9201067909304, 10313.690589282334, 10343.896561034444, 10802.567897284021, 10934.07060399859, 10946.397948112772, 565767.0, 567593.0, 581784.0, 591788.0, 594949.0, 128747.0, 130664.0, 133337.0, 135369.0, 137584.0, 990.5740531571494, 997.5660587644398, 1041.234692501011, 1046.1055797293204, 1050.24907191357, 16407.917960410123, 16874.818762686493, 17552.56118597045, 17999.0, 18319.175637096097, 43703.562964371224, 52874.73562632316, 53257.402277931724, 53520.0, 56426.717866412044, 82617.58691206506, 83759.5812020936, 84400.57799710962, 85766.0, 86502.13497865193, 6909.378155965987, 7305.488615796843, 7308.488405811542, 7541.660625271986, 7811.140774514913, 10844.0, 12448.502059917864, 12491.063170262085, 12787.61637150887, 12943.870561294267, 8774.692885748967, 10827.945860270649, 10996.505157268102, 11213.271137376045, 11248.831267531157, 10847.566097356017, 10964.451777411257, 10966.616168434066, 11222.046126079049, 11263.774724505627, 10931.83602245951, 10958.068564172134, 11060.225784195029, 11078.0, 11516.0, 7188.964055179691, 7266.200717920919, 7415.369693576106, 7481.326680598772, 7587.2412758723685, 10249.436281004617, 10801.567937282422, 10947.397893115636, 11017.898210178919, 11099.944500277446], "value": 9146.736527883451, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1441.2361448432334, 1472.7201831651987, 1604.8475394837494, 1788.079139243289, 1791.6685413198559, 8192.467489613171, 8535.487870727746, 8570.700025499102, 8572.971243450795, 8573.971123465186, 7449.66476508557, 7727.4204434667345, 7730.342920851735, 8038.196180381959, 8257.587120643973, 2296.8851557422145, 2330.0912644068826, 2334.0, 2336.8714720690355, 2337.4506990857135, 2261.9886900565475, 2280.3728974532055, 2309.6189128793812, 2310.90756369745, 2315.0045480443405, 2047.8361731061555, 2129.1376992318137, 2129.2015494189677, 2188.4857058591297, 2291.9083236670513, 2245.6070187717164, 2291.472961218912, 2294.804941579951, 2295.747467778535, 2301.8158547316175, 2309.9422514437106, 2357.2456813819585, 2366.2546297916274, 2373.2524254859836, 2374.608189648706, 7044.612546309951, 8165.67337306507, 8347.582620868992, 8348.165183481664, 8356.791080222982, 5040.823571174991, 5116.437191908887, 5120.6927584344685, 5188.92216616751, 5195.064888320101, 3294.5387645729616, 3366.175287054663, 3454.1709989602564, 3516.3494753470436, 3524.8942531724088, 1059.311447559085, 1077.8975997280245, 1083.6911480228175, 1090.4547726136932, 1097.4786976186329, 10455.634052808115, 10741.677749667522, 10919.890801091968, 11065.225434219608, 11085.445727713583, 4482.125985432875, 4545.204644165536, 4652.651051171176, 4670.182718024332, 4676.836310729176, 9491.38306010107, 9491.525423728786, 9500.287478439144, 9516.095970882756, 9519.857202141973, 5224.50367215114, 5237.188235823453, 5237.73811309433, 5238.109521381374, 5242.082635538765, 4596.4714057883775, 4798.5441383068555, 4804.183288840905, 4816.20532612115, 4834.347363105936, 3711.7030637548933, 3726.5341832270965, 3757.3612485877466, 3796.3925771876366, 3828.406596977473, 1211.5820042085643, 1227.9508819647115, 1229.065909908476, 1241.975160496785, 1245.3212998915612, 611.7675283392282, 750.9586972716444, 768.5696010234286, 771.5524995502595, 829.3033851564594, 5488.91766623501, 5497.8075767347955, 5555.77776888928, 5569.693666848281, 5804.9419505805245, 879.560219890055, 889.7775556110972, 919.7424721078108, 940.1397721085131, 949.928755343352, 2973.3755911258445, 3730.4963829883163, 3806.4861243731743, 3846.4038074098557, 3916.6475017248586, 9036.096390360912, 9710.75723106928, 10030.498475076218, 10114.949425252827, 10266.435346056038, 10024.298299119138, 10236.232282578836, 10245.282830201815, 10254.487275636338, 10257.846132308021, 7663.655135518804, 7993.400494962901, 7994.5602991836, 8007.439479236397, 8016.198380161937, 5546.417626149197, 5581.6930068846605, 5612.298462692163, 5824.5631577631, 5897.026990546512, 9327.486988215713, 9357.812843743222, 9467.053294670477, 9490.762730931505, 9493.24054075686, 9179.0, 9479.47862867549, 9487.525623718924, 9530.61877524919, 9726.076022777825, 7125.643717813985, 7193.992841002159, 7241.782746517613, 7264.273572642694, 7291.197968223385, 7767.805804854924, 7778.338841198562, 7791.0, 7791.104023037196, 7850.921490785247, 7048.436125110082, 7111.893321600283, 7130.268142168331, 7232.963835180791, 7312.415006799337, 6923.115351951003, 6982.476314276348, 7009.439244860501, 7057.36483716468, 7068.929310706827, 6537.673116344069, 6681.164854393201, 6689.364510371507, 6689.49828762835, 6699.229588597179, 4841.588464980516, 4874.512548745097, 4883.121038213156, 4924.63065270099, 4950.8267210647455, 4877.756112194305, 4883.145449546244, 4906.0433215522635, 5006.299118123538, 5009.599232061353], "value": 4399.658234111344, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [296.83377308707117, 297.73055384876693, 303.7682198684335, 309.16525381470035, 311.268518980396, 3933.3903244997, 4971.825986090487, 5028.597712183026, 5045.243213517974, 5101.872453188672, 4108.650764685005, 4435.201663700533, 4826.0, 4937.950620493794, 5000.824971126011, 3393.915152121192, 3887.7667339959553, 3893.9610603894025, 3898.512685914254, 3912.1784425270585, 3030.6060212172497, 3277.475603903376, 3827.10063135163, 3858.459815625815, 3890.474785903895, 3149.16603014373, 3374.7300215982787, 3825.713071519647, 3841.2125514269646, 3889.961100388988, 3832.310184166849, 3842.346801043829, 3856.3058649443087, 3863.632954869283, 3874.593167717377, 3367.8821241256437, 3383.2387712764676, 3392.4741665041956, 3397.8980630581123, 3405.3189362127673, 4344.391785150082, 4432.734035957853, 4742.691725037862, 4860.416749990024, 4907.460179380265, 4199.622034016923, 4361.520232774392, 4385.276429389147, 4401.163778882003, 4404.625606823424, 4088.795560221977, 4296.5059018213, 4457.465104187486, 5237.423883372826, 5246.0, 3277.6394596594355, 3563.3585954528176, 3596.6403359663823, 3716.8513259469587, 3726.441033844918, 5449.04641687703, 5456.7544460498975, 5485.6982865942355, 5495.279836480928, 5567.860803479945, 3170.25499007733, 3761.247750449921, 3761.3229618668356, 3763.5860055393887, 3786.807155745931, 3343.9665603343656, 3378.0, 3433.8881587823807, 3810.599887011879, 3834.581204954129, 3573.749837511349, 3679.9448008279896, 4219.409282700424, 4423.712458690175, 4437.534058923831, 4458.4426946631675, 5167.767450464738, 5230.895382092337, 5237.109691352479, 5257.684538927677, 1038.3354653022072, 1167.9708007299885, 1221.3893053473264, 1223.7980733179104, 1224.2287358963795, 749.865024295632, 806.5443024691031, 808.7989335836278, 841.4993079117914, 855.7078810995441, 4455.710378825366, 4608.654350923627, 4615.23848564983, 4671.416072990876, 4693.976530117396, 4707.552782485658, 4718.9292160617615, 4950.628702847301, 5036.79852805891, 5071.4928507149725, 4130.463039804806, 4490.2142125127975, 4634.096351211549, 4993.750312484434, 5051.348042277834, 2291.9541609167723, 2332.4518738096535, 2660.414708764069, 2661.8136730429073, 2673.9598906016427, 4587.72473651582, 5146.382434107931, 5217.921731173959, 5252.5535329497425, 5286.074936886106, 4306.224879521654, 4857.70093467284, 4969.08690154286, 5189.351331083614, 5271.128749294022, 4743.407074115736, 5258.949009886148, 5269.892043182835, 5279.762410691451, 5292.0, 4237.050956560017, 5042.798288068436, 5067.315912351785, 5087.694738315713, 5118.978003688597, 4098.508179018418, 4184.958150418457, 5007.899842003212, 5389.461053894579, 5489.533389661764, 5157.8452646420665, 5560.944390556205, 5567.164925261243, 5671.319441666861, 5693.4875861172695, 3281.3765384576855, 3598.208394153282, 3712.4431335299923, 3742.8877133686033, 3743.0242956848824, 4655.86032419028, 5224.843254702365, 5392.757325920422, 5483.643563168382, 5545.72271386421, 4428.977855110704, 5285.894282114413, 5345.812896548602, 5386.757595908115, 5387.353517577916, 5365.624406291522, 5368.355797304349, 5373.220882972026, 5374.951884382508, 5391.32608423947, 4082.693797965105, 4286.5070516890955, 4977.751112444435, 5065.0, 5257.39539952895, 4783.736894470699, 4971.1797553403285, 5243.6329456939175, 5248.60635452335, 5371.206972374265, 5227.477252274894, 5235.476452354734, 5250.0574787327405, 5252.711100889488, 5256.135406203552], "value": 3782.757910633945, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [687.7902239816854, 702.982425439364, 704.0882057735236, 731.6159016516332, 746.5035751225433, 12288.692782680426, 14019.299035048245, 14673.853261467382, 14675.559733207994, 14694.485693000746, 12866.742665146714, 12895.548655797049, 12936.417861196245, 12944.546940857072, 12945.870541294584, 651.2413038809805, 671.6205343980636, 800.7957970717459, 815.1033862750971, 833.9166083391673, 828.8922440082781, 829.0341751859107, 833.4374297349295, 837.3845223760532, 838.5220424358118, 884.1158841158841, 884.1953822021961, 886.4149661223594, 889.715291106846, 891.8974317953454, 686.5674624986227, 709.1844378964181, 878.8242351529719, 880.9427387219811, 882.2016075451727, 625.3184029407967, 646.9870602587922, 650.4471199480452, 651.1925212736173, 651.989416404571, 14057.016008879382, 14114.294285285694, 14115.858841411557, 14120.940929430231, 14122.929385353007, 12971.610851674464, 13047.282399468026, 13273.20811690419, 13319.334033298393, 13378.331083445788, 11523.366214858179, 11743.882561174365, 12006.579769708018, 12091.45588448522, 12263.448144833505, 4182.707210495267, 4544.818207271738, 4547.18150732868, 4562.315652652095, 4592.47186573545, 94727.52636236843, 94915.0, 95715.52142239314, 95869.9999999993, 95936.52031739797, 10482.895171048192, 10544.894551054545, 10601.57593696259, 10646.254762166729, 10768.56925722978, 20114.39656810298, 20290.898545507178, 20358.898205508878, 20368.388948331572, 20385.796142038686, 7208.459365547605, 7339.1926888043345, 7356.816079597946, 7450.739224127129, 7584.8483030340185, 4116.75299482032, 4154.439150714615, 4237.449131612871, 4287.721298115613, 4347.456567929009, 3247.4966380211104, 3286.194882253863, 3332.8333583320737, 3359.983200083984, 3372.0389688939026, 537.7096367961335, 622.7540121652013, 627.4980015987192, 640.1617250673884, 652.3378770547897, 524.8373004368658, 540.7917951588588, 573.6357413042665, 609.065085094378, 609.3084349263529, 7120.323569260912, 8163.67345306193, 8695.826083478294, 8719.346049046348, 8739.43193692408, 495.95784358329945, 565.8443927919773, 606.2936678769273, 610.8167549735061, 641.8106658535743, 3842.9807850961124, 3924.7448915820382, 3981.900452488711, 4003.5657686212708, 4059.4113853491667, 79822.0, 89365.87219447287, 115393.26910096355, 118354.0, 120238.79761202451, 84854.0, 96361.5545766814, 99582.0, 102885.97114028914, 103433.48283258536, 70235.0, 84063.5796821012, 86544.56727716321, 88785.55607221923, 88865.55567222252, 6655.866882662416, 7772.805679857823, 7794.1816109309975, 7951.960240198762, 7968.920310796818, 55850.44149558615, 56175.876482470936, 56639.43360566341, 57353.713231433576, 59206.11190832227, 67321.66339168469, 79604.80592790997, 79651.60174199092, 80800.59599702198, 82526.0, 10240.48797560134, 10595.205359597907, 10736.087432568007, 10820.917908209116, 10832.566697332333, 9293.0, 10456.47717614101, 11683.824742628698, 11833.52665893389, 11953.282803031845, 8774.868376974478, 10706.89293107059, 11373.54505819758, 12267.509299628271, 12523.56167534132, 12789.8081528779, 12916.22502649844, 13173.341332933507, 13184.208947463183, 13492.59522214335, 10227.335223210468, 10716.785664286515, 12074.335911524951, 12169.330686811956, 12437.93781031089, 10353.63762268317, 10636.295180722904, 10704.839427408751, 10819.18856085781, 10946.890531094587, 10617.150627949592, 10935.890641093487, 10941.398223097487, 10955.452227388758, 11036.944815276194], "value": 7062.424746050507, "unit": "score"}], "name": "dromaeo_css", "value": 6957.844028674723, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 114.0, 112.0, 112.0, 115.0, 113.0, 114.0, 113.0, 116.0], "value": 113.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 110.0, 110.0, 143.0, 111.0, 109.0, 109.0, 115.0, 174.0, 138.0], "value": 126.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 182.0, 183.0, 186.0, 185.0, 184.0, 183.0, 183.0, 184.0, 185.0], "value": 183.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 78.0, 83.0, 110.0, 63.0, 58.0, 59.0, 57.0, 84.0, 57.0], "value": 73.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 72.0, 94.0, 69.0, 67.0, 70.0, 89.0, 68.0, 69.0, 97.0], "value": 78.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 90.0, 84.0, 87.0, 85.0, 87.0, 85.0, 88.0, 84.0], "value": 86.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 94.0, 93.0, 93.0, 93.0, 104.0, 147.0, 118.0, 93.0], "value": 102.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 107.0, 123.0, 130.0, 84.0, 84.0, 84.0, 85.0, 84.0, 83.0], "value": 96.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 54.0, 54.0, 55.0, 58.0, 58.0, 58.0, 57.0, 57.0, 56.0], "value": 56.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 77.0, 82.0, 76.0, 74.0, 71.0, 72.0, 72.0, 98.0, 103.0], "value": 79.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 82.0, 84.0, 84.0, 84.0, 83.0, 83.0, 81.0, 81.0, 103.0], "value": 84.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 153.0, 155.0, 127.0, 145.0, 156.0, 148.0, 141.0, 183.0, 140.0], "value": 149.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 165.0, 165.0, 165.0, 164.0, 164.0, 170.0, 164.0, 164.0, 164.0], "value": 165.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 68.0, 69.0, 72.0, 85.0, 83.0, 86.0, 82.0, 83.0], "value": 76.4, "unit": "score"}], "name": "kraken", "value": 1472.0000000000002, "alertThreshold": 2.0}]} 16:49:35 INFO - Return code: 0 16:49:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:49:35 INFO - # TBPL SUCCESS # 16:49:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:49:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:49:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:49:35 INFO - 2017-01-02 16:49:35.675 screenresolution[1916:22704] starting screenresolution argv=screenresolution get 16:49:35 INFO - 2017-01-02 16:49:35.678 screenresolution[1916:22704] Display 0: 1600x1200x32@60 16:49:35 INFO - 2017-01-02 16:49:35.684 screenresolution[1917:22708] starting screenresolution argv=screenresolution list 16:49:35 INFO - Available Modes on Display 0 16:49:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:49:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:49:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:49:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:49:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:49:35 INFO - Intel Iris: 16:49:35 INFO - Chipset Model: Intel Iris 16:49:35 INFO - Type: GPU 16:49:35 INFO - Bus: Built-In 16:49:35 INFO - VRAM (Dynamic, Max): 1536 MB 16:49:35 INFO - Vendor: Intel (0x8086) 16:49:35 INFO - Device ID: 0x0a2e 16:49:35 INFO - Revision ID: 0x0009 16:49:35 INFO - Displays: 16:49:35 INFO - Display: 16:49:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:49:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:49:35 INFO - Display Serial Number: 1600x1200 60 16:49:35 INFO - Main Display: Yes 16:49:35 INFO - Mirror: Off 16:49:35 INFO - Online: Yes 16:49:35 INFO - Rotation: Supported 16:49:35 INFO - Return code: 0 16:49:35 INFO - Running post-action listener: _resource_record_post_action 16:49:35 INFO - [mozharness: 2017-01-03 00:49:35.929166Z] Finished run-tests step (success) 16:49:35 INFO - Running post-run listener: _resource_record_post_run 16:49:35 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 581632; Write bytes: 658018816; Read time: 8; Write time: 1891 16:49:35 INFO - TinderboxPrint: CPU usage
53.4% 16:49:35 INFO - TinderboxPrint: I/O read bytes / time
581,632 / 8 16:49:35 INFO - TinderboxPrint: I/O write bytes / time
658,018,816 / 1,891 16:49:35 INFO - TinderboxPrint: CPU idle
2,261.3 (46.6%) 16:49:35 INFO - TinderboxPrint: CPU system
973.2 (20.1%) 16:49:35 INFO - TinderboxPrint: CPU user
1,619.3 (33.4%) 16:49:35 INFO - TinderboxPrint: Swap in / out
349,229,056 / 0 16:49:35 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 346026496; Write bytes: 344868352; Read time: 26799; Write time: 718 16:49:35 INFO - run-tests - Wall time: 1183s; CPU: 53.0%; Read bytes: 581632; Write bytes: 306072576; Read time: 8; Write time: 1143 16:49:36 INFO - Running post-run listener: _upload_blobber_files 16:49:36 INFO - Blob upload gear active. 16:49:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:49:36 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:49:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:49:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:49:36 INFO - (blobuploader) - INFO - Open directory for files ... 16:49:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:49:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:49:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:49:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:49:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:49:37 INFO - (blobuploader) - INFO - Done attempting. 16:49:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:49:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:49:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:49:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:49:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:49:38 INFO - (blobuploader) - INFO - Done attempting. 16:49:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:49:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:49:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:49:40 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:49:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:49:40 INFO - (blobuploader) - INFO - Done attempting. 16:49:40 INFO - (blobuploader) - INFO - Iteration through files over. 16:49:40 INFO - Return code: 0 16:49:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:49:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:49:40 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/733792d2a82d2f0fe05e48ab912b91191f9c51e03a10952fa4d92211cf24f841510304bb8221dd98d0df95125a5e033828f7e638084c82e480b82e19e0ed6f43", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1554d54ef39e908d9188a15a4f3bc2b81634eeb9ddeeb3e7df005c869b6e3287eda69f9e0d33ddb9d8abdc5bba1880eaa27fddcc0c754d483caf67077d8e7cf4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/383227fbc32acdb9931e5e9e166e9a7899865059b897f9a275b85a44950cb842fc8d4c39fd3eb56053f251f715135c85cc7c26c1d3fbae1dd7dfbf02cf95adca"} 16:49:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:49:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:49:40 INFO - Contents: 16:49:40 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/733792d2a82d2f0fe05e48ab912b91191f9c51e03a10952fa4d92211cf24f841510304bb8221dd98d0df95125a5e033828f7e638084c82e480b82e19e0ed6f43", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1554d54ef39e908d9188a15a4f3bc2b81634eeb9ddeeb3e7df005c869b6e3287eda69f9e0d33ddb9d8abdc5bba1880eaa27fddcc0c754d483caf67077d8e7cf4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/383227fbc32acdb9931e5e9e166e9a7899865059b897f9a275b85a44950cb842fc8d4c39fd3eb56053f251f715135c85cc7c26c1d3fbae1dd7dfbf02cf95adca"} 16:49:40 INFO - Running post-run listener: copy_logs_to_upload_dir 16:49:40 INFO - Copying logs to upload dir... 16:49:40 INFO - mkdir: /builds/slave/test/build/upload/logs 16:49:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.132207 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-01-02 16:49:40.779855) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-02 16:49:40.783097) ========= 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.Ek4g7fephP/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.ZMIsonx6cb/Listeners TMPDIR=/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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/733792d2a82d2f0fe05e48ab912b91191f9c51e03a10952fa4d92211cf24f841510304bb8221dd98d0df95125a5e033828f7e638084c82e480b82e19e0ed6f43", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1554d54ef39e908d9188a15a4f3bc2b81634eeb9ddeeb3e7df005c869b6e3287eda69f9e0d33ddb9d8abdc5bba1880eaa27fddcc0c754d483caf67077d8e7cf4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/383227fbc32acdb9931e5e9e166e9a7899865059b897f9a275b85a44950cb842fc8d4c39fd3eb56053f251f715135c85cc7c26c1d3fbae1dd7dfbf02cf95adca"} build_url:https://queue.taskcluster.net/v1/task/K9_jxa-JRp-Bnbxsw8D-SQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010868 build_url: 'https://queue.taskcluster.net/v1/task/K9_jxa-JRp-Bnbxsw8D-SQ/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/733792d2a82d2f0fe05e48ab912b91191f9c51e03a10952fa4d92211cf24f841510304bb8221dd98d0df95125a5e033828f7e638084c82e480b82e19e0ed6f43", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1554d54ef39e908d9188a15a4f3bc2b81634eeb9ddeeb3e7df005c869b6e3287eda69f9e0d33ddb9d8abdc5bba1880eaa27fddcc0c754d483caf67077d8e7cf4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/383227fbc32acdb9931e5e9e166e9a7899865059b897f9a275b85a44950cb842fc8d4c39fd3eb56053f251f715135c85cc7c26c1d3fbae1dd7dfbf02cf95adca"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-02 16:49:40.817841) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-02 16:49:40.818210) ========= 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.Ek4g7fephP/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.ZMIsonx6cb/Listeners TMPDIR=/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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.005064 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-02 16:49:40.866797) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-02 16:49:40.867154) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-02 16:49:40.867520) ========= ========= Total master_lag: 0.19 =========