builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0263 starttime: 1487952409.85 results: success (0) buildid: 20170224065122 builduid: 31fd9f5324b54c6ca743a5e581c174e1 revision: eeeb9c132d0ed10d0f76f51a4b25cfb2f76bd671 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-24 08:06:49.845689) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-24 08:06:49.846143) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-24 08:06:49.846420) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-24 08:06:49.872880) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-24 08:06:49.873203) ========= 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.3FyvZ22uM2/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.kSFsaAohjP/Listeners TMPDIR=/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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-02-24 08:06:49-- 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% 19.7M=0.001s 2017-02-24 08:06:50 (19.7 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.130028 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-24 08:06:50.027285) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-24 08:06:50.027597) ========= 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.3FyvZ22uM2/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.kSFsaAohjP/Listeners TMPDIR=/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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.026549 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-24 08:06:50.077848) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-02-24 08:06:50.078242) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev eeeb9c132d0ed10d0f76f51a4b25cfb2f76bd671 --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 eeeb9c132d0ed10d0f76f51a4b25cfb2f76bd671 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.3FyvZ22uM2/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.kSFsaAohjP/Listeners TMPDIR=/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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-02-24 08:06:50,185 truncating revision to first 12 chars 2017-02-24 08:06:50,185 Setting DEBUG logging. 2017-02-24 08:06:50,185 attempt 1/10 2017-02-24 08:06:50,185 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/eeeb9c132d0e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-24 08:06:51,476 unpacking tar archive at: mozilla-aurora-eeeb9c132d0e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.561652 ========= master_lag: 1.77 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-02-24 08:06:53.412308) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-24 08:06:53.412629) ========= script_repo_revision: eeeb9c132d0ed10d0f76f51a4b25cfb2f76bd671 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-24 08:06:53.413047) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-24 08:06:53.413317) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-24 08:06:53.433878) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-02-24 08:06:53.434248) ========= /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.3FyvZ22uM2/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.kSFsaAohjP/Listeners TMPDIR=/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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 08:06:53 INFO - MultiFileLogger online at 20170224 08:06:53 in /builds/slave/test 08:06:53 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 08:06:53 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:06:53 INFO - {'append_to_log': False, 08:06:53 INFO - 'base_work_dir': '/builds/slave/test', 08:06:53 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:06:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:06:53 INFO - 'branch': 'Mozilla-Aurora', 08:06:53 INFO - 'buildbot_json_path': 'buildprops.json', 08:06:53 INFO - 'config_files': ('talos/mac_config.py',), 08:06:53 INFO - 'default_actions': ('clobber', 08:06:53 INFO - 'read-buildbot-config', 08:06:53 INFO - 'download-and-extract', 08:06:53 INFO - 'populate-webroot', 08:06:53 INFO - 'create-virtualenv', 08:06:53 INFO - 'install', 08:06:53 INFO - 'run-tests'), 08:06:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:06:53 INFO - 'download_minidump_stackwalk': True, 08:06:53 INFO - 'download_symbols': 'ondemand', 08:06:53 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:06:53 INFO - 'tooltool.py': '/tools/tooltool.py', 08:06:53 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:06:53 INFO - '/tools/misc-python/virtualenv.py')}, 08:06:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:06:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:06:53 INFO - 'installer_path': 'installer.exe', 08:06:53 INFO - 'log_level': 'info', 08:06:53 INFO - 'log_name': 'talos', 08:06:53 INFO - 'log_to_console': True, 08:06:53 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:06:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:06:53 INFO - 'opt_config_files': (), 08:06:53 INFO - 'pip_index': False, 08:06:53 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:06:53 INFO - 'cmd': ('bash', 08:06:53 INFO - '-c', 08:06:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:06:53 INFO - 'enabled': True, 08:06:53 INFO - 'halt_on_failure': False, 08:06:53 INFO - 'name': 'check_screen_resolution'},), 08:06:53 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:06:53 INFO - 'cmd': ('bash', 08:06:53 INFO - '-c', 08:06:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:06:53 INFO - 'enabled': True, 08:06:53 INFO - 'halt_on_failure': False, 08:06:53 INFO - 'name': 'check_screen_resolution'},), 08:06:53 INFO - 'run_cmd_checks_enabled': True, 08:06:53 INFO - 'sps_profile': False, 08:06:53 INFO - 'sps_profile_interval': 0, 08:06:53 INFO - 'suite': 'dromaeojs', 08:06:53 INFO - 'system_bits': '32', 08:06:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:06:53 INFO - 'title': 't-yosemite-r7-0263', 08:06:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:06:53 INFO - 'use_talos_json': True, 08:06:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:06:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:06:53 INFO - 'work_dir': 'build'} 08:06:53 INFO - [mozharness: 2017-02-24 16:06:53.646235Z] Running clobber step. 08:06:53 INFO - Running pre-action listener: _resource_record_pre_action 08:06:53 INFO - Running main action method: clobber 08:06:53 INFO - rmtree: /builds/slave/test/build 08:06:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:06:56 INFO - Running post-action listener: _resource_record_post_action 08:06:56 INFO - [mozharness: 2017-02-24 16:06:56.002232Z] Finished clobber step (success) 08:06:56 INFO - [mozharness: 2017-02-24 16:06:56.002452Z] Running read-buildbot-config step. 08:06:56 INFO - Running pre-action listener: _resource_record_pre_action 08:06:56 INFO - Running main action method: read_buildbot_config 08:06:56 INFO - Using buildbot properties: 08:06:56 INFO - { 08:06:56 INFO - "project": "", 08:06:56 INFO - "product": "firefox", 08:06:56 INFO - "script_repo_revision": "production", 08:06:56 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 08:06:56 INFO - "repository": "", 08:06:56 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 08:06:56 INFO - "buildid": "20170224065122", 08:06:56 INFO - "pgo_build": "False", 08:06:56 INFO - "basedir": "/builds/slave/test", 08:06:56 INFO - "buildnumber": 40, 08:06:56 INFO - "slavename": "t-yosemite-r7-0263", 08:06:56 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 08:06:56 INFO - "platform": "yosemite_r7", 08:06:56 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 08:06:56 INFO - "branch": "mozilla-aurora", 08:06:56 INFO - "repo_path": "releases/mozilla-aurora", 08:06:56 INFO - "revision": "eeeb9c132d0ed10d0f76f51a4b25cfb2f76bd671", 08:06:56 INFO - "stage_platform": "macosx64", 08:06:56 INFO - "builduid": "31fd9f5324b54c6ca743a5e581c174e1", 08:06:56 INFO - "slavebuilddir": "test" 08:06:56 INFO - } 08:06:56 INFO - Found installer url https://queue.taskcluster.net/v1/task/GCi8I4R0T_iT7Vb1p6Sy-g/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg. 08:06:56 INFO - Running post-action listener: _resource_record_post_action 08:06:56 INFO - [mozharness: 2017-02-24 16:06:56.008556Z] Finished read-buildbot-config step (success) 08:06:56 INFO - [mozharness: 2017-02-24 16:06:56.008821Z] Running download-and-extract step. 08:06:56 INFO - Running pre-action listener: _resource_record_pre_action 08:06:56 INFO - Running main action method: download_and_extract 08:06:56 INFO - mkdir: /builds/slave/test/build/tests 08:06:56 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')]} 08:06:56 INFO - trying https://queue.taskcluster.net/v1/task/GCi8I4R0T_iT7Vb1p6Sy-g/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json 08:06:56 INFO - Downloading https://queue.taskcluster.net/v1/task/GCi8I4R0T_iT7Vb1p6Sy-g/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 08:06:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GCi8I4R0T_iT7Vb1p6Sy-g/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json'}, attempt #1 08:06:57 INFO - Downloaded 1199 bytes. 08:06:57 INFO - Reading from file /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 08:06:57 INFO - Using the following test package requirements: 08:06:57 INFO - {u'common': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 08:06:57 INFO - u'cppunittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 08:06:57 INFO - u'firefox-53.0a2.en-US.mac.cppunittest.tests.zip'], 08:06:57 INFO - u'gtest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 08:06:57 INFO - u'firefox-53.0a2.en-US.mac.gtest.tests.zip'], 08:06:57 INFO - u'jittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 08:06:57 INFO - u'jsshell-mac.zip'], 08:06:57 INFO - u'mochitest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 08:06:57 INFO - u'firefox-53.0a2.en-US.mac.mochitest.tests.zip'], 08:06:57 INFO - u'mozbase': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 08:06:57 INFO - u'reftest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 08:06:57 INFO - u'firefox-53.0a2.en-US.mac.reftest.tests.zip'], 08:06:57 INFO - u'talos': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 08:06:57 INFO - u'firefox-53.0a2.en-US.mac.talos.tests.zip'], 08:06:57 INFO - u'web-platform': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 08:06:57 INFO - u'firefox-53.0a2.en-US.mac.web-platform.tests.zip'], 08:06:57 INFO - u'xpcshell': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 08:06:57 INFO - u'firefox-53.0a2.en-US.mac.xpcshell.tests.zip']} 08:06:57 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip'] for test suite category: common 08:06:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GCi8I4R0T_iT7Vb1p6Sy-g/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 08:06:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GCi8I4R0T_iT7Vb1p6Sy-g/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:06:57 INFO - Fetch https://queue.taskcluster.net/v1/task/GCi8I4R0T_iT7Vb1p6Sy-g/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 08:06:58 INFO - Expected file size: 16357831 08:06:58 INFO - Obtained file size: 16357831 08:07:04 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip', u'firefox-53.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 08:07:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GCi8I4R0T_iT7Vb1p6Sy-g/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 08:07:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GCi8I4R0T_iT7Vb1p6Sy-g/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:07:04 INFO - Fetch https://queue.taskcluster.net/v1/task/GCi8I4R0T_iT7Vb1p6Sy-g/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 08:07:05 INFO - Expected file size: 16357831 08:07:05 INFO - Obtained file size: 16357831 08:07:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GCi8I4R0T_iT7Vb1p6Sy-g/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip 08:07:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GCi8I4R0T_iT7Vb1p6Sy-g/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip'}, attempt #1 08:07:11 INFO - Fetch https://queue.taskcluster.net/v1/task/GCi8I4R0T_iT7Vb1p6Sy-g/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip into memory 08:07:12 INFO - Expected file size: 14403472 08:07:12 INFO - Obtained file size: 14403472 08:07:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:07:13 INFO - trying https://queue.taskcluster.net/v1/task/GCi8I4R0T_iT7Vb1p6Sy-g/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 08:07:13 INFO - Downloading https://queue.taskcluster.net/v1/task/GCi8I4R0T_iT7Vb1p6Sy-g/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg 08:07:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GCi8I4R0T_iT7Vb1p6Sy-g/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg'}, attempt #1 08:07:15 INFO - Downloaded 73925409 bytes. 08:07:15 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GCi8I4R0T_iT7Vb1p6Sy-g/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 08:07:15 INFO - mkdir: /builds/slave/test/properties 08:07:15 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:07:15 INFO - Writing to file /builds/slave/test/properties/build_url 08:07:15 INFO - Contents: 08:07:15 INFO - build_url:https://queue.taskcluster.net/v1/task/GCi8I4R0T_iT7Vb1p6Sy-g/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 08:07:15 INFO - Running post-action listener: _resource_record_post_action 08:07:15 INFO - Running post-action listener: set_extra_try_arguments 08:07:15 INFO - [mozharness: 2017-02-24 16:07:15.954388Z] Finished download-and-extract step (success) 08:07:15 INFO - [mozharness: 2017-02-24 16:07:15.954507Z] Running populate-webroot step. 08:07:15 INFO - Running pre-action listener: _resource_record_pre_action 08:07:15 INFO - Running main action method: populate_webroot 08:07:15 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:07:15 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:07:15 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:07:15 INFO - '--tppagecycles', 08:07:15 INFO - '7'], 08:07:15 INFO - 'tests': ['tsvgm']}}, 08:07:15 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:07:15 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:07:15 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:07:15 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:07:15 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:07:15 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:07:15 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:07:15 INFO - 'talos_options': ['--disable-e10s'], 08:07:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:07:15 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:07:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:07:15 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:07:15 INFO - 'talos_options': ['--disable-e10s'], 08:07:15 INFO - 'tests': ['damp', 'tps']}, 08:07:15 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:07:15 INFO - 'tests': ['damp', 'tps']}, 08:07:15 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:07:15 INFO - 'tests': ['dromaeo_dom']}, 08:07:15 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:07:15 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:07:15 INFO - 'tests': ['basic_compositor_video']}, 08:07:15 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:07:15 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:07:15 INFO - 'tests': ['a11yr', 08:07:15 INFO - 'ts_paint', 08:07:15 INFO - 'tpaint', 08:07:15 INFO - 'sessionrestore', 08:07:15 INFO - 'sessionrestore_no_auto_restore', 08:07:15 INFO - 'tabpaint']}, 08:07:15 INFO - 'other-e10s': {'tests': ['a11yr', 08:07:15 INFO - 'ts_paint', 08:07:15 INFO - 'tpaint', 08:07:15 INFO - 'sessionrestore', 08:07:15 INFO - 'sessionrestore_no_auto_restore', 08:07:15 INFO - 'tabpaint']}, 08:07:15 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:07:15 INFO - 'ts_paint', 08:07:15 INFO - 'tpaint', 08:07:15 INFO - 'sessionrestore', 08:07:15 INFO - 'sessionrestore_no_auto_restore', 08:07:15 INFO - 'tabpaint']}, 08:07:15 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:07:15 INFO - 'ts_paint', 08:07:15 INFO - 'tpaint', 08:07:15 INFO - 'sessionrestore', 08:07:15 INFO - 'sessionrestore_no_auto_restore', 08:07:15 INFO - 'tabpaint']}, 08:07:15 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:07:15 INFO - 'tests': ['a11yr', 08:07:15 INFO - 'ts_paint', 08:07:15 INFO - 'tpaint', 08:07:15 INFO - 'sessionrestore', 08:07:15 INFO - 'sessionrestore_no_auto_restore', 08:07:15 INFO - 'tabpaint']}, 08:07:15 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:07:15 INFO - 'tests': ['a11yr', 08:07:15 INFO - 'ts_paint', 08:07:15 INFO - 'tpaint', 08:07:15 INFO - 'sessionrestore', 08:07:15 INFO - 'sessionrestore_no_auto_restore', 08:07:15 INFO - 'tabpaint']}, 08:07:15 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:07:15 INFO - 'tests': ['tsvgx', 08:07:15 INFO - 'tsvgr_opacity', 08:07:15 INFO - 'tart', 08:07:15 INFO - 'tscrollx', 08:07:15 INFO - 'cart', 08:07:15 INFO - 'tsvg_static']}, 08:07:15 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:07:15 INFO - 'tsvgr_opacity', 08:07:15 INFO - 'tart', 08:07:15 INFO - 'tscrollx', 08:07:15 INFO - 'cart', 08:07:15 INFO - 'tsvg_static']}, 08:07:15 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:07:15 INFO - 'talos_options': ['--disable-e10s'], 08:07:15 INFO - 'tests': ['tp5o']}, 08:07:15 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:07:15 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:07:15 INFO - 'talos_options': ['--disable-e10s', 08:07:15 INFO - '--xperf_path', 08:07:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:07:15 INFO - 'tests': ['tp5n']}, 08:07:15 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:07:15 INFO - 'talos_options': ['--xperf_path', 08:07:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:07:15 INFO - 'tests': ['tp5n']}}, 08:07:15 INFO - 'talos.zip': {'path': '', 08:07:15 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:07:15 INFO - Running post-action listener: _resource_record_post_action 08:07:15 INFO - [mozharness: 2017-02-24 16:07:15.972890Z] Finished populate-webroot step (success) 08:07:15 INFO - [mozharness: 2017-02-24 16:07:15.973022Z] Running create-virtualenv step. 08:07:15 INFO - Running pre-action listener: _resource_record_pre_action 08:07:15 INFO - Running main action method: create_virtualenv 08:07:15 INFO - Creating virtualenv /builds/slave/test/build/venv 08:07:15 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 08:07:15 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:07:16 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:07:16 INFO - Using real prefix '/tools/python27' 08:07:16 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:07:16 INFO - Installing distribute.............................................................................................................................................................................................done. 08:07:19 INFO - Installing pip.................done. 08:07:19 INFO - Return code: 0 08:07:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:07:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:07:19 INFO - Reading from file tmpfile_stdout 08:07:19 INFO - Output received: 08:07:19 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) 08:07:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:07:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:07:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:07:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:07:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:07:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103457b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10345a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8a1c5ef60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103235d30>, '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 0x10315cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10322abe8>, '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.3FyvZ22uM2/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.kSFsaAohjP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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 08:07:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:07:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:07:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3FyvZ22uM2/Render', 08:07:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:07:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:07:19 INFO - 'HOME': '/Users/cltbld', 08:07:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:07:19 INFO - 'LOGNAME': 'cltbld', 08:07:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:07:19 INFO - 'MOZ_NO_REMOTE': '1', 08:07:19 INFO - 'NO_EM_RESTART': '1', 08:07:19 INFO - 'PAGER': '/bin/cat', 08:07:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:07:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:07:19 INFO - 'PWD': '/builds/slave/test', 08:07:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:07:19 INFO - 'SHELL': '/bin/bash', 08:07:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kSFsaAohjP/Listeners', 08:07:19 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 08:07:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:07:19 INFO - 'USER': 'cltbld', 08:07:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:07:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:07:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:07:19 INFO - 'XPC_FLAGS': '0x0', 08:07:19 INFO - 'XPC_SERVICE_NAME': '0', 08:07:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:07:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:07:20 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 08:07:20 INFO - Cleaning up... 08:07:20 INFO - Return code: 0 08:07:20 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:07:20 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')]} 08:07:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:07:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:20 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')]} 08:07:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:07:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:20 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 0x103457b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10345a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8a1c5ef60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103235d30>, '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 0x10315cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10322abe8>, '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.3FyvZ22uM2/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.kSFsaAohjP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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 08:07:20 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 08:07:20 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 08:07:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3FyvZ22uM2/Render', 08:07:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:07:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:07:20 INFO - 'HOME': '/Users/cltbld', 08:07:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:07:20 INFO - 'LOGNAME': 'cltbld', 08:07:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:07:20 INFO - 'MOZ_NO_REMOTE': '1', 08:07:20 INFO - 'NO_EM_RESTART': '1', 08:07:20 INFO - 'PAGER': '/bin/cat', 08:07:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:07:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:07:20 INFO - 'PWD': '/builds/slave/test', 08:07:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:07:20 INFO - 'SHELL': '/bin/bash', 08:07:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kSFsaAohjP/Listeners', 08:07:20 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 08:07:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:07:20 INFO - 'USER': 'cltbld', 08:07:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:07:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:07:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:07:20 INFO - 'XPC_FLAGS': '0x0', 08:07:20 INFO - 'XPC_SERVICE_NAME': '0', 08:07:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:07:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:07:20 INFO - Downloading/unpacking psutil>=3.1.1 08:07:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:07:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:07:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:07:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:07:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:07:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:07:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:07:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:07:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:07:23 INFO - Installing collected packages: psutil 08:07:23 INFO - Running setup.py install for psutil 08:07:24 INFO - building 'psutil._psutil_osx' extension 08:07:24 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 08:07:24 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 08:07:24 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 08:07:24 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 08:07:24 INFO - building 'psutil._psutil_posix' extension 08:07:24 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 08:07:24 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:07:24 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:07:24 INFO - ^ 08:07:24 INFO - 1 warning generated. 08:07:24 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 08:07:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:07:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:07:25 INFO - Successfully installed psutil 08:07:25 INFO - Cleaning up... 08:07:25 INFO - Return code: 0 08:07:25 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:07:25 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')]} 08:07:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:07:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:25 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')]} 08:07:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:07:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:25 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 0x103457b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10345a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8a1c5ef60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103235d30>, '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 0x10315cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10322abe8>, '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.3FyvZ22uM2/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.kSFsaAohjP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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 08:07:25 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 08:07:25 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 08:07:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3FyvZ22uM2/Render', 08:07:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:07:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:07:25 INFO - 'HOME': '/Users/cltbld', 08:07:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:07:25 INFO - 'LOGNAME': 'cltbld', 08:07:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:07:25 INFO - 'MOZ_NO_REMOTE': '1', 08:07:25 INFO - 'NO_EM_RESTART': '1', 08:07:25 INFO - 'PAGER': '/bin/cat', 08:07:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:07:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:07:25 INFO - 'PWD': '/builds/slave/test', 08:07:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:07:25 INFO - 'SHELL': '/bin/bash', 08:07:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kSFsaAohjP/Listeners', 08:07:25 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 08:07:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:07:25 INFO - 'USER': 'cltbld', 08:07:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:07:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:07:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:07:25 INFO - 'XPC_FLAGS': '0x0', 08:07:25 INFO - 'XPC_SERVICE_NAME': '0', 08:07:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:07:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:07:25 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:07:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:07:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:07:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:07:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:07:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:07:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:07:28 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:07:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:07:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:07:28 INFO - Installing collected packages: mozsystemmonitor 08:07:28 INFO - Running setup.py install for mozsystemmonitor 08:07:28 INFO - Successfully installed mozsystemmonitor 08:07:28 INFO - Cleaning up... 08:07:28 INFO - Return code: 0 08:07:28 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:07:28 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')]} 08:07:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:07:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:28 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')]} 08:07:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:07:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:28 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 0x103457b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10345a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8a1c5ef60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103235d30>, '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 0x10315cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10322abe8>, '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.3FyvZ22uM2/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.kSFsaAohjP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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 08:07:28 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 08:07:28 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 08:07:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3FyvZ22uM2/Render', 08:07:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:07:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:07:28 INFO - 'HOME': '/Users/cltbld', 08:07:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:07:28 INFO - 'LOGNAME': 'cltbld', 08:07:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:07:28 INFO - 'MOZ_NO_REMOTE': '1', 08:07:28 INFO - 'NO_EM_RESTART': '1', 08:07:28 INFO - 'PAGER': '/bin/cat', 08:07:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:07:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:07:28 INFO - 'PWD': '/builds/slave/test', 08:07:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:07:28 INFO - 'SHELL': '/bin/bash', 08:07:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kSFsaAohjP/Listeners', 08:07:28 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 08:07:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:07:28 INFO - 'USER': 'cltbld', 08:07:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:07:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:07:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:07:28 INFO - 'XPC_FLAGS': '0x0', 08:07:28 INFO - 'XPC_SERVICE_NAME': '0', 08:07:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:07:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:07:28 INFO - Downloading/unpacking jsonschema==2.5.1 08:07:28 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 08:07:28 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 08:07:28 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 08:07:28 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 08:07:28 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 08:07:28 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 08:07:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:07:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:07:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:07:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:07:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:07:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:07:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:07:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:07:31 INFO - Downloading functools32-3.2.3-2.tar.gz 08:07:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:07:31 INFO - warning: no files found matching '*.txt' 08:07:31 INFO - no previously-included directories found matching 'build' 08:07:31 INFO - no previously-included directories found matching 'dist' 08:07:31 INFO - no previously-included directories found matching '.git*' 08:07:31 INFO - Installing collected packages: jsonschema, functools32 08:07:31 INFO - Running setup.py install for functools32 08:07:31 INFO - warning: no files found matching '*.txt' 08:07:31 INFO - no previously-included directories found matching 'build' 08:07:31 INFO - no previously-included directories found matching 'dist' 08:07:31 INFO - no previously-included directories found matching '.git*' 08:07:31 INFO - Successfully installed jsonschema functools32 08:07:31 INFO - Cleaning up... 08:07:32 INFO - Return code: 0 08:07:32 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:07:32 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')]} 08:07:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:07:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:32 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')]} 08:07:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:07:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:32 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 0x103457b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10345a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8a1c5ef60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103235d30>, '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 0x10315cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10322abe8>, '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.3FyvZ22uM2/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.kSFsaAohjP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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 08:07:32 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 08:07:32 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 08:07:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3FyvZ22uM2/Render', 08:07:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:07:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:07:32 INFO - 'HOME': '/Users/cltbld', 08:07:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:07:32 INFO - 'LOGNAME': 'cltbld', 08:07:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:07:32 INFO - 'MOZ_NO_REMOTE': '1', 08:07:32 INFO - 'NO_EM_RESTART': '1', 08:07:32 INFO - 'PAGER': '/bin/cat', 08:07:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:07:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:07:32 INFO - 'PWD': '/builds/slave/test', 08:07:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:07:32 INFO - 'SHELL': '/bin/bash', 08:07:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kSFsaAohjP/Listeners', 08:07:32 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 08:07:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:07:32 INFO - 'USER': 'cltbld', 08:07:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:07:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:07:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:07:32 INFO - 'XPC_FLAGS': '0x0', 08:07:32 INFO - 'XPC_SERVICE_NAME': '0', 08:07:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:07:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:07:32 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:07:32 INFO - Cleaning up... 08:07:32 INFO - Return code: 0 08:07:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:07:32 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')]} 08:07:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:07:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:32 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')]} 08:07:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:07:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:32 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 0x103457b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10345a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8a1c5ef60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103235d30>, '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 0x10315cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10322abe8>, '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.3FyvZ22uM2/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.kSFsaAohjP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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 08:07:32 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 08:07:32 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 08:07:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3FyvZ22uM2/Render', 08:07:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:07:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:07:32 INFO - 'HOME': '/Users/cltbld', 08:07:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:07:32 INFO - 'LOGNAME': 'cltbld', 08:07:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:07:32 INFO - 'MOZ_NO_REMOTE': '1', 08:07:32 INFO - 'NO_EM_RESTART': '1', 08:07:32 INFO - 'PAGER': '/bin/cat', 08:07:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:07:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:07:32 INFO - 'PWD': '/builds/slave/test', 08:07:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:07:32 INFO - 'SHELL': '/bin/bash', 08:07:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kSFsaAohjP/Listeners', 08:07:32 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 08:07:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:07:32 INFO - 'USER': 'cltbld', 08:07:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:07:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:07:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:07:32 INFO - 'XPC_FLAGS': '0x0', 08:07:32 INFO - 'XPC_SERVICE_NAME': '0', 08:07:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:07:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:07:32 INFO - Downloading/unpacking blobuploader==1.2.4 08:07:32 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 08:07:32 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 08:07:32 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 08:07:32 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 08:07:32 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 08:07:32 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 08:07:35 INFO - Downloading blobuploader-1.2.4.tar.gz 08:07:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:07:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:07:35 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 08:07:35 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 08:07:35 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 08:07:35 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 08:07:35 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 08:07:35 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 08:07:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:07:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:07:36 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 08:07:36 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 08:07:36 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 08:07:36 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 08:07:36 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 08:07:36 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 08:07:36 INFO - Downloading docopt-0.6.1.tar.gz 08:07:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:07:36 INFO - Installing collected packages: blobuploader, requests, docopt 08:07:36 INFO - Running setup.py install for blobuploader 08:07:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:07:37 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:07:37 INFO - Running setup.py install for requests 08:07:37 INFO - Running setup.py install for docopt 08:07:37 INFO - Successfully installed blobuploader requests docopt 08:07:37 INFO - Cleaning up... 08:07:37 INFO - Return code: 0 08:07:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:07:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:07:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:07:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:38 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')]} 08:07:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:07:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:38 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 0x103457b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10345a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8a1c5ef60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103235d30>, '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 0x10315cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10322abe8>, '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.3FyvZ22uM2/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.kSFsaAohjP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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 08:07:38 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 08:07:38 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 08:07:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3FyvZ22uM2/Render', 08:07:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:07:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:07:38 INFO - 'HOME': '/Users/cltbld', 08:07:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:07:38 INFO - 'LOGNAME': 'cltbld', 08:07:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:07:38 INFO - 'MOZ_NO_REMOTE': '1', 08:07:38 INFO - 'NO_EM_RESTART': '1', 08:07:38 INFO - 'PAGER': '/bin/cat', 08:07:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:07:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:07:38 INFO - 'PWD': '/builds/slave/test', 08:07:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:07:38 INFO - 'SHELL': '/bin/bash', 08:07:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kSFsaAohjP/Listeners', 08:07:38 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 08:07:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:07:38 INFO - 'USER': 'cltbld', 08:07:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:07:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:07:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:07:38 INFO - 'XPC_FLAGS': '0x0', 08:07:38 INFO - 'XPC_SERVICE_NAME': '0', 08:07:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:07:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:07:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:07:38 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-LmVXMU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:07:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:07:38 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-C28i37-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:07:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:07:38 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-tmheiO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:07:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:07:38 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-CYwWdz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:07:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:07:38 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-RtKDgU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:07:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:07:38 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-cDUMey-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:07:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:07:39 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-xDrGpC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:07:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:07:39 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-SYruNb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:07:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:07:39 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-UTTiyN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:07:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:07:39 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-WxaSkC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:07:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:07:39 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-G4S29o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:07:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:07:39 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-ZCkHz9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:07:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:07:39 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-93uW64-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:07:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:07:39 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-5vukgL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:07:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:07:39 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-iM7JN7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:07:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:07:40 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-dQsyAb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:07:40 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-BlzQJE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:07:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:07:40 INFO - Running setup.py install for manifestparser 08:07:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:07:40 INFO - Running setup.py install for mozcrash 08:07:40 INFO - Running setup.py install for mozdebug 08:07:40 INFO - Running setup.py install for mozdevice 08:07:40 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:07:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:07:40 INFO - Running setup.py install for mozfile 08:07:40 INFO - Running setup.py install for mozhttpd 08:07:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:07:41 INFO - Running setup.py install for mozinfo 08:07:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:07:41 INFO - Running setup.py install for mozInstall 08:07:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:07:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:07:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:07:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:07:41 INFO - Running setup.py install for mozleak 08:07:41 INFO - Running setup.py install for mozlog 08:07:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:07:41 INFO - Running setup.py install for moznetwork 08:07:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:07:41 INFO - Running setup.py install for mozprocess 08:07:41 INFO - Running setup.py install for mozprofile 08:07:42 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:07:42 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:07:42 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:07:42 INFO - Running setup.py install for mozrunner 08:07:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:07:42 INFO - Running setup.py install for mozscreenshot 08:07:42 INFO - Running setup.py install for moztest 08:07:42 INFO - Running setup.py install for mozversion 08:07:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:07:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:07:42 INFO - Cleaning up... 08:07:42 INFO - Return code: 0 08:07:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:07:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:07:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:07:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:07:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:07:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:42 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 0x103457b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10345a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8a1c5ef60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103235d30>, '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 0x10315cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10322abe8>, '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.3FyvZ22uM2/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.kSFsaAohjP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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 08:07:42 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 08:07:42 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 08:07:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3FyvZ22uM2/Render', 08:07:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:07:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:07:42 INFO - 'HOME': '/Users/cltbld', 08:07:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:07:42 INFO - 'LOGNAME': 'cltbld', 08:07:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:07:42 INFO - 'MOZ_NO_REMOTE': '1', 08:07:42 INFO - 'NO_EM_RESTART': '1', 08:07:42 INFO - 'PAGER': '/bin/cat', 08:07:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:07:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:07:42 INFO - 'PWD': '/builds/slave/test', 08:07:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:07:42 INFO - 'SHELL': '/bin/bash', 08:07:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kSFsaAohjP/Listeners', 08:07:42 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 08:07:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:07:42 INFO - 'USER': 'cltbld', 08:07:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:07:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:07:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:07:42 INFO - 'XPC_FLAGS': '0x0', 08:07:42 INFO - 'XPC_SERVICE_NAME': '0', 08:07:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:07:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:07:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:07:43 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-imaTGS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:07:43 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)) 08:07:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:07:43 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-l8n8El-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:07:43 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)) 08:07:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:07:43 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-1cJWui-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:07:43 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)) 08:07:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:07:43 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-wn5rgj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:07:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 08:07:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:07:43 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-9fuWzD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:07:43 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)) 08:07:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:07:43 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-daSPgl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:07:43 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)) 08:07:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:07:43 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-cgHxcO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:07:43 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)) 08:07:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:07:43 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-d63izU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:07:43 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)) 08:07:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:07:43 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-xhssaf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:07:43 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)) 08:07:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:07:43 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-xlolaJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:07:44 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)) 08:07:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:07:44 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-Md_2hD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:07:44 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)) 08:07:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:07:44 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-Gci6jL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:07:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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)) 08:07:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:07:44 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-Uv5kM5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:07:44 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)) 08:07:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:07:44 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-YGZz9h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:07:44 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)) 08:07:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:07:44 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-34YG3M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:07:44 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)) 08:07:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:07:44 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-M4PuWm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:07:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 08:07:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:07:44 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-rn3dJW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:07:44 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)) 08:07:44 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)) 08:07:44 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)) 08:07:44 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)) 08:07:44 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:07:44 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:07:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:07:44 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 08:07:44 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 08:07:44 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 08:07:44 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 08:07:44 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 08:07:44 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 08:07:47 INFO - Downloading blessings-1.6.tar.gz 08:07:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:07:47 INFO - Installing collected packages: blessings 08:07:47 INFO - Running setup.py install for blessings 08:07:47 INFO - Successfully installed blessings 08:07:47 INFO - Cleaning up... 08:07:47 INFO - Return code: 0 08:07:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:07:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:07:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:07:48 INFO - Reading from file tmpfile_stdout 08:07:48 INFO - Current package versions: 08:07:48 INFO - blessings == 1.6 08:07:48 INFO - blobuploader == 1.2.4 08:07:48 INFO - docopt == 0.6.1 08:07:48 INFO - functools32 == 3.2.3-2 08:07:48 INFO - jsonschema == 2.5.1 08:07:48 INFO - manifestparser == 1.1 08:07:48 INFO - mozInstall == 1.12 08:07:48 INFO - mozcrash == 1.0 08:07:48 INFO - mozdebug == 0.1 08:07:48 INFO - mozdevice == 0.49 08:07:48 INFO - mozfile == 1.2 08:07:48 INFO - mozhttpd == 0.7 08:07:48 INFO - mozinfo == 0.9 08:07:48 INFO - mozleak == 0.1 08:07:48 INFO - mozlog == 3.4 08:07:48 INFO - moznetwork == 0.27 08:07:48 INFO - mozprocess == 0.24 08:07:48 INFO - mozprofile == 0.28 08:07:48 INFO - mozrunner == 6.13 08:07:48 INFO - mozscreenshot == 0.1 08:07:48 INFO - mozsystemmonitor == 0.3 08:07:48 INFO - moztest == 0.8 08:07:48 INFO - mozversion == 1.4 08:07:48 INFO - psutil == 3.1.1 08:07:48 INFO - requests == 1.2.3 08:07:48 INFO - wsgiref == 0.1.2 08:07:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:07:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:07:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:07:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:07:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:07:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:48 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 0x103457b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10345a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8a1c5ef60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103235d30>, '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 0x10315cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10322abe8>, '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.3FyvZ22uM2/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.kSFsaAohjP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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 08:07:48 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 08:07:48 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 08:07:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3FyvZ22uM2/Render', 08:07:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:07:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:07:48 INFO - 'HOME': '/Users/cltbld', 08:07:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:07:48 INFO - 'LOGNAME': 'cltbld', 08:07:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:07:48 INFO - 'MOZ_NO_REMOTE': '1', 08:07:48 INFO - 'NO_EM_RESTART': '1', 08:07:48 INFO - 'PAGER': '/bin/cat', 08:07:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:07:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:07:48 INFO - 'PWD': '/builds/slave/test', 08:07:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:07:48 INFO - 'SHELL': '/bin/bash', 08:07:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kSFsaAohjP/Listeners', 08:07:48 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 08:07:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:07:48 INFO - 'USER': 'cltbld', 08:07:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:07:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:07:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:07:48 INFO - 'XPC_FLAGS': '0x0', 08:07:48 INFO - 'XPC_SERVICE_NAME': '0', 08:07:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:07:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:07:48 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)) 08:07:48 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)) 08:07:48 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)) 08:07:48 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)) 08:07:48 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)) 08:07:48 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)) 08:07:48 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)) 08:07:48 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)) 08:07:48 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)) 08:07:48 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)) 08:07:48 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)) 08:07:48 INFO - Cleaning up... 08:07:48 INFO - Return code: 0 08:07:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:07:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:07:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:07:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:07:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:07:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:48 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 0x103457b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10345a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8a1c5ef60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103235d30>, '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 0x10315cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10322abe8>, '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.3FyvZ22uM2/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.kSFsaAohjP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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 08:07:48 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 08:07:48 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 08:07:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3FyvZ22uM2/Render', 08:07:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:07:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:07:48 INFO - 'HOME': '/Users/cltbld', 08:07:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:07:48 INFO - 'LOGNAME': 'cltbld', 08:07:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:07:48 INFO - 'MOZ_NO_REMOTE': '1', 08:07:48 INFO - 'NO_EM_RESTART': '1', 08:07:48 INFO - 'PAGER': '/bin/cat', 08:07:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:07:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:07:48 INFO - 'PWD': '/builds/slave/test', 08:07:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:07:48 INFO - 'SHELL': '/bin/bash', 08:07:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kSFsaAohjP/Listeners', 08:07:48 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 08:07:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:07:48 INFO - 'USER': 'cltbld', 08:07:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:07:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:07:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:07:48 INFO - 'XPC_FLAGS': '0x0', 08:07:48 INFO - 'XPC_SERVICE_NAME': '0', 08:07:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:07:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:07:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:07:48 INFO - Cleaning up... 08:07:48 INFO - Return code: 0 08:07:48 INFO - Running post-action listener: _resource_record_post_action 08:07:48 INFO - Running post-action listener: _start_resource_monitoring 08:07:48 INFO - Starting resource monitoring. 08:07:48 INFO - [mozharness: 2017-02-24 16:07:48.724639Z] Finished create-virtualenv step (success) 08:07:48 INFO - [mozharness: 2017-02-24 16:07:48.725646Z] Running install step. 08:07:48 INFO - Running pre-action listener: _resource_record_pre_action 08:07:48 INFO - Running main action method: install 08:07:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:07:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:07:48 INFO - Reading from file tmpfile_stdout 08:07:48 INFO - Detecting whether we're running mozinstall >=1.0... 08:07:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:07:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:07:49 INFO - Reading from file tmpfile_stdout 08:07:49 INFO - Output received: 08:07:49 INFO - Usage: mozinstall [options] installer 08:07:49 INFO - Options: 08:07:49 INFO - -h, --help show this help message and exit 08:07:49 INFO - -d DEST, --destination=DEST 08:07:49 INFO - Directory to install application into. [default: 08:07:49 INFO - "/builds/slave/test"] 08:07:49 INFO - --app=APP Application being installed. [default: firefox] 08:07:49 INFO - mkdir: /builds/slave/test/build/application 08:07:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:07:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 08:08:08 INFO - Reading from file tmpfile_stdout 08:08:08 INFO - Output received: 08:08:08 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 08:08:08 INFO - Running post-action listener: _resource_record_post_action 08:08:08 INFO - [mozharness: 2017-02-24 16:08:08.820750Z] Finished install step (success) 08:08:08 INFO - [mozharness: 2017-02-24 16:08:08.820888Z] Running run-tests step. 08:08:08 INFO - Running pre-action listener: _resource_record_pre_action 08:08:08 INFO - Running main action method: run_tests 08:08:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:08:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:08:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:08:08 INFO - 2017-02-24 08:08:08.834 screenresolution[1633:7641] starting screenresolution argv=screenresolution get 08:08:08 INFO - 2017-02-24 08:08:08.836 screenresolution[1633:7641] Display 0: 1600x1200x32@60 08:08:08 INFO - 2017-02-24 08:08:08.842 screenresolution[1634:7643] starting screenresolution argv=screenresolution list 08:08:08 INFO - Available Modes on Display 0 08:08:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:08:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:08:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:08:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:08:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:08:09 INFO - Intel Iris: 08:08:09 INFO - Chipset Model: Intel Iris 08:08:09 INFO - Type: GPU 08:08:09 INFO - Bus: Built-In 08:08:09 INFO - VRAM (Dynamic, Max): 1536 MB 08:08:09 INFO - Vendor: Intel (0x8086) 08:08:09 INFO - Device ID: 0x0a2e 08:08:09 INFO - Revision ID: 0x0009 08:08:09 INFO - Displays: 08:08:09 INFO - Display: 08:08:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:08:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:08:09 INFO - Display Serial Number: 1600x1200 60 08:08:09 INFO - Main Display: Yes 08:08:09 INFO - Mirror: Off 08:08:09 INFO - Online: Yes 08:08:09 INFO - Rotation: Supported 08:08:09 INFO - Return code: 0 08:08:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:08:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:08:09 INFO - Python 2.7.3 08:08:09 INFO - Return code: 0 08:08:09 INFO - grabbing minidump binary from tooltool 08:08:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:08:09 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103235d30>, '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 0x10315cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10322abe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:08:09 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 08:08:09 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 08:08:09 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 08:08:09 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:08:09 INFO - Return code: 0 08:08:09 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:08:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:08:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:08:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:08:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:08:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GCi8I4R0T_iT7Vb1p6Sy-g/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0263', '--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 08:08:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/GCi8I4R0T_iT7Vb1p6Sy-g/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0263 --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 08:08:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3FyvZ22uM2/Render', 08:08:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:08:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:08:09 INFO - 'HOME': '/Users/cltbld', 08:08:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:08:09 INFO - 'LOGNAME': 'cltbld', 08:08:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:08:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:08:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:08:09 INFO - 'MOZ_NO_REMOTE': '1', 08:08:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:08:09 INFO - 'NO_EM_RESTART': '1', 08:08:09 INFO - 'PAGER': '/bin/cat', 08:08:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:08:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:08:09 INFO - 'PWD': '/builds/slave/test', 08:08:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:08:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:08:09 INFO - 'SHELL': '/bin/bash', 08:08:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kSFsaAohjP/Listeners', 08:08:09 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 08:08:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:08:09 INFO - 'USER': 'cltbld', 08:08:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:08:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:08:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:08:09 INFO - 'XPC_FLAGS': '0x0', 08:08:09 INFO - 'XPC_SERVICE_NAME': '0', 08:08:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:08:09 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GCi8I4R0T_iT7Vb1p6Sy-g/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0263', '--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 08:08:09 INFO - mozversion application_buildid: 20170224065122 08:08:09 INFO - mozversion application_changeset: eeeb9c132d0ed10d0f76f51a4b25cfb2f76bd671 08:08:09 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:08:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:08:09 INFO - mozversion application_name: Firefox 08:08:09 INFO - mozversion application_remotingname: firefox-dev 08:08:09 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:08:09 INFO - mozversion application_vendor: Mozilla 08:08:09 INFO - mozversion application_version: 53.0a2 08:08:09 INFO - mozversion platform_buildid: 20170224065122 08:08:09 INFO - mozversion platform_changeset: eeeb9c132d0ed10d0f76f51a4b25cfb2f76bd671 08:08:09 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:08:09 INFO - mozversion platform_version: 53.0a2 08:08:09 INFO - using testdate: 1487952489 08:08:09 INFO - actual date: 1487952489 08:08:09 INFO - starting webserver on 'localhost:49245' 08:08:09 INFO - SUITE-START | Running 2 tests 08:08:09 INFO - TEST-START | dromaeo_css 08:08:09 INFO - operating with platform_type : mac_ 08:08:09 INFO - Initialising browser for dromaeo_css test... 08:08:09 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/tmphTIyg3/profile http://localhost:49245/getInfo.html) 08:08:10 INFO - PROCESS | 1644 | 1487952490017 addons.xpi WARN Error loading bootstrap.js for disableSHA1rollout@mozilla.org: Error opening input stream (invalid filename?): jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/disableSHA1rollout@mozilla.org.xpi!/bootstrap.js 08:08:10 INFO - PROCESS | 1644 | 1487952490018 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method install 08:08:10 INFO - PROCESS | 1644 | 1487952490059 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method startup 08:08:11 INFO - PROCESS | 1644 | __metrics Screen width/height:1600/1200 08:08:11 INFO - PROCESS | 1644 | colorDepth:24 08:08:11 INFO - PROCESS | 1644 | Browser inner width/height: 1024/689 08:08:11 INFO - PROCESS | 1644 | __metrics 08:08:11 INFO - PROCESS | 1644 | 1487952491840 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method shutdown 08:08:11 INFO - PROCESS | 1644 | 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" 08:08:11 INFO - PROCESS | 1644 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:08:12 INFO - TEST-INFO | 1644: exit 0 08:08:12 INFO - Browser initialized. 08:08:12 INFO - Running cycle 1/1 for dromaeo_css test... 08:08:12 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/tmphTIyg3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:08:13 INFO - PROCESS | 1646 | 1487952493112 addons.xpi WARN Error loading bootstrap.js for disableSHA1rollout@mozilla.org: Error opening input stream (invalid filename?): jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/disableSHA1rollout@mozilla.org.xpi!/bootstrap.js 08:08:13 INFO - PROCESS | 1646 | 1487952493112 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method startup 08:08:24 INFO - PROCESS | 1646 | RSS: Main: 212815872 08:08:24 INFO - PROCESS | 1646 | 08:11:28 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49245/tests/dromaeo/cssquery-ext.html) 08:11:29 INFO - PROCESS | 1646 | RSS: Main: 342114304 08:11:29 INFO - PROCESS | 1646 | 08:13:14 INFO - PROCESS | 1646 | ************************* 08:13:14 INFO - PROCESS | 1646 | A coding exception was thrown and uncaught in a Task. 08:13:14 INFO - PROCESS | 1646 | 08:13:14 ERROR - PROCESS | 1646 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:13:14 INFO - PROCESS | 1646 | Full stack: 08:13:14 INFO - PROCESS | 1646 | ************************* 08:14:32 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-ext.html (next: http://localhost:49245/tests/dromaeo/cssquery-jquery.html) 08:14:33 INFO - PROCESS | 1646 | RSS: Main: 356413440 08:14:33 INFO - PROCESS | 1646 | 08:17:35 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49245/tests/dromaeo/cssquery-mootools.html) 08:17:36 INFO - PROCESS | 1646 | RSS: Main: 322654208 08:17:36 INFO - PROCESS | 1646 | 08:20:38 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49245/tests/dromaeo/cssquery-prototype.html) 08:20:39 INFO - PROCESS | 1646 | RSS: Main: 322076672 08:20:39 INFO - PROCESS | 1646 | 08:23:42 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49245/tests/dromaeo/cssquery-yui.html) 08:23:43 INFO - PROCESS | 1646 | RSS: Main: 315621376 08:23:43 INFO - PROCESS | 1646 | 08:26:46 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-yui.html (next: http://localhost:49245/tests/dromaeo/cssquery-dojo.html) 08:26:46 INFO - PROCESS | 1646 | RSS: Main: 459915264 08:26:46 INFO - PROCESS | 1646 | 08:26:46 INFO - PROCESS | 1646 | __start_tp_report 08:26:46 INFO - PROCESS | 1646 | _x_x_mozilla_page_load 08:26:46 INFO - PROCESS | 1646 | _x_x_mozilla_page_load_details 08:26:46 INFO - PROCESS | 1646 | |i|pagename|runs| 08:26:46 INFO - PROCESS | 1646 | |0;dojo.html;3190.808551486911;3365.7475689323305;3367.562216911801;3385.5091011803306;3437.3469040882233;5678.744456499458;5705.172749951254;5725.2843394575675;5736.340320863102;5747.281589801275;5725;5778.191053252537;5786.537077033838;5810.709464526767;5826;5223.6343455958095;5230.084735171339;5276.261323414706;5292.285541451913;5344.866378340534;5151.74241287936;5182.559482443997;5344.91982620259;5350.3312085989255;5351.09031464652;5255;5258.395284542288;5296.020236256286;5297.12597421425;5368.91946620801;5303.65526240795;5318;5320.308359913187;5326.360836699582;5345.1982202669715;5174.456682048369;5230;5258.079836028718;5292.92060619091;5324.361076670785;5622.184783206412;5695.060315048012;5695.629784064064;5737.2254745609225;5738.942610573882;6130.662813545253;6197.628142311476;6198.101275315099;6216.285127210338;6268.341824108448;6230;6281.528885333618;6293.527985401113;6311.8737625247695;6336.46140078094;5798.797042103548;5805.5065319447895;5808.941910580882;5826.679532625746;5856.502197313191;6217.813465596039;6245.656488893154;6258.530610204253;6299.433051025429;6317.715702793385;6189.535784816157;6191.783287584913;6199.194104766351;6218.409251121137;6239.344868788802;6238.469730072904;6282.2147231596055;6298.118263443122;6300.243970723543;6320.936790632035;6126.142340072393;6163.137160797492;6173.629582225081;6265.8120256392385;6290.842728931813;6204.534659900525;6239.095331176954;6246.875062498815;6254;6330.556861019684;4965.776540055706;4974.27872958419;4979.775910084055;4982.701037937735;5002;4345.543717909637;4481.955180448219;4488.528704486047;4490.977545112319;4497.595216430537;5725;5786.132080187921;5817.883642327214;5820.854478638068;5829.650220986754;6282.371762823773;6299.212598425197;6321.273053598803;6341.524385671093;6346.524010699124;5624.85937851557;5690.658560486301;5698.344690360662;5745.626534275259;5832.737526811304;5458.863528411742;5507.118860982304;5534.667919924817;5543.1408131739645;5551.2228288039705;6210.31686514474;6289.842753931005;6366.872662546816;6367.681615919093;6372.74509019634;6204.968975155095;6230.408111229426;6268.592541484789;6336.366363363627;6352.809415717536;6319.652419116809;6328.493720502257;6328.588641738273;6333.113364129118;6346.238451385863;6109.908351374644;6128.747575574743;6237.5321850860455;6260.092286618324;6267.0286105653695;6227.003679411272;6236.126942228001;6275.309715931153;6294.874102518015;6314.242290925118;5947.375525569926;6002.819915402545;6021.729022194099;6131.202943617302;6178.320384757583;5989.341172471112;6100.664463454375;6157.96921015392;6173.413525715229;6186.845328866814;5868.031774757117;5947.167396564398;5969.313528944227;5978.8206353809455;6077.4226448487325;6179.536534759821;6194.225721784777;6215.471684906653;6216.595921265103;6244.1570387997035;6036.6679832609625;6042.818715438544;6065.089496808491;6072.14989901423;6103.511719062376 08:26:46 INFO - PROCESS | 1646 | |1;ext.html;450.74758135444154;458.28049961560356;464.45426623717134;520.9687418754877;531.3305235403391;31342.373152536966;40930.38604420932;43010.78494607526;43081;43146.35280470795;44661.33008004883;45206.54793452073;45311.64065078045;45382.319265211045;45423.31865022027;8390.2868256198;9161.083891610835;9363.531823408837;9365.625374984993;9368;8472.406931514766;8679.783005424852;8680.131986801332;8696.826063478728;11085.889141108566;11130.05394541465;11318.434078296094;11334.716632084223;11356.943215283913;11391.974722274994;11297.887021129765;11400.71498212543;11414.201005929588;11415.42922853854;11420.885791142066;8358.707445239388;8593.441426307332;11361.829572556418;11395.658130256106;11426.37154956477;32797.67202327994;33065;41256.1748765026;41852.162956741;42141.36787948183;3920.901977450558;3932.5954137903755;3957.3668213085766;3958.7426817256787;4068.1863627274665;28400;30492.237694057603;31505.684943150503;31574;32343.191420214447;3079.6612372638992;3148.197209711509;3184.379046086014;3211.1169428407375;3224.8226347550876;397293;443751;472331.6383418095;472796;474652;60580;61055;61170.38829611647;61206.693966529885;61212.693936530035;63818.72362552723;63914;63983.6800815993;64110;64168.35831641717;15752;16251.349946002027;16342.509724708278;16344.34622615106;16392.688584913416;12540;12598.055145864097;12604.873951260553;12624;12630.431630576646;13526.256055916821;14529.055611385225;16045.839541604668;17788.152735569583;18838.52903677419;7369.81575460607;7759.7672069838;7834.569098699512;7920.207979202034;7951.642176101974;866.7096522664851;876.4916348517844;891.2825175733597;892.5001998880648;923.9861402078974;29839.254018649706;30492.390152197277;30807.8459607705;31395.37209255848;31551.84224078865;1071.6570697376765;1077.2405454154803;1100.510272928539;1113.6102364172475;1185.4902391971534;15933.521994340188;15984.440544581117;16015.886806503662;16167.838321616633;16307.592310192103;671801;676731;677576;677719;680094;63919;64415.355846441875;64473.355266447674;64578.354216458174;64686.70626587442;1427.6573622330566;1442.5945007863502;1461.554225961092;1492.7462331403688;1493.5295381954866;29004;29007.85496072506;30378;30551.2362190947;31156.688433116284;46455.30317045179;54396.456035439136;55399.72300138474;55485.167722485;55558.72220638871;561876;563034;564345;564904;565226;23451.76548234496;27656.8617156921;27908.58137127904;28180.15459536217;28373.574396384483;40571.1885762289;41563.7921810389;48962.51037489579;50360.4891853245;50652;37638.24723505569;39776.403353949136;40768.3884741735;40967.97580060522;42342.78828605961;39032.80483597677;39812.40281395723;40582.985425364604;41539.79230103951;41543.37684934668;38289.80855095707;38461.4230786544;38635.2272954545;38853.8343849685;39272.60727392689;34542.82728586341;35035.64964350426;35573;35595.466068008485;35754.463683044254;24868.751312486642;25236.74763252344;25384.603846788603;25427.74572254254;25555.361115971507 08:26:46 INFO - PROCESS | 1646 | |2;jquery.html;369.3093914380106;390.1650467998482;394.3040533458445;401.5402364292885;421.70269959678427;14318.642033949143;15221.619459513518;18157.636847263046;20369.694454583194;20872.165113395484;21303.786962130373;21649;21843.017064232106;21891;22366.66450003251;5787.855303617401;5814.389489103657;5911.88176236475;5959.523238140939;6100.450959413652;5947.256592925884;5957.463828255456;5984.3417224105315;5984.670843103628;6015.368386319439;4194.517630472505;4352.782360881959;4364.410804541378;6469.094326794253;6520.93479065208;6314.179156709644;6495.642739649317;6502.6748662566915;6508.446782023534;6511.153550038512;5526.806561770359;5744.540436765091;5750.942490575083;5862.443067908542;5882.852928676774;26229.606555901675;26649.600255996174;26752.59871101935;26872.865635671893;26933.730662693317;2699.303549857572;2737.9726202738116;2750.5324094903913;2751.5735061065566;2754.3664957059787;15343.00270482415;15681.921590392089;15730.842691573052;15751.291191896393;16442.75335869963;1613.4594910704927;1619.4331983805573;1627.6826018926315;1627.6907387596436;1641.4747280870126;128266;131167;134741.28405505858;135033;136363;11615.593454229062;11649.650510484698;11728.237664551947;11765.823227728466;11915.940420298017;14972.203335599797;14978;14978.925105374403;14993.850061499244;15046.849531504764;8658.177473140042;8791.5164665944;8806.163414475615;8882;8989.190972812477;7150.678219480136;8647.783805404915;8723.58244248475;8837.425567338103;8957.059508751618;6230.968845155745;7194.964025179946;7320.084989376328;7320.780376588711;7327.816804579928;1882.4729075858509;1915.415798181568;1934.8258656720682;1935.158206180316;1936.2545420013396;894.9731508054768;1015.9477854733398;1039.4698703661188;1044.3942513342242;1070.0155856611937;22343.55312893733;22957.540849183257;22965.311040668807;23056.53886922252;23074.538509229722;1065.9093977012042;1088.5319312695617;1095.1676725688535;1116.3413585984308;1122.0967121467297;11083.72290692739;11817.113716471298;11970.521179152736;12049.638510844688;12114.454849531792;749405;756984;765734;773533;782465;133305;133660.33169834284;137174;137293;138658;1013.8023085498257;1023.2428003277521;1042.6455005298235;1043.1967385113421;1048.1876545677162;18642.974636394585;19141.13864876056;20286;20333.085011174237;20338.288159914333;58657.70671146617;59840.10239846319;61029.77940441255;61040.694796525735;61127;82114;82537;83282;85027;86180;7354.411647068328;7538.811529711801;7646.885296720665;7894.842103158019;8324.750257492286;11894.583689570823;13379.063465557318;13380.665483362605;13757.587272381845;13795.17228966225;11079.390633515233;11135;11188;11267.154963377616;11478;11410.315381077162;11690;11782.410879456163;11958.402079896145;11979.161458698156;11455.59905403307;11584.652460426201;11682;11888.989435898045;11975.940120299343;7675.846483070196;7779.144294127755;7898.802529936797;7919.920800792149;7965.721199758213;9659.855102173333;9868.457234852152;10444.321119127233;10599;11480.311181329147 08:26:46 INFO - PROCESS | 1646 | |3;mootools.html;1434.5552878607632;1439.8347665481706;1443.292786534598;1455.585158229904;1457.5408746244932;8105.351571874251;8552.486850788957;8552.615132319053;8558.572071396437;8565.501037318467;7443.739469118582;7694.576798276093;8004.199580041993;8268.173182681743;8362.498250104998;2307.3424074138875;2344.988275058622;2345.0150936606583;2350.670906073151;2364.9645255321097;2343.320437073247;2363.8108951283857;2370.9170179043736;2374.27584586701;2379.5003049359657;2294.908203671851;2308.5729140109133;2316.420894776306;2344.871032093234;2348.9295321140307;2298.977010229893;2308.53829234153;2333;2334.5914464968564;2338.427085364097;2289.9084036638515;2362.1968530699646;2363.3855197648563;2367.644853272023;2370.4073981504625;8436.620352084172;8507.149285071506;8513.95743021281;8524.360672949491;8538.316934645214;3806.695464362844;5248.475152484759;5251.501107394792;5264.263003179558;5280.392754833205;3196.7762256642045;3307.603087629476;3448.3103379324234;3499.527563778908;3513.4027215373194;1054.783769327286;1184.1414974143763;1189.7620475904853;1192.7077865922818;1231.4889320931802;10716.196285278635;10837.620683276047;10855.945720271347;10859.728506787314;11062.004419602274;4473.351364052194;4624.237000894882;4726.007538416902;4735.076660051257;4764.285357196379;9450.952745236229;9527.999560046233;9534.380265282734;9535.90464095364;9556.331056826095;5267.894642107136;5272.525472707474;5283.656562323436;5285.207218917193;5298.788048478094;4012;4135.421041054255;4733.668643195012;4805.471398146201;4807.471178170398;3848.172642881738;3860;3948.032731980683;3950.2099580083523;3989.7606143631474;1297.195738642048;1301.9609411717663;1302.0820321673198;1315.2963164707028;1315.5395611535887;807.6567458829974;851.9275861551714;861.3023451004706;888.3026823943173;918.3158546882481;4687.835925742583;5261;5595.496405323541;5604.887902241932;5684.715764211773;876.8772371867943;969.8205831921117;982.6167794560128;983.6557204978343;987.6098940918297;3683.2081102562543;3683.889483315505;3790.298794722985;3813.56144043433;3816.46569480273;7859.6463159157975;9429.764255893657;9750.463724495079;10165.44090075053;10176.338537995154;9669.226461883032;10019.048190421898;10032.79934401308;10046.04562566555;10092.949535252277;7345.522541034816;7667.8849817252785;7695.769126926201;7726.227377262228;7874.448788584858;5256.9737151314;5287.9735601321745;5372.928590692492;5581.665100094007;5903.26209223847;9071;9116.08839116083;9315;9372.67195648149;9412;9266.21237194846;9271.582778774837;9523.28575356837;9574.808503829745;9615.375000624937;6994.2306346302885;7276.090488688914;7284.599347035752;7568;7816.374690024898;7294.343509084208;7405.185429602631;7493.625318734151;7866.72466463671;7898.68405263783;6954.408875245661;7115.928840711526;7142.821429464305;7201.71193152268;7234.240404757425;6762.49281303914;6765.627890466071;6984.371406573433;6997;7028.894566581607;6150.938490615215;6305.054241863573;6400.135981642418;6408.423241908251;6415.1981002374705;4867.245576935581;4885.682430641997;4912.7543622818;4929.926051109308;4976.800927962841;4891;4901.828436004723;4903.6077113831525;5065.341505604248;5067.77195026232 08:26:46 INFO - PROCESS | 1646 | |4;prototype.html;334.4581777520417;354.53732878593433;358.6144894238693;363.16834449111536;365.5412457366007;4931.1617025105725;5136.743162841853;5194.766235519402;5311.867203319915;5352.919706204405;4316.84891028814;4639.1881420751315;4663.953360466395;4667.3430932019155;5123.743812809364;3500.3174380995715;4020.216057868724;4157.542670306264;4200.0339921818;4204.453421055258;3753.86861459849;4191.476065491814;4192.392103145042;4221.451211342521;4230.513490948534;3434.3989801784714;3994.241094192109;4002.9599704003026;4012.7592344459426;4219.936700949488;3437.9656203438144;4016.8869126427107;4036.9394459083137;4135.793210339471;4139.399787030864;3203.2632494526156;3636.236425333711;3686.870959516431;3692.7415080944343;3741.5884252732176;4321.848735294281;4437.4009508716235;5251.140411797326;5255.816046438356;5286.762095705702;3908.218356328746;4034.487067349744;4443.711158774701;4794.832180873653;4845.806167753286;5225.399079105913;5631.493165615114;5646.6894320812335;5652.660840349551;5660.792291006511;3280.885169019097;3653.8173091345325;4113.177364527077;4115.468986573266;4131.586841315874;5158.4067832199025;5466.446809573769;5576.442355764391;5687.601867869251;5837.620554663933;4124.638869173165;4124.711270211056;4129.224433493567;4130.463039804806;4136.793160341971;3800.923981520354;3932.5618116767787;4171.33258678616;4198.7956322930095;4200.936985945214;4276.8289268429535;4576.908461830812;4587.9082418351445;4811.350467686818;4831.227003679394;4760.9765849513815;5649.265595472644;5660.037793575102;5661.264035675335;5668.659880407189;1155.381870699172;1160.570588882119;1236.9567065152676;1274.4392467314537;1282.4229096906242;773.7407968330559;822.2814318075251;870.2950610005915;870.4820631724114;883.240413244622;4471.910561788746;4556.792450000752;4603.49361570227;4855.053264613366;5110.463401342806;4836.396884321133;5234.3718753749035;5418.4039755626845;5487.259220005328;5507.091329930597;4270.846871344719;4729.834455794031;5003.824866129668;5028.7737051832755;5068.758154252158;2501.5872381057284;2727.768139708147;2783.3737409082796;2894.8552572371295;2922.8246305221755;4777.856664300077;4891.633127515451;5470.11621748555;5687.175359572839;5702.914456283159;4650.279206722963;4897.987737541458;5367.403839427593;5440;5689.862027594415;5136.614753893398;5156.716380599028;5644.78637093027;5670.688112153789;5676.914846277309;4602.815887364468;4773.498782627913;5143.408508021475;5153.510416510426;5533.0243337194925;4887.095887260868;5082.974585127051;5204.531592156724;5364.7049412282695;5425.376081750492;5288.4182739897815;5382.677039377493;5555.333399980071;5647.701028763326;5759.7408116634015;3338.4825352070466;3383.4755612880044;3519.131907495383;3654.7705899401876;3666.4151773000503;4228.1332326873535;5258.579313654975;5288;5411.972940135274;5521.668699878021;4518.615917647036;4728.456227533878;4991.9500804991485;5323.0950738373795;5350.277712508761;4616.776554213069;4764.809407623657;5133.845984620468;5370.872116855504;5380.650257733235;4744.8885246065165;5102.2346648003095;5336.306280183551;5498.525663451543;5569.331680198402;4382.233109205812;5030;5538.483063254943;5665.348484924287;5684.403137670485;4478.753668548131;5246.554042906243;5537.889242215053;5593.300837395325;5623.465770751772 08:26:46 INFO - PROCESS | 1646 | |5;yui.html;645.4352441613588;677.1603212017101;719.4244604316549;807.0920214758397;820.0774129104756;14620.4882829101;14829;15265.542033738979;15268.618284542892;15375.69248615027;13786;14034.438622455089;14105.365258563364;14161.362738676758;14253.643658908533;857.4340934982913;880.7093659092516;884.0099089020274;885.5483703311294;887.0464250930244;680.1600023970393;693.9763848323732;822.7860756203401;840.7267638017638;848.5417874347845;925.2228128372166;925.9814803703922;927.6892241099206;928.7399528132133;928.9303302252324;919.5402298850574;921.138735282513;922.4695799915075;922.5710044829186;924.6301479408223;531.6544246239936;541.0450554770832;669.7019826177353;684.7192651013061;688.9965550172267;14798.964072514927;14860;14871.884608654285;14874.033187842864;14876.851231487655;13422.798658020138;13501.527446539421;13563.118397304144;13580.592582222549;13692.452301907912;11645.324233446052;11747.177697561088;11832.408379580986;12027.939860300643;12345.69135771604;4379.233634114017;4428.756418396987;4457.799399027052;4458.108378324348;4582.977085114553;96435;102076.48961755144;103721;104006.47996760043;104617;10335.74160645975;10564.524596393181;10771.515281812337;10789;11306.152038596974;20892.059857306456;21048.684269735968;21410.25064122748;21484.892575537022;21547.030383632773;7407.481476296715;7633.2366763324335;7651.120121186135;7679.961600191963;7815.29662330393;4155.127423241092;4214.852480163241;4215.894602634898;4344.218040752695;4411.205982923105;2939.6913324101088;3318.1206980150278;3365.545651337038;3423.9144021399666;3424.109731469786;634.1249076274711;641.6503005861816;646.9773557925544;659.9637019963948;660.2572106380322;602.4425765852648;616.981490555284;623.9875202496015;634.9619022858643;636.6466611030829;9318.627254909728;9674.226106681086;9676.322657413913;9689.806203875884;9892.207332692102;606.0061499141324;610.0868353611452;630.1870586942792;656.2846497317946;664.3257094049544;4550.294704320836;4648.697834640738;4663;4669.276262179367;4699.436067671902;92659;105246.47376763067;105949.47025264824;106126.46936765421;107018.46490767496;79788;80080.5995970028;81599.36801263942;86072.1267542962;88805.11194887968;79583;80351.19648803554;80791.59604201942;81109.59445202736;83267.5836620813;10346.548987110531;10687.46562671879;10873.673789786319;10904.836427453436;10905.781884362426;47784.52215477801;48486;48697;49332;51109;68761;69441.65279173572;69716.65141674259;70309.59380812457;73898;11607.6517704469;13059.02057345722;13139.408726607517;13204.207747535178;13525.526606568723;13957.720845583235;14229.50196743109;14428.567142985727;14605.488807891672;15162.393504259706;12966;13048.934755326163;13520;13550.728985420039;13715.931420342835;13197.34013299312;13349.332533373487;13525.729485410433;13721.656958576115;13769.518066867611;12376;12610.621681349574;13307.401166947317;13400.530981415604;13421.194728316332;10781.299215550964;10841.67474975752;11042.834357484804;11204.775904481701;11222.889797056183;10656.200784941315;10789.493265868121;10827.025567698945;10905.236633435583;10916.727081823017 08:26:46 INFO - PROCESS | 1646 | __end_tp_report 08:26:46 INFO - PROCESS | 1646 | __start_cc_report 08:26:46 INFO - PROCESS | 1646 | _x_x_mozilla_cycle_collect,1383 08:26:46 INFO - PROCESS | 1646 | __end_cc_report 08:26:46 INFO - PROCESS | 1646 | __startTimestamp1487953606849__endTimestamp 08:26:46 INFO - PROCESS | 1646 | 08:26:46 INFO - PROCESS | 1646 | ------- Summary: start ------- 08:26:46 INFO - PROCESS | 1646 | Number of tests: 6 08:26:46 INFO - PROCESS | 1646 | 08:26:46 INFO - PROCESS | 1646 | [#0] dojo.html Cycles:165 Average:5791.63 Median:6021.73 stddev:635.44 (10.6%) stddev-sans-first:603.75 08:26:46 INFO - PROCESS | 1646 | Values: 3190.8 3365.7 3367.6 3385.5 3437.3 5678.7 5705.2 5725.3 5736.3 5747.3 5725.0 5778.2 5786.5 5810.7 5826.0 5223.6 5230.1 5276.3 5292.3 5344.9 5151.7 5182.6 5344.9 5350.3 5351.1 5255.0 5258.4 5296.0 5297.1 5368.9 5303.7 5318.0 5320.3 5326.4 5345.2 5174.5 5230.0 5258.1 5292.9 5324.4 5622.2 5695.1 5695.6 5737.2 5738.9 6130.7 6197.6 6198.1 6216.3 6268.3 6230.0 6281.5 6293.5 6311.9 6336.5 5798.8 5805.5 5808.9 5826.7 5856.5 6217.8 6245.7 6258.5 6299.4 6317.7 6189.5 6191.8 6199.2 6218.4 6239.3 6238.5 6282.2 6298.1 6300.2 6320.9 6126.1 6163.1 6173.6 6265.8 6290.8 6204.5 6239.1 6246.9 6254.0 6330.6 4965.8 4974.3 4979.8 4982.7 5002.0 4345.5 4482.0 4488.5 4491.0 4497.6 5725.0 5786.1 5817.9 5820.9 5829.7 6282.4 6299.2 6321.3 6341.5 6346.5 5624.9 5690.7 5698.3 5745.6 5832.7 5458.9 5507.1 5534.7 5543.1 5551.2 6210.3 6289.8 6366.9 6367.7 6372.7 6205.0 6230.4 6268.6 6336.4 6352.8 6319.7 6328.5 6328.6 6333.1 6346.2 6109.9 6128.7 6237.5 6260.1 6267.0 6227.0 6236.1 6275.3 6294.9 6314.2 5947.4 6002.8 6021.7 6131.2 6178.3 5989.3 6100.7 6158.0 6173.4 6186.8 5868.0 5947.2 5969.3 5978.8 6077.4 6179.5 6194.2 6215.5 6216.6 6244.2 6036.7 6042.8 6065.1 6072.1 6103.5 08:26:46 INFO - PROCESS | 1646 | 08:26:46 INFO - PROCESS | 1646 | [#1] ext.html Cycles:180 Average:70440.90 Median:3944.98 stddev:152873.73 (3875.1%) stddev-sans-first:153212.27 08:26:46 INFO - PROCESS | 1646 | Values: 450.7 458.3 464.5 521.0 531.3 31342.4 40930.4 43010.8 43081.0 43146.4 44661.3 45206.5 45311.6 45382.3 45423.3 8390.3 9161.1 9363.5 9365.6 9368.0 8472.4 8679.8 8680.1 8696.8 11085.9 11130.1 11318.4 11334.7 11356.9 11392.0 11297.9 11400.7 11414.2 11415.4 11420.9 8358.7 8593.4 11361.8 11395.7 11426.4 32797.7 33065.0 41256.2 41852.2 42141.4 3920.9 3932.6 3957.4 3958.7 4068.2 28400.0 30492.2 31505.7 31574.0 32343.2 3079.7 3148.2 3184.4 3211.1 3224.8 397293.0 443751.0 472331.6 472796.0 474652.0 60580.0 61055.0 61170.4 61206.7 61212.7 63818.7 63914.0 63983.7 64110.0 64168.4 15752.0 16251.3 16342.5 16344.3 16392.7 12540.0 12598.1 12604.9 12624.0 12630.4 13526.3 14529.1 16045.8 17788.2 18838.5 7369.8 7759.8 7834.6 7920.2 7951.6 866.7 876.5 891.3 892.5 924.0 29839.3 30492.4 30807.8 31395.4 31551.8 1071.7 1077.2 1100.5 1113.6 1185.5 15933.5 15984.4 16015.9 16167.8 16307.6 671801.0 676731.0 677576.0 677719.0 680094.0 63919.0 64415.4 64473.4 64578.4 64686.7 1427.7 1442.6 1461.6 1492.7 1493.5 29004.0 29007.9 30378.0 30551.2 31156.7 46455.3 54396.5 55399.7 55485.2 55558.7 561876.0 563034.0 564345.0 564904.0 565226.0 23451.8 27656.9 27908.6 28180.2 28373.6 40571.2 41563.8 48962.5 50360.5 50652.0 37638.2 39776.4 40768.4 40968.0 42342.8 39032.8 39812.4 40583.0 41539.8 41543.4 38289.8 38461.4 38635.2 38853.8 39272.6 34542.8 35035.6 35573.0 35595.5 35754.5 24868.8 25236.7 25384.6 25427.7 25555.4 08:26:46 INFO - PROCESS | 1646 | 08:26:46 INFO - PROCESS | 1646 | [#2] jquery.html Cycles:180 Average:41100.35 Median:22117.28 stddev:126930.47 (573.9%) stddev-sans-first:127249.70 08:26:46 INFO - PROCESS | 1646 | Values: 369.3 390.2 394.3 401.5 421.7 14318.6 15221.6 18157.6 20369.7 20872.2 21303.8 21649.0 21843.0 21891.0 22366.7 5787.9 5814.4 5911.9 5959.5 6100.5 5947.3 5957.5 5984.3 5984.7 6015.4 4194.5 4352.8 4364.4 6469.1 6520.9 6314.2 6495.6 6502.7 6508.4 6511.2 5526.8 5744.5 5750.9 5862.4 5882.9 26229.6 26649.6 26752.6 26872.9 26933.7 2699.3 2738.0 2750.5 2751.6 2754.4 15343.0 15681.9 15730.8 15751.3 16442.8 1613.5 1619.4 1627.7 1627.7 1641.5 128266.0 131167.0 134741.3 135033.0 136363.0 11615.6 11649.7 11728.2 11765.8 11915.9 14972.2 14978.0 14978.9 14993.9 15046.8 8658.2 8791.5 8806.2 8882.0 8989.2 7150.7 8647.8 8723.6 8837.4 8957.1 6231.0 7195.0 7320.1 7320.8 7327.8 1882.5 1915.4 1934.8 1935.2 1936.3 895.0 1015.9 1039.5 1044.4 1070.0 22343.6 22957.5 22965.3 23056.5 23074.5 1065.9 1088.5 1095.2 1116.3 1122.1 11083.7 11817.1 11970.5 12049.6 12114.5 749405.0 756984.0 765734.0 773533.0 782465.0 133305.0 133660.3 137174.0 137293.0 138658.0 1013.8 1023.2 1042.6 1043.2 1048.2 18643.0 19141.1 20286.0 20333.1 20338.3 58657.7 59840.1 61029.8 61040.7 61127.0 82114.0 82537.0 83282.0 85027.0 86180.0 7354.4 7538.8 7646.9 7894.8 8324.8 11894.6 13379.1 13380.7 13757.6 13795.2 11079.4 11135.0 11188.0 11267.2 11478.0 11410.3 11690.0 11782.4 11958.4 11979.2 11455.6 11584.7 11682.0 11889.0 11975.9 7675.8 7779.1 7898.8 7919.9 7965.7 9659.9 9868.5 10444.3 10599.0 11480.3 08:26:46 INFO - PROCESS | 1646 | 08:26:46 INFO - PROCESS | 1646 | [#3] mootools.html Cycles:180 Average:5420.85 Median:5258.99 stddev:2949.04 (56.1%) stddev-sans-first:2942.09 08:26:46 INFO - PROCESS | 1646 | Values: 1434.6 1439.8 1443.3 1455.6 1457.5 8105.4 8552.5 8552.6 8558.6 8565.5 7443.7 7694.6 8004.2 8268.2 8362.5 2307.3 2345.0 2345.0 2350.7 2365.0 2343.3 2363.8 2370.9 2374.3 2379.5 2294.9 2308.6 2316.4 2344.9 2348.9 2299.0 2308.5 2333.0 2334.6 2338.4 2289.9 2362.2 2363.4 2367.6 2370.4 8436.6 8507.1 8514.0 8524.4 8538.3 3806.7 5248.5 5251.5 5264.3 5280.4 3196.8 3307.6 3448.3 3499.5 3513.4 1054.8 1184.1 1189.8 1192.7 1231.5 10716.2 10837.6 10855.9 10859.7 11062.0 4473.4 4624.2 4726.0 4735.1 4764.3 9451.0 9528.0 9534.4 9535.9 9556.3 5267.9 5272.5 5283.7 5285.2 5298.8 4012.0 4135.4 4733.7 4805.5 4807.5 3848.2 3860.0 3948.0 3950.2 3989.8 1297.2 1302.0 1302.1 1315.3 1315.5 807.7 851.9 861.3 888.3 918.3 4687.8 5261.0 5595.5 5604.9 5684.7 876.9 969.8 982.6 983.7 987.6 3683.2 3683.9 3790.3 3813.6 3816.5 7859.6 9429.8 9750.5 10165.4 10176.3 9669.2 10019.0 10032.8 10046.0 10092.9 7345.5 7667.9 7695.8 7726.2 7874.4 5257.0 5288.0 5372.9 5581.7 5903.3 9071.0 9116.1 9315.0 9372.7 9412.0 9266.2 9271.6 9523.3 9574.8 9615.4 6994.2 7276.1 7284.6 7568.0 7816.4 7294.3 7405.2 7493.6 7866.7 7898.7 6954.4 7115.9 7142.8 7201.7 7234.2 6762.5 6765.6 6984.4 6997.0 7028.9 6150.9 6305.1 6400.1 6408.4 6415.2 4867.2 4885.7 4912.8 4929.9 4976.8 4891.0 4901.8 4903.6 5065.3 5067.8 08:26:46 INFO - PROCESS | 1646 | 08:26:46 INFO - PROCESS | 1646 | [#4] prototype.html Cycles:180 Average:4397.35 Median:4803.09 stddev:1311.50 (27.3%) stddev-sans-first:1279.24 08:26:46 INFO - PROCESS | 1646 | Values: 334.5 354.5 358.6 363.2 365.5 4931.2 5136.7 5194.8 5311.9 5352.9 4316.8 4639.2 4664.0 4667.3 5123.7 3500.3 4020.2 4157.5 4200.0 4204.5 3753.9 4191.5 4192.4 4221.5 4230.5 3434.4 3994.2 4003.0 4012.8 4219.9 3438.0 4016.9 4036.9 4135.8 4139.4 3203.3 3636.2 3686.9 3692.7 3741.6 4321.8 4437.4 5251.1 5255.8 5286.8 3908.2 4034.5 4443.7 4794.8 4845.8 5225.4 5631.5 5646.7 5652.7 5660.8 3280.9 3653.8 4113.2 4115.5 4131.6 5158.4 5466.4 5576.4 5687.6 5837.6 4124.6 4124.7 4129.2 4130.5 4136.8 3800.9 3932.6 4171.3 4198.8 4200.9 4276.8 4576.9 4587.9 4811.4 4831.2 4761.0 5649.3 5660.0 5661.3 5668.7 1155.4 1160.6 1237.0 1274.4 1282.4 773.7 822.3 870.3 870.5 883.2 4471.9 4556.8 4603.5 4855.1 5110.5 4836.4 5234.4 5418.4 5487.3 5507.1 4270.8 4729.8 5003.8 5028.8 5068.8 2501.6 2727.8 2783.4 2894.9 2922.8 4777.9 4891.6 5470.1 5687.2 5702.9 4650.3 4898.0 5367.4 5440.0 5689.9 5136.6 5156.7 5644.8 5670.7 5676.9 4602.8 4773.5 5143.4 5153.5 5533.0 4887.1 5083.0 5204.5 5364.7 5425.4 5288.4 5382.7 5555.3 5647.7 5759.7 3338.5 3383.5 3519.1 3654.8 3666.4 4228.1 5258.6 5288.0 5412.0 5521.7 4518.6 4728.5 4992.0 5323.1 5350.3 4616.8 4764.8 5133.8 5370.9 5380.7 4744.9 5102.2 5336.3 5498.5 5569.3 4382.2 5030.0 5538.5 5665.3 5684.4 4478.8 5246.6 5537.9 5593.3 5623.5 08:26:46 INFO - PROCESS | 1646 | 08:26:46 INFO - PROCESS | 1646 | [#5] yui.html Cycles:180 Average:20318.13 Median:4280.06 stddev:29054.84 (678.8%) stddev-sans-first:29098.80 08:26:46 INFO - PROCESS | 1646 | Values: 645.4 677.2 719.4 807.1 820.1 14620.5 14829.0 15265.5 15268.6 15375.7 13786.0 14034.4 14105.4 14161.4 14253.6 857.4 880.7 884.0 885.5 887.0 680.2 694.0 822.8 840.7 848.5 925.2 926.0 927.7 928.7 928.9 919.5 921.1 922.5 922.6 924.6 531.7 541.0 669.7 684.7 689.0 14799.0 14860.0 14871.9 14874.0 14876.9 13422.8 13501.5 13563.1 13580.6 13692.5 11645.3 11747.2 11832.4 12027.9 12345.7 4379.2 4428.8 4457.8 4458.1 4583.0 96435.0 102076.5 103721.0 104006.5 104617.0 10335.7 10564.5 10771.5 10789.0 11306.2 20892.1 21048.7 21410.3 21484.9 21547.0 7407.5 7633.2 7651.1 7680.0 7815.3 4155.1 4214.9 4215.9 4344.2 4411.2 2939.7 3318.1 3365.5 3423.9 3424.1 634.1 641.7 647.0 660.0 660.3 602.4 617.0 624.0 635.0 636.6 9318.6 9674.2 9676.3 9689.8 9892.2 606.0 610.1 630.2 656.3 664.3 4550.3 4648.7 4663.0 4669.3 4699.4 92659.0 105246.5 105949.5 106126.5 107018.5 79788.0 80080.6 81599.4 86072.1 88805.1 79583.0 80351.2 80791.6 81109.6 83267.6 10346.5 10687.5 10873.7 10904.8 10905.8 47784.5 48486.0 48697.0 49332.0 51109.0 68761.0 69441.7 69716.7 70309.6 73898.0 11607.7 13059.0 13139.4 13204.2 13525.5 13957.7 14229.5 14428.6 14605.5 15162.4 12966.0 13048.9 13520.0 13550.7 13715.9 13197.3 13349.3 13525.7 13721.7 13769.5 12376.0 12610.6 13307.4 13400.5 13421.2 10781.3 10841.7 11042.8 11204.8 11222.9 10656.2 10789.5 10827.0 10905.2 10916.7 08:26:46 INFO - PROCESS | 1646 | -------- Summary: end -------- 08:26:46 INFO - PROCESS | 1646 | 08:26:46 INFO - PROCESS | 1646 | 1487953606871 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method shutdown 08:26:47 INFO - TEST-INFO | 1646: exit 0 08:26:47 INFO - TEST-OK | dromaeo_css | took 1118049ms 08:26:47 INFO - TEST-START | kraken 08:26:47 INFO - operating with platform_type : mac_ 08:26:47 INFO - Initialising browser for kraken test... 08:26:47 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/tmpAxYpjB/profile http://localhost:49245/getInfo.html) 08:26:47 INFO - PROCESS | 1655 | 1487953607975 addons.xpi WARN Error loading bootstrap.js for disableSHA1rollout@mozilla.org: Error opening input stream (invalid filename?): jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/disableSHA1rollout@mozilla.org.xpi!/bootstrap.js 08:26:47 INFO - PROCESS | 1655 | 1487953607976 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method install 08:26:48 INFO - PROCESS | 1655 | 1487953608028 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method startup 08:26:49 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 08:26:49 INFO - PROCESS | 1655 | colorDepth:24 08:26:49 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 08:26:49 INFO - PROCESS | 1655 | __metrics 08:26:50 INFO - PROCESS | 1655 | 1487953610006 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method shutdown 08:26:50 INFO - PROCESS | 1655 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:26:50 INFO - PROCESS | 1655 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:26:50 INFO - TEST-INFO | 1655: exit 0 08:26:50 INFO - Browser initialized. 08:26:50 INFO - Running cycle 1/1 for kraken test... 08:26:50 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/tmpAxYpjB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:26:51 INFO - PROCESS | 1656 | 1487953611139 addons.xpi WARN Error loading bootstrap.js for disableSHA1rollout@mozilla.org: Error opening input stream (invalid filename?): jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/disableSHA1rollout@mozilla.org.xpi!/bootstrap.js 08:26:51 INFO - PROCESS | 1656 | 1487953611140 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method startup 08:27:02 INFO - PROCESS | 1656 | RSS: Main: 213176320 08:27:02 INFO - PROCESS | 1656 | 08:27:05 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-beat-detection) 08:27:06 INFO - PROCESS | 1656 | RSS: Main: 266092544 08:27:06 INFO - PROCESS | 1656 | 08:27:09 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-dft) 08:27:09 INFO - PROCESS | 1656 | RSS: Main: 276803584 08:27:09 INFO - PROCESS | 1656 | 08:27:13 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-fft) 08:27:13 INFO - PROCESS | 1656 | RSS: Main: 372346880 08:27:13 INFO - PROCESS | 1656 | 08:27:15 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-oscillator) 08:27:16 INFO - PROCESS | 1656 | RSS: Main: 277479424 08:27:16 INFO - PROCESS | 1656 | 08:27:18 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:27:18 INFO - PROCESS | 1656 | RSS: Main: 278831104 08:27:18 INFO - PROCESS | 1656 | 08:27:21 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-darkroom) 08:27:22 INFO - PROCESS | 1656 | RSS: Main: 318132224 08:27:22 INFO - PROCESS | 1656 | 08:27:25 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-desaturate) 08:27:25 INFO - PROCESS | 1656 | RSS: Main: 321028096 08:27:25 INFO - PROCESS | 1656 | 08:27:28 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49245/tests/kraken/driver.html?testName=json-parse-financial) 08:27:29 INFO - PROCESS | 1656 | RSS: Main: 318570496 08:27:29 INFO - PROCESS | 1656 | 08:27:30 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49245/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:27:31 INFO - PROCESS | 1656 | RSS: Main: 294326272 08:27:31 INFO - PROCESS | 1656 | 08:27:33 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:27:34 INFO - PROCESS | 1656 | RSS: Main: 296939520 08:27:34 INFO - PROCESS | 1656 | 08:27:36 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:27:36 INFO - PROCESS | 1656 | RSS: Main: 299950080 08:27:36 INFO - PROCESS | 1656 | 08:27:39 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:27:40 INFO - PROCESS | 1656 | RSS: Main: 317992960 08:27:40 INFO - PROCESS | 1656 | 08:27:43 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:27:44 INFO - PROCESS | 1656 | RSS: Main: 311259136 08:27:44 INFO - PROCESS | 1656 | 08:27:45 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49245/tests/kraken/driver.html?testName=ai-astar) 08:27:46 INFO - PROCESS | 1656 | RSS: Main: 355790848 08:27:46 INFO - PROCESS | 1656 | 08:27:46 INFO - PROCESS | 1656 | __start_tp_report 08:27:46 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 08:27:46 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 08:27:46 INFO - PROCESS | 1656 | |i|pagename|runs| 08:27:46 INFO - PROCESS | 1656 | |0;ai-astar;116;115;114;114;114;115;112;113;121;169 08:27:46 INFO - PROCESS | 1656 | |1;audio-beat-detection;168;139;140;145;112;109;113;138;163;140 08:27:46 INFO - PROCESS | 1656 | |2;audio-dft;184;180;183;182;181;183;183;185;183;185 08:27:46 INFO - PROCESS | 1656 | |3;audio-fft;81;58;57;89;62;59;56;56;85;58 08:27:46 INFO - PROCESS | 1656 | |4;audio-oscillator;85;70;87;70;68;68;82;67;69;81 08:27:46 INFO - PROCESS | 1656 | |5;imaging-gaussian-blur;88;85;135;85;88;86;88;85;87;85 08:27:46 INFO - PROCESS | 1656 | |6;imaging-darkroom;97;96;97;97;97;97;96;96;97;97 08:27:46 INFO - PROCESS | 1656 | |7;imaging-desaturate;87;86;86;88;84;85;85;88;89;88 08:27:46 INFO - PROCESS | 1656 | |8;json-parse-financial;56;51;52;54;53;56;56;56;55;56 08:27:46 INFO - PROCESS | 1656 | |9;json-stringify-tinderbox;66;76;63;83;113;101;84;70;70;70 08:27:46 INFO - PROCESS | 1656 | |10;stanford-crypto-aes;82;86;82;83;82;82;86;83;83;82 08:27:46 INFO - PROCESS | 1656 | |11;stanford-crypto-ccm;141;149;144;145;138;140;140;140;183;139 08:27:46 INFO - PROCESS | 1656 | |12;stanford-crypto-pbkdf2;166;166;164;164;167;169;169;237;233;173 08:27:46 INFO - PROCESS | 1656 | |13;stanford-crypto-sha256-iterative;66;65;65;65;66;66;63;65;66;64 08:27:46 INFO - PROCESS | 1656 | __end_tp_report 08:27:46 INFO - PROCESS | 1656 | __start_cc_report 08:27:46 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,2066 08:27:46 INFO - PROCESS | 1656 | __end_cc_report 08:27:46 INFO - PROCESS | 1656 | __startTimestamp1487953666182__endTimestamp 08:27:46 INFO - PROCESS | 1656 | 08:27:46 INFO - PROCESS | 1656 | ------- Summary: start ------- 08:27:46 INFO - PROCESS | 1656 | Number of tests: 14 08:27:46 INFO - PROCESS | 1656 | 08:27:46 INFO - PROCESS | 1656 | [#0] ai-astar Cycles:10 Average:120.30 Median:115.00 stddev:17.28 (15.0%) stddev-sans-first:18.26 08:27:46 INFO - PROCESS | 1656 | Values: 116.0 115.0 114.0 114.0 114.0 115.0 112.0 113.0 121.0 169.0 08:27:46 INFO - PROCESS | 1656 | 08:27:46 INFO - PROCESS | 1656 | [#1] audio-beat-detection Cycles:10 Average:136.70 Median:140.00 stddev:20.30 (14.5%) stddev-sans-first:18.10 08:27:46 INFO - PROCESS | 1656 | Values: 168.0 139.0 140.0 145.0 112.0 109.0 113.0 138.0 163.0 140.0 08:27:46 INFO - PROCESS | 1656 | 08:27:46 INFO - PROCESS | 1656 | [#2] audio-dft Cycles:10 Average:182.90 Median:183.00 stddev:1.60 (0.9%) stddev-sans-first:1.64 08:27:46 INFO - PROCESS | 1656 | Values: 184.0 180.0 183.0 182.0 181.0 183.0 183.0 185.0 183.0 185.0 08:27:46 INFO - PROCESS | 1656 | 08:27:46 INFO - PROCESS | 1656 | [#3] audio-fft Cycles:10 Average:66.10 Median:60.50 stddev:13.29 (22.0%) stddev-sans-first:12.95 08:27:46 INFO - PROCESS | 1656 | Values: 81.0 58.0 57.0 89.0 62.0 59.0 56.0 56.0 85.0 58.0 08:27:46 INFO - PROCESS | 1656 | 08:27:46 INFO - PROCESS | 1656 | [#4] audio-oscillator Cycles:10 Average:74.70 Median:75.50 stddev:8.00 (10.6%) stddev-sans-first:7.57 08:27:46 INFO - PROCESS | 1656 | Values: 85.0 70.0 87.0 70.0 68.0 68.0 82.0 67.0 69.0 81.0 08:27:46 INFO - PROCESS | 1656 | 08:27:46 INFO - PROCESS | 1656 | [#5] imaging-gaussian-blur Cycles:10 Average:91.20 Median:86.50 stddev:15.45 (17.9%) stddev-sans-first:16.34 08:27:46 INFO - PROCESS | 1656 | Values: 88.0 85.0 135.0 85.0 88.0 86.0 88.0 85.0 87.0 85.0 08:27:46 INFO - PROCESS | 1656 | 08:27:46 INFO - PROCESS | 1656 | [#6] imaging-darkroom Cycles:10 Average:96.70 Median:97.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 08:27:46 INFO - PROCESS | 1656 | Values: 97.0 96.0 97.0 97.0 97.0 97.0 96.0 96.0 97.0 97.0 08:27:46 INFO - PROCESS | 1656 | 08:27:46 INFO - PROCESS | 1656 | [#7] imaging-desaturate Cycles:10 Average:86.60 Median:87.50 stddev:1.65 (1.9%) stddev-sans-first:1.74 08:27:46 INFO - PROCESS | 1656 | Values: 87.0 86.0 86.0 88.0 84.0 85.0 85.0 88.0 89.0 88.0 08:27:46 INFO - PROCESS | 1656 | 08:27:46 INFO - PROCESS | 1656 | [#8] json-parse-financial Cycles:10 Average:54.50 Median:56.00 stddev:1.90 (3.4%) stddev-sans-first:1.94 08:27:46 INFO - PROCESS | 1656 | Values: 56.0 51.0 52.0 54.0 53.0 56.0 56.0 56.0 55.0 56.0 08:27:46 INFO - PROCESS | 1656 | 08:27:46 INFO - PROCESS | 1656 | [#9] json-stringify-tinderbox Cycles:10 Average:79.60 Median:70.00 stddev:16.17 (23.1%) stddev-sans-first:16.39 08:27:46 INFO - PROCESS | 1656 | Values: 66.0 76.0 63.0 83.0 113.0 101.0 84.0 70.0 70.0 70.0 08:27:46 INFO - PROCESS | 1656 | 08:27:46 INFO - PROCESS | 1656 | [#10] stanford-crypto-aes Cycles:10 Average:83.10 Median:83.00 stddev:1.60 (1.9%) stddev-sans-first:1.64 08:27:46 INFO - PROCESS | 1656 | Values: 82.0 86.0 82.0 83.0 82.0 82.0 86.0 83.0 83.0 82.0 08:27:46 INFO - PROCESS | 1656 | 08:27:46 INFO - PROCESS | 1656 | [#11] stanford-crypto-ccm Cycles:10 Average:145.90 Median:142.50 stddev:13.45 (9.4%) stddev-sans-first:14.15 08:27:46 INFO - PROCESS | 1656 | Values: 141.0 149.0 144.0 145.0 138.0 140.0 140.0 140.0 183.0 139.0 08:27:46 INFO - PROCESS | 1656 | 08:27:46 INFO - PROCESS | 1656 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.80 Median:169.00 stddev:28.70 (17.0%) stddev-sans-first:29.94 08:27:46 INFO - PROCESS | 1656 | Values: 166.0 166.0 164.0 164.0 167.0 169.0 169.0 237.0 233.0 173.0 08:27:46 INFO - PROCESS | 1656 | 08:27:46 INFO - PROCESS | 1656 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.10 Median:65.50 stddev:0.99 (1.5%) stddev-sans-first:1.00 08:27:46 INFO - PROCESS | 1656 | Values: 66.0 65.0 65.0 65.0 66.0 66.0 63.0 65.0 66.0 64.0 08:27:46 INFO - PROCESS | 1656 | -------- Summary: end -------- 08:27:46 INFO - PROCESS | 1656 | 08:27:46 INFO - PROCESS | 1656 | 1487953666203 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method shutdown 08:27:47 INFO - TEST-INFO | 1656: exit 0 08:27:47 INFO - TEST-OK | kraken | took 59620ms 08:27:47 INFO - SUITE-END | took 1177s 08:27:47 INFO - Completed test suite (00:19:37) 08:27:47 INFO - javascript benchmark 08:27:47 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3190.808551486911, 3365.7475689323305, 3367.562216911801, 3385.5091011803306, 3437.3469040882233, 5678.744456499458, 5705.172749951254, 5725.2843394575675, 5736.340320863102, 5747.281589801275, 5725.0, 5778.191053252537, 5786.537077033838, 5810.709464526767, 5826.0, 5223.6343455958095, 5230.084735171339, 5276.261323414706, 5292.285541451913, 5344.866378340534, 5151.74241287936, 5182.559482443997, 5344.91982620259, 5350.3312085989255, 5351.09031464652, 5255.0, 5258.395284542288, 5296.020236256286, 5297.12597421425, 5368.91946620801, 5303.65526240795, 5318.0, 5320.308359913187, 5326.360836699582, 5345.1982202669715, 5174.456682048369, 5230.0, 5258.079836028718, 5292.92060619091, 5324.361076670785, 5622.184783206412, 5695.060315048012, 5695.629784064064, 5737.2254745609225, 5738.942610573882, 6130.662813545253, 6197.628142311476, 6198.101275315099, 6216.285127210338, 6268.341824108448, 6230.0, 6281.528885333618, 6293.527985401113, 6311.8737625247695, 6336.46140078094, 5798.797042103548, 5805.5065319447895, 5808.941910580882, 5826.679532625746, 5856.502197313191, 6217.813465596039, 6245.656488893154, 6258.530610204253, 6299.433051025429, 6317.715702793385, 6189.535784816157, 6191.783287584913, 6199.194104766351, 6218.409251121137, 6239.344868788802, 6238.469730072904, 6282.2147231596055, 6298.118263443122, 6300.243970723543, 6320.936790632035, 6126.142340072393, 6163.137160797492, 6173.629582225081, 6265.8120256392385, 6290.842728931813, 6204.534659900525, 6239.095331176954, 6246.875062498815, 6254.0, 6330.556861019684, 4965.776540055706, 4974.27872958419, 4979.775910084055, 4982.701037937735, 5002.0, 4345.543717909637, 4481.955180448219, 4488.528704486047, 4490.977545112319, 4497.595216430537, 5725.0, 5786.132080187921, 5817.883642327214, 5820.854478638068, 5829.650220986754, 6282.371762823773, 6299.212598425197, 6321.273053598803, 6341.524385671093, 6346.524010699124, 5624.85937851557, 5690.658560486301, 5698.344690360662, 5745.626534275259, 5832.737526811304, 5458.863528411742, 5507.118860982304, 5534.667919924817, 5543.1408131739645, 5551.2228288039705, 6210.31686514474, 6289.842753931005, 6366.872662546816, 6367.681615919093, 6372.74509019634, 6204.968975155095, 6230.408111229426, 6268.592541484789, 6336.366363363627, 6352.809415717536, 6319.652419116809, 6328.493720502257, 6328.588641738273, 6333.113364129118, 6346.238451385863, 6109.908351374644, 6128.747575574743, 6237.5321850860455, 6260.092286618324, 6267.0286105653695, 6227.003679411272, 6236.126942228001, 6275.309715931153, 6294.874102518015, 6314.242290925118, 5947.375525569926, 6002.819915402545, 6021.729022194099, 6131.202943617302, 6178.320384757583, 5989.341172471112, 6100.664463454375, 6157.96921015392, 6173.413525715229, 6186.845328866814, 5868.031774757117, 5947.167396564398, 5969.313528944227, 5978.8206353809455, 6077.4226448487325, 6179.536534759821, 6194.225721784777, 6215.471684906653, 6216.595921265103, 6244.1570387997035, 6036.6679832609625, 6042.818715438544, 6065.089496808491, 6072.14989901423, 6103.511719062376], "value": 5750.038914184068, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [450.74758135444154, 458.28049961560356, 464.45426623717134, 520.9687418754877, 531.3305235403391, 31342.373152536966, 40930.38604420932, 43010.78494607526, 43081.0, 43146.35280470795, 44661.33008004883, 45206.54793452073, 45311.64065078045, 45382.319265211045, 45423.31865022027, 8390.2868256198, 9161.083891610835, 9363.531823408837, 9365.625374984993, 9368.0, 8472.406931514766, 8679.783005424852, 8680.131986801332, 8696.826063478728, 11085.889141108566, 11130.05394541465, 11318.434078296094, 11334.716632084223, 11356.943215283913, 11391.974722274994, 11297.887021129765, 11400.71498212543, 11414.201005929588, 11415.42922853854, 11420.885791142066, 8358.707445239388, 8593.441426307332, 11361.829572556418, 11395.658130256106, 11426.37154956477, 32797.67202327994, 33065.0, 41256.1748765026, 41852.162956741, 42141.36787948183, 3920.901977450558, 3932.5954137903755, 3957.3668213085766, 3958.7426817256787, 4068.1863627274665, 28400.0, 30492.237694057603, 31505.684943150503, 31574.0, 32343.191420214447, 3079.6612372638992, 3148.197209711509, 3184.379046086014, 3211.1169428407375, 3224.8226347550876, 397293.0, 443751.0, 472331.6383418095, 472796.0, 474652.0, 60580.0, 61055.0, 61170.38829611647, 61206.693966529885, 61212.693936530035, 63818.72362552723, 63914.0, 63983.6800815993, 64110.0, 64168.35831641717, 15752.0, 16251.349946002027, 16342.509724708278, 16344.34622615106, 16392.688584913416, 12540.0, 12598.055145864097, 12604.873951260553, 12624.0, 12630.431630576646, 13526.256055916821, 14529.055611385225, 16045.839541604668, 17788.152735569583, 18838.52903677419, 7369.81575460607, 7759.7672069838, 7834.569098699512, 7920.207979202034, 7951.642176101974, 866.7096522664851, 876.4916348517844, 891.2825175733597, 892.5001998880648, 923.9861402078974, 29839.254018649706, 30492.390152197277, 30807.8459607705, 31395.37209255848, 31551.84224078865, 1071.6570697376765, 1077.2405454154803, 1100.510272928539, 1113.6102364172475, 1185.4902391971534, 15933.521994340188, 15984.440544581117, 16015.886806503662, 16167.838321616633, 16307.592310192103, 671801.0, 676731.0, 677576.0, 677719.0, 680094.0, 63919.0, 64415.355846441875, 64473.355266447674, 64578.354216458174, 64686.70626587442, 1427.6573622330566, 1442.5945007863502, 1461.554225961092, 1492.7462331403688, 1493.5295381954866, 29004.0, 29007.85496072506, 30378.0, 30551.2362190947, 31156.688433116284, 46455.30317045179, 54396.456035439136, 55399.72300138474, 55485.167722485, 55558.72220638871, 561876.0, 563034.0, 564345.0, 564904.0, 565226.0, 23451.76548234496, 27656.8617156921, 27908.58137127904, 28180.15459536217, 28373.574396384483, 40571.1885762289, 41563.7921810389, 48962.51037489579, 50360.4891853245, 50652.0, 37638.24723505569, 39776.403353949136, 40768.3884741735, 40967.97580060522, 42342.78828605961, 39032.80483597677, 39812.40281395723, 40582.985425364604, 41539.79230103951, 41543.37684934668, 38289.80855095707, 38461.4230786544, 38635.2272954545, 38853.8343849685, 39272.60727392689, 34542.82728586341, 35035.64964350426, 35573.0, 35595.466068008485, 35754.463683044254, 24868.751312486642, 25236.74763252344, 25384.603846788603, 25427.74572254254, 25555.361115971507], "value": 20362.879944721102, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [369.3093914380106, 390.1650467998482, 394.3040533458445, 401.5402364292885, 421.70269959678427, 14318.642033949143, 15221.619459513518, 18157.636847263046, 20369.694454583194, 20872.165113395484, 21303.786962130373, 21649.0, 21843.017064232106, 21891.0, 22366.66450003251, 5787.855303617401, 5814.389489103657, 5911.88176236475, 5959.523238140939, 6100.450959413652, 5947.256592925884, 5957.463828255456, 5984.3417224105315, 5984.670843103628, 6015.368386319439, 4194.517630472505, 4352.782360881959, 4364.410804541378, 6469.094326794253, 6520.93479065208, 6314.179156709644, 6495.642739649317, 6502.6748662566915, 6508.446782023534, 6511.153550038512, 5526.806561770359, 5744.540436765091, 5750.942490575083, 5862.443067908542, 5882.852928676774, 26229.606555901675, 26649.600255996174, 26752.59871101935, 26872.865635671893, 26933.730662693317, 2699.303549857572, 2737.9726202738116, 2750.5324094903913, 2751.5735061065566, 2754.3664957059787, 15343.00270482415, 15681.921590392089, 15730.842691573052, 15751.291191896393, 16442.75335869963, 1613.4594910704927, 1619.4331983805573, 1627.6826018926315, 1627.6907387596436, 1641.4747280870126, 128266.0, 131167.0, 134741.28405505858, 135033.0, 136363.0, 11615.593454229062, 11649.650510484698, 11728.237664551947, 11765.823227728466, 11915.940420298017, 14972.203335599797, 14978.0, 14978.925105374403, 14993.850061499244, 15046.849531504764, 8658.177473140042, 8791.5164665944, 8806.163414475615, 8882.0, 8989.190972812477, 7150.678219480136, 8647.783805404915, 8723.58244248475, 8837.425567338103, 8957.059508751618, 6230.968845155745, 7194.964025179946, 7320.084989376328, 7320.780376588711, 7327.816804579928, 1882.4729075858509, 1915.415798181568, 1934.8258656720682, 1935.158206180316, 1936.2545420013396, 894.9731508054768, 1015.9477854733398, 1039.4698703661188, 1044.3942513342242, 1070.0155856611937, 22343.55312893733, 22957.540849183257, 22965.311040668807, 23056.53886922252, 23074.538509229722, 1065.9093977012042, 1088.5319312695617, 1095.1676725688535, 1116.3413585984308, 1122.0967121467297, 11083.72290692739, 11817.113716471298, 11970.521179152736, 12049.638510844688, 12114.454849531792, 749405.0, 756984.0, 765734.0, 773533.0, 782465.0, 133305.0, 133660.33169834284, 137174.0, 137293.0, 138658.0, 1013.8023085498257, 1023.2428003277521, 1042.6455005298235, 1043.1967385113421, 1048.1876545677162, 18642.974636394585, 19141.13864876056, 20286.0, 20333.085011174237, 20338.288159914333, 58657.70671146617, 59840.10239846319, 61029.77940441255, 61040.694796525735, 61127.0, 82114.0, 82537.0, 83282.0, 85027.0, 86180.0, 7354.411647068328, 7538.811529711801, 7646.885296720665, 7894.842103158019, 8324.750257492286, 11894.583689570823, 13379.063465557318, 13380.665483362605, 13757.587272381845, 13795.17228966225, 11079.390633515233, 11135.0, 11188.0, 11267.154963377616, 11478.0, 11410.315381077162, 11690.0, 11782.410879456163, 11958.402079896145, 11979.161458698156, 11455.59905403307, 11584.652460426201, 11682.0, 11888.989435898045, 11975.940120299343, 7675.846483070196, 7779.144294127755, 7898.802529936797, 7919.920800792149, 7965.721199758213, 9659.855102173333, 9868.457234852152, 10444.321119127233, 10599.0, 11480.311181329147], "value": 10189.961014697185, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1434.5552878607632, 1439.8347665481706, 1443.292786534598, 1455.585158229904, 1457.5408746244932, 8105.351571874251, 8552.486850788957, 8552.615132319053, 8558.572071396437, 8565.501037318467, 7443.739469118582, 7694.576798276093, 8004.199580041993, 8268.173182681743, 8362.498250104998, 2307.3424074138875, 2344.988275058622, 2345.0150936606583, 2350.670906073151, 2364.9645255321097, 2343.320437073247, 2363.8108951283857, 2370.9170179043736, 2374.27584586701, 2379.5003049359657, 2294.908203671851, 2308.5729140109133, 2316.420894776306, 2344.871032093234, 2348.9295321140307, 2298.977010229893, 2308.53829234153, 2333.0, 2334.5914464968564, 2338.427085364097, 2289.9084036638515, 2362.1968530699646, 2363.3855197648563, 2367.644853272023, 2370.4073981504625, 8436.620352084172, 8507.149285071506, 8513.95743021281, 8524.360672949491, 8538.316934645214, 3806.695464362844, 5248.475152484759, 5251.501107394792, 5264.263003179558, 5280.392754833205, 3196.7762256642045, 3307.603087629476, 3448.3103379324234, 3499.527563778908, 3513.4027215373194, 1054.783769327286, 1184.1414974143763, 1189.7620475904853, 1192.7077865922818, 1231.4889320931802, 10716.196285278635, 10837.620683276047, 10855.945720271347, 10859.728506787314, 11062.004419602274, 4473.351364052194, 4624.237000894882, 4726.007538416902, 4735.076660051257, 4764.285357196379, 9450.952745236229, 9527.999560046233, 9534.380265282734, 9535.90464095364, 9556.331056826095, 5267.894642107136, 5272.525472707474, 5283.656562323436, 5285.207218917193, 5298.788048478094, 4012.0, 4135.421041054255, 4733.668643195012, 4805.471398146201, 4807.471178170398, 3848.172642881738, 3860.0, 3948.032731980683, 3950.2099580083523, 3989.7606143631474, 1297.195738642048, 1301.9609411717663, 1302.0820321673198, 1315.2963164707028, 1315.5395611535887, 807.6567458829974, 851.9275861551714, 861.3023451004706, 888.3026823943173, 918.3158546882481, 4687.835925742583, 5261.0, 5595.496405323541, 5604.887902241932, 5684.715764211773, 876.8772371867943, 969.8205831921117, 982.6167794560128, 983.6557204978343, 987.6098940918297, 3683.2081102562543, 3683.889483315505, 3790.298794722985, 3813.56144043433, 3816.46569480273, 7859.6463159157975, 9429.764255893657, 9750.463724495079, 10165.44090075053, 10176.338537995154, 9669.226461883032, 10019.048190421898, 10032.79934401308, 10046.04562566555, 10092.949535252277, 7345.522541034816, 7667.8849817252785, 7695.769126926201, 7726.227377262228, 7874.448788584858, 5256.9737151314, 5287.9735601321745, 5372.928590692492, 5581.665100094007, 5903.26209223847, 9071.0, 9116.08839116083, 9315.0, 9372.67195648149, 9412.0, 9266.21237194846, 9271.582778774837, 9523.28575356837, 9574.808503829745, 9615.375000624937, 6994.2306346302885, 7276.090488688914, 7284.599347035752, 7568.0, 7816.374690024898, 7294.343509084208, 7405.185429602631, 7493.625318734151, 7866.72466463671, 7898.68405263783, 6954.408875245661, 7115.928840711526, 7142.821429464305, 7201.71193152268, 7234.240404757425, 6762.49281303914, 6765.627890466071, 6984.371406573433, 6997.0, 7028.894566581607, 6150.938490615215, 6305.054241863573, 6400.135981642418, 6408.423241908251, 6415.1981002374705, 4867.245576935581, 4885.682430641997, 4912.7543622818, 4929.926051109308, 4976.800927962841, 4891.0, 4901.828436004723, 4903.6077113831525, 5065.341505604248, 5067.77195026232], "value": 4417.324473740553, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [334.4581777520417, 354.53732878593433, 358.6144894238693, 363.16834449111536, 365.5412457366007, 4931.1617025105725, 5136.743162841853, 5194.766235519402, 5311.867203319915, 5352.919706204405, 4316.84891028814, 4639.1881420751315, 4663.953360466395, 4667.3430932019155, 5123.743812809364, 3500.3174380995715, 4020.216057868724, 4157.542670306264, 4200.0339921818, 4204.453421055258, 3753.86861459849, 4191.476065491814, 4192.392103145042, 4221.451211342521, 4230.513490948534, 3434.3989801784714, 3994.241094192109, 4002.9599704003026, 4012.7592344459426, 4219.936700949488, 3437.9656203438144, 4016.8869126427107, 4036.9394459083137, 4135.793210339471, 4139.399787030864, 3203.2632494526156, 3636.236425333711, 3686.870959516431, 3692.7415080944343, 3741.5884252732176, 4321.848735294281, 4437.4009508716235, 5251.140411797326, 5255.816046438356, 5286.762095705702, 3908.218356328746, 4034.487067349744, 4443.711158774701, 4794.832180873653, 4845.806167753286, 5225.399079105913, 5631.493165615114, 5646.6894320812335, 5652.660840349551, 5660.792291006511, 3280.885169019097, 3653.8173091345325, 4113.177364527077, 4115.468986573266, 4131.586841315874, 5158.4067832199025, 5466.446809573769, 5576.442355764391, 5687.601867869251, 5837.620554663933, 4124.638869173165, 4124.711270211056, 4129.224433493567, 4130.463039804806, 4136.793160341971, 3800.923981520354, 3932.5618116767787, 4171.33258678616, 4198.7956322930095, 4200.936985945214, 4276.8289268429535, 4576.908461830812, 4587.9082418351445, 4811.350467686818, 4831.227003679394, 4760.9765849513815, 5649.265595472644, 5660.037793575102, 5661.264035675335, 5668.659880407189, 1155.381870699172, 1160.570588882119, 1236.9567065152676, 1274.4392467314537, 1282.4229096906242, 773.7407968330559, 822.2814318075251, 870.2950610005915, 870.4820631724114, 883.240413244622, 4471.910561788746, 4556.792450000752, 4603.49361570227, 4855.053264613366, 5110.463401342806, 4836.396884321133, 5234.3718753749035, 5418.4039755626845, 5487.259220005328, 5507.091329930597, 4270.846871344719, 4729.834455794031, 5003.824866129668, 5028.7737051832755, 5068.758154252158, 2501.5872381057284, 2727.768139708147, 2783.3737409082796, 2894.8552572371295, 2922.8246305221755, 4777.856664300077, 4891.633127515451, 5470.11621748555, 5687.175359572839, 5702.914456283159, 4650.279206722963, 4897.987737541458, 5367.403839427593, 5440.0, 5689.862027594415, 5136.614753893398, 5156.716380599028, 5644.78637093027, 5670.688112153789, 5676.914846277309, 4602.815887364468, 4773.498782627913, 5143.408508021475, 5153.510416510426, 5533.0243337194925, 4887.095887260868, 5082.974585127051, 5204.531592156724, 5364.7049412282695, 5425.376081750492, 5288.4182739897815, 5382.677039377493, 5555.333399980071, 5647.701028763326, 5759.7408116634015, 3338.4825352070466, 3383.4755612880044, 3519.131907495383, 3654.7705899401876, 3666.4151773000503, 4228.1332326873535, 5258.579313654975, 5288.0, 5411.972940135274, 5521.668699878021, 4518.615917647036, 4728.456227533878, 4991.9500804991485, 5323.0950738373795, 5350.277712508761, 4616.776554213069, 4764.809407623657, 5133.845984620468, 5370.872116855504, 5380.650257733235, 4744.8885246065165, 5102.2346648003095, 5336.306280183551, 5498.525663451543, 5569.331680198402, 4382.233109205812, 5030.0, 5538.483063254943, 5665.348484924287, 5684.403137670485, 4478.753668548131, 5246.554042906243, 5537.889242215053, 5593.300837395325, 5623.465770751772], "value": 3991.0534837555006, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [645.4352441613588, 677.1603212017101, 719.4244604316549, 807.0920214758397, 820.0774129104756, 14620.4882829101, 14829.0, 15265.542033738979, 15268.618284542892, 15375.69248615027, 13786.0, 14034.438622455089, 14105.365258563364, 14161.362738676758, 14253.643658908533, 857.4340934982913, 880.7093659092516, 884.0099089020274, 885.5483703311294, 887.0464250930244, 680.1600023970393, 693.9763848323732, 822.7860756203401, 840.7267638017638, 848.5417874347845, 925.2228128372166, 925.9814803703922, 927.6892241099206, 928.7399528132133, 928.9303302252324, 919.5402298850574, 921.138735282513, 922.4695799915075, 922.5710044829186, 924.6301479408223, 531.6544246239936, 541.0450554770832, 669.7019826177353, 684.7192651013061, 688.9965550172267, 14798.964072514927, 14860.0, 14871.884608654285, 14874.033187842864, 14876.851231487655, 13422.798658020138, 13501.527446539421, 13563.118397304144, 13580.592582222549, 13692.452301907912, 11645.324233446052, 11747.177697561088, 11832.408379580986, 12027.939860300643, 12345.69135771604, 4379.233634114017, 4428.756418396987, 4457.799399027052, 4458.108378324348, 4582.977085114553, 96435.0, 102076.48961755144, 103721.0, 104006.47996760043, 104617.0, 10335.74160645975, 10564.524596393181, 10771.515281812337, 10789.0, 11306.152038596974, 20892.059857306456, 21048.684269735968, 21410.25064122748, 21484.892575537022, 21547.030383632773, 7407.481476296715, 7633.2366763324335, 7651.120121186135, 7679.961600191963, 7815.29662330393, 4155.127423241092, 4214.852480163241, 4215.894602634898, 4344.218040752695, 4411.205982923105, 2939.6913324101088, 3318.1206980150278, 3365.545651337038, 3423.9144021399666, 3424.109731469786, 634.1249076274711, 641.6503005861816, 646.9773557925544, 659.9637019963948, 660.2572106380322, 602.4425765852648, 616.981490555284, 623.9875202496015, 634.9619022858643, 636.6466611030829, 9318.627254909728, 9674.226106681086, 9676.322657413913, 9689.806203875884, 9892.207332692102, 606.0061499141324, 610.0868353611452, 630.1870586942792, 656.2846497317946, 664.3257094049544, 4550.294704320836, 4648.697834640738, 4663.0, 4669.276262179367, 4699.436067671902, 92659.0, 105246.47376763067, 105949.47025264824, 106126.46936765421, 107018.46490767496, 79788.0, 80080.5995970028, 81599.36801263942, 86072.1267542962, 88805.11194887968, 79583.0, 80351.19648803554, 80791.59604201942, 81109.59445202736, 83267.5836620813, 10346.548987110531, 10687.46562671879, 10873.673789786319, 10904.836427453436, 10905.781884362426, 47784.52215477801, 48486.0, 48697.0, 49332.0, 51109.0, 68761.0, 69441.65279173572, 69716.65141674259, 70309.59380812457, 73898.0, 11607.6517704469, 13059.02057345722, 13139.408726607517, 13204.207747535178, 13525.526606568723, 13957.720845583235, 14229.50196743109, 14428.567142985727, 14605.488807891672, 15162.393504259706, 12966.0, 13048.934755326163, 13520.0, 13550.728985420039, 13715.931420342835, 13197.34013299312, 13349.332533373487, 13525.729485410433, 13721.656958576115, 13769.518066867611, 12376.0, 12610.621681349574, 13307.401166947317, 13400.530981415604, 13421.194728316332, 10781.299215550964, 10841.67474975752, 11042.834357484804, 11204.775904481701, 11222.889797056183, 10656.200784941315, 10789.493265868121, 10827.025567698945, 10905.236633435583, 10916.727081823017], "value": 7441.168562811396, "unit": "score"}], "name": "dromaeo_css", "value": 7341.251202930573, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 115.0, 114.0, 114.0, 114.0, 115.0, 112.0, 113.0, 121.0, 169.0], "value": 120.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 139.0, 140.0, 145.0, 112.0, 109.0, 113.0, 138.0, 163.0, 140.0], "value": 136.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 180.0, 183.0, 182.0, 181.0, 183.0, 183.0, 185.0, 183.0, 185.0], "value": 182.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 58.0, 57.0, 89.0, 62.0, 59.0, 56.0, 56.0, 85.0, 58.0], "value": 66.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 70.0, 87.0, 70.0, 68.0, 68.0, 82.0, 67.0, 69.0, 81.0], "value": 74.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 135.0, 85.0, 88.0, 86.0, 88.0, 85.0, 87.0, 85.0], "value": 91.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 96.0, 97.0, 97.0, 97.0, 97.0, 96.0, 96.0, 97.0, 97.0], "value": 96.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 86.0, 88.0, 84.0, 85.0, 85.0, 88.0, 89.0, 88.0], "value": 86.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 51.0, 52.0, 54.0, 53.0, 56.0, 56.0, 56.0, 55.0, 56.0], "value": 54.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 76.0, 63.0, 83.0, 113.0, 101.0, 84.0, 70.0, 70.0, 70.0], "value": 79.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 86.0, 82.0, 83.0, 82.0, 82.0, 86.0, 83.0, 83.0, 82.0], "value": 83.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 149.0, 144.0, 145.0, 138.0, 140.0, 140.0, 140.0, 183.0, 139.0], "value": 145.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 166.0, 164.0, 164.0, 167.0, 169.0, 169.0, 237.0, 233.0, 173.0], "value": 180.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 65.0, 65.0, 66.0, 66.0, 63.0, 65.0, 66.0, 64.0], "value": 65.1, "unit": "score"}], "name": "kraken", "value": 1464.2, "alertThreshold": 2.0}]} 08:27:47 INFO - Return code: 0 08:27:47 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:27:47 ERROR - # TBPL SUCCESS # 08:27:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:27:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:27:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:27:47 INFO - 2017-02-24 08:27:47.504 screenresolution[1660:9937] starting screenresolution argv=screenresolution get 08:27:47 INFO - 2017-02-24 08:27:47.506 screenresolution[1660:9937] Display 0: 1600x1200x32@60 08:27:47 INFO - 2017-02-24 08:27:47.513 screenresolution[1661:9940] starting screenresolution argv=screenresolution list 08:27:47 INFO - Available Modes on Display 0 08:27:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:27:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:27:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:27:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:27:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:27:47 INFO - Intel Iris: 08:27:47 INFO - Chipset Model: Intel Iris 08:27:47 INFO - Type: GPU 08:27:47 INFO - Bus: Built-In 08:27:47 INFO - VRAM (Dynamic, Max): 1536 MB 08:27:47 INFO - Vendor: Intel (0x8086) 08:27:47 INFO - Device ID: 0x0a2e 08:27:47 INFO - Revision ID: 0x0009 08:27:47 INFO - Displays: 08:27:47 INFO - Display: 08:27:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:27:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:27:47 INFO - Display Serial Number: 1600x1200 60 08:27:47 INFO - Main Display: Yes 08:27:47 INFO - Mirror: Off 08:27:47 INFO - Online: Yes 08:27:47 INFO - Rotation: Supported 08:27:47 INFO - Return code: 0 08:27:47 INFO - Running post-action listener: _resource_record_post_action 08:27:47 INFO - [mozharness: 2017-02-24 16:27:47.749175Z] Finished run-tests step (success) 08:27:47 INFO - Running post-run listener: _resource_record_post_run 08:27:47 INFO - Total resource usage - Wall time: 1198s; CPU: 54.0%; Read bytes: 38040576; Write bytes: 506941440; Read time: 238; Write time: 1565 08:27:47 INFO - TinderboxPrint: CPU usage
53.8% 08:27:47 INFO - TinderboxPrint: I/O read bytes / time
38,040,576 / 238 08:27:47 INFO - TinderboxPrint: I/O write bytes / time
506,941,440 / 1,565 08:27:47 INFO - TinderboxPrint: CPU idle
2,213.0 (46.2%) 08:27:47 INFO - TinderboxPrint: CPU system
960.8 (20.0%) 08:27:47 INFO - TinderboxPrint: CPU user
1,619.9 (33.8%) 08:27:47 INFO - TinderboxPrint: Swap in / out
245,100,544 / 0 08:27:47 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 209091072; Write bytes: 210347520; Read time: 15949; Write time: 477 08:27:47 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 34345984; Write bytes: 295242240; Read time: 213; Write time: 1084 08:27:48 INFO - Running post-run listener: _upload_blobber_files 08:27:48 INFO - Blob upload gear active. 08:27:48 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:27:48 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 08:27:48 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'] 08:27:48 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 08:27:48 INFO - (blobuploader) - INFO - Open directory for files ... 08:27:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:27:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:27:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:27:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:27:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:27:50 INFO - (blobuploader) - INFO - Done attempting. 08:27:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:27:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:27:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:27:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:27:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:27:51 INFO - (blobuploader) - INFO - Done attempting. 08:27:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:27:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:27:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:27:52 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:27:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:27:52 INFO - (blobuploader) - INFO - Done attempting. 08:27:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:27:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:27:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:27:55 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:27:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:27:55 INFO - (blobuploader) - INFO - Done attempting. 08:27:55 INFO - (blobuploader) - INFO - Iteration through files over. 08:27:55 INFO - Return code: 0 08:27:55 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:27:55 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:27:55 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/00ea593b58beaf6b4590e6660fbafb08175bf7b5f763ca317f6994ef337f594d0da85ec81fa30a767f6e61b5f45bada58cb49aa2c62a063ef051493ea2c04154", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cb05ee3f44c673061bbcfd9e38107110c8eefbd210b15294efbd4c2e89595e6ee4732af88462fb325c18a351c884622c0ea98f5041246e5ed55fe5988ae47196", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0527fda3b804448a6346e6fe5587f14c0be3a7dc130834dbc58cb8ed95be897d0692ed74de846b82b1f85fad5ac3870124bfd21d0817c929a34a67dcba1d5a69", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/112d330546c124119d0fcdac63f93bb658223643048a79bb9c1acd2c3f549fccf4c9e014e7c8fcd8583af2b1489c9365b45c61ed0517fa05c332909f640381bf"} 08:27:55 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:27:55 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:27:55 INFO - Contents: 08:27:55 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/00ea593b58beaf6b4590e6660fbafb08175bf7b5f763ca317f6994ef337f594d0da85ec81fa30a767f6e61b5f45bada58cb49aa2c62a063ef051493ea2c04154", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cb05ee3f44c673061bbcfd9e38107110c8eefbd210b15294efbd4c2e89595e6ee4732af88462fb325c18a351c884622c0ea98f5041246e5ed55fe5988ae47196", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0527fda3b804448a6346e6fe5587f14c0be3a7dc130834dbc58cb8ed95be897d0692ed74de846b82b1f85fad5ac3870124bfd21d0817c929a34a67dcba1d5a69", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/112d330546c124119d0fcdac63f93bb658223643048a79bb9c1acd2c3f549fccf4c9e014e7c8fcd8583af2b1489c9365b45c61ed0517fa05c332909f640381bf"} 08:27:55 INFO - Running post-run listener: copy_logs_to_upload_dir 08:27:55 INFO - Copying logs to upload dir... 08:27:55 INFO - mkdir: /builds/slave/test/build/upload/logs 08:27:55 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1261.634756 ========= master_lag: 0.27 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-02-24 08:27:55.340832) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-24 08:27:55.344436) ========= 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.3FyvZ22uM2/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.kSFsaAohjP/Listeners TMPDIR=/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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/00ea593b58beaf6b4590e6660fbafb08175bf7b5f763ca317f6994ef337f594d0da85ec81fa30a767f6e61b5f45bada58cb49aa2c62a063ef051493ea2c04154", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cb05ee3f44c673061bbcfd9e38107110c8eefbd210b15294efbd4c2e89595e6ee4732af88462fb325c18a351c884622c0ea98f5041246e5ed55fe5988ae47196", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0527fda3b804448a6346e6fe5587f14c0be3a7dc130834dbc58cb8ed95be897d0692ed74de846b82b1f85fad5ac3870124bfd21d0817c929a34a67dcba1d5a69", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/112d330546c124119d0fcdac63f93bb658223643048a79bb9c1acd2c3f549fccf4c9e014e7c8fcd8583af2b1489c9365b45c61ed0517fa05c332909f640381bf"} build_url:https://queue.taskcluster.net/v1/task/GCi8I4R0T_iT7Vb1p6Sy-g/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010945 build_url: 'https://queue.taskcluster.net/v1/task/GCi8I4R0T_iT7Vb1p6Sy-g/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/00ea593b58beaf6b4590e6660fbafb08175bf7b5f763ca317f6994ef337f594d0da85ec81fa30a767f6e61b5f45bada58cb49aa2c62a063ef051493ea2c04154", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cb05ee3f44c673061bbcfd9e38107110c8eefbd210b15294efbd4c2e89595e6ee4732af88462fb325c18a351c884622c0ea98f5041246e5ed55fe5988ae47196", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0527fda3b804448a6346e6fe5587f14c0be3a7dc130834dbc58cb8ed95be897d0692ed74de846b82b1f85fad5ac3870124bfd21d0817c929a34a67dcba1d5a69", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/112d330546c124119d0fcdac63f93bb658223643048a79bb9c1acd2c3f549fccf4c9e014e7c8fcd8583af2b1489c9365b45c61ed0517fa05c332909f640381bf"}' ========= master_lag: 0.07 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-24 08:27:55.425584) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-24 08:27:55.425908) ========= 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.3FyvZ22uM2/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.kSFsaAohjP/Listeners TMPDIR=/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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.005071 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-24 08:27:55.473960) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-24 08:27:55.474280) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-24 08:27:55.474619) ========= ========= Total master_lag: 2.21 =========