builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0245 starttime: 1486297537.63 results: success (0) buildid: 20170205030206 builduid: 796af599b56145448c8149710829c898 revision: 3e555770a90a41e04bbb4ac41b65fa2f1db6977d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-05 04:25:37.631360) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-05 04:25:37.631816) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-05 04:25:37.632100) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-05 04:25:37.656894) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-05 04:25:37.657294) ========= 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.HGgOEBT2bf/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.CgNH6AW2qT/Listeners TMPDIR=/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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-05 04:25:37-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.17M=0.001s 2017-02-05 04:25:38 (8.17 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.611586 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-05 04:25:38.292014) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-05 04:25:38.292330) ========= 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.HGgOEBT2bf/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.CgNH6AW2qT/Listeners TMPDIR=/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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.022269 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-05 04:25:38.341024) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-05 04:25:38.341383) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 3e555770a90a41e04bbb4ac41b65fa2f1db6977d --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 3e555770a90a41e04bbb4ac41b65fa2f1db6977d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.HGgOEBT2bf/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.CgNH6AW2qT/Listeners TMPDIR=/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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-05 04:25:38,435 truncating revision to first 12 chars 2017-02-05 04:25:38,435 Setting DEBUG logging. 2017-02-05 04:25:38,435 attempt 1/10 2017-02-05 04:25:38,435 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/3e555770a90a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-05 04:25:39,586 unpacking tar archive at: mozilla-central-3e555770a90a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.417818 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-05 04:25:39.782962) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-05 04:25:39.783267) ========= script_repo_revision: 3e555770a90a41e04bbb4ac41b65fa2f1db6977d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-05 04:25:39.783686) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-05 04:25:39.783957) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-05 04:25:39.803494) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2017-02-05 04:25:39.803870) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.HGgOEBT2bf/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.CgNH6AW2qT/Listeners TMPDIR=/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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 04:25:40 INFO - MultiFileLogger online at 20170205 04:25:40 in /builds/slave/test 04:25:40 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:25:40 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:25:40 INFO - {'append_to_log': False, 04:25:40 INFO - 'base_work_dir': '/builds/slave/test', 04:25:40 INFO - 'blob_upload_branch': 'Firefox', 04:25:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:25:40 INFO - 'branch': 'Firefox', 04:25:40 INFO - 'buildbot_json_path': 'buildprops.json', 04:25:40 INFO - 'config_files': ('talos/mac_config.py',), 04:25:40 INFO - 'default_actions': ('clobber', 04:25:40 INFO - 'read-buildbot-config', 04:25:40 INFO - 'download-and-extract', 04:25:40 INFO - 'populate-webroot', 04:25:40 INFO - 'create-virtualenv', 04:25:40 INFO - 'install', 04:25:40 INFO - 'run-tests'), 04:25:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:25:40 INFO - 'download_minidump_stackwalk': True, 04:25:40 INFO - 'download_symbols': 'ondemand', 04:25:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:25:40 INFO - 'tooltool.py': '/tools/tooltool.py', 04:25:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:25:40 INFO - '/tools/misc-python/virtualenv.py')}, 04:25:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:25:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:25:40 INFO - 'installer_path': 'installer.exe', 04:25:40 INFO - 'log_level': 'info', 04:25:40 INFO - 'log_name': 'talos', 04:25:40 INFO - 'log_to_console': True, 04:25:40 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:25:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:25:40 INFO - 'opt_config_files': (), 04:25:40 INFO - 'pip_index': False, 04:25:40 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:25:40 INFO - 'cmd': ('bash', 04:25:40 INFO - '-c', 04:25:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:25:40 INFO - 'enabled': True, 04:25:40 INFO - 'halt_on_failure': False, 04:25:40 INFO - 'name': 'check_screen_resolution'},), 04:25:40 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:25:40 INFO - 'cmd': ('bash', 04:25:40 INFO - '-c', 04:25:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:25:40 INFO - 'enabled': True, 04:25:40 INFO - 'halt_on_failure': False, 04:25:40 INFO - 'name': 'check_screen_resolution'},), 04:25:40 INFO - 'run_cmd_checks_enabled': True, 04:25:40 INFO - 'sps_profile': False, 04:25:40 INFO - 'sps_profile_interval': 0, 04:25:40 INFO - 'suite': 'dromaeojs', 04:25:40 INFO - 'system_bits': '32', 04:25:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:25:40 INFO - 'title': 't-yosemite-r7-0245', 04:25:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:25:40 INFO - 'use_talos_json': True, 04:25:40 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:25:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:25:40 INFO - 'work_dir': 'build'} 04:25:40 INFO - [mozharness: 2017-02-05 12:25:40.015627Z] Running clobber step. 04:25:40 INFO - Running pre-action listener: _resource_record_pre_action 04:25:40 INFO - Running main action method: clobber 04:25:40 INFO - rmtree: /builds/slave/test/build 04:25:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:25:40 INFO - Running post-action listener: _resource_record_post_action 04:25:40 INFO - [mozharness: 2017-02-05 12:25:40.378164Z] Finished clobber step (success) 04:25:40 INFO - [mozharness: 2017-02-05 12:25:40.378304Z] Running read-buildbot-config step. 04:25:40 INFO - Running pre-action listener: _resource_record_pre_action 04:25:40 INFO - Running main action method: read_buildbot_config 04:25:40 INFO - Using buildbot properties: 04:25:40 INFO - { 04:25:40 INFO - "project": "", 04:25:40 INFO - "product": "firefox", 04:25:40 INFO - "script_repo_revision": "production", 04:25:40 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 04:25:40 INFO - "repository": "", 04:25:40 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 04:25:40 INFO - "buildid": "20170205030206", 04:25:40 INFO - "stage_platform": "macosx64", 04:25:40 INFO - "basedir": "/builds/slave/test", 04:25:40 INFO - "nightly_build": "True", 04:25:40 INFO - "buildnumber": 42, 04:25:40 INFO - "slavename": "t-yosemite-r7-0245", 04:25:40 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 04:25:40 INFO - "platform": "yosemite_r7", 04:25:40 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:25:40 INFO - "branch": "mozilla-central", 04:25:40 INFO - "repo_path": "mozilla-central", 04:25:40 INFO - "revision": "3e555770a90a41e04bbb4ac41b65fa2f1db6977d", 04:25:40 INFO - "pgo_build": "False", 04:25:40 INFO - "builduid": "796af599b56145448c8149710829c898", 04:25:40 INFO - "slavebuilddir": "test" 04:25:40 INFO - } 04:25:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/I_C_Ga0fR3aUopphaa4L-A/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 04:25:40 INFO - Running post-action listener: _resource_record_post_action 04:25:40 INFO - [mozharness: 2017-02-05 12:25:40.381685Z] Finished read-buildbot-config step (success) 04:25:40 INFO - [mozharness: 2017-02-05 12:25:40.381789Z] Running download-and-extract step. 04:25:40 INFO - Running pre-action listener: _resource_record_pre_action 04:25:40 INFO - Running main action method: download_and_extract 04:25:40 INFO - mkdir: /builds/slave/test/build/tests 04:25:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:25:40 INFO - trying https://queue.taskcluster.net/v1/task/I_C_Ga0fR3aUopphaa4L-A/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 04:25:40 INFO - Downloading https://queue.taskcluster.net/v1/task/I_C_Ga0fR3aUopphaa4L-A/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 04:25:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/I_C_Ga0fR3aUopphaa4L-A/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json'}, attempt #1 04:25:41 INFO - Downloaded 1199 bytes. 04:25:41 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 04:25:41 INFO - Using the following test package requirements: 04:25:41 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 04:25:41 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:25:41 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 04:25:41 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:25:41 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 04:25:41 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:25:41 INFO - u'jsshell-mac.zip'], 04:25:41 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:25:41 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 04:25:41 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 04:25:41 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:25:41 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 04:25:41 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:25:41 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 04:25:41 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:25:41 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 04:25:41 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:25:41 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 04:25:41 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:25:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/I_C_Ga0fR3aUopphaa4L-A/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 04:25:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/I_C_Ga0fR3aUopphaa4L-A/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:25:41 INFO - Fetch https://queue.taskcluster.net/v1/task/I_C_Ga0fR3aUopphaa4L-A/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 04:25:42 INFO - Expected file size: 34851186 04:25:42 INFO - Obtained file size: 34851186 04:25:49 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip', u'firefox-54.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 04:25:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/I_C_Ga0fR3aUopphaa4L-A/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 04:25:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/I_C_Ga0fR3aUopphaa4L-A/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:25:49 INFO - Fetch https://queue.taskcluster.net/v1/task/I_C_Ga0fR3aUopphaa4L-A/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 04:25:50 INFO - Expected file size: 34851186 04:25:50 INFO - Obtained file size: 34851186 04:25:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/I_C_Ga0fR3aUopphaa4L-A/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 04:25:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/I_C_Ga0fR3aUopphaa4L-A/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:25:57 INFO - Fetch https://queue.taskcluster.net/v1/task/I_C_Ga0fR3aUopphaa4L-A/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 04:25:58 INFO - Expected file size: 14403454 04:25:58 INFO - Obtained file size: 14403454 04:25:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:25:58 INFO - trying https://queue.taskcluster.net/v1/task/I_C_Ga0fR3aUopphaa4L-A/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 04:25:58 INFO - Downloading https://queue.taskcluster.net/v1/task/I_C_Ga0fR3aUopphaa4L-A/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 04:25:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/I_C_Ga0fR3aUopphaa4L-A/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg'}, attempt #1 04:26:01 INFO - Downloaded 70361166 bytes. 04:26:01 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/I_C_Ga0fR3aUopphaa4L-A/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 04:26:01 INFO - mkdir: /builds/slave/test/properties 04:26:01 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:26:01 INFO - Writing to file /builds/slave/test/properties/build_url 04:26:01 INFO - Contents: 04:26:01 INFO - build_url:https://queue.taskcluster.net/v1/task/I_C_Ga0fR3aUopphaa4L-A/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 04:26:01 INFO - Running post-action listener: _resource_record_post_action 04:26:01 INFO - Running post-action listener: set_extra_try_arguments 04:26:01 INFO - [mozharness: 2017-02-05 12:26:01.717900Z] Finished download-and-extract step (success) 04:26:01 INFO - [mozharness: 2017-02-05 12:26:01.718029Z] Running populate-webroot step. 04:26:01 INFO - Running pre-action listener: _resource_record_pre_action 04:26:01 INFO - Running main action method: populate_webroot 04:26:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:26:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:26:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:26:01 INFO - '--tppagecycles', 04:26:01 INFO - '7'], 04:26:01 INFO - 'tests': ['tsvgm']}}, 04:26:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:26:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:26:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:26:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:26:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:26:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:26:01 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:26:01 INFO - 'talos_options': ['--disable-e10s'], 04:26:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:26:01 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:26:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:26:01 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:26:01 INFO - 'talos_options': ['--disable-e10s'], 04:26:01 INFO - 'tests': ['damp', 'tps']}, 04:26:01 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:26:01 INFO - 'tests': ['damp', 'tps']}, 04:26:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:26:01 INFO - 'tests': ['dromaeo_dom']}, 04:26:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:26:01 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:26:01 INFO - 'tests': ['basic_compositor_video']}, 04:26:01 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:26:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:26:01 INFO - 'tests': ['a11yr', 04:26:01 INFO - 'ts_paint', 04:26:01 INFO - 'tpaint', 04:26:01 INFO - 'sessionrestore', 04:26:01 INFO - 'sessionrestore_no_auto_restore', 04:26:01 INFO - 'tabpaint']}, 04:26:01 INFO - 'other-e10s': {'tests': ['a11yr', 04:26:01 INFO - 'ts_paint', 04:26:01 INFO - 'tpaint', 04:26:01 INFO - 'sessionrestore', 04:26:01 INFO - 'sessionrestore_no_auto_restore', 04:26:01 INFO - 'tabpaint']}, 04:26:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:26:01 INFO - 'ts_paint', 04:26:01 INFO - 'tpaint', 04:26:01 INFO - 'sessionrestore', 04:26:01 INFO - 'sessionrestore_no_auto_restore', 04:26:01 INFO - 'tabpaint']}, 04:26:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:26:01 INFO - 'ts_paint', 04:26:01 INFO - 'tpaint', 04:26:01 INFO - 'sessionrestore', 04:26:01 INFO - 'sessionrestore_no_auto_restore', 04:26:01 INFO - 'tabpaint']}, 04:26:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:26:01 INFO - 'tests': ['a11yr', 04:26:01 INFO - 'ts_paint', 04:26:01 INFO - 'tpaint', 04:26:01 INFO - 'sessionrestore', 04:26:01 INFO - 'sessionrestore_no_auto_restore', 04:26:01 INFO - 'tabpaint']}, 04:26:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:26:01 INFO - 'tests': ['a11yr', 04:26:01 INFO - 'ts_paint', 04:26:01 INFO - 'tpaint', 04:26:01 INFO - 'sessionrestore', 04:26:01 INFO - 'sessionrestore_no_auto_restore', 04:26:01 INFO - 'tabpaint']}, 04:26:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:26:01 INFO - 'tests': ['tsvgx', 04:26:01 INFO - 'tsvgr_opacity', 04:26:01 INFO - 'tart', 04:26:01 INFO - 'tscrollx', 04:26:01 INFO - 'cart', 04:26:01 INFO - 'tsvg_static']}, 04:26:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:26:01 INFO - 'tsvgr_opacity', 04:26:01 INFO - 'tart', 04:26:01 INFO - 'tscrollx', 04:26:01 INFO - 'cart', 04:26:01 INFO - 'tsvg_static']}, 04:26:01 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:26:01 INFO - 'talos_options': ['--disable-e10s'], 04:26:01 INFO - 'tests': ['tp5o']}, 04:26:01 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:26:01 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:26:01 INFO - 'talos_options': ['--disable-e10s', 04:26:01 INFO - '--xperf_path', 04:26:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:26:01 INFO - 'tests': ['tp5n']}, 04:26:01 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:26:01 INFO - 'talos_options': ['--xperf_path', 04:26:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:26:01 INFO - 'tests': ['tp5n']}}, 04:26:01 INFO - 'talos.zip': {'path': '', 04:26:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:26:01 INFO - Running post-action listener: _resource_record_post_action 04:26:01 INFO - [mozharness: 2017-02-05 12:26:01.736393Z] Finished populate-webroot step (success) 04:26:01 INFO - [mozharness: 2017-02-05 12:26:01.736530Z] Running create-virtualenv step. 04:26:01 INFO - Running pre-action listener: _resource_record_pre_action 04:26:01 INFO - Running main action method: create_virtualenv 04:26:01 INFO - Creating virtualenv /builds/slave/test/build/venv 04:26:01 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 04:26:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:26:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:26:01 INFO - Using real prefix '/tools/python27' 04:26:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:26:02 INFO - Installing distribute.............................................................................................................................................................................................done. 04:26:05 INFO - Installing pip.................done. 04:26:05 INFO - Return code: 0 04:26:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:26:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:26:05 INFO - Reading from file tmpfile_stdout 04:26:05 INFO - Output received: 04:26:05 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) 04:26:05 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:26:05 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')]} 04:26:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:26:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:26:05 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')]} 04:26:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:26:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:26:05 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 0x102c98b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102c9b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0fa4b35a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a76d30>, '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 0x10299ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102a6bbe8>, '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.HGgOEBT2bf/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.CgNH6AW2qT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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 04:26:05 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 04:26:05 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 04:26:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HGgOEBT2bf/Render', 04:26:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:26:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:26:05 INFO - 'HOME': '/Users/cltbld', 04:26:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:26:05 INFO - 'LOGNAME': 'cltbld', 04:26:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:26:05 INFO - 'MOZ_NO_REMOTE': '1', 04:26:05 INFO - 'NO_EM_RESTART': '1', 04:26:05 INFO - 'PAGER': '/bin/cat', 04:26:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:26:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:26:05 INFO - 'PWD': '/builds/slave/test', 04:26:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:26:05 INFO - 'SHELL': '/bin/bash', 04:26:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CgNH6AW2qT/Listeners', 04:26:05 INFO - 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', 04:26:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:26:05 INFO - 'USER': 'cltbld', 04:26:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:26:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:26:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:26:05 INFO - 'XPC_FLAGS': '0x0', 04:26:05 INFO - 'XPC_SERVICE_NAME': '0', 04:26:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:26:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:26:05 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 04:26:05 INFO - Cleaning up... 04:26:05 INFO - Return code: 0 04:26:05 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:26:05 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')]} 04:26:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:26:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:26:05 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')]} 04:26:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:26:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:26:05 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 0x102c98b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102c9b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0fa4b35a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a76d30>, '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 0x10299ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102a6bbe8>, '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.HGgOEBT2bf/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.CgNH6AW2qT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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 04:26:05 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 04:26:05 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 04:26:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HGgOEBT2bf/Render', 04:26:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:26:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:26:05 INFO - 'HOME': '/Users/cltbld', 04:26:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:26:05 INFO - 'LOGNAME': 'cltbld', 04:26:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:26:05 INFO - 'MOZ_NO_REMOTE': '1', 04:26:05 INFO - 'NO_EM_RESTART': '1', 04:26:05 INFO - 'PAGER': '/bin/cat', 04:26:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:26:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:26:05 INFO - 'PWD': '/builds/slave/test', 04:26:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:26:05 INFO - 'SHELL': '/bin/bash', 04:26:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CgNH6AW2qT/Listeners', 04:26:05 INFO - 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', 04:26:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:26:05 INFO - 'USER': 'cltbld', 04:26:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:26:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:26:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:26:05 INFO - 'XPC_FLAGS': '0x0', 04:26:05 INFO - 'XPC_SERVICE_NAME': '0', 04:26:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:26:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:26:06 INFO - Downloading/unpacking psutil>=3.1.1 04:26:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:26:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:26:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:26:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:26:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:26:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:26:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:26:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:26:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:26:10 INFO - Installing collected packages: psutil 04:26:10 INFO - Running setup.py install for psutil 04:26:10 INFO - building 'psutil._psutil_osx' extension 04:26:10 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 04:26:10 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 04:26:10 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 04:26:10 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 04:26:10 INFO - building 'psutil._psutil_posix' extension 04:26:10 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 04:26:10 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:26:10 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:26:10 INFO - ^ 04:26:10 INFO - 1 warning generated. 04:26:10 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 04:26:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:26:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:26:10 INFO - Successfully installed psutil 04:26:10 INFO - Cleaning up... 04:26:11 INFO - Return code: 0 04:26:11 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:26:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:26:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:26:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:26:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:26:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:26:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:26:11 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 0x102c98b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102c9b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0fa4b35a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a76d30>, '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 0x10299ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102a6bbe8>, '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.HGgOEBT2bf/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.CgNH6AW2qT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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 04:26:11 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 04:26:11 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 04:26:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HGgOEBT2bf/Render', 04:26:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:26:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:26:11 INFO - 'HOME': '/Users/cltbld', 04:26:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:26:11 INFO - 'LOGNAME': 'cltbld', 04:26:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:26:11 INFO - 'MOZ_NO_REMOTE': '1', 04:26:11 INFO - 'NO_EM_RESTART': '1', 04:26:11 INFO - 'PAGER': '/bin/cat', 04:26:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:26:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:26:11 INFO - 'PWD': '/builds/slave/test', 04:26:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:26:11 INFO - 'SHELL': '/bin/bash', 04:26:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CgNH6AW2qT/Listeners', 04:26:11 INFO - 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', 04:26:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:26:11 INFO - 'USER': 'cltbld', 04:26:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:26:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:26:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:26:11 INFO - 'XPC_FLAGS': '0x0', 04:26:11 INFO - 'XPC_SERVICE_NAME': '0', 04:26:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:26:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:26:11 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:26:11 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 04:26:11 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 04:26:11 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 04:26:11 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 04:26:11 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 04:26:11 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 04:26:13 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:26:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:26:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:26:14 INFO - Installing collected packages: mozsystemmonitor 04:26:14 INFO - Running setup.py install for mozsystemmonitor 04:26:14 INFO - Successfully installed mozsystemmonitor 04:26:14 INFO - Cleaning up... 04:26:14 INFO - Return code: 0 04:26:14 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:26:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:26:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:26:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:26:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:26:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:26:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:26:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x102c98b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102c9b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0fa4b35a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a76d30>, '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 0x10299ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102a6bbe8>, '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.HGgOEBT2bf/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.CgNH6AW2qT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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 04:26:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:26:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:26:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HGgOEBT2bf/Render', 04:26:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:26:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:26:14 INFO - 'HOME': '/Users/cltbld', 04:26:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:26:14 INFO - 'LOGNAME': 'cltbld', 04:26:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:26:14 INFO - 'MOZ_NO_REMOTE': '1', 04:26:14 INFO - 'NO_EM_RESTART': '1', 04:26:14 INFO - 'PAGER': '/bin/cat', 04:26:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:26:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:26:14 INFO - 'PWD': '/builds/slave/test', 04:26:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:26:14 INFO - 'SHELL': '/bin/bash', 04:26:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CgNH6AW2qT/Listeners', 04:26:14 INFO - 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', 04:26:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:26:14 INFO - 'USER': 'cltbld', 04:26:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:26:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:26:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:26:14 INFO - 'XPC_FLAGS': '0x0', 04:26:14 INFO - 'XPC_SERVICE_NAME': '0', 04:26:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:26:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:26:14 INFO - Downloading/unpacking jsonschema==2.5.1 04:26:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:26:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:26:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:26:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:26:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:26:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:26:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:26:17 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:26:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:26:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:26:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:26:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:26:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:26:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:26:17 INFO - Downloading functools32-3.2.3-2.tar.gz 04:26:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:26:17 INFO - warning: no files found matching '*.txt' 04:26:17 INFO - no previously-included directories found matching 'build' 04:26:17 INFO - no previously-included directories found matching 'dist' 04:26:17 INFO - no previously-included directories found matching '.git*' 04:26:17 INFO - Installing collected packages: jsonschema, functools32 04:26:17 INFO - Running setup.py install for functools32 04:26:17 INFO - warning: no files found matching '*.txt' 04:26:17 INFO - no previously-included directories found matching 'build' 04:26:17 INFO - no previously-included directories found matching 'dist' 04:26:17 INFO - no previously-included directories found matching '.git*' 04:26:17 INFO - Successfully installed jsonschema functools32 04:26:17 INFO - Cleaning up... 04:26:17 INFO - Return code: 0 04:26:17 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:26:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:26:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:26:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:26:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:26:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:26:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:26:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x102c98b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102c9b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0fa4b35a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a76d30>, '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 0x10299ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102a6bbe8>, '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.HGgOEBT2bf/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.CgNH6AW2qT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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 04:26:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:26:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 04:26:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HGgOEBT2bf/Render', 04:26:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:26:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:26:17 INFO - 'HOME': '/Users/cltbld', 04:26:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:26:17 INFO - 'LOGNAME': 'cltbld', 04:26:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:26:17 INFO - 'MOZ_NO_REMOTE': '1', 04:26:17 INFO - 'NO_EM_RESTART': '1', 04:26:17 INFO - 'PAGER': '/bin/cat', 04:26:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:26:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:26:17 INFO - 'PWD': '/builds/slave/test', 04:26:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:26:17 INFO - 'SHELL': '/bin/bash', 04:26:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CgNH6AW2qT/Listeners', 04:26:17 INFO - 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', 04:26:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:26:17 INFO - 'USER': 'cltbld', 04:26:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:26:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:26:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:26:17 INFO - 'XPC_FLAGS': '0x0', 04:26:17 INFO - 'XPC_SERVICE_NAME': '0', 04:26:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:26:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:26:18 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:26:18 INFO - Cleaning up... 04:26:18 INFO - Return code: 0 04:26:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:26:18 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')]} 04:26:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:26:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:26:18 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')]} 04:26:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:26:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:26:18 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 0x102c98b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102c9b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0fa4b35a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a76d30>, '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 0x10299ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102a6bbe8>, '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.HGgOEBT2bf/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.CgNH6AW2qT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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 04:26:18 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 04:26:18 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 04:26:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HGgOEBT2bf/Render', 04:26:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:26:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:26:18 INFO - 'HOME': '/Users/cltbld', 04:26:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:26:18 INFO - 'LOGNAME': 'cltbld', 04:26:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:26:18 INFO - 'MOZ_NO_REMOTE': '1', 04:26:18 INFO - 'NO_EM_RESTART': '1', 04:26:18 INFO - 'PAGER': '/bin/cat', 04:26:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:26:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:26:18 INFO - 'PWD': '/builds/slave/test', 04:26:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:26:18 INFO - 'SHELL': '/bin/bash', 04:26:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CgNH6AW2qT/Listeners', 04:26:18 INFO - 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', 04:26:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:26:18 INFO - 'USER': 'cltbld', 04:26:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:26:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:26:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:26:18 INFO - 'XPC_FLAGS': '0x0', 04:26:18 INFO - 'XPC_SERVICE_NAME': '0', 04:26:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:26:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:26:18 INFO - Downloading/unpacking blobuploader==1.2.4 04:26:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:26:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:26:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:26:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:26:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:26:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:26:21 INFO - Downloading blobuploader-1.2.4.tar.gz 04:26:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:26:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:26:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:26:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:26:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:26:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:26:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:26:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:26:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:26:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:26:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:26:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:26:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:26:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:26:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:26:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:26:22 INFO - Downloading docopt-0.6.1.tar.gz 04:26:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:26:22 INFO - Installing collected packages: blobuploader, requests, docopt 04:26:22 INFO - Running setup.py install for blobuploader 04:26:22 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:26:22 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:26:22 INFO - Running setup.py install for requests 04:26:23 INFO - Running setup.py install for docopt 04:26:23 INFO - Successfully installed blobuploader requests docopt 04:26:23 INFO - Cleaning up... 04:26:23 INFO - Return code: 0 04:26:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:26:23 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')]} 04:26:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:26:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:26:23 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')]} 04:26:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:26:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:26:23 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 0x102c98b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102c9b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0fa4b35a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a76d30>, '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 0x10299ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102a6bbe8>, '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.HGgOEBT2bf/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.CgNH6AW2qT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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 04:26:23 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 04:26:23 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 04:26:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HGgOEBT2bf/Render', 04:26:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:26:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:26:23 INFO - 'HOME': '/Users/cltbld', 04:26:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:26:23 INFO - 'LOGNAME': 'cltbld', 04:26:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:26:23 INFO - 'MOZ_NO_REMOTE': '1', 04:26:23 INFO - 'NO_EM_RESTART': '1', 04:26:23 INFO - 'PAGER': '/bin/cat', 04:26:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:26:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:26:23 INFO - 'PWD': '/builds/slave/test', 04:26:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:26:23 INFO - 'SHELL': '/bin/bash', 04:26:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CgNH6AW2qT/Listeners', 04:26:23 INFO - 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', 04:26:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:26:23 INFO - 'USER': 'cltbld', 04:26:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:26:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:26:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:26:23 INFO - 'XPC_FLAGS': '0x0', 04:26:23 INFO - 'XPC_SERVICE_NAME': '0', 04:26:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:26:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:26:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:26:23 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-8XTmeK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:26:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:26:23 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-u0gbvf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:26:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:26:23 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-8aKxaK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:26:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:26:23 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-_mkYsK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:26:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:26:24 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-aeyDuw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:26:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:26:24 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-Leym7x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:26:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:26:24 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-2lt9cw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:26:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:26:24 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-R1y6Rr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:26:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:26:24 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-KnTQxg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:26:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:26:24 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-Am55_9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:26:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:26:24 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-ZHGthP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:26:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:26:24 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-Ezxuxd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:26:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:26:24 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-xcGM0f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:26:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:26:24 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-fZFCKH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:26:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:26:25 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-s13Djd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:26:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:26:25 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-HZYtVu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:26:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:26:25 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-KPPTGK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:26:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:26:25 INFO - Running setup.py install for manifestparser 04:26:25 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:26:25 INFO - Running setup.py install for mozcrash 04:26:25 INFO - Running setup.py install for mozdebug 04:26:25 INFO - Running setup.py install for mozdevice 04:26:26 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:26:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:26:26 INFO - Running setup.py install for mozfile 04:26:26 INFO - Running setup.py install for mozhttpd 04:26:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:26:26 INFO - Running setup.py install for mozinfo 04:26:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:26:26 INFO - Running setup.py install for mozInstall 04:26:26 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:26:26 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:26:26 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:26:26 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:26:26 INFO - Running setup.py install for mozleak 04:26:26 INFO - Running setup.py install for mozlog 04:26:26 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:26:26 INFO - Running setup.py install for moznetwork 04:26:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:26:27 INFO - Running setup.py install for mozprocess 04:26:27 INFO - Running setup.py install for mozprofile 04:26:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:26:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:26:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:26:27 INFO - Running setup.py install for mozrunner 04:26:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:26:27 INFO - Running setup.py install for mozscreenshot 04:26:27 INFO - Running setup.py install for moztest 04:26:27 INFO - Running setup.py install for mozversion 04:26:27 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:26:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:26:28 INFO - Cleaning up... 04:26:28 INFO - Return code: 0 04:26:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:26: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')]} 04:26:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:26:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:26: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')]} 04:26:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:26:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:26:28 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 0x102c98b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102c9b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0fa4b35a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a76d30>, '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 0x10299ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102a6bbe8>, '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.HGgOEBT2bf/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.CgNH6AW2qT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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 04:26:28 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 04:26:28 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 04:26:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HGgOEBT2bf/Render', 04:26:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:26:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:26:28 INFO - 'HOME': '/Users/cltbld', 04:26:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:26:28 INFO - 'LOGNAME': 'cltbld', 04:26:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:26:28 INFO - 'MOZ_NO_REMOTE': '1', 04:26:28 INFO - 'NO_EM_RESTART': '1', 04:26:28 INFO - 'PAGER': '/bin/cat', 04:26:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:26:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:26:28 INFO - 'PWD': '/builds/slave/test', 04:26:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:26:28 INFO - 'SHELL': '/bin/bash', 04:26:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CgNH6AW2qT/Listeners', 04:26:28 INFO - 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', 04:26:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:26:28 INFO - 'USER': 'cltbld', 04:26:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:26:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:26:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:26:28 INFO - 'XPC_FLAGS': '0x0', 04:26:28 INFO - 'XPC_SERVICE_NAME': '0', 04:26:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:26:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:26:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:26:28 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-cBRnHB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:26:28 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)) 04:26:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:26:28 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-GyhoBP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:26:28 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)) 04:26:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:26:28 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-LGJ4Vm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:26:28 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)) 04:26:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:26:28 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-TD9Jjz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:26:28 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)) 04:26:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:26:28 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-NBK6mW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:26:28 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)) 04:26:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:26:28 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-HwzKr5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:26:28 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)) 04:26:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:26:28 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-Kv3zT5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:26:29 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)) 04:26:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:26:29 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-SIgxz7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:26:29 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)) 04:26:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:26:29 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-kGm8Y7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:26:29 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)) 04:26:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:26:29 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-wSNHtL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:26:29 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)) 04:26:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:26:29 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-wsCUZe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:26:29 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)) 04:26:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:26:29 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-H2ADr4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:26:29 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)) 04:26:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:26:29 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-5Augeq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:26:29 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)) 04:26:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:26:29 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-2c5cAX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:26:29 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)) 04:26:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:26:29 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-FSeFtp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:26:29 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)) 04:26:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:26:29 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-hrjEeu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:26:30 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)) 04:26:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:26:30 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-GZXhMd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:26:30 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)) 04:26:30 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)) 04:26:30 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)) 04:26:30 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)) 04:26:30 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)) 04:26:30 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)) 04:26:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:26:30 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 04:26:30 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 04:26:30 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 04:26:30 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 04:26:30 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 04:26:30 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 04:26:32 INFO - Downloading blessings-1.6.tar.gz 04:26:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:26:32 INFO - Installing collected packages: blessings 04:26:32 INFO - Running setup.py install for blessings 04:26:33 INFO - Successfully installed blessings 04:26:33 INFO - Cleaning up... 04:26:33 INFO - Return code: 0 04:26:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:26:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:26:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:26:33 INFO - Reading from file tmpfile_stdout 04:26:33 INFO - Current package versions: 04:26:33 INFO - blessings == 1.6 04:26:33 INFO - blobuploader == 1.2.4 04:26:33 INFO - docopt == 0.6.1 04:26:33 INFO - functools32 == 3.2.3-2 04:26:33 INFO - jsonschema == 2.5.1 04:26:33 INFO - manifestparser == 1.1 04:26:33 INFO - mozInstall == 1.12 04:26:33 INFO - mozcrash == 1.0 04:26:33 INFO - mozdebug == 0.1 04:26:33 INFO - mozdevice == 0.49 04:26:33 INFO - mozfile == 1.2 04:26:33 INFO - mozhttpd == 0.7 04:26:33 INFO - mozinfo == 0.9 04:26:33 INFO - mozleak == 0.1 04:26:33 INFO - mozlog == 3.4 04:26:33 INFO - moznetwork == 0.27 04:26:33 INFO - mozprocess == 0.24 04:26:33 INFO - mozprofile == 0.28 04:26:33 INFO - mozrunner == 6.13 04:26:33 INFO - mozscreenshot == 0.1 04:26:33 INFO - mozsystemmonitor == 0.3 04:26:33 INFO - moztest == 0.8 04:26:33 INFO - mozversion == 1.4 04:26:33 INFO - psutil == 3.1.1 04:26:33 INFO - requests == 1.2.3 04:26:33 INFO - wsgiref == 0.1.2 04:26:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:26:33 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')]} 04:26:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:26:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:26:33 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')]} 04:26:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:26:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:26:33 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 0x102c98b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102c9b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0fa4b35a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a76d30>, '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 0x10299ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102a6bbe8>, '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.HGgOEBT2bf/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.CgNH6AW2qT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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 04:26:33 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 04:26:33 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 04:26:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HGgOEBT2bf/Render', 04:26:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:26:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:26:33 INFO - 'HOME': '/Users/cltbld', 04:26:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:26:33 INFO - 'LOGNAME': 'cltbld', 04:26:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:26:33 INFO - 'MOZ_NO_REMOTE': '1', 04:26:33 INFO - 'NO_EM_RESTART': '1', 04:26:33 INFO - 'PAGER': '/bin/cat', 04:26:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:26:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:26:33 INFO - 'PWD': '/builds/slave/test', 04:26:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:26:33 INFO - 'SHELL': '/bin/bash', 04:26:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CgNH6AW2qT/Listeners', 04:26:33 INFO - 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', 04:26:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:26:33 INFO - 'USER': 'cltbld', 04:26:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:26:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:26:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:26:33 INFO - 'XPC_FLAGS': '0x0', 04:26:33 INFO - 'XPC_SERVICE_NAME': '0', 04:26:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:26:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:26:33 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)) 04:26:33 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)) 04:26:33 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)) 04:26:33 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)) 04:26:33 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)) 04:26:33 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)) 04:26:33 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)) 04:26:33 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)) 04:26:33 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)) 04:26:33 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)) 04:26:33 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)) 04:26:33 INFO - Cleaning up... 04:26:33 INFO - Return code: 0 04:26:33 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:26:33 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')]} 04:26:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:26:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:26:33 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')]} 04:26:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:26:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:26:33 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 0x102c98b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102c9b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0fa4b35a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a76d30>, '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 0x10299ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102a6bbe8>, '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.HGgOEBT2bf/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.CgNH6AW2qT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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 04:26:33 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 04:26:33 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 04:26:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HGgOEBT2bf/Render', 04:26:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:26:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:26:33 INFO - 'HOME': '/Users/cltbld', 04:26:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:26:33 INFO - 'LOGNAME': 'cltbld', 04:26:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:26:33 INFO - 'MOZ_NO_REMOTE': '1', 04:26:33 INFO - 'NO_EM_RESTART': '1', 04:26:33 INFO - 'PAGER': '/bin/cat', 04:26:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:26:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:26:33 INFO - 'PWD': '/builds/slave/test', 04:26:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:26:33 INFO - 'SHELL': '/bin/bash', 04:26:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CgNH6AW2qT/Listeners', 04:26:33 INFO - 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', 04:26:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:26:33 INFO - 'USER': 'cltbld', 04:26:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:26:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:26:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:26:33 INFO - 'XPC_FLAGS': '0x0', 04:26:33 INFO - 'XPC_SERVICE_NAME': '0', 04:26:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:26:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:26:34 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:26:34 INFO - Cleaning up... 04:26:34 INFO - Return code: 0 04:26:34 INFO - Running post-action listener: _resource_record_post_action 04:26:34 INFO - Running post-action listener: _start_resource_monitoring 04:26:34 INFO - Starting resource monitoring. 04:26:34 INFO - [mozharness: 2017-02-05 12:26:34.068733Z] Finished create-virtualenv step (success) 04:26:34 INFO - [mozharness: 2017-02-05 12:26:34.069790Z] Running install step. 04:26:34 INFO - Running pre-action listener: _resource_record_pre_action 04:26:34 INFO - Running main action method: install 04:26:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:26:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:26:34 INFO - Reading from file tmpfile_stdout 04:26:34 INFO - Detecting whether we're running mozinstall >=1.0... 04:26:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:26:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:26:34 INFO - Reading from file tmpfile_stdout 04:26:34 INFO - Output received: 04:26:34 INFO - Usage: mozinstall [options] installer 04:26:34 INFO - Options: 04:26:34 INFO - -h, --help show this help message and exit 04:26:34 INFO - -d DEST, --destination=DEST 04:26:34 INFO - Directory to install application into. [default: 04:26:34 INFO - "/builds/slave/test"] 04:26:34 INFO - --app=APP Application being installed. [default: firefox] 04:26:34 INFO - mkdir: /builds/slave/test/build/application 04:26:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:26:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 04:26:56 INFO - Reading from file tmpfile_stdout 04:26:56 INFO - Output received: 04:26:56 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 04:26:56 INFO - Running post-action listener: _resource_record_post_action 04:26:56 INFO - [mozharness: 2017-02-05 12:26:56.464516Z] Finished install step (success) 04:26:56 INFO - [mozharness: 2017-02-05 12:26:56.464654Z] Running run-tests step. 04:26:56 INFO - Running pre-action listener: _resource_record_pre_action 04:26:56 INFO - Running main action method: run_tests 04:26:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:26:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:26:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:26:56 INFO - 2017-02-05 04:26:56.479 screenresolution[3109:101522] starting screenresolution argv=screenresolution get 04:26:56 INFO - 2017-02-05 04:26:56.482 screenresolution[3109:101522] Display 0: 1600x1200x32@60 04:26:56 INFO - 2017-02-05 04:26:56.488 screenresolution[3110:101524] starting screenresolution argv=screenresolution list 04:26:56 INFO - Available Modes on Display 0 04:26:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:26:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:26:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:26:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:26:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:26:56 INFO - Intel Iris: 04:26:56 INFO - Chipset Model: Intel Iris 04:26:56 INFO - Type: GPU 04:26:56 INFO - Bus: Built-In 04:26:56 INFO - VRAM (Dynamic, Max): 1536 MB 04:26:56 INFO - Vendor: Intel (0x8086) 04:26:56 INFO - Device ID: 0x0a2e 04:26:56 INFO - Revision ID: 0x0009 04:26:56 INFO - Displays: 04:26:56 INFO - Display: 04:26:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:26:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:26:56 INFO - Display Serial Number: 1600x1200 60 04:26:56 INFO - Main Display: Yes 04:26:56 INFO - Mirror: Off 04:26:56 INFO - Online: Yes 04:26:56 INFO - Rotation: Supported 04:26:56 INFO - Return code: 0 04:26:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:26:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:26:56 INFO - Python 2.7.3 04:26:56 INFO - Return code: 0 04:26:56 INFO - grabbing minidump binary from tooltool 04:26: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')]} 04:26:56 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 0x102a76d30>, '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 0x10299ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102a6bbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:26:56 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 04:26:56 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 04:26:56 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 04:26:56 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:26:56 INFO - Return code: 0 04:26:56 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:26:56 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:26:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:26:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:26:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:26:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/I_C_Ga0fR3aUopphaa4L-A/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0245', '--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 04:26:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/I_C_Ga0fR3aUopphaa4L-A/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0245 --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 04:26:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HGgOEBT2bf/Render', 04:26:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:26:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:26:56 INFO - 'HOME': '/Users/cltbld', 04:26:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:26:56 INFO - 'LOGNAME': 'cltbld', 04:26:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:26:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:26:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:26:56 INFO - 'MOZ_NO_REMOTE': '1', 04:26:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:26:56 INFO - 'NO_EM_RESTART': '1', 04:26:56 INFO - 'PAGER': '/bin/cat', 04:26:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:26:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:26:56 INFO - 'PWD': '/builds/slave/test', 04:26:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:26:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:26:56 INFO - 'SHELL': '/bin/bash', 04:26:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CgNH6AW2qT/Listeners', 04:26:56 INFO - 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', 04:26:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:26:56 INFO - 'USER': 'cltbld', 04:26:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:26:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:26:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:26:56 INFO - 'XPC_FLAGS': '0x0', 04:26:56 INFO - 'XPC_SERVICE_NAME': '0', 04:26:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:26:56 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/I_C_Ga0fR3aUopphaa4L-A/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0245', '--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 04:26:57 INFO - mozversion application_buildid: 20170205030206 04:26:57 INFO - mozversion application_changeset: 3e555770a90a41e04bbb4ac41b65fa2f1db6977d 04:26:57 INFO - mozversion application_display_name: Nightly 04:26:57 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:26:57 INFO - mozversion application_name: Firefox 04:26:57 INFO - mozversion application_remotingname: firefox 04:26:57 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:26:57 INFO - mozversion application_vendor: Mozilla 04:26:57 INFO - mozversion application_version: 54.0a1 04:26:57 INFO - mozversion platform_buildid: 20170205030206 04:26:57 INFO - mozversion platform_changeset: 3e555770a90a41e04bbb4ac41b65fa2f1db6977d 04:26:57 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:26:57 INFO - mozversion platform_version: 54.0a1 04:26:57 INFO - using testdate: 1486297617 04:26:57 INFO - actual date: 1486297617 04:26:57 INFO - starting webserver on 'localhost:63671' 04:26:57 INFO - SUITE-START | Running 2 tests 04:26:57 INFO - TEST-START | dromaeo_css 04:26:57 INFO - operating with platform_type : mac_ 04:26:57 INFO - Initialising browser for dromaeo_css test... 04:26:57 INFO - TEST-INFO | started process 3120 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/tmpYlRiXc/profile http://localhost:63671/getInfo.html) 04:26:59 INFO - PROCESS | 3120 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 04:26:59 INFO - PROCESS | 3120 | __metrics Screen width/height:1600/1200 04:26:59 INFO - PROCESS | 3120 | colorDepth:24 04:26:59 INFO - PROCESS | 3120 | Browser inner width/height: 1024/689 04:26:59 INFO - PROCESS | 3120 | __metrics 04:26:59 INFO - PROCESS | 3120 | 1486297619694 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 04:26:59 INFO - PROCESS | 3120 | 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" 04:26:59 INFO - PROCESS | 3120 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:27:00 INFO - TEST-INFO | 3120: exit 0 04:27:00 INFO - Browser initialized. 04:27:00 INFO - Running cycle 1/1 for dromaeo_css test... 04:27:00 INFO - TEST-INFO | started process 3123 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/tmpYlRiXc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:27:12 INFO - PROCESS | 3123 | RSS: Main: 237264896 04:27:12 INFO - PROCESS | 3123 | 04:30:14 INFO - PROCESS | 3123 | Cycle 1(1): loaded http://localhost:63671/tests/dromaeo/cssquery-dojo.html (next: http://localhost:63671/tests/dromaeo/cssquery-ext.html) 04:30:17 INFO - PROCESS | 3123 | RSS: Main: 473804800 04:30:17 INFO - PROCESS | 3123 | 04:32:03 INFO - PROCESS | 3123 | ************************* 04:32:03 INFO - PROCESS | 3123 | A coding exception was thrown and uncaught in a Task. 04:32:03 INFO - PROCESS | 3123 | 04:32:03 ERROR - PROCESS | 3123 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:32:03 INFO - PROCESS | 3123 | Full stack: 04:32:03 INFO - PROCESS | 3123 | ************************* 04:33:20 INFO - PROCESS | 3123 | Cycle 1(1): loaded http://localhost:63671/tests/dromaeo/cssquery-ext.html (next: http://localhost:63671/tests/dromaeo/cssquery-jquery.html) 04:33:21 INFO - PROCESS | 3123 | RSS: Main: 377262080 04:33:21 INFO - PROCESS | 3123 | 04:36:23 INFO - PROCESS | 3123 | Cycle 1(1): loaded http://localhost:63671/tests/dromaeo/cssquery-jquery.html (next: http://localhost:63671/tests/dromaeo/cssquery-mootools.html) 04:36:24 INFO - PROCESS | 3123 | RSS: Main: 345362432 04:36:24 INFO - PROCESS | 3123 | 04:39:26 INFO - PROCESS | 3123 | Cycle 1(1): loaded http://localhost:63671/tests/dromaeo/cssquery-mootools.html (next: http://localhost:63671/tests/dromaeo/cssquery-prototype.html) 04:39:27 INFO - PROCESS | 3123 | RSS: Main: 339419136 04:39:27 INFO - PROCESS | 3123 | 04:42:31 INFO - PROCESS | 3123 | Cycle 1(1): loaded http://localhost:63671/tests/dromaeo/cssquery-prototype.html (next: http://localhost:63671/tests/dromaeo/cssquery-yui.html) 04:42:31 INFO - PROCESS | 3123 | RSS: Main: 328380416 04:42:31 INFO - PROCESS | 3123 | 04:45:35 INFO - PROCESS | 3123 | Cycle 1(1): loaded http://localhost:63671/tests/dromaeo/cssquery-yui.html (next: http://localhost:63671/tests/dromaeo/cssquery-dojo.html) 04:45:35 INFO - PROCESS | 3123 | RSS: Main: 420175872 04:45:35 INFO - PROCESS | 3123 | 04:45:35 INFO - PROCESS | 3123 | __start_tp_report 04:45:35 INFO - PROCESS | 3123 | _x_x_mozilla_page_load 04:45:35 INFO - PROCESS | 3123 | _x_x_mozilla_page_load_details 04:45:35 INFO - PROCESS | 3123 | |i|pagename|runs| 04:45:35 INFO - PROCESS | 3123 | |0;dojo.html;3225.8064516129048;3238.449463591189;3310.619278782941;3312.6852948969845;3313;5446.700431476267;5545.195946587743;5596.104623260279;5656.208130861683;5656.406077361879;5485.259489968853;5573.498385145336;5578.2748242728385;5579.776808927638;5590.301212348457;5020.623453241013;5047.31861198739;5141.434442211373;5168.32811734474;5223.138182199932;4549.567791059861;4878.85346943468;5178.818741344054;5204.869878253055;5278.419373868871;454.3894094094867;5099.5410413063;5108.080545501808;5185.611079169059;5216.374035115791;5126.102931986925;5171.172612382001;5175.171972484422;5178.715170665612;5227.973860130675;5135.871603209912;5144.485551444825;5157.30376399185;5161.69029858206;5206.76569554371;5448.291722076145;5469.15228139639;5661.122526008476;5666.971665141689;5704.201411802351;6111.66088984588;6137.775380989496;6194.5354098442795;6243.344448832852;6258.06129080637;5471.644343117685;6210.45149296394;6247.4377306042215;6265.154204182468;6278.246610406781;5610.074337734228;5801.187833703285;5803.071508558611;5805.5065319447895;5845;5128.743562821918;6164.260288765287;6276.278228003746;6297.4647154992335;6313.05304204373;6136.9079463808075;6161;6167.105769663373;6171;6180.443760061616;6057.575969682168;6103.84740381494;6120.14317995481;6192.938070619236;6281;6139.048447490646;6146.955017647011;6155.984262596711;6233.844153896098;6238.157848690369;6204.348543402968;6258.186435763321;6269.874602508015;6277.466415354746;6282.026285925689;4826.444958829709;5000.649954503223;5006.724630145377;5010.348654674869;5019.67372120811;4377.759223242689;4380.0582874682095;4434.046679963823;4465.173942820588;4482.417285752831;5705.515031222392;5716.771329146787;5721;5722.971385143048;5744.167095771089;6108.694565271719;6283;6289.119523266746;6292.961661325921;6299.559030867887;5516.14499752539;5552.888942221133;5560.972195139079;5590.8043218487155;5693.231413759172;5324.680519168863;5361.08861493547;5369.785208591613;5400.324959380077;5407.405185429599;6266.655333956585;6270.6551139685935;6286.937130628635;6288.842778930563;6302.464290535356;6010.759569617167;6102.671035646872;6241.719122639402;6256.7810126645345;6258.155149054819;6194.349593292639;6272.749090036347;6290.968545157245;6297.433231009231;6304.464120549805;5161.458046905021;6180.907286390798;6200.689965501797;6203.627782332894;6208.751649933952;6181.907271391023;6203;6256.092866534236;6262.279837818709;6273.686315684106;5656.406077361818;5754.6547207167705;5792.623479473821;6021.54838387114;6110.480609148272;6063.211782468251;6105.459747277989;6114.966544785144;6117.796956032597;6137.693115344304;5678.261825962599;5723.399043100415;5736.110902810071;5750;5912.674802885883;5860.765569377178;5916.31962324321;5927.644341339534;5929.52563794904;5935.554833387427;5767.769289228384;5771;5778.653280803165;5785.768569257182;5792.420757924174 04:45:35 INFO - PROCESS | 3123 | |1;ext.html;453.573640777669;462.86808259646006;492.96477397465327;514.930484384608;522.9320188375511;37681;40286.798566007164;40561.985950351256;41766.164676706525;41864.95337616561;41487;42055.78972105143;42063;42165.57834421655;42201.78899105508;7903.802404939865;8173.346132309431;9155.72532824013;9382.765430864249;9414.623415063423;10831.404272764994;11216.046636035906;11216.607418740346;11345.149113816455;11376.488058037408;9506.47714375709;9547.665831695893;10490.947545262301;11006.449677516131;11096.05683516902;7920.603969801545;8158.184181581853;9145.222656074242;11135.164862635294;11231.26996745213;10878.238523303371;11040.503177357039;11055.336679799238;11060.004599586076;11074;39322.60677393218;39612;39622.405663915066;39644.20711585781;39659.40510892339;3913.8630147944687;3927.3716205407;3938.2517321708783;3938.7833669148185;4076.429299898017;30096;30174.849125754452;30232.8488357559;30240.94156704527;30299.242518936982;2479.590867506859;3201.679832016803;3257.9022629321157;3258.201740573551;3264.069740124077;433404;433499;437977.9999999968;438805;439041;48164.75917620459;50362.24456633154;50379;58157.4184258152;58691.11963320553;52058.21912671313;52275.73862130665;52427.73786131045;52492;57521.71239143777;14551.199684017269;14611.269436528131;14700.264986750619;14702.338394772261;14705.411783528752;9615.759606009906;11249.71875703114;11259.887401126047;11387.65837024874;11405.600803971822;15657.686846263012;15719.528414147593;15771.870797442429;15913.363465461483;15926.920365398099;5252.579793616502;6294.716737746812;6339.461145802658;6340.239171299474;6372.968135159294;844.3582877013391;853.6116067189414;862.4954401675091;862.8403745307138;879.5030807593689;28406.71593284082;28862.567061494094;29478;29478.841202789426;29665.555016674767;984.5471083301618;993.4784238274964;996.7125447098925;1029.9330543514648;1048.4181279390011;13908.721825563433;13925.570228091245;14056.437742490185;14181.574552763434;14196.432142714175;537418;541195;544207;546577;547678;49567.75216123896;49679.75160124176;49717.50282497201;49783.75540611514;50060.74969625129;1363.2297751770416;1398.3008495752124;1409.7603407420559;1419.2832619527073;1419.396756378535;26030.219093427226;26259.21222363332;26259.343516412242;26504.807283672668;26525;35522.467162992056;41539.7923010383;43562.12875742404;43661.78169109134;43664.56335436605;448990;450289;451046;451357;454376;20792.896035519727;21407.357779266647;23751;24126.276211713677;24167.879160604083;37416;38143;38696.41955370615;38951.415728763524;39377.015574610865;31980.68019319777;32531.186720331236;32813.507797383536;33309.50035749417;33518;25080;31760.205994849388;32710.509342359408;32939.83530082334;33269.83365083256;31428.528572071893;31976.36047279088;34775.65224347724;35504.644953551164;35550.82224588861;28900.855495722386;30584.541231881096;30838.229044274074;30860;30986;22798;23236.535269294858;23418.06327746871;23523.294301170994;24088.397790055387 04:45:35 INFO - PROCESS | 3123 | |2;jquery.html;386.82979489024825;388.71041074399574;390.3896427237645;400.74752905669425;422.76959057313763;19514.609707805834;20276.188952441884;20786.06462709178;20810.791892081055;21202.89398553009;10004.449755263458;12280.078994075458;12332.260064396143;12348.814767778491;12556.497740090386;5339.252504649352;5558.360788509312;5562.16567514872;5563.860903477405;5566.4990150886415;5569.554435645138;6031.939680603182;6098.176746139281;6114.144019837227;6115.541334399915;5830.912536311937;6067.7269522871575;6074.696265186745;6081.78713745019;6083.482903953147;6004.639721616717;6092.5125989920925;6112;6143.815685529441;6146.38536146384;5179.093658609766;5334.653247538898;5406.08096623571;5451.509364157245;5462.317210348707;13593.930872759987;13676.179429234178;13787.862121378757;13789.931050344683;25537.361565960815;2625.9474810503875;3017.7585793136495;3062.6477955035234;3112.1441603558974;3153.9684603153905;8792.47245165292;10178.949105254427;10479.318844275098;10614.938506149401;10647;1399.6360946154073;1790.5255107396551;1814.6089384092188;1894.8484121270264;1896.810318968106;131955;134200;135226;137665;139345;11405;11422.771544569063;11569.553805774278;11597.18819682631;11884.643460696192;15666.530004099895;15821.920890395475;15854;15862.762058569131;15869.52391428259;7974.5215287082965;8213.548254845922;8219.54792486419;8386.203310685598;8389.748307550783;7524.172341042481;7749.186335434811;7756.457048006585;7880.172581878935;7980.880286795703;4704.85885423431;4765.611145047213;4779.235322348408;4879.536444037811;4949.925751113737;1878.8538991215348;1894.8768330058501;1971.6845304751457;2019.4446527205137;2037.1545808489607;829.5437509369943;849.1678155407611;853.7182729699308;883.1918794303141;887.3477993674644;21308.147674093172;22369.664455033188;22461.32616021522;22476.662850057262;22541;822.2978434640356;867.7613656244583;885.5970533407414;891.888513935758;893.6380765789866;11245.606403775828;11803.763924721628;11813.350265735467;11817.527298908119;11826.940865295619;657680;659456;660622;685099;686319;139928;140103;141552.29223854022;142300;153594;975.5902520941222;986.8618393424927;1008.6620981970975;1010.1767059846202;1012.3217444312328;18979.24083036663;19516.8048319515;19677.114529846476;19689.409317720492;19781.901090494455;52608.47391526036;54537.63655908634;57005.594076976835;58403.70798145982;59040.40959590349;77148;80768.78846817184;82430;82963;83968;6981.371676549135;8369;8476.06763256029;8624.827503450022;8786.780330491532;10628.415437150721;13281.66795830112;13627.250501222528;13644.727105458034;14049.789253161007;11453.96914277719;11626.069914406997;11953.521859125538;12009.099317551394;12043.156979011384;11272.718182045515;11435;11550.82673759911;11794;11916.642500724993;10784.19118566095;11614.70963225926;11681.824772628248;11935.820962685393;11973.521059157885;7181.712731490682;7398.371138453292;7592.582407967615;7920.287174154354;9036.774080647774;10167.237457190593;11532.25040372373;11834.940825295818;11905.702357441134;11938.104642152048 04:45:35 INFO - PROCESS | 3123 | |3;mootools.html;1300.3693208793727;1432.1764985133543;1446.9059511131777;1461.523860900491;1464.0629996802045;7417.666205020781;7821.413393995452;8217.383696222778;8438.83122337553;8463.322934165268;8347.332213422927;8446.15538446155;8457.57712114395;8461;8464.91535084649;1977.4660841572768;2226.254204841376;2257.322803159054;2263.8981245843893;2265.0486795545826;2043.1725151313742;2241.361212054565;2299.5515874404496;2310.849794763343;2323.012719594174;2256.751757306695;2278.6012447821563;2282.4179834142356;2284.360379093848;2286.0398632574274;2248.651459023854;2275.067222438792;2279.350385140227;2282.1213832674375;2283.5889539882814;2231.9509830379698;2265.3430505153488;2266.5580211858696;2273.30664147435;2274.0903638544714;8312.293455056268;8523;8529.488230706178;8532.57337133141;8582.227599516074;5043.495650434964;5198.636095473318;5234.790608375661;5272.31459910213;5320.600954928396;2815.971840281612;3483.5471388719557;3515.7187425005936;3539.557555305587;3542.557180352456;1172.7302720374362;1206.324458303353;1213.5509861351268;1253.1979533098825;1260.1493991555662;10973.23187376884;11065.280756750866;11082.778344433147;11089.83365249522;11128.554857805679;4427.645788336926;4661.417322834645;4703.553162449562;4721.6458765592715;4734.857954261378;9600.903990960001;9636.084571965652;9638.90361096394;9643.71068867921;9659.32384733076;5276.208568714724;5287.735613219401;5290.100682883919;5294.993951149269;5295.099833028395;4650.604698600657;4728.692634978715;4806.4712881583;4838.637102217277;4882.072406242803;3757.267332870064;3793.981030094887;3811.371123764548;3851.306764782366;3870.3807390817897;1263.92416455013;1266.1326991011188;1266.8353114095294;1277.923324600527;1279.3347459321287;887.9023307436177;911.334725650279;913.5614904845711;940.0881145289059;950.5769932380025;5656;5718.056520674042;5909.556783241188;5979.521638268928;6145.170401995762;796.3151689546985;964.4791812421354;1004.6684594083935;1009.4347165587294;1017.29298137795;3859.8456061757215;3887.047673319998;3947.546032206333;3956.3867600521967;3970.404439334065;9894.455804930654;10242.6415075472;10245.948770256251;10249.641262555775;10280.485975701335;9931.80136397268;10029.69910902674;10164.644237451654;10231.641892533726;10276.486175691185;6729.8654026919185;7908.090569584457;7953.922106417923;7975.800604984922;8030.317423019109;5460.645058071213;5640.774369025193;5641.774329026793;5734.569907256889;5845.3277873045145;9149.359544831797;9167.724968250963;9262.861057084014;9277.536123193948;9411.10594493522;8312.501249924782;9292.535373231447;9502.904970950201;9521.714348569554;9596.280278978966;6739.528233023641;6867.450603951572;6870;6872.965635171792;6882.621455819778;7602.391808655405;7884.802879928047;7972.6412311444965;7982.9600851995365;8077.313428358655;7249.710011599477;7322.414206863545;7347.375473084848;7436.70253189888;7459.067616547932;7144.85710285781;7202.927970720226;7218.927810721825;7270.854582908207;7287.744928927462;6368.299487056327;6493.058506516434;6497.25281592604;6539.934600653933;6559.4752419805345;4972.726500042532;5029.798808047637;5057.671251368649;5114.232865070269;5148.016710839609;4993.875153121201;5072.82245121419;5080.49195080489;5111.769970351269;5266.368035835571 04:45:35 INFO - PROCESS | 3123 | |4;prototype.html;308.20790568239636;309.76303128106986;356.1008453654522;356.5133592646039;365.73849697466306;4763.595094416979;5002.574781143602;5052.646314757969;5182.948170518293;5353.946460535393;4875.634327425449;4909.901801963968;5264.368275806899;5267.52592266696;5299.8410047698635;3918.9804050979706;4148.128892932487;4172.687048471362;4178.456800615923;4191.203671302443;3923;4035.717499775017;4071.2875246831686;4140.689448291375;4186.665066794664;3415.8975230742994;4034.677225821927;4097;4104.5074591049115;4136.379543068549;3856.421536769493;4045.8267102540226;4089.7750623715683;4125.855595054159;4149.439825623563;3465.9480107798404;3579.964200358015;3596.478510615972;3682.410814269704;3727.7390582658954;4956.479569645171;5054.721990290533;5126.6414400183985;5149.897002059975;5186.351706036746;4009.719319647626;4066.190545207384;4765.833195838129;4798.904021919542;4849.369581954324;5292.497212764782;5544;5545.11653939949;5567.413287213165;5588.497035266805;3885.378339465672;3895.5714871364125;3996.100877302613;4061.715679902408;4066.471358723347;4991.875203119915;5050.60096454185;5919.230500035011;5996.70016499182;6003.27960644721;3834.3481608126685;3993.5207775066597;4013.9197216055513;4147.689109619594;4243.639290660267;3550.4496802995577;3859.6877061799123;4009.3785463253244;4249.107687385622;4251.0010147615285;3941.1723538056754;4521.615662668644;4643.419572553431;4761.47623761386;4791.113643975876;5609.635373700697;5613.410591887875;5617.354004289477;5633.521150702236;5638.9699708105045;1119.7648493816228;1293.5537239652306;1350.8851747601368;1387.6193187778226;1401.3693837773044;978.7797745507346;980.6616717232544;987.1313244344931;989.5200827598609;999.615148167946;4813.253945638432;4886.389201349832;4923.507649235048;4948.05986862495;5043.899122017612;4783.827962149227;5165.509276618715;5176.033107419156;5559.833205003856;5568.5776687140415;4837.685550439211;5050.419201791768;5193.948060519422;5235;5254.605904557174;2682.3964607963053;2819.209801775887;2831.957520637192;2874.956875646867;2896.362800183956;4629.513901040342;4633.536646335339;5113.923291150636;5448.56411487088;5712.428757124338;5345.65253258537;5443.346798384219;5570.08093664549;5668.716564171775;5711;4789.44921334051;4910;5481.424450432806;5644.883168811695;5716.599838011382;5204.453532379197;5418.485243901823;5434.456554344692;5493.8684949578355;5520.282363292747;4698.389209402756;5123.7190702324415;5427.99582077317;5441.455854414685;5446.809361672323;4640;4871.9075976180175;5225.451327610546;5632.211490391431;5651.519620832275;3549.591796943281;3663.9298796180074;3677.4851520786574;3864.343061679465;3906.7655940643654;5303.416624171261;5481.397046324981;5656.604037717485;5778.739956701875;5791;4735.981763920774;5324.946750532445;5466.217060072437;5475.289731582892;5493.560515158858;4727.131055013342;4843.927341089957;5614.915776263441;5627.240322556402;5675.31896172462;4483.484399293992;5420.620556561003;5438.619296649196;5476.890462190654;5505.862353441196;5289.968456151168;5565.805196818092;5613.596600849787;5628.48031031611;5672.319321681424;4381.737095774264;5025.170846810235;5247.58019358458;5325.627206095536;5492.505674489315 04:45:35 INFO - PROCESS | 3123 | |5;yui.html;724.786188074518;756.1040167401629;769.1961899814695;771.8494893495767;837.430547227885;14256.857431425682;14381.1371317721;14772.261386930651;14897.255137243148;14938.925305373485;13506.932465337684;13734.313284335793;13836.169829810218;13886;13922.234277114754;877.3770622857762;890.7060669978924;893.0845882969975;893.7988952485704;896.135229503529;859.5487369131193;886.7517095213351;889.4618755652834;894.6242578117174;901.6753968571309;905.41148253635;924.0482303227652;934.2339281788936;941.8869735631733;944.6788092048702;899.8785164002874;908.881845360099;932.2029664636706;933.3466573398648;938.1181689212119;668.381746884135;669.2638098092109;680.9455243580502;698.5703792167831;698.8916717908733;14795.926020369829;14872.925635371863;14915.627109322246;14934.47729329479;15147;13891;14049.719005619932;14176.078554893898;14225.359858806378;14307.713845723027;11798.292102473793;11873.821892671616;11878.227915185571;11923.94038029813;12105.576304829377;3622.5290712207507;3731.70146388291;3753.774773513598;3784.6215378462207;3837.1174629835014;100876.48685269727;101264.49367753188;101697;105288;106289;10321.174306055498;10536.473176341153;11059.336439813636;11075.72310692223;11109.666709998713;21998.89000554987;22003;22015.779842201373;22034.228801992173;22063.448413789785;6939.791806245821;7296.671649775667;7400.259974002557;7583.886241706268;7645.541267523967;4211.347241177622;4259.787010649455;4262.318029115327;4278.4010238566625;4383.539728328544;3435.089701229176;3506.333796578642;3577.2308953574584;3586.264815712773;3602.3875941090078;585.9941400586025;668.5921587831419;673.2224280955504;680.3944489404336;685.6366125953252;618.83600845775;632.3549979021465;639.5139693832627;661.3023260460164;679.6601699150425;9465.90534094664;9677.70966870995;9741.561629726679;9958.253131015057;9964.701058968243;603.5111559636708;625.487100577522;634.7810005548079;657.7434800427837;666.2904007232282;4749.95250047502;4779.450363208206;4912.45962944083;5183.3261675981885;5209.765560549784;91192.08807911968;92221.53889230645;92576.53711731399;96521;98284.52573211408;85865.57067214623;86690;87830;88545.22909541902;88600;69622;70522.64738676377;73597.6320118396;73787.63106184435;74526;10360.326578772356;10436;10646.89353106459;10740.785184296426;10946.616868409568;46773.29840052334;46980;48771.5122848767;50097.99216062633;50240.49759502358;47787.40750719685;65622.34377656163;65743.6712816452;65911.67044164748;66297.33702662912;12922.353882305659;12941.352932353533;13019.414126364147;13411.396487158296;13614.591562253148;14423.42306307736;14664.560063198121;14733.336999835248;14762.335694893542;15110.244487775348;13303.068785184943;13538.120022198525;13636;13658.453661853415;14109.29453527299;12308.199967002116;12618.684532886751;13517.86482135166;14228.573142805319;14320.14079155254;13199.63918527632;13309.267990260629;13489.325533723471;13524.855710284162;13960.930195348958;10912.454377281263;11098.667039988813;11332.6033588824;11414.315141091562;11731.765364692828;9852.655157069468;10599.417032063002;10621.628243011457;10627.787444251226;10650.414227217267 04:45:35 INFO - PROCESS | 3123 | __end_tp_report 04:45:35 INFO - PROCESS | 3123 | __start_cc_report 04:45:35 INFO - PROCESS | 3123 | _x_x_mozilla_cycle_collect,2882 04:45:35 INFO - PROCESS | 3123 | __end_cc_report 04:45:35 INFO - PROCESS | 3123 | __startTimestamp1486298735660__endTimestamp 04:45:35 INFO - PROCESS | 3123 | 04:45:35 INFO - PROCESS | 3123 | ------- Summary: start ------- 04:45:35 INFO - PROCESS | 3123 | Number of tests: 6 04:45:35 INFO - PROCESS | 3123 | 04:45:35 INFO - PROCESS | 3123 | [#0] dojo.html Cycles:165 Average:5644.38 Median:5778.65 stddev:763.95 (13.2%) stddev-sans-first:742.36 04:45:35 INFO - PROCESS | 3123 | Values: 3225.8 3238.4 3310.6 3312.7 3313.0 5446.7 5545.2 5596.1 5656.2 5656.4 5485.3 5573.5 5578.3 5579.8 5590.3 5020.6 5047.3 5141.4 5168.3 5223.1 4549.6 4878.9 5178.8 5204.9 5278.4 454.4 5099.5 5108.1 5185.6 5216.4 5126.1 5171.2 5175.2 5178.7 5228.0 5135.9 5144.5 5157.3 5161.7 5206.8 5448.3 5469.2 5661.1 5667.0 5704.2 6111.7 6137.8 6194.5 6243.3 6258.1 5471.6 6210.5 6247.4 6265.2 6278.2 5610.1 5801.2 5803.1 5805.5 5845.0 5128.7 6164.3 6276.3 6297.5 6313.1 6136.9 6161.0 6167.1 6171.0 6180.4 6057.6 6103.8 6120.1 6192.9 6281.0 6139.0 6147.0 6156.0 6233.8 6238.2 6204.3 6258.2 6269.9 6277.5 6282.0 4826.4 5000.6 5006.7 5010.3 5019.7 4377.8 4380.1 4434.0 4465.2 4482.4 5705.5 5716.8 5721.0 5723.0 5744.2 6108.7 6283.0 6289.1 6293.0 6299.6 5516.1 5552.9 5561.0 5590.8 5693.2 5324.7 5361.1 5369.8 5400.3 5407.4 6266.7 6270.7 6286.9 6288.8 6302.5 6010.8 6102.7 6241.7 6256.8 6258.2 6194.3 6272.7 6291.0 6297.4 6304.5 5161.5 6180.9 6200.7 6203.6 6208.8 6181.9 6203.0 6256.1 6262.3 6273.7 5656.4 5754.7 5792.6 6021.5 6110.5 6063.2 6105.5 6115.0 6117.8 6137.7 5678.3 5723.4 5736.1 5750.0 5912.7 5860.8 5916.3 5927.6 5929.5 5935.6 5767.8 5771.0 5778.7 5785.8 5792.4 04:45:35 INFO - PROCESS | 3123 | 04:45:35 INFO - PROCESS | 3123 | [#1] ext.html Cycles:180 Average:60363.06 Median:35536.64 stddev:127690.43 (359.3%) stddev-sans-first:127969.41 04:45:35 INFO - PROCESS | 3123 | Values: 453.6 462.9 493.0 514.9 522.9 37681.0 40286.8 40562.0 41766.2 41865.0 41487.0 42055.8 42063.0 42165.6 42201.8 7903.8 8173.3 9155.7 9382.8 9414.6 10831.4 11216.0 11216.6 11345.1 11376.5 9506.5 9547.7 10490.9 11006.4 11096.1 7920.6 8158.2 9145.2 11135.2 11231.3 10878.2 11040.5 11055.3 11060.0 11074.0 39322.6 39612.0 39622.4 39644.2 39659.4 3913.9 3927.4 3938.3 3938.8 4076.4 30096.0 30174.8 30232.8 30240.9 30299.2 2479.6 3201.7 3257.9 3258.2 3264.1 433404.0 433499.0 437978.0 438805.0 439041.0 48164.8 50362.2 50379.0 58157.4 58691.1 52058.2 52275.7 52427.7 52492.0 57521.7 14551.2 14611.3 14700.3 14702.3 14705.4 9615.8 11249.7 11259.9 11387.7 11405.6 15657.7 15719.5 15771.9 15913.4 15926.9 5252.6 6294.7 6339.5 6340.2 6373.0 844.4 853.6 862.5 862.8 879.5 28406.7 28862.6 29478.0 29478.8 29665.6 984.5 993.5 996.7 1029.9 1048.4 13908.7 13925.6 14056.4 14181.6 14196.4 537418.0 541195.0 544207.0 546577.0 547678.0 49567.8 49679.8 49717.5 49783.8 50060.7 1363.2 1398.3 1409.8 1419.3 1419.4 26030.2 26259.2 26259.3 26504.8 26525.0 35522.5 41539.8 43562.1 43661.8 43664.6 448990.0 450289.0 451046.0 451357.0 454376.0 20792.9 21407.4 23751.0 24126.3 24167.9 37416.0 38143.0 38696.4 38951.4 39377.0 31980.7 32531.2 32813.5 33309.5 33518.0 25080.0 31760.2 32710.5 32939.8 33269.8 31428.5 31976.4 34775.7 35504.6 35550.8 28900.9 30584.5 30838.2 30860.0 30986.0 22798.0 23236.5 23418.1 23523.3 24088.4 04:45:35 INFO - PROCESS | 3123 | 04:45:35 INFO - PROCESS | 3123 | [#2] jquery.html Cycles:180 Average:37820.98 Median:24039.18 stddev:112081.72 (466.2%) stddev-sans-first:112360.89 04:45:35 INFO - PROCESS | 3123 | Values: 386.8 388.7 390.4 400.7 422.8 19514.6 20276.2 20786.1 20810.8 21202.9 10004.4 12280.1 12332.3 12348.8 12556.5 5339.3 5558.4 5562.2 5563.9 5566.5 5569.6 6031.9 6098.2 6114.1 6115.5 5830.9 6067.7 6074.7 6081.8 6083.5 6004.6 6092.5 6112.0 6143.8 6146.4 5179.1 5334.7 5406.1 5451.5 5462.3 13593.9 13676.2 13787.9 13789.9 25537.4 2625.9 3017.8 3062.6 3112.1 3154.0 8792.5 10178.9 10479.3 10614.9 10647.0 1399.6 1790.5 1814.6 1894.8 1896.8 131955.0 134200.0 135226.0 137665.0 139345.0 11405.0 11422.8 11569.6 11597.2 11884.6 15666.5 15821.9 15854.0 15862.8 15869.5 7974.5 8213.5 8219.5 8386.2 8389.7 7524.2 7749.2 7756.5 7880.2 7980.9 4704.9 4765.6 4779.2 4879.5 4949.9 1878.9 1894.9 1971.7 2019.4 2037.2 829.5 849.2 853.7 883.2 887.3 21308.1 22369.7 22461.3 22476.7 22541.0 822.3 867.8 885.6 891.9 893.6 11245.6 11803.8 11813.4 11817.5 11826.9 657680.0 659456.0 660622.0 685099.0 686319.0 139928.0 140103.0 141552.3 142300.0 153594.0 975.6 986.9 1008.7 1010.2 1012.3 18979.2 19516.8 19677.1 19689.4 19781.9 52608.5 54537.6 57005.6 58403.7 59040.4 77148.0 80768.8 82430.0 82963.0 83968.0 6981.4 8369.0 8476.1 8624.8 8786.8 10628.4 13281.7 13627.3 13644.7 14049.8 11454.0 11626.1 11953.5 12009.1 12043.2 11272.7 11435.0 11550.8 11794.0 11916.6 10784.2 11614.7 11681.8 11935.8 11973.5 7181.7 7398.4 7592.6 7920.3 9036.8 10167.2 11532.3 11834.9 11905.7 11938.1 04:45:35 INFO - PROCESS | 3123 | 04:45:35 INFO - PROCESS | 3123 | [#3] mootools.html Cycles:180 Average:5496.74 Median:5173.33 stddev:3006.34 (58.1%) stddev-sans-first:2998.23 04:45:35 INFO - PROCESS | 3123 | Values: 1300.4 1432.2 1446.9 1461.5 1464.1 7417.7 7821.4 8217.4 8438.8 8463.3 8347.3 8446.2 8457.6 8461.0 8464.9 1977.5 2226.3 2257.3 2263.9 2265.0 2043.2 2241.4 2299.6 2310.8 2323.0 2256.8 2278.6 2282.4 2284.4 2286.0 2248.7 2275.1 2279.4 2282.1 2283.6 2232.0 2265.3 2266.6 2273.3 2274.1 8312.3 8523.0 8529.5 8532.6 8582.2 5043.5 5198.6 5234.8 5272.3 5320.6 2816.0 3483.5 3515.7 3539.6 3542.6 1172.7 1206.3 1213.6 1253.2 1260.1 10973.2 11065.3 11082.8 11089.8 11128.6 4427.6 4661.4 4703.6 4721.6 4734.9 9600.9 9636.1 9638.9 9643.7 9659.3 5276.2 5287.7 5290.1 5295.0 5295.1 4650.6 4728.7 4806.5 4838.6 4882.1 3757.3 3794.0 3811.4 3851.3 3870.4 1263.9 1266.1 1266.8 1277.9 1279.3 887.9 911.3 913.6 940.1 950.6 5656.0 5718.1 5909.6 5979.5 6145.2 796.3 964.5 1004.7 1009.4 1017.3 3859.8 3887.0 3947.5 3956.4 3970.4 9894.5 10242.6 10245.9 10249.6 10280.5 9931.8 10029.7 10164.6 10231.6 10276.5 6729.9 7908.1 7953.9 7975.8 8030.3 5460.6 5640.8 5641.8 5734.6 5845.3 9149.4 9167.7 9262.9 9277.5 9411.1 8312.5 9292.5 9502.9 9521.7 9596.3 6739.5 6867.5 6870.0 6873.0 6882.6 7602.4 7884.8 7972.6 7983.0 8077.3 7249.7 7322.4 7347.4 7436.7 7459.1 7144.9 7202.9 7218.9 7270.9 7287.7 6368.3 6493.1 6497.3 6539.9 6559.5 4972.7 5029.8 5057.7 5114.2 5148.0 4993.9 5072.8 5080.5 5111.8 5266.4 04:45:35 INFO - PROCESS | 3123 | 04:45:35 INFO - PROCESS | 3123 | [#4] prototype.html Cycles:180 Average:4479.71 Median:4952.27 stddev:1314.79 (26.5%) stddev-sans-first:1280.65 04:45:35 INFO - PROCESS | 3123 | Values: 308.2 309.8 356.1 356.5 365.7 4763.6 5002.6 5052.6 5182.9 5353.9 4875.6 4909.9 5264.4 5267.5 5299.8 3919.0 4148.1 4172.7 4178.5 4191.2 3923.0 4035.7 4071.3 4140.7 4186.7 3415.9 4034.7 4097.0 4104.5 4136.4 3856.4 4045.8 4089.8 4125.9 4149.4 3465.9 3580.0 3596.5 3682.4 3727.7 4956.5 5054.7 5126.6 5149.9 5186.4 4009.7 4066.2 4765.8 4798.9 4849.4 5292.5 5544.0 5545.1 5567.4 5588.5 3885.4 3895.6 3996.1 4061.7 4066.5 4991.9 5050.6 5919.2 5996.7 6003.3 3834.3 3993.5 4013.9 4147.7 4243.6 3550.4 3859.7 4009.4 4249.1 4251.0 3941.2 4521.6 4643.4 4761.5 4791.1 5609.6 5613.4 5617.4 5633.5 5639.0 1119.8 1293.6 1350.9 1387.6 1401.4 978.8 980.7 987.1 989.5 999.6 4813.3 4886.4 4923.5 4948.1 5043.9 4783.8 5165.5 5176.0 5559.8 5568.6 4837.7 5050.4 5193.9 5235.0 5254.6 2682.4 2819.2 2832.0 2875.0 2896.4 4629.5 4633.5 5113.9 5448.6 5712.4 5345.7 5443.3 5570.1 5668.7 5711.0 4789.4 4910.0 5481.4 5644.9 5716.6 5204.5 5418.5 5434.5 5493.9 5520.3 4698.4 5123.7 5428.0 5441.5 5446.8 4640.0 4871.9 5225.5 5632.2 5651.5 3549.6 3663.9 3677.5 3864.3 3906.8 5303.4 5481.4 5656.6 5778.7 5791.0 4736.0 5324.9 5466.2 5475.3 5493.6 4727.1 4843.9 5614.9 5627.2 5675.3 4483.5 5420.6 5438.6 5476.9 5505.9 5290.0 5565.8 5613.6 5628.5 5672.3 4381.7 5025.2 5247.6 5325.6 5492.5 04:45:35 INFO - PROCESS | 3123 | 04:45:35 INFO - PROCESS | 3123 | [#5] yui.html Cycles:180 Average:19772.46 Median:4261.05 stddev:27823.60 (653.0%) stddev-sans-first:27864.89 04:45:35 INFO - PROCESS | 3123 | Values: 724.8 756.1 769.2 771.8 837.4 14256.9 14381.1 14772.3 14897.3 14938.9 13506.9 13734.3 13836.2 13886.0 13922.2 877.4 890.7 893.1 893.8 896.1 859.5 886.8 889.5 894.6 901.7 905.4 924.0 934.2 941.9 944.7 899.9 908.9 932.2 933.3 938.1 668.4 669.3 680.9 698.6 698.9 14795.9 14872.9 14915.6 14934.5 15147.0 13891.0 14049.7 14176.1 14225.4 14307.7 11798.3 11873.8 11878.2 11923.9 12105.6 3622.5 3731.7 3753.8 3784.6 3837.1 100876.5 101264.5 101697.0 105288.0 106289.0 10321.2 10536.5 11059.3 11075.7 11109.7 21998.9 22003.0 22015.8 22034.2 22063.4 6939.8 7296.7 7400.3 7583.9 7645.5 4211.3 4259.8 4262.3 4278.4 4383.5 3435.1 3506.3 3577.2 3586.3 3602.4 586.0 668.6 673.2 680.4 685.6 618.8 632.4 639.5 661.3 679.7 9465.9 9677.7 9741.6 9958.3 9964.7 603.5 625.5 634.8 657.7 666.3 4750.0 4779.5 4912.5 5183.3 5209.8 91192.1 92221.5 92576.5 96521.0 98284.5 85865.6 86690.0 87830.0 88545.2 88600.0 69622.0 70522.6 73597.6 73787.6 74526.0 10360.3 10436.0 10646.9 10740.8 10946.6 46773.3 46980.0 48771.5 50098.0 50240.5 47787.4 65622.3 65743.7 65911.7 66297.3 12922.4 12941.4 13019.4 13411.4 13614.6 14423.4 14664.6 14733.3 14762.3 15110.2 13303.1 13538.1 13636.0 13658.5 14109.3 12308.2 12618.7 13517.9 14228.6 14320.1 13199.6 13309.3 13489.3 13524.9 13960.9 10912.5 11098.7 11332.6 11414.3 11731.8 9852.7 10599.4 10621.6 10627.8 10650.4 04:45:35 INFO - PROCESS | 3123 | -------- Summary: end -------- 04:45:35 INFO - PROCESS | 3123 | 04:45:36 INFO - TEST-INFO | 3123: exit 0 04:45:36 INFO - TEST-OK | dromaeo_css | took 1119355ms 04:45:36 INFO - TEST-START | kraken 04:45:36 INFO - operating with platform_type : mac_ 04:45:36 INFO - Initialising browser for kraken test... 04:45:36 INFO - TEST-INFO | started process 3127 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/tmpv92dpL/profile http://localhost:63671/getInfo.html) 04:45:38 INFO - PROCESS | 3127 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 04:45:38 INFO - PROCESS | 3127 | __metrics Screen width/height:1600/1200 04:45:38 INFO - PROCESS | 3127 | colorDepth:24 04:45:38 INFO - PROCESS | 3127 | Browser inner width/height: 1024/689 04:45:38 INFO - PROCESS | 3127 | __metrics 04:45:38 INFO - PROCESS | 3127 | 1486298738837 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 04:45:38 INFO - PROCESS | 3127 | 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" 04:45:38 INFO - PROCESS | 3127 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:45:39 INFO - TEST-INFO | 3127: exit 0 04:45:39 INFO - Browser initialized. 04:45:39 INFO - Running cycle 1/1 for kraken test... 04:45:39 INFO - TEST-INFO | started process 3129 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/tmpv92dpL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:45:51 INFO - PROCESS | 3129 | RSS: Main: 236535808 04:45:51 INFO - PROCESS | 3129 | 04:45:54 INFO - PROCESS | 3129 | Cycle 1(1): loaded http://localhost:63671/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:63671/tests/kraken/driver.html?testName=audio-beat-detection) 04:45:55 INFO - PROCESS | 3129 | RSS: Main: 288759808 04:45:55 INFO - PROCESS | 3129 | 04:45:58 INFO - PROCESS | 3129 | Cycle 1(1): loaded http://localhost:63671/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:63671/tests/kraken/driver.html?testName=audio-dft) 04:45:58 INFO - PROCESS | 3129 | RSS: Main: 290840576 04:45:58 INFO - PROCESS | 3129 | 04:46:02 INFO - PROCESS | 3129 | Cycle 1(1): loaded http://localhost:63671/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:63671/tests/kraken/driver.html?testName=audio-fft) 04:46:02 INFO - PROCESS | 3129 | RSS: Main: 382803968 04:46:02 INFO - PROCESS | 3129 | 04:46:04 INFO - PROCESS | 3129 | Cycle 1(1): loaded http://localhost:63671/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:63671/tests/kraken/driver.html?testName=audio-oscillator) 04:46:05 INFO - PROCESS | 3129 | RSS: Main: 285720576 04:46:05 INFO - PROCESS | 3129 | 04:46:07 INFO - PROCESS | 3129 | Cycle 1(1): loaded http://localhost:63671/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:63671/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:46:07 INFO - PROCESS | 3129 | RSS: Main: 287617024 04:46:07 INFO - PROCESS | 3129 | 04:46:10 INFO - PROCESS | 3129 | Cycle 1(1): loaded http://localhost:63671/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:63671/tests/kraken/driver.html?testName=imaging-darkroom) 04:46:11 INFO - PROCESS | 3129 | RSS: Main: 330084352 04:46:11 INFO - PROCESS | 3129 | 04:46:14 INFO - PROCESS | 3129 | Cycle 1(1): loaded http://localhost:63671/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:63671/tests/kraken/driver.html?testName=imaging-desaturate) 04:46:14 INFO - PROCESS | 3129 | RSS: Main: 333406208 04:46:14 INFO - PROCESS | 3129 | 04:46:17 INFO - PROCESS | 3129 | Cycle 1(1): loaded http://localhost:63671/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:63671/tests/kraken/driver.html?testName=json-parse-financial) 04:46:18 INFO - PROCESS | 3129 | RSS: Main: 325910528 04:46:18 INFO - PROCESS | 3129 | 04:46:20 INFO - PROCESS | 3129 | Cycle 1(1): loaded http://localhost:63671/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:63671/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:46:20 INFO - PROCESS | 3129 | RSS: Main: 306704384 04:46:20 INFO - PROCESS | 3129 | 04:46:22 INFO - PROCESS | 3129 | Cycle 1(1): loaded http://localhost:63671/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:63671/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:46:23 INFO - PROCESS | 3129 | RSS: Main: 310349824 04:46:23 INFO - PROCESS | 3129 | 04:46:25 INFO - PROCESS | 3129 | Cycle 1(1): loaded http://localhost:63671/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:63671/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:46:26 INFO - PROCESS | 3129 | RSS: Main: 313458688 04:46:26 INFO - PROCESS | 3129 | 04:46:29 INFO - PROCESS | 3129 | Cycle 1(1): loaded http://localhost:63671/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:63671/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:46:30 INFO - PROCESS | 3129 | RSS: Main: 330641408 04:46:30 INFO - PROCESS | 3129 | 04:46:33 INFO - PROCESS | 3129 | Cycle 1(1): loaded http://localhost:63671/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:63671/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:46:33 INFO - PROCESS | 3129 | RSS: Main: 324898816 04:46:33 INFO - PROCESS | 3129 | 04:46:35 INFO - PROCESS | 3129 | Cycle 1(1): loaded http://localhost:63671/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:63671/tests/kraken/driver.html?testName=ai-astar) 04:46:35 INFO - PROCESS | 3129 | RSS: Main: 369795072 04:46:35 INFO - PROCESS | 3129 | 04:46:35 INFO - PROCESS | 3129 | __start_tp_report 04:46:35 INFO - PROCESS | 3129 | _x_x_mozilla_page_load 04:46:35 INFO - PROCESS | 3129 | _x_x_mozilla_page_load_details 04:46:35 INFO - PROCESS | 3129 | |i|pagename|runs| 04:46:35 INFO - PROCESS | 3129 | |0;ai-astar;116;114;114;114;113;114;112;115;114;115 04:46:35 INFO - PROCESS | 3129 | |1;audio-beat-detection;144;108;110;148;111;111;110;110;138;110 04:46:35 INFO - PROCESS | 3129 | |2;audio-dft;186;185;185;185;184;185;186;186;181;213 04:46:35 INFO - PROCESS | 3129 | |3;audio-fft;81;59;59;89;59;58;59;58;77;58 04:46:35 INFO - PROCESS | 3129 | |4;audio-oscillator;94;71;87;70;68;70;85;67;69;82 04:46:35 INFO - PROCESS | 3129 | |5;imaging-gaussian-blur;88;85;89;85;88;84;88;86;88;85 04:46:35 INFO - PROCESS | 3129 | |6;imaging-darkroom;94;94;96;94;95;93;94;95;95;93 04:46:35 INFO - PROCESS | 3129 | |7;imaging-desaturate;86;88;88;99;135;132;84;85;88;84 04:46:35 INFO - PROCESS | 3129 | |8;json-parse-financial;60;59;58;57;58;59;59;58;60;58 04:46:35 INFO - PROCESS | 3129 | |9;json-stringify-tinderbox;73;69;79;75;74;73;73;70;71;72 04:46:35 INFO - PROCESS | 3129 | |10;stanford-crypto-aes;88;88;95;99;87;90;92;87;85;89 04:46:35 INFO - PROCESS | 3129 | |11;stanford-crypto-ccm;150;152;153;134;139;139;138;185;217;196 04:46:35 INFO - PROCESS | 3129 | |12;stanford-crypto-pbkdf2;178;170;174;178;171;172;172;175;179;230 04:46:35 INFO - PROCESS | 3129 | |13;stanford-crypto-sha256-iterative;66;70;66;68;68;67;67;66;68;66 04:46:35 INFO - PROCESS | 3129 | __end_tp_report 04:46:35 INFO - PROCESS | 3129 | __start_cc_report 04:46:35 INFO - PROCESS | 3129 | _x_x_mozilla_cycle_collect,2089 04:46:35 INFO - PROCESS | 3129 | __end_cc_report 04:46:35 INFO - PROCESS | 3129 | __startTimestamp1486298795708__endTimestamp 04:46:35 INFO - PROCESS | 3129 | 04:46:35 INFO - PROCESS | 3129 | ------- Summary: start ------- 04:46:35 INFO - PROCESS | 3129 | Number of tests: 14 04:46:35 INFO - PROCESS | 3129 | 04:46:35 INFO - PROCESS | 3129 | [#0] ai-astar Cycles:10 Average:114.10 Median:114.00 stddev:1.10 (1.0%) stddev-sans-first:0.93 04:46:35 INFO - PROCESS | 3129 | Values: 116.0 114.0 114.0 114.0 113.0 114.0 112.0 115.0 114.0 115.0 04:46:35 INFO - PROCESS | 3129 | 04:46:35 INFO - PROCESS | 3129 | [#1] audio-beat-detection Cycles:10 Average:120.00 Median:111.00 stddev:16.30 (14.7%) stddev-sans-first:14.79 04:46:35 INFO - PROCESS | 3129 | Values: 144.0 108.0 110.0 148.0 111.0 111.0 110.0 110.0 138.0 110.0 04:46:35 INFO - PROCESS | 3129 | 04:46:35 INFO - PROCESS | 3129 | [#2] audio-dft Cycles:10 Average:187.60 Median:185.50 stddev:9.05 (4.9%) stddev-sans-first:9.58 04:46:35 INFO - PROCESS | 3129 | Values: 186.0 185.0 185.0 185.0 184.0 185.0 186.0 186.0 181.0 213.0 04:46:35 INFO - PROCESS | 3129 | 04:46:35 INFO - PROCESS | 3129 | [#3] audio-fft Cycles:10 Average:65.70 Median:59.00 stddev:11.84 (20.1%) stddev-sans-first:11.19 04:46:35 INFO - PROCESS | 3129 | Values: 81.0 59.0 59.0 89.0 59.0 58.0 59.0 58.0 77.0 58.0 04:46:35 INFO - PROCESS | 3129 | 04:46:35 INFO - PROCESS | 3129 | [#4] audio-oscillator Cycles:10 Average:76.30 Median:76.50 stddev:9.73 (12.7%) stddev-sans-first:7.94 04:46:35 INFO - PROCESS | 3129 | Values: 94.0 71.0 87.0 70.0 68.0 70.0 85.0 67.0 69.0 82.0 04:46:35 INFO - PROCESS | 3129 | 04:46:35 INFO - PROCESS | 3129 | [#5] imaging-gaussian-blur Cycles:10 Average:86.60 Median:88.00 stddev:1.78 (2.0%) stddev-sans-first:1.81 04:46:35 INFO - PROCESS | 3129 | Values: 88.0 85.0 89.0 85.0 88.0 84.0 88.0 86.0 88.0 85.0 04:46:35 INFO - PROCESS | 3129 | 04:46:35 INFO - PROCESS | 3129 | [#6] imaging-darkroom Cycles:10 Average:94.30 Median:94.50 stddev:0.95 (1.0%) stddev-sans-first:1.00 04:46:35 INFO - PROCESS | 3129 | Values: 94.0 94.0 96.0 94.0 95.0 93.0 94.0 95.0 95.0 93.0 04:46:35 INFO - PROCESS | 3129 | 04:46:35 INFO - PROCESS | 3129 | [#7] imaging-desaturate Cycles:10 Average:96.90 Median:87.00 stddev:19.77 (22.7%) stddev-sans-first:20.58 04:46:35 INFO - PROCESS | 3129 | Values: 86.0 88.0 88.0 99.0 135.0 132.0 84.0 85.0 88.0 84.0 04:46:35 INFO - PROCESS | 3129 | 04:46:35 INFO - PROCESS | 3129 | [#8] json-parse-financial Cycles:10 Average:58.60 Median:59.00 stddev:0.97 (1.6%) stddev-sans-first:0.88 04:46:35 INFO - PROCESS | 3129 | Values: 60.0 59.0 58.0 57.0 58.0 59.0 59.0 58.0 60.0 58.0 04:46:35 INFO - PROCESS | 3129 | 04:46:35 INFO - PROCESS | 3129 | [#9] json-stringify-tinderbox Cycles:10 Average:72.90 Median:73.00 stddev:2.81 (3.8%) stddev-sans-first:2.98 04:46:35 INFO - PROCESS | 3129 | Values: 73.0 69.0 79.0 75.0 74.0 73.0 73.0 70.0 71.0 72.0 04:46:35 INFO - PROCESS | 3129 | 04:46:35 INFO - PROCESS | 3129 | [#10] stanford-crypto-aes Cycles:10 Average:90.00 Median:89.50 stddev:4.24 (4.7%) stddev-sans-first:4.44 04:46:35 INFO - PROCESS | 3129 | Values: 88.0 88.0 95.0 99.0 87.0 90.0 92.0 87.0 85.0 89.0 04:46:35 INFO - PROCESS | 3129 | 04:46:35 INFO - PROCESS | 3129 | [#11] stanford-crypto-ccm Cycles:10 Average:160.30 Median:152.50 stddev:28.72 (18.8%) stddev-sans-first:30.22 04:46:35 INFO - PROCESS | 3129 | Values: 150.0 152.0 153.0 134.0 139.0 139.0 138.0 185.0 217.0 196.0 04:46:35 INFO - PROCESS | 3129 | 04:46:35 INFO - PROCESS | 3129 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.90 Median:176.50 stddev:17.89 (10.1%) stddev-sans-first:18.96 04:46:35 INFO - PROCESS | 3129 | Values: 178.0 170.0 174.0 178.0 171.0 172.0 172.0 175.0 179.0 230.0 04:46:35 INFO - PROCESS | 3129 | 04:46:35 INFO - PROCESS | 3129 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.20 Median:67.50 stddev:1.32 (2.0%) stddev-sans-first:1.32 04:46:35 INFO - PROCESS | 3129 | Values: 66.0 70.0 66.0 68.0 68.0 67.0 67.0 66.0 68.0 66.0 04:46:35 INFO - PROCESS | 3129 | -------- Summary: end -------- 04:46:35 INFO - PROCESS | 3129 | 04:46:36 INFO - TEST-INFO | 3129: exit 0 04:46:36 INFO - TEST-OK | kraken | took 60230ms 04:46:36 INFO - SUITE-END | took 1179s 04:46:36 INFO - Completed test suite (00:19:39) 04:46:36 INFO - javascript benchmark 04:46:36 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7154.3655425049365, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3225.8064516129048, 3238.449463591189, 3310.619278782941, 3312.6852948969845, 3313.0, 5446.700431476267, 5545.195946587743, 5596.104623260279, 5656.208130861683, 5656.406077361879, 5485.259489968853, 5573.498385145336, 5578.2748242728385, 5579.776808927638, 5590.301212348457, 5020.623453241013, 5047.31861198739, 5141.434442211373, 5168.32811734474, 5223.138182199932, 4549.567791059861, 4878.85346943468, 5178.818741344054, 5204.869878253055, 5278.419373868871, 454.3894094094867, 5099.5410413063, 5108.080545501808, 5185.611079169059, 5216.374035115791, 5126.102931986925, 5171.172612382001, 5175.171972484422, 5178.715170665612, 5227.973860130675, 5135.871603209912, 5144.485551444825, 5157.30376399185, 5161.69029858206, 5206.76569554371, 5448.291722076145, 5469.15228139639, 5661.122526008476, 5666.971665141689, 5704.201411802351, 6111.66088984588, 6137.775380989496, 6194.5354098442795, 6243.344448832852, 6258.06129080637, 5471.644343117685, 6210.45149296394, 6247.4377306042215, 6265.154204182468, 6278.246610406781, 5610.074337734228, 5801.187833703285, 5803.071508558611, 5805.5065319447895, 5845.0, 5128.743562821918, 6164.260288765287, 6276.278228003746, 6297.4647154992335, 6313.05304204373, 6136.9079463808075, 6161.0, 6167.105769663373, 6171.0, 6180.443760061616, 6057.575969682168, 6103.84740381494, 6120.14317995481, 6192.938070619236, 6281.0, 6139.048447490646, 6146.955017647011, 6155.984262596711, 6233.844153896098, 6238.157848690369, 6204.348543402968, 6258.186435763321, 6269.874602508015, 6277.466415354746, 6282.026285925689, 4826.444958829709, 5000.649954503223, 5006.724630145377, 5010.348654674869, 5019.67372120811, 4377.759223242689, 4380.0582874682095, 4434.046679963823, 4465.173942820588, 4482.417285752831, 5705.515031222392, 5716.771329146787, 5721.0, 5722.971385143048, 5744.167095771089, 6108.694565271719, 6283.0, 6289.119523266746, 6292.961661325921, 6299.559030867887, 5516.14499752539, 5552.888942221133, 5560.972195139079, 5590.8043218487155, 5693.231413759172, 5324.680519168863, 5361.08861493547, 5369.785208591613, 5400.324959380077, 5407.405185429599, 6266.655333956585, 6270.6551139685935, 6286.937130628635, 6288.842778930563, 6302.464290535356, 6010.759569617167, 6102.671035646872, 6241.719122639402, 6256.7810126645345, 6258.155149054819, 6194.349593292639, 6272.749090036347, 6290.968545157245, 6297.433231009231, 6304.464120549805, 5161.458046905021, 6180.907286390798, 6200.689965501797, 6203.627782332894, 6208.751649933952, 6181.907271391023, 6203.0, 6256.092866534236, 6262.279837818709, 6273.686315684106, 5656.406077361818, 5754.6547207167705, 5792.623479473821, 6021.54838387114, 6110.480609148272, 6063.211782468251, 6105.459747277989, 6114.966544785144, 6117.796956032597, 6137.693115344304, 5678.261825962599, 5723.399043100415, 5736.110902810071, 5750.0, 5912.674802885883, 5860.765569377178, 5916.31962324321, 5927.644341339534, 5929.52563794904, 5935.554833387427, 5767.769289228384, 5771.0, 5778.653280803165, 5785.768569257182, 5792.420757924174], "value": 5596.796461055835, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [453.573640777669, 462.86808259646006, 492.96477397465327, 514.930484384608, 522.9320188375511, 37681.0, 40286.798566007164, 40561.985950351256, 41766.164676706525, 41864.95337616561, 41487.0, 42055.78972105143, 42063.0, 42165.57834421655, 42201.78899105508, 7903.802404939865, 8173.346132309431, 9155.72532824013, 9382.765430864249, 9414.623415063423, 10831.404272764994, 11216.046636035906, 11216.607418740346, 11345.149113816455, 11376.488058037408, 9506.47714375709, 9547.665831695893, 10490.947545262301, 11006.449677516131, 11096.05683516902, 7920.603969801545, 8158.184181581853, 9145.222656074242, 11135.164862635294, 11231.26996745213, 10878.238523303371, 11040.503177357039, 11055.336679799238, 11060.004599586076, 11074.0, 39322.60677393218, 39612.0, 39622.405663915066, 39644.20711585781, 39659.40510892339, 3913.8630147944687, 3927.3716205407, 3938.2517321708783, 3938.7833669148185, 4076.429299898017, 30096.0, 30174.849125754452, 30232.8488357559, 30240.94156704527, 30299.242518936982, 2479.590867506859, 3201.679832016803, 3257.9022629321157, 3258.201740573551, 3264.069740124077, 433404.0, 433499.0, 437977.9999999968, 438805.0, 439041.0, 48164.75917620459, 50362.24456633154, 50379.0, 58157.4184258152, 58691.11963320553, 52058.21912671313, 52275.73862130665, 52427.73786131045, 52492.0, 57521.71239143777, 14551.199684017269, 14611.269436528131, 14700.264986750619, 14702.338394772261, 14705.411783528752, 9615.759606009906, 11249.71875703114, 11259.887401126047, 11387.65837024874, 11405.600803971822, 15657.686846263012, 15719.528414147593, 15771.870797442429, 15913.363465461483, 15926.920365398099, 5252.579793616502, 6294.716737746812, 6339.461145802658, 6340.239171299474, 6372.968135159294, 844.3582877013391, 853.6116067189414, 862.4954401675091, 862.8403745307138, 879.5030807593689, 28406.71593284082, 28862.567061494094, 29478.0, 29478.841202789426, 29665.555016674767, 984.5471083301618, 993.4784238274964, 996.7125447098925, 1029.9330543514648, 1048.4181279390011, 13908.721825563433, 13925.570228091245, 14056.437742490185, 14181.574552763434, 14196.432142714175, 537418.0, 541195.0, 544207.0, 546577.0, 547678.0, 49567.75216123896, 49679.75160124176, 49717.50282497201, 49783.75540611514, 50060.74969625129, 1363.2297751770416, 1398.3008495752124, 1409.7603407420559, 1419.2832619527073, 1419.396756378535, 26030.219093427226, 26259.21222363332, 26259.343516412242, 26504.807283672668, 26525.0, 35522.467162992056, 41539.7923010383, 43562.12875742404, 43661.78169109134, 43664.56335436605, 448990.0, 450289.0, 451046.0, 451357.0, 454376.0, 20792.896035519727, 21407.357779266647, 23751.0, 24126.276211713677, 24167.879160604083, 37416.0, 38143.0, 38696.41955370615, 38951.415728763524, 39377.015574610865, 31980.68019319777, 32531.186720331236, 32813.507797383536, 33309.50035749417, 33518.0, 25080.0, 31760.205994849388, 32710.509342359408, 32939.83530082334, 33269.83365083256, 31428.528572071893, 31976.36047279088, 34775.65224347724, 35504.644953551164, 35550.82224588861, 28900.855495722386, 30584.541231881096, 30838.229044274074, 30860.0, 30986.0, 22798.0, 23236.535269294858, 23418.06327746871, 23523.294301170994, 24088.397790055387], "value": 18433.54400733765, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.82979489024825, 388.71041074399574, 390.3896427237645, 400.74752905669425, 422.76959057313763, 19514.609707805834, 20276.188952441884, 20786.06462709178, 20810.791892081055, 21202.89398553009, 10004.449755263458, 12280.078994075458, 12332.260064396143, 12348.814767778491, 12556.497740090386, 5339.252504649352, 5558.360788509312, 5562.16567514872, 5563.860903477405, 5566.4990150886415, 5569.554435645138, 6031.939680603182, 6098.176746139281, 6114.144019837227, 6115.541334399915, 5830.912536311937, 6067.7269522871575, 6074.696265186745, 6081.78713745019, 6083.482903953147, 6004.639721616717, 6092.5125989920925, 6112.0, 6143.815685529441, 6146.38536146384, 5179.093658609766, 5334.653247538898, 5406.08096623571, 5451.509364157245, 5462.317210348707, 13593.930872759987, 13676.179429234178, 13787.862121378757, 13789.931050344683, 25537.361565960815, 2625.9474810503875, 3017.7585793136495, 3062.6477955035234, 3112.1441603558974, 3153.9684603153905, 8792.47245165292, 10178.949105254427, 10479.318844275098, 10614.938506149401, 10647.0, 1399.6360946154073, 1790.5255107396551, 1814.6089384092188, 1894.8484121270264, 1896.810318968106, 131955.0, 134200.0, 135226.0, 137665.0, 139345.0, 11405.0, 11422.771544569063, 11569.553805774278, 11597.18819682631, 11884.643460696192, 15666.530004099895, 15821.920890395475, 15854.0, 15862.762058569131, 15869.52391428259, 7974.5215287082965, 8213.548254845922, 8219.54792486419, 8386.203310685598, 8389.748307550783, 7524.172341042481, 7749.186335434811, 7756.457048006585, 7880.172581878935, 7980.880286795703, 4704.85885423431, 4765.611145047213, 4779.235322348408, 4879.536444037811, 4949.925751113737, 1878.8538991215348, 1894.8768330058501, 1971.6845304751457, 2019.4446527205137, 2037.1545808489607, 829.5437509369943, 849.1678155407611, 853.7182729699308, 883.1918794303141, 887.3477993674644, 21308.147674093172, 22369.664455033188, 22461.32616021522, 22476.662850057262, 22541.0, 822.2978434640356, 867.7613656244583, 885.5970533407414, 891.888513935758, 893.6380765789866, 11245.606403775828, 11803.763924721628, 11813.350265735467, 11817.527298908119, 11826.940865295619, 657680.0, 659456.0, 660622.0, 685099.0, 686319.0, 139928.0, 140103.0, 141552.29223854022, 142300.0, 153594.0, 975.5902520941222, 986.8618393424927, 1008.6620981970975, 1010.1767059846202, 1012.3217444312328, 18979.24083036663, 19516.8048319515, 19677.114529846476, 19689.409317720492, 19781.901090494455, 52608.47391526036, 54537.63655908634, 57005.594076976835, 58403.70798145982, 59040.40959590349, 77148.0, 80768.78846817184, 82430.0, 82963.0, 83968.0, 6981.371676549135, 8369.0, 8476.06763256029, 8624.827503450022, 8786.780330491532, 10628.415437150721, 13281.66795830112, 13627.250501222528, 13644.727105458034, 14049.789253161007, 11453.96914277719, 11626.069914406997, 11953.521859125538, 12009.099317551394, 12043.156979011384, 11272.718182045515, 11435.0, 11550.82673759911, 11794.0, 11916.642500724993, 10784.19118566095, 11614.70963225926, 11681.824772628248, 11935.820962685393, 11973.521059157885, 7181.712731490682, 7398.371138453292, 7592.582407967615, 7920.287174154354, 9036.774080647774, 10167.237457190593, 11532.25040372373, 11834.940825295818, 11905.702357441134, 11938.104642152048], "value": 9578.40638712722, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1300.3693208793727, 1432.1764985133543, 1446.9059511131777, 1461.523860900491, 1464.0629996802045, 7417.666205020781, 7821.413393995452, 8217.383696222778, 8438.83122337553, 8463.322934165268, 8347.332213422927, 8446.15538446155, 8457.57712114395, 8461.0, 8464.91535084649, 1977.4660841572768, 2226.254204841376, 2257.322803159054, 2263.8981245843893, 2265.0486795545826, 2043.1725151313742, 2241.361212054565, 2299.5515874404496, 2310.849794763343, 2323.012719594174, 2256.751757306695, 2278.6012447821563, 2282.4179834142356, 2284.360379093848, 2286.0398632574274, 2248.651459023854, 2275.067222438792, 2279.350385140227, 2282.1213832674375, 2283.5889539882814, 2231.9509830379698, 2265.3430505153488, 2266.5580211858696, 2273.30664147435, 2274.0903638544714, 8312.293455056268, 8523.0, 8529.488230706178, 8532.57337133141, 8582.227599516074, 5043.495650434964, 5198.636095473318, 5234.790608375661, 5272.31459910213, 5320.600954928396, 2815.971840281612, 3483.5471388719557, 3515.7187425005936, 3539.557555305587, 3542.557180352456, 1172.7302720374362, 1206.324458303353, 1213.5509861351268, 1253.1979533098825, 1260.1493991555662, 10973.23187376884, 11065.280756750866, 11082.778344433147, 11089.83365249522, 11128.554857805679, 4427.645788336926, 4661.417322834645, 4703.553162449562, 4721.6458765592715, 4734.857954261378, 9600.903990960001, 9636.084571965652, 9638.90361096394, 9643.71068867921, 9659.32384733076, 5276.208568714724, 5287.735613219401, 5290.100682883919, 5294.993951149269, 5295.099833028395, 4650.604698600657, 4728.692634978715, 4806.4712881583, 4838.637102217277, 4882.072406242803, 3757.267332870064, 3793.981030094887, 3811.371123764548, 3851.306764782366, 3870.3807390817897, 1263.92416455013, 1266.1326991011188, 1266.8353114095294, 1277.923324600527, 1279.3347459321287, 887.9023307436177, 911.334725650279, 913.5614904845711, 940.0881145289059, 950.5769932380025, 5656.0, 5718.056520674042, 5909.556783241188, 5979.521638268928, 6145.170401995762, 796.3151689546985, 964.4791812421354, 1004.6684594083935, 1009.4347165587294, 1017.29298137795, 3859.8456061757215, 3887.047673319998, 3947.546032206333, 3956.3867600521967, 3970.404439334065, 9894.455804930654, 10242.6415075472, 10245.948770256251, 10249.641262555775, 10280.485975701335, 9931.80136397268, 10029.69910902674, 10164.644237451654, 10231.641892533726, 10276.486175691185, 6729.8654026919185, 7908.090569584457, 7953.922106417923, 7975.800604984922, 8030.317423019109, 5460.645058071213, 5640.774369025193, 5641.774329026793, 5734.569907256889, 5845.3277873045145, 9149.359544831797, 9167.724968250963, 9262.861057084014, 9277.536123193948, 9411.10594493522, 8312.501249924782, 9292.535373231447, 9502.904970950201, 9521.714348569554, 9596.280278978966, 6739.528233023641, 6867.450603951572, 6870.0, 6872.965635171792, 6882.621455819778, 7602.391808655405, 7884.802879928047, 7972.6412311444965, 7982.9600851995365, 8077.313428358655, 7249.710011599477, 7322.414206863545, 7347.375473084848, 7436.70253189888, 7459.067616547932, 7144.85710285781, 7202.927970720226, 7218.927810721825, 7270.854582908207, 7287.744928927462, 6368.299487056327, 6493.058506516434, 6497.25281592604, 6539.934600653933, 6559.4752419805345, 4972.726500042532, 5029.798808047637, 5057.671251368649, 5114.232865070269, 5148.016710839609, 4993.875153121201, 5072.82245121419, 5080.49195080489, 5111.769970351269, 5266.368035835571], "value": 4459.434073964426, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [308.20790568239636, 309.76303128106986, 356.1008453654522, 356.5133592646039, 365.73849697466306, 4763.595094416979, 5002.574781143602, 5052.646314757969, 5182.948170518293, 5353.946460535393, 4875.634327425449, 4909.901801963968, 5264.368275806899, 5267.52592266696, 5299.8410047698635, 3918.9804050979706, 4148.128892932487, 4172.687048471362, 4178.456800615923, 4191.203671302443, 3923.0, 4035.717499775017, 4071.2875246831686, 4140.689448291375, 4186.665066794664, 3415.8975230742994, 4034.677225821927, 4097.0, 4104.5074591049115, 4136.379543068549, 3856.421536769493, 4045.8267102540226, 4089.7750623715683, 4125.855595054159, 4149.439825623563, 3465.9480107798404, 3579.964200358015, 3596.478510615972, 3682.410814269704, 3727.7390582658954, 4956.479569645171, 5054.721990290533, 5126.6414400183985, 5149.897002059975, 5186.351706036746, 4009.719319647626, 4066.190545207384, 4765.833195838129, 4798.904021919542, 4849.369581954324, 5292.497212764782, 5544.0, 5545.11653939949, 5567.413287213165, 5588.497035266805, 3885.378339465672, 3895.5714871364125, 3996.100877302613, 4061.715679902408, 4066.471358723347, 4991.875203119915, 5050.60096454185, 5919.230500035011, 5996.70016499182, 6003.27960644721, 3834.3481608126685, 3993.5207775066597, 4013.9197216055513, 4147.689109619594, 4243.639290660267, 3550.4496802995577, 3859.6877061799123, 4009.3785463253244, 4249.107687385622, 4251.0010147615285, 3941.1723538056754, 4521.615662668644, 4643.419572553431, 4761.47623761386, 4791.113643975876, 5609.635373700697, 5613.410591887875, 5617.354004289477, 5633.521150702236, 5638.9699708105045, 1119.7648493816228, 1293.5537239652306, 1350.8851747601368, 1387.6193187778226, 1401.3693837773044, 978.7797745507346, 980.6616717232544, 987.1313244344931, 989.5200827598609, 999.615148167946, 4813.253945638432, 4886.389201349832, 4923.507649235048, 4948.05986862495, 5043.899122017612, 4783.827962149227, 5165.509276618715, 5176.033107419156, 5559.833205003856, 5568.5776687140415, 4837.685550439211, 5050.419201791768, 5193.948060519422, 5235.0, 5254.605904557174, 2682.3964607963053, 2819.209801775887, 2831.957520637192, 2874.956875646867, 2896.362800183956, 4629.513901040342, 4633.536646335339, 5113.923291150636, 5448.56411487088, 5712.428757124338, 5345.65253258537, 5443.346798384219, 5570.08093664549, 5668.716564171775, 5711.0, 4789.44921334051, 4910.0, 5481.424450432806, 5644.883168811695, 5716.599838011382, 5204.453532379197, 5418.485243901823, 5434.456554344692, 5493.8684949578355, 5520.282363292747, 4698.389209402756, 5123.7190702324415, 5427.99582077317, 5441.455854414685, 5446.809361672323, 4640.0, 4871.9075976180175, 5225.451327610546, 5632.211490391431, 5651.519620832275, 3549.591796943281, 3663.9298796180074, 3677.4851520786574, 3864.343061679465, 3906.7655940643654, 5303.416624171261, 5481.397046324981, 5656.604037717485, 5778.739956701875, 5791.0, 4735.981763920774, 5324.946750532445, 5466.217060072437, 5475.289731582892, 5493.560515158858, 4727.131055013342, 4843.927341089957, 5614.915776263441, 5627.240322556402, 5675.31896172462, 4483.484399293992, 5420.620556561003, 5438.619296649196, 5476.890462190654, 5505.862353441196, 5289.968456151168, 5565.805196818092, 5613.596600849787, 5628.48031031611, 5672.319321681424, 4381.737095774264, 5025.170846810235, 5247.58019358458, 5325.627206095536, 5492.505674489315], "value": 4075.692493177495, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [724.786188074518, 756.1040167401629, 769.1961899814695, 771.8494893495767, 837.430547227885, 14256.857431425682, 14381.1371317721, 14772.261386930651, 14897.255137243148, 14938.925305373485, 13506.932465337684, 13734.313284335793, 13836.169829810218, 13886.0, 13922.234277114754, 877.3770622857762, 890.7060669978924, 893.0845882969975, 893.7988952485704, 896.135229503529, 859.5487369131193, 886.7517095213351, 889.4618755652834, 894.6242578117174, 901.6753968571309, 905.41148253635, 924.0482303227652, 934.2339281788936, 941.8869735631733, 944.6788092048702, 899.8785164002874, 908.881845360099, 932.2029664636706, 933.3466573398648, 938.1181689212119, 668.381746884135, 669.2638098092109, 680.9455243580502, 698.5703792167831, 698.8916717908733, 14795.926020369829, 14872.925635371863, 14915.627109322246, 14934.47729329479, 15147.0, 13891.0, 14049.719005619932, 14176.078554893898, 14225.359858806378, 14307.713845723027, 11798.292102473793, 11873.821892671616, 11878.227915185571, 11923.94038029813, 12105.576304829377, 3622.5290712207507, 3731.70146388291, 3753.774773513598, 3784.6215378462207, 3837.1174629835014, 100876.48685269727, 101264.49367753188, 101697.0, 105288.0, 106289.0, 10321.174306055498, 10536.473176341153, 11059.336439813636, 11075.72310692223, 11109.666709998713, 21998.89000554987, 22003.0, 22015.779842201373, 22034.228801992173, 22063.448413789785, 6939.791806245821, 7296.671649775667, 7400.259974002557, 7583.886241706268, 7645.541267523967, 4211.347241177622, 4259.787010649455, 4262.318029115327, 4278.4010238566625, 4383.539728328544, 3435.089701229176, 3506.333796578642, 3577.2308953574584, 3586.264815712773, 3602.3875941090078, 585.9941400586025, 668.5921587831419, 673.2224280955504, 680.3944489404336, 685.6366125953252, 618.83600845775, 632.3549979021465, 639.5139693832627, 661.3023260460164, 679.6601699150425, 9465.90534094664, 9677.70966870995, 9741.561629726679, 9958.253131015057, 9964.701058968243, 603.5111559636708, 625.487100577522, 634.7810005548079, 657.7434800427837, 666.2904007232282, 4749.95250047502, 4779.450363208206, 4912.45962944083, 5183.3261675981885, 5209.765560549784, 91192.08807911968, 92221.53889230645, 92576.53711731399, 96521.0, 98284.52573211408, 85865.57067214623, 86690.0, 87830.0, 88545.22909541902, 88600.0, 69622.0, 70522.64738676377, 73597.6320118396, 73787.63106184435, 74526.0, 10360.326578772356, 10436.0, 10646.89353106459, 10740.785184296426, 10946.616868409568, 46773.29840052334, 46980.0, 48771.5122848767, 50097.99216062633, 50240.49759502358, 47787.40750719685, 65622.34377656163, 65743.6712816452, 65911.67044164748, 66297.33702662912, 12922.353882305659, 12941.352932353533, 13019.414126364147, 13411.396487158296, 13614.591562253148, 14423.42306307736, 14664.560063198121, 14733.336999835248, 14762.335694893542, 15110.244487775348, 13303.068785184943, 13538.120022198525, 13636.0, 13658.453661853415, 14109.29453527299, 12308.199967002116, 12618.684532886751, 13517.86482135166, 14228.573142805319, 14320.14079155254, 13199.63918527632, 13309.267990260629, 13489.325533723471, 13524.855710284162, 13960.930195348958, 10912.454377281263, 11098.667039988813, 11332.6033588824, 11414.315141091562, 11731.765364692828, 9852.655157069468, 10599.417032063002, 10621.628243011457, 10627.787444251226, 10650.414227217267], "value": 7465.512088754724, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1470.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 114.0, 114.0, 114.0, 113.0, 114.0, 112.0, 115.0, 114.0, 115.0], "value": 114.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 108.0, 110.0, 148.0, 111.0, 111.0, 110.0, 110.0, 138.0, 110.0], "value": 120.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 185.0, 185.0, 185.0, 184.0, 185.0, 186.0, 186.0, 181.0, 213.0], "value": 187.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 59.0, 89.0, 59.0, 58.0, 59.0, 58.0, 77.0, 58.0], "value": 65.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 71.0, 87.0, 70.0, 68.0, 70.0, 85.0, 67.0, 69.0, 82.0], "value": 76.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 89.0, 85.0, 88.0, 84.0, 88.0, 86.0, 88.0, 85.0], "value": 86.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 96.0, 94.0, 95.0, 93.0, 94.0, 95.0, 95.0, 93.0], "value": 94.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 88.0, 88.0, 99.0, 135.0, 132.0, 84.0, 85.0, 88.0, 84.0], "value": 96.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 59.0, 58.0, 57.0, 58.0, 59.0, 59.0, 58.0, 60.0, 58.0], "value": 58.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 69.0, 79.0, 75.0, 74.0, 73.0, 73.0, 70.0, 71.0, 72.0], "value": 72.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 95.0, 99.0, 87.0, 90.0, 92.0, 87.0, 85.0, 89.0], "value": 90.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 152.0, 153.0, 134.0, 139.0, 139.0, 138.0, 185.0, 217.0, 196.0], "value": 160.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 170.0, 174.0, 178.0, 171.0, 172.0, 172.0, 175.0, 179.0, 230.0], "value": 179.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 70.0, 66.0, 68.0, 68.0, 67.0, 67.0, 66.0, 68.0, 66.0], "value": 67.2, "unit": "score"}]}]} 04:46:36 INFO - Return code: 0 04:46:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:46:37 ERROR - # TBPL SUCCESS # 04:46:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:46:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:46:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:46:37 INFO - 2017-02-05 04:46:37.033 screenresolution[3133:103642] starting screenresolution argv=screenresolution get 04:46:37 INFO - 2017-02-05 04:46:37.036 screenresolution[3133:103642] Display 0: 1600x1200x32@60 04:46:37 INFO - 2017-02-05 04:46:37.042 screenresolution[3134:103645] starting screenresolution argv=screenresolution list 04:46:37 INFO - Available Modes on Display 0 04:46:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:46:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:46:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:46:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:46:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:46:37 INFO - Intel Iris: 04:46:37 INFO - Chipset Model: Intel Iris 04:46:37 INFO - Type: GPU 04:46:37 INFO - Bus: Built-In 04:46:37 INFO - VRAM (Dynamic, Max): 1536 MB 04:46:37 INFO - Vendor: Intel (0x8086) 04:46:37 INFO - Device ID: 0x0a2e 04:46:37 INFO - Revision ID: 0x0009 04:46:37 INFO - Displays: 04:46:37 INFO - Display: 04:46:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:46:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:46:37 INFO - Display Serial Number: 1600x1200 60 04:46:37 INFO - Main Display: Yes 04:46:37 INFO - Mirror: Off 04:46:37 INFO - Online: Yes 04:46:37 INFO - Rotation: Supported 04:46:37 INFO - Return code: 0 04:46:37 INFO - Running post-action listener: _resource_record_post_action 04:46:37 INFO - [mozharness: 2017-02-05 12:46:37.278985Z] Finished run-tests step (success) 04:46:37 INFO - Running post-run listener: _resource_record_post_run 04:46:37 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 1183744; Write bytes: 557952512; Read time: 3; Write time: 1673 04:46:37 INFO - TinderboxPrint: CPU usage
53.7% 04:46:37 INFO - TinderboxPrint: I/O read bytes / time
1,183,744 / 3 04:46:37 INFO - TinderboxPrint: I/O write bytes / time
557,952,512 / 1,673 04:46:37 INFO - TinderboxPrint: CPU idle
2,226.6 (46.3%) 04:46:37 INFO - TinderboxPrint: CPU system
963.0 (20.0%) 04:46:37 INFO - TinderboxPrint: CPU user
1,620.2 (33.7%) 04:46:37 INFO - TinderboxPrint: Swap in / out
252,387,328 / 0 04:46:37 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 245953024; Write bytes: 242362368; Read time: 17891; Write time: 505 04:46:37 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 1183744; Write bytes: 300180992; Read time: 3; Write time: 1126 04:46:37 INFO - Running post-run listener: _upload_blobber_files 04:46:37 INFO - Blob upload gear active. 04:46:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:46:37 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 04:46:37 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:46:37 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:46:38 INFO - (blobuploader) - INFO - Open directory for files ... 04:46:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:46:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:46:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:46:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:46:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:46:38 INFO - (blobuploader) - INFO - Done attempting. 04:46:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:46:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:46:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:46:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:46:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:46:39 INFO - (blobuploader) - INFO - Done attempting. 04:46:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:46:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:46:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:46:41 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:46:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:46:41 INFO - (blobuploader) - INFO - Done attempting. 04:46:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:46:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:46:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:46:43 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:46:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:46:43 INFO - (blobuploader) - INFO - Done attempting. 04:46:43 INFO - (blobuploader) - INFO - Iteration through files over. 04:46:43 INFO - Return code: 0 04:46:43 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:46:43 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:46:43 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/89a4e7c8dcf47dcba241d080984dbe2a82b82e00c31fcdb59ba523fc21bc34d6f8565728c9640542e073ae9adddc7719a73767f54a21fdbaa7d32c656ac9416e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cc4857fd36dd65e82ff3fdcc89242f4d91d4ccc7d00da1d625d85f0be0b7bc87c25d96a6ada7c960e5f44cf505eac33946f47feb5a5d79facf102cec09defbc8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1f1034299463a519b3f3a872f152445050c5f38edb8be1cbfb1733f719ecd6db15c5daa0dee35156034a99d767648727fd4e2f4bdbf726934085e5523fd536d5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/73c011745f9eb8a0d01eb9a076d117e4beca5951edaeb768a7497bbe2f142f222f8766b28b33068f35e7010617358549d9ac168a2dea7730cba516c1efbc443b"} 04:46:43 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:46:43 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:46:43 INFO - Contents: 04:46:43 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/89a4e7c8dcf47dcba241d080984dbe2a82b82e00c31fcdb59ba523fc21bc34d6f8565728c9640542e073ae9adddc7719a73767f54a21fdbaa7d32c656ac9416e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cc4857fd36dd65e82ff3fdcc89242f4d91d4ccc7d00da1d625d85f0be0b7bc87c25d96a6ada7c960e5f44cf505eac33946f47feb5a5d79facf102cec09defbc8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1f1034299463a519b3f3a872f152445050c5f38edb8be1cbfb1733f719ecd6db15c5daa0dee35156034a99d767648727fd4e2f4bdbf726934085e5523fd536d5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/73c011745f9eb8a0d01eb9a076d117e4beca5951edaeb768a7497bbe2f142f222f8766b28b33068f35e7010617358549d9ac168a2dea7730cba516c1efbc443b"} 04:46:43 INFO - Running post-run listener: copy_logs_to_upload_dir 04:46:43 INFO - Copying logs to upload dir... 04:46:43 INFO - mkdir: /builds/slave/test/build/upload/logs 04:46:43 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1263.666715 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2017-02-05 04:46:43.511475) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-05 04:46:43.515059) ========= 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.HGgOEBT2bf/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.CgNH6AW2qT/Listeners TMPDIR=/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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/Firefox/sha512/89a4e7c8dcf47dcba241d080984dbe2a82b82e00c31fcdb59ba523fc21bc34d6f8565728c9640542e073ae9adddc7719a73767f54a21fdbaa7d32c656ac9416e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cc4857fd36dd65e82ff3fdcc89242f4d91d4ccc7d00da1d625d85f0be0b7bc87c25d96a6ada7c960e5f44cf505eac33946f47feb5a5d79facf102cec09defbc8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1f1034299463a519b3f3a872f152445050c5f38edb8be1cbfb1733f719ecd6db15c5daa0dee35156034a99d767648727fd4e2f4bdbf726934085e5523fd536d5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/73c011745f9eb8a0d01eb9a076d117e4beca5951edaeb768a7497bbe2f142f222f8766b28b33068f35e7010617358549d9ac168a2dea7730cba516c1efbc443b"} build_url:https://queue.taskcluster.net/v1/task/I_C_Ga0fR3aUopphaa4L-A/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011376 build_url: 'https://queue.taskcluster.net/v1/task/I_C_Ga0fR3aUopphaa4L-A/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/89a4e7c8dcf47dcba241d080984dbe2a82b82e00c31fcdb59ba523fc21bc34d6f8565728c9640542e073ae9adddc7719a73767f54a21fdbaa7d32c656ac9416e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cc4857fd36dd65e82ff3fdcc89242f4d91d4ccc7d00da1d625d85f0be0b7bc87c25d96a6ada7c960e5f44cf505eac33946f47feb5a5d79facf102cec09defbc8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1f1034299463a519b3f3a872f152445050c5f38edb8be1cbfb1733f719ecd6db15c5daa0dee35156034a99d767648727fd4e2f4bdbf726934085e5523fd536d5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/73c011745f9eb8a0d01eb9a076d117e4beca5951edaeb768a7497bbe2f142f222f8766b28b33068f35e7010617358549d9ac168a2dea7730cba516c1efbc443b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-05 04:46:43.549741) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-05 04:46:43.550075) ========= 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.HGgOEBT2bf/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.CgNH6AW2qT/Listeners TMPDIR=/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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.005559 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-05 04:46:43.598734) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-05 04:46:43.599056) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-05 04:46:43.599402) ========= ========= Total master_lag: 0.18 =========