builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0076 starttime: 1486210957.31 results: success (0) buildid: 20170204030205 builduid: 919b4b863515410bb6db811abb7219b8 revision: 7aa5e444af0ff686714d6165bd0e7e6d1abd0970 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-04 04:22:37.312697) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-04 04:22:37.313115) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-04 04:22:37.313394) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-04 04:22:37.341577) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-04 04:22:37.341904) ========= 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.me28Byi9vq/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.iItRurIuej/Listeners TMPDIR=/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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-04 04:22: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% 20.6M=0.001s 2017-02-04 04:22:37 (20.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.505942 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-04 04:22:37.872034) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-04 04:22:37.872389) ========= 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.me28Byi9vq/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.iItRurIuej/Listeners TMPDIR=/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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.028903 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-04 04:22:37.924533) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-04 04:22:37.924919) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 7aa5e444af0ff686714d6165bd0e7e6d1abd0970 --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 7aa5e444af0ff686714d6165bd0e7e6d1abd0970 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.me28Byi9vq/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.iItRurIuej/Listeners TMPDIR=/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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-04 04:22:38,031 truncating revision to first 12 chars 2017-02-04 04:22:38,031 Setting DEBUG logging. 2017-02-04 04:22:38,031 attempt 1/10 2017-02-04 04:22:38,031 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/7aa5e444af0f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-04 04:22:39,157 unpacking tar archive at: mozilla-central-7aa5e444af0f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.398224 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-04 04:22:39.350223) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-04 04:22:39.350566) ========= script_repo_revision: 7aa5e444af0ff686714d6165bd0e7e6d1abd0970 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-04 04:22:39.351000) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-04 04:22:39.351340) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-04 04:22:39.371729) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2017-02-04 04:22:39.372031) ========= /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.me28Byi9vq/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.iItRurIuej/Listeners TMPDIR=/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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:22:39 INFO - MultiFileLogger online at 20170204 04:22:39 in /builds/slave/test 04:22:39 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:22:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:22:39 INFO - {'append_to_log': False, 04:22:39 INFO - 'base_work_dir': '/builds/slave/test', 04:22:39 INFO - 'blob_upload_branch': 'Firefox', 04:22:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:22:39 INFO - 'branch': 'Firefox', 04:22:39 INFO - 'buildbot_json_path': 'buildprops.json', 04:22:39 INFO - 'config_files': ('talos/mac_config.py',), 04:22:39 INFO - 'default_actions': ('clobber', 04:22:39 INFO - 'read-buildbot-config', 04:22:39 INFO - 'download-and-extract', 04:22:39 INFO - 'populate-webroot', 04:22:39 INFO - 'create-virtualenv', 04:22:39 INFO - 'install', 04:22:39 INFO - 'run-tests'), 04:22:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:22:39 INFO - 'download_minidump_stackwalk': True, 04:22:39 INFO - 'download_symbols': 'ondemand', 04:22:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:22:39 INFO - 'tooltool.py': '/tools/tooltool.py', 04:22:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:22:39 INFO - '/tools/misc-python/virtualenv.py')}, 04:22:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:22:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:22:39 INFO - 'installer_path': 'installer.exe', 04:22:39 INFO - 'log_level': 'info', 04:22:39 INFO - 'log_name': 'talos', 04:22:39 INFO - 'log_to_console': True, 04:22:39 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:22:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:22:39 INFO - 'opt_config_files': (), 04:22:39 INFO - 'pip_index': False, 04:22:39 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:22:39 INFO - 'cmd': ('bash', 04:22:39 INFO - '-c', 04:22:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:22:39 INFO - 'enabled': True, 04:22:39 INFO - 'halt_on_failure': False, 04:22:39 INFO - 'name': 'check_screen_resolution'},), 04:22:39 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:22:39 INFO - 'cmd': ('bash', 04:22:39 INFO - '-c', 04:22:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:22:39 INFO - 'enabled': True, 04:22:39 INFO - 'halt_on_failure': False, 04:22:39 INFO - 'name': 'check_screen_resolution'},), 04:22:39 INFO - 'run_cmd_checks_enabled': True, 04:22:39 INFO - 'sps_profile': False, 04:22:39 INFO - 'sps_profile_interval': 0, 04:22:39 INFO - 'suite': 'dromaeojs', 04:22:39 INFO - 'system_bits': '32', 04:22:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:22:39 INFO - 'title': 't-yosemite-r7-0076', 04:22:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:22:39 INFO - 'use_talos_json': True, 04:22:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:22:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:22:39 INFO - 'work_dir': 'build'} 04:22:39 INFO - [mozharness: 2017-02-04 12:22:39.593258Z] Running clobber step. 04:22:39 INFO - Running pre-action listener: _resource_record_pre_action 04:22:39 INFO - Running main action method: clobber 04:22:39 INFO - rmtree: /builds/slave/test/build 04:22:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:22:42 INFO - Running post-action listener: _resource_record_post_action 04:22:42 INFO - [mozharness: 2017-02-04 12:22:42.078154Z] Finished clobber step (success) 04:22:42 INFO - [mozharness: 2017-02-04 12:22:42.078320Z] Running read-buildbot-config step. 04:22:42 INFO - Running pre-action listener: _resource_record_pre_action 04:22:42 INFO - Running main action method: read_buildbot_config 04:22:42 INFO - Using buildbot properties: 04:22:42 INFO - { 04:22:42 INFO - "project": "", 04:22:42 INFO - "product": "firefox", 04:22:42 INFO - "script_repo_revision": "production", 04:22:42 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 04:22:42 INFO - "repository": "", 04:22:42 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 04:22:42 INFO - "buildid": "20170204030205", 04:22:42 INFO - "stage_platform": "macosx64", 04:22:42 INFO - "basedir": "/builds/slave/test", 04:22:42 INFO - "nightly_build": "True", 04:22:42 INFO - "buildnumber": 40, 04:22:42 INFO - "slavename": "t-yosemite-r7-0076", 04:22:42 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 04:22:42 INFO - "platform": "yosemite_r7", 04:22:42 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:22:42 INFO - "branch": "mozilla-central", 04:22:42 INFO - "repo_path": "mozilla-central", 04:22:42 INFO - "revision": "7aa5e444af0ff686714d6165bd0e7e6d1abd0970", 04:22:42 INFO - "pgo_build": "False", 04:22:42 INFO - "builduid": "919b4b863515410bb6db811abb7219b8", 04:22:42 INFO - "slavebuilddir": "test" 04:22:42 INFO - } 04:22:42 INFO - Found installer url https://queue.taskcluster.net/v1/task/NS6fQSoISISw3FvvWU7BUA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 04:22:42 INFO - Running post-action listener: _resource_record_post_action 04:22:42 INFO - [mozharness: 2017-02-04 12:22:42.081530Z] Finished read-buildbot-config step (success) 04:22:42 INFO - [mozharness: 2017-02-04 12:22:42.081666Z] Running download-and-extract step. 04:22:42 INFO - Running pre-action listener: _resource_record_pre_action 04:22:42 INFO - Running main action method: download_and_extract 04:22:42 INFO - mkdir: /builds/slave/test/build/tests 04:22:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:22:42 INFO - trying https://queue.taskcluster.net/v1/task/NS6fQSoISISw3FvvWU7BUA/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 04:22:42 INFO - Downloading https://queue.taskcluster.net/v1/task/NS6fQSoISISw3FvvWU7BUA/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:22:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NS6fQSoISISw3FvvWU7BUA/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:22:43 INFO - Downloaded 1199 bytes. 04:22:43 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 04:22:43 INFO - Using the following test package requirements: 04:22:43 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 04:22:43 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:22:43 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 04:22:43 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:22:43 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 04:22:43 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:22:43 INFO - u'jsshell-mac.zip'], 04:22:43 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:22:43 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 04:22:43 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 04:22:43 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:22:43 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 04:22:43 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:22:43 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 04:22:43 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:22:43 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 04:22:43 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:22:43 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 04:22:43 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:22:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NS6fQSoISISw3FvvWU7BUA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 04:22:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NS6fQSoISISw3FvvWU7BUA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:22:43 INFO - Fetch https://queue.taskcluster.net/v1/task/NS6fQSoISISw3FvvWU7BUA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 04:22:44 INFO - Expected file size: 34851118 04:22:44 INFO - Obtained file size: 34851118 04:22:51 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:22:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NS6fQSoISISw3FvvWU7BUA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 04:22:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NS6fQSoISISw3FvvWU7BUA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:22:51 INFO - Fetch https://queue.taskcluster.net/v1/task/NS6fQSoISISw3FvvWU7BUA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 04:22:52 INFO - Expected file size: 34851118 04:22:52 INFO - Obtained file size: 34851118 04:22:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NS6fQSoISISw3FvvWU7BUA/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 04:22:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NS6fQSoISISw3FvvWU7BUA/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:22:59 INFO - Fetch https://queue.taskcluster.net/v1/task/NS6fQSoISISw3FvvWU7BUA/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 04:23:00 INFO - Expected file size: 14403454 04:23:00 INFO - Obtained file size: 14403454 04:23:00 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:23:00 INFO - trying https://queue.taskcluster.net/v1/task/NS6fQSoISISw3FvvWU7BUA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 04:23:00 INFO - Downloading https://queue.taskcluster.net/v1/task/NS6fQSoISISw3FvvWU7BUA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 04:23:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NS6fQSoISISw3FvvWU7BUA/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:23:02 INFO - Downloaded 70365052 bytes. 04:23:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NS6fQSoISISw3FvvWU7BUA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 04:23:02 INFO - mkdir: /builds/slave/test/properties 04:23:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:23:02 INFO - Writing to file /builds/slave/test/properties/build_url 04:23:02 INFO - Contents: 04:23:02 INFO - build_url:https://queue.taskcluster.net/v1/task/NS6fQSoISISw3FvvWU7BUA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 04:23:03 INFO - Running post-action listener: _resource_record_post_action 04:23:03 INFO - Running post-action listener: set_extra_try_arguments 04:23:03 INFO - [mozharness: 2017-02-04 12:23:03.547080Z] Finished download-and-extract step (success) 04:23:03 INFO - [mozharness: 2017-02-04 12:23:03.547206Z] Running populate-webroot step. 04:23:03 INFO - Running pre-action listener: _resource_record_pre_action 04:23:03 INFO - Running main action method: populate_webroot 04:23:03 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:23:03 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:23:03 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:23:03 INFO - '--tppagecycles', 04:23:03 INFO - '7'], 04:23:03 INFO - 'tests': ['tsvgm']}}, 04:23:03 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:23:03 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:23:03 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:23:03 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:23:03 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:23:03 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:23:03 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:23:03 INFO - 'talos_options': ['--disable-e10s'], 04:23:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:23:03 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:23:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:23:03 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:23:03 INFO - 'talos_options': ['--disable-e10s'], 04:23:03 INFO - 'tests': ['damp', 'tps']}, 04:23:03 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:23:03 INFO - 'tests': ['damp', 'tps']}, 04:23:03 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:23:03 INFO - 'tests': ['dromaeo_dom']}, 04:23:03 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:23:03 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:23:03 INFO - 'tests': ['basic_compositor_video']}, 04:23:03 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:23:03 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:23:03 INFO - 'tests': ['a11yr', 04:23:03 INFO - 'ts_paint', 04:23:03 INFO - 'tpaint', 04:23:03 INFO - 'sessionrestore', 04:23:03 INFO - 'sessionrestore_no_auto_restore', 04:23:03 INFO - 'tabpaint']}, 04:23:03 INFO - 'other-e10s': {'tests': ['a11yr', 04:23:03 INFO - 'ts_paint', 04:23:03 INFO - 'tpaint', 04:23:03 INFO - 'sessionrestore', 04:23:03 INFO - 'sessionrestore_no_auto_restore', 04:23:03 INFO - 'tabpaint']}, 04:23:03 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:23:03 INFO - 'ts_paint', 04:23:03 INFO - 'tpaint', 04:23:03 INFO - 'sessionrestore', 04:23:03 INFO - 'sessionrestore_no_auto_restore', 04:23:03 INFO - 'tabpaint']}, 04:23:03 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:23:03 INFO - 'ts_paint', 04:23:03 INFO - 'tpaint', 04:23:03 INFO - 'sessionrestore', 04:23:03 INFO - 'sessionrestore_no_auto_restore', 04:23:03 INFO - 'tabpaint']}, 04:23:03 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:23:03 INFO - 'tests': ['a11yr', 04:23:03 INFO - 'ts_paint', 04:23:03 INFO - 'tpaint', 04:23:03 INFO - 'sessionrestore', 04:23:03 INFO - 'sessionrestore_no_auto_restore', 04:23:03 INFO - 'tabpaint']}, 04:23:03 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:23:03 INFO - 'tests': ['a11yr', 04:23:03 INFO - 'ts_paint', 04:23:03 INFO - 'tpaint', 04:23:03 INFO - 'sessionrestore', 04:23:03 INFO - 'sessionrestore_no_auto_restore', 04:23:03 INFO - 'tabpaint']}, 04:23:03 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:23:03 INFO - 'tests': ['tsvgx', 04:23:03 INFO - 'tsvgr_opacity', 04:23:03 INFO - 'tart', 04:23:03 INFO - 'tscrollx', 04:23:03 INFO - 'cart', 04:23:03 INFO - 'tsvg_static']}, 04:23:03 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:23:03 INFO - 'tsvgr_opacity', 04:23:03 INFO - 'tart', 04:23:03 INFO - 'tscrollx', 04:23:03 INFO - 'cart', 04:23:03 INFO - 'tsvg_static']}, 04:23:03 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:23:03 INFO - 'talos_options': ['--disable-e10s'], 04:23:03 INFO - 'tests': ['tp5o']}, 04:23:03 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:23:03 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:23:03 INFO - 'talos_options': ['--disable-e10s', 04:23:03 INFO - '--xperf_path', 04:23:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:23:03 INFO - 'tests': ['tp5n']}, 04:23:03 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:23:03 INFO - 'talos_options': ['--xperf_path', 04:23:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:23:03 INFO - 'tests': ['tp5n']}}, 04:23:03 INFO - 'talos.zip': {'path': '', 04:23:03 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:23:03 INFO - Running post-action listener: _resource_record_post_action 04:23:03 INFO - [mozharness: 2017-02-04 12:23:03.566122Z] Finished populate-webroot step (success) 04:23:03 INFO - [mozharness: 2017-02-04 12:23:03.566207Z] Running create-virtualenv step. 04:23:03 INFO - Running pre-action listener: _resource_record_pre_action 04:23:03 INFO - Running main action method: create_virtualenv 04:23:03 INFO - Creating virtualenv /builds/slave/test/build/venv 04:23:03 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:23:03 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:23:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:23:03 INFO - Using real prefix '/tools/python27' 04:23:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:23:04 INFO - Installing distribute.............................................................................................................................................................................................done. 04:23:07 INFO - Installing pip.................done. 04:23:07 INFO - Return code: 0 04:23:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:23:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:23:07 INFO - Reading from file tmpfile_stdout 04:23:07 INFO - Output received: 04:23:07 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:23:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:23:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:23:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:23:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:23:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:23:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:07 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 0x10c290b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c293768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c5ad98980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c06ed30>, '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 0x10bf95db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c063be8>, '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.me28Byi9vq/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.iItRurIuej/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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:23:07 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:23:07 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:23:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.me28Byi9vq/Render', 04:23:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:23:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:23:07 INFO - 'HOME': '/Users/cltbld', 04:23:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:23:07 INFO - 'LOGNAME': 'cltbld', 04:23:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:23:07 INFO - 'MOZ_NO_REMOTE': '1', 04:23:07 INFO - 'NO_EM_RESTART': '1', 04:23:07 INFO - 'PAGER': '/bin/cat', 04:23:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:23:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:23:07 INFO - 'PWD': '/builds/slave/test', 04:23:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:23:07 INFO - 'SHELL': '/bin/bash', 04:23:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iItRurIuej/Listeners', 04:23:07 INFO - 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/', 04:23:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:23:07 INFO - 'USER': 'cltbld', 04:23:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:23:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:23:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:23:07 INFO - 'XPC_FLAGS': '0x0', 04:23:07 INFO - 'XPC_SERVICE_NAME': '0', 04:23:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:23:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:23:07 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:23:07 INFO - Cleaning up... 04:23:07 INFO - Return code: 0 04:23:07 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:23:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:23:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:23:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:23:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:23:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:07 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 0x10c290b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c293768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c5ad98980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c06ed30>, '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 0x10bf95db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c063be8>, '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.me28Byi9vq/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.iItRurIuej/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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:23:07 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:23:07 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:23:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.me28Byi9vq/Render', 04:23:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:23:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:23:07 INFO - 'HOME': '/Users/cltbld', 04:23:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:23:07 INFO - 'LOGNAME': 'cltbld', 04:23:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:23:07 INFO - 'MOZ_NO_REMOTE': '1', 04:23:07 INFO - 'NO_EM_RESTART': '1', 04:23:07 INFO - 'PAGER': '/bin/cat', 04:23:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:23:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:23:07 INFO - 'PWD': '/builds/slave/test', 04:23:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:23:07 INFO - 'SHELL': '/bin/bash', 04:23:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iItRurIuej/Listeners', 04:23:07 INFO - 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/', 04:23:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:23:07 INFO - 'USER': 'cltbld', 04:23:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:23:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:23:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:23:07 INFO - 'XPC_FLAGS': '0x0', 04:23:07 INFO - 'XPC_SERVICE_NAME': '0', 04:23:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:23:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:23:08 INFO - Downloading/unpacking psutil>=3.1.1 04:23:08 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:23:08 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:23:08 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:23:08 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:23:08 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:23:08 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:23:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:23:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:23:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:23:12 INFO - Installing collected packages: psutil 04:23:12 INFO - Running setup.py install for psutil 04:23:12 INFO - building 'psutil._psutil_osx' extension 04:23:12 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:23:12 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:23:13 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:23:13 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:23:13 INFO - building 'psutil._psutil_posix' extension 04:23:13 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:23:13 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:23:13 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:23:13 INFO - ^ 04:23:13 INFO - 1 warning generated. 04:23:13 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:23:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:23:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:23:13 INFO - Successfully installed psutil 04:23:13 INFO - Cleaning up... 04:23:13 INFO - Return code: 0 04:23:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:23:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:23:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:23:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:23:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:23:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:13 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 0x10c290b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c293768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c5ad98980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c06ed30>, '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 0x10bf95db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c063be8>, '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.me28Byi9vq/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.iItRurIuej/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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:23:13 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:23:13 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:23:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.me28Byi9vq/Render', 04:23:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:23:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:23:13 INFO - 'HOME': '/Users/cltbld', 04:23:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:23:13 INFO - 'LOGNAME': 'cltbld', 04:23:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:23:13 INFO - 'MOZ_NO_REMOTE': '1', 04:23:13 INFO - 'NO_EM_RESTART': '1', 04:23:13 INFO - 'PAGER': '/bin/cat', 04:23:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:23:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:23:13 INFO - 'PWD': '/builds/slave/test', 04:23:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:23:13 INFO - 'SHELL': '/bin/bash', 04:23:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iItRurIuej/Listeners', 04:23:13 INFO - 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/', 04:23:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:23:13 INFO - 'USER': 'cltbld', 04:23:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:23:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:23:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:23:13 INFO - 'XPC_FLAGS': '0x0', 04:23:13 INFO - 'XPC_SERVICE_NAME': '0', 04:23:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:23:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:23:13 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:23:13 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:23:13 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:23:13 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:23:13 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:23:13 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:23:13 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:23:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:23:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:23:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:23:16 INFO - Installing collected packages: mozsystemmonitor 04:23:16 INFO - Running setup.py install for mozsystemmonitor 04:23:16 INFO - Successfully installed mozsystemmonitor 04:23:16 INFO - Cleaning up... 04:23:16 INFO - Return code: 0 04:23:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:23:16 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:23:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:23:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:16 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:23:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:23:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:16 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 0x10c290b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c293768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c5ad98980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c06ed30>, '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 0x10bf95db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c063be8>, '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.me28Byi9vq/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.iItRurIuej/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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:23:16 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:23:16 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:23:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.me28Byi9vq/Render', 04:23:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:23:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:23:16 INFO - 'HOME': '/Users/cltbld', 04:23:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:23:16 INFO - 'LOGNAME': 'cltbld', 04:23:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:23:16 INFO - 'MOZ_NO_REMOTE': '1', 04:23:16 INFO - 'NO_EM_RESTART': '1', 04:23:16 INFO - 'PAGER': '/bin/cat', 04:23:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:23:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:23:16 INFO - 'PWD': '/builds/slave/test', 04:23:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:23:16 INFO - 'SHELL': '/bin/bash', 04:23:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iItRurIuej/Listeners', 04:23:16 INFO - 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/', 04:23:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:23:16 INFO - 'USER': 'cltbld', 04:23:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:23:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:23:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:23:16 INFO - 'XPC_FLAGS': '0x0', 04:23:16 INFO - 'XPC_SERVICE_NAME': '0', 04:23:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:23:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:23:16 INFO - Downloading/unpacking jsonschema==2.5.1 04:23:16 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:23:16 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:23:16 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:23:16 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:23:16 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:23:16 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:23:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:23:19 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:23:19 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:23:19 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:23:19 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:23:19 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:23:19 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:23:19 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:23:20 INFO - Downloading functools32-3.2.3-2.tar.gz 04:23:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:23:20 INFO - warning: no files found matching '*.txt' 04:23:20 INFO - no previously-included directories found matching 'build' 04:23:20 INFO - no previously-included directories found matching 'dist' 04:23:20 INFO - no previously-included directories found matching '.git*' 04:23:20 INFO - Installing collected packages: jsonschema, functools32 04:23:20 INFO - Running setup.py install for functools32 04:23:20 INFO - warning: no files found matching '*.txt' 04:23:20 INFO - no previously-included directories found matching 'build' 04:23:20 INFO - no previously-included directories found matching 'dist' 04:23:20 INFO - no previously-included directories found matching '.git*' 04:23:20 INFO - Successfully installed jsonschema functools32 04:23:20 INFO - Cleaning up... 04:23:20 INFO - Return code: 0 04:23:20 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:23:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:23:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:23:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:23:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:23:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c290b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c293768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c5ad98980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c06ed30>, '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 0x10bf95db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c063be8>, '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.me28Byi9vq/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.iItRurIuej/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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:23:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:23:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 04:23:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.me28Byi9vq/Render', 04:23:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:23:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:23:20 INFO - 'HOME': '/Users/cltbld', 04:23:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:23:20 INFO - 'LOGNAME': 'cltbld', 04:23:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:23:20 INFO - 'MOZ_NO_REMOTE': '1', 04:23:20 INFO - 'NO_EM_RESTART': '1', 04:23:20 INFO - 'PAGER': '/bin/cat', 04:23:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:23:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:23:20 INFO - 'PWD': '/builds/slave/test', 04:23:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:23:20 INFO - 'SHELL': '/bin/bash', 04:23:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iItRurIuej/Listeners', 04:23:20 INFO - 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/', 04:23:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:23:20 INFO - 'USER': 'cltbld', 04:23:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:23:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:23:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:23:20 INFO - 'XPC_FLAGS': '0x0', 04:23:20 INFO - 'XPC_SERVICE_NAME': '0', 04:23:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:23:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:23:20 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:23:20 INFO - Cleaning up... 04:23:20 INFO - Return code: 0 04:23:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:23:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:23:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:23:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:23:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:23:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c290b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c293768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c5ad98980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c06ed30>, '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 0x10bf95db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c063be8>, '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.me28Byi9vq/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.iItRurIuej/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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:23:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:23:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:23:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.me28Byi9vq/Render', 04:23:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:23:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:23:20 INFO - 'HOME': '/Users/cltbld', 04:23:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:23:20 INFO - 'LOGNAME': 'cltbld', 04:23:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:23:20 INFO - 'MOZ_NO_REMOTE': '1', 04:23:20 INFO - 'NO_EM_RESTART': '1', 04:23:20 INFO - 'PAGER': '/bin/cat', 04:23:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:23:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:23:20 INFO - 'PWD': '/builds/slave/test', 04:23:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:23:20 INFO - 'SHELL': '/bin/bash', 04:23:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iItRurIuej/Listeners', 04:23:20 INFO - 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/', 04:23:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:23:20 INFO - 'USER': 'cltbld', 04:23:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:23:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:23:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:23:20 INFO - 'XPC_FLAGS': '0x0', 04:23:20 INFO - 'XPC_SERVICE_NAME': '0', 04:23:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:23:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:23:20 INFO - Downloading/unpacking blobuploader==1.2.4 04:23:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:23:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:23:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:23 INFO - Downloading blobuploader-1.2.4.tar.gz 04:23:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:23:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:23:23 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:23:23 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:23:23 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:23:23 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:23:23 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:23:23 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:23:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:23:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:23:24 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:23:24 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:23:24 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:23:24 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:23:24 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:23:24 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:23:24 INFO - Downloading docopt-0.6.1.tar.gz 04:23:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:23:24 INFO - Installing collected packages: blobuploader, requests, docopt 04:23:24 INFO - Running setup.py install for blobuploader 04:23:25 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:23:25 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:23:25 INFO - Running setup.py install for requests 04:23:25 INFO - Running setup.py install for docopt 04:23:25 INFO - Successfully installed blobuploader requests docopt 04:23:25 INFO - Cleaning up... 04:23:25 INFO - Return code: 0 04:23:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:23:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:23:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:23:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:23:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:23:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:25 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 0x10c290b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c293768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c5ad98980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c06ed30>, '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 0x10bf95db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c063be8>, '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.me28Byi9vq/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.iItRurIuej/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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:23:25 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:23:25 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:23:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.me28Byi9vq/Render', 04:23:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:23:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:23:25 INFO - 'HOME': '/Users/cltbld', 04:23:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:23:25 INFO - 'LOGNAME': 'cltbld', 04:23:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:23:25 INFO - 'MOZ_NO_REMOTE': '1', 04:23:25 INFO - 'NO_EM_RESTART': '1', 04:23:25 INFO - 'PAGER': '/bin/cat', 04:23:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:23:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:23:25 INFO - 'PWD': '/builds/slave/test', 04:23:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:23:25 INFO - 'SHELL': '/bin/bash', 04:23:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iItRurIuej/Listeners', 04:23:25 INFO - 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/', 04:23:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:23:25 INFO - 'USER': 'cltbld', 04:23:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:23:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:23:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:23:25 INFO - 'XPC_FLAGS': '0x0', 04:23:25 INFO - 'XPC_SERVICE_NAME': '0', 04:23:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:23:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:23:26 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-UD_hP7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:23:26 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-YlQRpX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:23:26 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-vAyb7V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:23:26 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-PE8UJI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:23:26 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-jp39uA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:23:26 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-H9Nw2T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:23:26 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-w4qgI_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:23:26 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-1guRxo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:23:26 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-YUPeWu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:23:27 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-OrTPOf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:23:27 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-FSmp7Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:23:27 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-mgrPzc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:23:27 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-8Y3Lnj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:23:27 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-9YUJSY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:23:27 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-l6T8R1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:23:27 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-1OdNF1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:23:27 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-m7dMIC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:23:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:23:27 INFO - Running setup.py install for manifestparser 04:23:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:23:28 INFO - Running setup.py install for mozcrash 04:23:28 INFO - Running setup.py install for mozdebug 04:23:28 INFO - Running setup.py install for mozdevice 04:23:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:23:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:23:28 INFO - Running setup.py install for mozfile 04:23:28 INFO - Running setup.py install for mozhttpd 04:23:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:23:28 INFO - Running setup.py install for mozinfo 04:23:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:23:28 INFO - Running setup.py install for mozInstall 04:23:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:23:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:23:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:23:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:23:29 INFO - Running setup.py install for mozleak 04:23:29 INFO - Running setup.py install for mozlog 04:23:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:23:29 INFO - Running setup.py install for moznetwork 04:23:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:23:29 INFO - Running setup.py install for mozprocess 04:23:29 INFO - Running setup.py install for mozprofile 04:23:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:23:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:23:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:23:29 INFO - Running setup.py install for mozrunner 04:23:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:23:30 INFO - Running setup.py install for mozscreenshot 04:23:30 INFO - Running setup.py install for moztest 04:23:30 INFO - Running setup.py install for mozversion 04:23:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:23:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:23:30 INFO - Cleaning up... 04:23:30 INFO - Return code: 0 04:23:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:23:30 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:23:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:23:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:30 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:23:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:23:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:30 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 0x10c290b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c293768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c5ad98980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c06ed30>, '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 0x10bf95db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c063be8>, '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.me28Byi9vq/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.iItRurIuej/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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:23:30 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:23:30 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:23:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.me28Byi9vq/Render', 04:23:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:23:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:23:30 INFO - 'HOME': '/Users/cltbld', 04:23:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:23:30 INFO - 'LOGNAME': 'cltbld', 04:23:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:23:30 INFO - 'MOZ_NO_REMOTE': '1', 04:23:30 INFO - 'NO_EM_RESTART': '1', 04:23:30 INFO - 'PAGER': '/bin/cat', 04:23:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:23:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:23:30 INFO - 'PWD': '/builds/slave/test', 04:23:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:23:30 INFO - 'SHELL': '/bin/bash', 04:23:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iItRurIuej/Listeners', 04:23:30 INFO - 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/', 04:23:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:23:30 INFO - 'USER': 'cltbld', 04:23:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:23:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:23:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:23:30 INFO - 'XPC_FLAGS': '0x0', 04:23:30 INFO - 'XPC_SERVICE_NAME': '0', 04:23:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:23:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:23:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:23:30 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-zdrHEH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:23:30 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:23:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:23:30 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-3_gNU9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:23:30 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:23:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:23:30 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-cc3W6z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:23:31 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:23:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:23:31 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-cMFt5W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:23:31 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:23:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:23:31 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-7TRbOX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:23:31 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:23:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:23:31 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-_GDVOj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:23:31 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:23:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:23:31 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-aP4Jo6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:23:31 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:23:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:23:31 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-fohI7p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:23:31 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:23:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:23:31 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-onq7re-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:23:31 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:23:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:23:31 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-vk6FBk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:23:31 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:23:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:23:31 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-5pER3U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:23:31 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:23:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:23:31 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-sF3Okg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:23:32 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:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:23:32 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-yRnyGX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:23:32 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:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:23:32 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-oulJKK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:23:32 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:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:23:32 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-kys2oy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:23:32 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:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:23:32 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-TlXr8u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:23:32 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:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:23:32 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-O_fwQR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:23:32 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:23:32 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:23:32 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:23:32 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:23:32 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:23:32 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:23:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:23:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:23:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:23:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:35 INFO - Downloading blessings-1.6.tar.gz 04:23:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:23:35 INFO - Installing collected packages: blessings 04:23:35 INFO - Running setup.py install for blessings 04:23:35 INFO - Successfully installed blessings 04:23:35 INFO - Cleaning up... 04:23:35 INFO - Return code: 0 04:23:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:23:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:23:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:23:35 INFO - Reading from file tmpfile_stdout 04:23:35 INFO - Current package versions: 04:23:35 INFO - blessings == 1.6 04:23:35 INFO - blobuploader == 1.2.4 04:23:35 INFO - docopt == 0.6.1 04:23:35 INFO - functools32 == 3.2.3-2 04:23:35 INFO - jsonschema == 2.5.1 04:23:35 INFO - manifestparser == 1.1 04:23:35 INFO - mozInstall == 1.12 04:23:35 INFO - mozcrash == 1.0 04:23:35 INFO - mozdebug == 0.1 04:23:35 INFO - mozdevice == 0.49 04:23:35 INFO - mozfile == 1.2 04:23:35 INFO - mozhttpd == 0.7 04:23:35 INFO - mozinfo == 0.9 04:23:35 INFO - mozleak == 0.1 04:23:35 INFO - mozlog == 3.4 04:23:35 INFO - moznetwork == 0.27 04:23:35 INFO - mozprocess == 0.24 04:23:35 INFO - mozprofile == 0.28 04:23:35 INFO - mozrunner == 6.13 04:23:35 INFO - mozscreenshot == 0.1 04:23:35 INFO - mozsystemmonitor == 0.3 04:23:35 INFO - moztest == 0.8 04:23:35 INFO - mozversion == 1.4 04:23:35 INFO - psutil == 3.1.1 04:23:35 INFO - requests == 1.2.3 04:23:35 INFO - wsgiref == 0.1.2 04:23:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:23:35 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:23:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:23:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:35 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:23:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:23:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:35 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 0x10c290b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c293768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c5ad98980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c06ed30>, '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 0x10bf95db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c063be8>, '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.me28Byi9vq/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.iItRurIuej/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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:23:35 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:23:35 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:23:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.me28Byi9vq/Render', 04:23:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:23:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:23:35 INFO - 'HOME': '/Users/cltbld', 04:23:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:23:35 INFO - 'LOGNAME': 'cltbld', 04:23:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:23:35 INFO - 'MOZ_NO_REMOTE': '1', 04:23:35 INFO - 'NO_EM_RESTART': '1', 04:23:35 INFO - 'PAGER': '/bin/cat', 04:23:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:23:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:23:35 INFO - 'PWD': '/builds/slave/test', 04:23:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:23:35 INFO - 'SHELL': '/bin/bash', 04:23:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iItRurIuej/Listeners', 04:23:35 INFO - 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/', 04:23:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:23:35 INFO - 'USER': 'cltbld', 04:23:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:23:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:23:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:23:35 INFO - 'XPC_FLAGS': '0x0', 04:23:35 INFO - 'XPC_SERVICE_NAME': '0', 04:23:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:23:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:23:36 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:23:36 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:23:36 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:23:36 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:23:36 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:23:36 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:23:36 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:23:36 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:23:36 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:23:36 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:23:36 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:23:36 INFO - Cleaning up... 04:23:36 INFO - Return code: 0 04:23:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:23:36 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:23:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:23:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:36 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:23:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:23:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:36 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 0x10c290b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c293768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c5ad98980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c06ed30>, '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 0x10bf95db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c063be8>, '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.me28Byi9vq/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.iItRurIuej/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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:23:36 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:23:36 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:23:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.me28Byi9vq/Render', 04:23:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:23:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:23:36 INFO - 'HOME': '/Users/cltbld', 04:23:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:23:36 INFO - 'LOGNAME': 'cltbld', 04:23:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:23:36 INFO - 'MOZ_NO_REMOTE': '1', 04:23:36 INFO - 'NO_EM_RESTART': '1', 04:23:36 INFO - 'PAGER': '/bin/cat', 04:23:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:23:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:23:36 INFO - 'PWD': '/builds/slave/test', 04:23:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:23:36 INFO - 'SHELL': '/bin/bash', 04:23:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iItRurIuej/Listeners', 04:23:36 INFO - 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/', 04:23:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:23:36 INFO - 'USER': 'cltbld', 04:23:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:23:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:23:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:23:36 INFO - 'XPC_FLAGS': '0x0', 04:23:36 INFO - 'XPC_SERVICE_NAME': '0', 04:23:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:23:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:23:36 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:23:36 INFO - Cleaning up... 04:23:36 INFO - Return code: 0 04:23:36 INFO - Running post-action listener: _resource_record_post_action 04:23:36 INFO - Running post-action listener: _start_resource_monitoring 04:23:36 INFO - Starting resource monitoring. 04:23:36 INFO - [mozharness: 2017-02-04 12:23:36.453397Z] Finished create-virtualenv step (success) 04:23:36 INFO - [mozharness: 2017-02-04 12:23:36.454375Z] Running install step. 04:23:36 INFO - Running pre-action listener: _resource_record_pre_action 04:23:36 INFO - Running main action method: install 04:23:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:23:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:23:36 INFO - Reading from file tmpfile_stdout 04:23:36 INFO - Detecting whether we're running mozinstall >=1.0... 04:23:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:23:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:23:36 INFO - Reading from file tmpfile_stdout 04:23:36 INFO - Output received: 04:23:36 INFO - Usage: mozinstall [options] installer 04:23:36 INFO - Options: 04:23:36 INFO - -h, --help show this help message and exit 04:23:36 INFO - -d DEST, --destination=DEST 04:23:36 INFO - Directory to install application into. [default: 04:23:36 INFO - "/builds/slave/test"] 04:23:36 INFO - --app=APP Application being installed. [default: firefox] 04:23:36 INFO - mkdir: /builds/slave/test/build/application 04:23:36 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:23:36 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:23:59 INFO - Reading from file tmpfile_stdout 04:23:59 INFO - Output received: 04:23:59 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 04:23:59 INFO - Running post-action listener: _resource_record_post_action 04:23:59 INFO - [mozharness: 2017-02-04 12:23:59.872507Z] Finished install step (success) 04:23:59 INFO - [mozharness: 2017-02-04 12:23:59.872670Z] Running run-tests step. 04:23:59 INFO - Running pre-action listener: _resource_record_pre_action 04:23:59 INFO - Running main action method: run_tests 04:23:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:23:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:23:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:23:59 INFO - 2017-02-04 04:23:59.887 screenresolution[1622:7559] starting screenresolution argv=screenresolution get 04:23:59 INFO - 2017-02-04 04:23:59.889 screenresolution[1622:7559] Display 0: 1600x1200x32@60 04:23:59 INFO - 2017-02-04 04:23:59.895 screenresolution[1623:7561] starting screenresolution argv=screenresolution list 04:23:59 INFO - Available Modes on Display 0 04:23:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:23:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:23:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:23:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:24:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:24:00 INFO - Intel Iris: 04:24:00 INFO - Chipset Model: Intel Iris 04:24:00 INFO - Type: GPU 04:24:00 INFO - Bus: Built-In 04:24:00 INFO - VRAM (Dynamic, Max): 1536 MB 04:24:00 INFO - Vendor: Intel (0x8086) 04:24:00 INFO - Device ID: 0x0a2e 04:24:00 INFO - Revision ID: 0x0009 04:24:00 INFO - Displays: 04:24:00 INFO - Display: 04:24:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:24:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:24:00 INFO - Display Serial Number: 1600x1200 60 04:24:00 INFO - Main Display: Yes 04:24:00 INFO - Mirror: Off 04:24:00 INFO - Online: Yes 04:24:00 INFO - Rotation: Supported 04:24:00 INFO - Return code: 0 04:24:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:24:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:24:00 INFO - Python 2.7.3 04:24:00 INFO - Return code: 0 04:24:00 INFO - grabbing minidump binary from tooltool 04:24:00 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:24:00 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 0x10c06ed30>, '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 0x10bf95db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c063be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:24:00 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:24:00 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:24:00 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:24:00 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:24:00 INFO - Return code: 0 04:24:00 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:24:00 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:24:00 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:24:00 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:24:00 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:24:00 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/NS6fQSoISISw3FvvWU7BUA/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0076', '--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:24:00 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/NS6fQSoISISw3FvvWU7BUA/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0076 --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:24:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.me28Byi9vq/Render', 04:24:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:24:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:24:00 INFO - 'HOME': '/Users/cltbld', 04:24:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:24:00 INFO - 'LOGNAME': 'cltbld', 04:24:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:24:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:24:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:24:00 INFO - 'MOZ_NO_REMOTE': '1', 04:24:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:24:00 INFO - 'NO_EM_RESTART': '1', 04:24:00 INFO - 'PAGER': '/bin/cat', 04:24:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:24:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:24:00 INFO - 'PWD': '/builds/slave/test', 04:24:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:24:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:24:00 INFO - 'SHELL': '/bin/bash', 04:24:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iItRurIuej/Listeners', 04:24:00 INFO - 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/', 04:24:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:24:00 INFO - 'USER': 'cltbld', 04:24:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:24:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:24:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:24:00 INFO - 'XPC_FLAGS': '0x0', 04:24:00 INFO - 'XPC_SERVICE_NAME': '0', 04:24:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:24:00 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/NS6fQSoISISw3FvvWU7BUA/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0076', '--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:24:00 INFO - mozversion application_buildid: 20170204030205 04:24:00 INFO - mozversion application_changeset: 7aa5e444af0ff686714d6165bd0e7e6d1abd0970 04:24:00 INFO - mozversion application_display_name: Nightly 04:24:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:24:00 INFO - mozversion application_name: Firefox 04:24:00 INFO - mozversion application_remotingname: firefox 04:24:00 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:24:00 INFO - mozversion application_vendor: Mozilla 04:24:00 INFO - mozversion application_version: 54.0a1 04:24:00 INFO - mozversion platform_buildid: 20170204030205 04:24:00 INFO - mozversion platform_changeset: 7aa5e444af0ff686714d6165bd0e7e6d1abd0970 04:24:00 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:24:00 INFO - mozversion platform_version: 54.0a1 04:24:00 INFO - using testdate: 1486211040 04:24:00 INFO - actual date: 1486211040 04:24:00 INFO - starting webserver on 'localhost:49243' 04:24:00 INFO - SUITE-START | Running 2 tests 04:24:00 INFO - TEST-START | dromaeo_css 04:24:00 INFO - operating with platform_type : mac_ 04:24:00 INFO - Initialising browser for dromaeo_css test... 04:24:00 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/tmpAI1AlF/profile http://localhost:49243/getInfo.html) 04:24:02 INFO - PROCESS | 1633 | 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:24:02 INFO - PROCESS | 1633 | __metrics Screen width/height:1600/1200 04:24:02 INFO - PROCESS | 1633 | colorDepth:24 04:24:02 INFO - PROCESS | 1633 | Browser inner width/height: 1024/689 04:24:02 INFO - PROCESS | 1633 | __metrics 04:24:03 INFO - PROCESS | 1633 | 1486211043011 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:24:03 INFO - PROCESS | 1633 | 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:24:03 INFO - PROCESS | 1633 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:24:03 INFO - TEST-INFO | 1633: exit 0 04:24:03 INFO - Browser initialized. 04:24:03 INFO - Running cycle 1/1 for dromaeo_css test... 04:24:03 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/tmpAI1AlF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:24:15 INFO - PROCESS | 1636 | RSS: Main: 236773376 04:24:15 INFO - PROCESS | 1636 | 04:27:19 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49243/tests/dromaeo/cssquery-ext.html) 04:27:21 INFO - PROCESS | 1636 | RSS: Main: 386809856 04:27:21 INFO - PROCESS | 1636 | 04:29:05 INFO - PROCESS | 1636 | ************************* 04:29:05 INFO - PROCESS | 1636 | A coding exception was thrown and uncaught in a Task. 04:29:05 INFO - PROCESS | 1636 | 04:29:05 ERROR - PROCESS | 1636 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:29:05 INFO - PROCESS | 1636 | Full stack: 04:29:05 INFO - PROCESS | 1636 | ************************* 04:30:24 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-ext.html (next: http://localhost:49243/tests/dromaeo/cssquery-jquery.html) 04:30:25 INFO - PROCESS | 1636 | RSS: Main: 381816832 04:30:25 INFO - PROCESS | 1636 | 04:33:27 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49243/tests/dromaeo/cssquery-mootools.html) 04:33:28 INFO - PROCESS | 1636 | RSS: Main: 367943680 04:33:28 INFO - PROCESS | 1636 | 04:36:30 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49243/tests/dromaeo/cssquery-prototype.html) 04:36:30 INFO - PROCESS | 1636 | RSS: Main: 343498752 04:36:30 INFO - PROCESS | 1636 | 04:39:34 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49243/tests/dromaeo/cssquery-yui.html) 04:39:35 INFO - PROCESS | 1636 | RSS: Main: 332656640 04:39:35 INFO - PROCESS | 1636 | 04:42:38 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-yui.html (next: http://localhost:49243/tests/dromaeo/cssquery-dojo.html) 04:42:39 INFO - PROCESS | 1636 | RSS: Main: 460140544 04:42:39 INFO - PROCESS | 1636 | 04:42:39 INFO - PROCESS | 1636 | __start_tp_report 04:42:39 INFO - PROCESS | 1636 | _x_x_mozilla_page_load 04:42:39 INFO - PROCESS | 1636 | _x_x_mozilla_page_load_details 04:42:39 INFO - PROCESS | 1636 | |i|pagename|runs| 04:42:39 INFO - PROCESS | 1636 | |0;dojo.html;3252.4633435483147;3259.837008149592;3282.6224984126816;3293.752968527361;3337.566116404867;5314.468553144684;5386.272853164822;5462.562994960399;5512;5528.695921724304;4510.503844577094;5041.344625198719;5289.074411977907;5386.057439948013;5527.060399732045;4250.617444429993;4964.528369804862;4990.276409920578;5014.147594908856;5042.319286896277;4524.638028957692;4581.862544123682;5129;5157.5358217760395;5190.870228244286;4220;4901.872569309043;4998.550130488274;5080.923786143211;5098.796048158069;4971.552560269593;5010.599152067826;5053.418856831475;5128.692278463304;5135.460776618439;4980.302757613937;5021.648484606079;5033.320501732256;5038.84883453497;5080.644354895159;5498.5876059295715;5513.972430137824;5518.310211223597;5526.696031718254;5530.170474428827;5802.5938184327115;5856.502197313191;5856.619319744203;5944.940550594482;6008.429199226067;6111.205543279345;6138.51101655979;6142.201513803222;6152.538559608047;6162.506999440034;4760.381150450454;5645.1532270159805;5654.349749778787;5666.858328541737;5682.573806964494;6028.849278768066;6091.664958427329;6177.413145751147;6257.9687101564205;6271.122042913996;5798.9420105799245;5807.622504537191;5898.8230353089475;5914.290285165722;6062.242219722535;6170.629762214281;6170.81487555374;6177.073438984188;6180.443760061616;6183;6005.39946005396;6084.421979911901;6094.969525152346;6098.664573448415;6106.4809491192855;4894.143524883131;5849.181114643953;5866.501347385435;6111.174991376196;6115.755369785159;4799.808007679723;4836.516348365135;4846.975765121152;4849.636277279218;4904.8038078477175;4326.221280169537;4327.199468098349;4338.197433474817;4348.369486424451;4360.890977725582;5381.4349493303425;5534.308211473566;5561.63849349791;5569.415211402826;5608.242887210175;5903.3801450847905;5977.342492325841;6012.398760123952;6037.909431358533;6038.486728628028;5506.091494903376;5513.696746678891;5514.834554963358;5546.55627549795;5563.860903477445;5239;5259.0796610593;5265.973135238592;5271.077561426735;5311.575073994071;5962.373950735284;6095.329513753572;6191.824367953058;6255.593386429868;6261.843453913598;5050.267711181931;6109.009944784373;6109.053096770008;6113.628337950474;6204.937950620436;6076.118962750288;6095;6124.991279909952;6187.783427580013;6200.844978875564;5948.226730525004;6080.90878636812;6115.3578874217565;6118.694065296629;6127.387261273837;6135.079468778513;6167.383261673976;6167.599106058092;6169.475594574512;6175.567710260419;5565.860853478695;5890.263717035371;5936.198613187337;5938.703064846654;5962.612430192024;5130.102232109291;5701.40135285789;5793.304803423616;5795.565332599987;5799.681017543907;5691.8861622768145;5701.173329867229;5713.285839270091;5773.595848290579;5812.564057695774;5817.447342502455;5860.9450298946595;5877.265341832271;5886.293644762656;5891.646501209942;5551.361593416808;5733.541316694571;5733.71331433435;5738.454846789548;5746.597738158456 04:42:39 INFO - PROCESS | 1636 | |1;ext.html;464.3267262469419;488.0702262190526;493.79260710501586;518.1062666899599;524.8215606693724;29235.56146657802;38782.22435551287;40412.79793601031;40498.59501404985;40554.59445405545;40815.387769183486;41197.58802411975;41207.58792412075;41253.58746412543;41262.38106428406;7925.524468531878;7955.960220198891;9392;9398.906010939905;9412.811743765154;7981.441299109065;10983.725406864853;11241.60654377097;11253.66239012831;11378.658640240807;11132.332060076422;11191.496382662759;11274.32354058755;11278.15413843961;11304.547818087307;7947.483413578157;11203.04774094203;11259.54961801527;11280.492377842977;11288.209825312231;8101.837963240702;10955.287906286067;11159.497822597921;11203.495842687018;11372.658820235407;39446.40830387546;39810.40284395737;39871;39882.60117398818;39918.00204994869;4007.919841603181;4015.574471062789;4019.718619696622;4020.2160578687167;4157.688173386978;24995.25071239329;25950.74049259502;29494.26264343387;30330.69669303301;30477.69522304793;3042.34589563245;3215.983920080408;3222.822744749038;3260.3479304139028;3262.787918785295;449063;455463;456048;458367;467915;50477.24284135741;52217.95564088697;61106.694466527384;61422;61532.38467615356;54264.72867635637;54508.72745636326;54536;54546;61731;15305.923470382577;15802.60493487672;15803.841961580238;15807.209639517978;15820.05079695222;12223.755524889453;12339.197952133261;12366.44351004207;12377.319247441477;12394.504219831106;16738.916305418395;16804.32782688703;16972.575685607957;16988.235529401205;16989.915050424916;6780.525363224527;6920.550164239308;6935.133108361455;6983.406410455168;6996.580205187705;839.8824164616958;854.2525290370926;864.0927026622022;869.1742844297816;887.2458410351279;24867.378315542257;28263.858680706464;29053.692583833777;29209.561856572167;29899.103026909226;871.3987348729356;1045.414567842011;1061.5329255127874;1062.6280801719336;1085.2837127495816;14659.706805863823;15140.318685658951;15318.979067388405;15398.230088495531;15524.068555886683;522197;526831;593048;593840;594127;58008.709956450795;58048.70975645179;58094.4190558089;58211.12683309669;58273;1373.6085215713674;1423.4804296431935;1437;1440.9351579178751;1445.7542217822995;27861.58207626928;28534;28671.794260274863;29136.854315728287;29538;49413;49797.75101124616;50115.74479148374;50131;50143.498565013884;503710;505927;506756;507078;508632;21691.457713557287;22078;26028;26058.34854128662;26138.215853524423;41181.382279266436;41429.96425089397;42446.787766060974;44059.89850253769;46642.300365493866;31540.36919261556;34373.65626343704;34832.47751283682;35121.121971949884;36042;29463;33641.66358336385;34713.479297811056;35966.640333596326;36295.45556816597;34831.30337393187;35792.28415431729;35888.4616730744;35890;35997.5600975958;28759.712402875703;29384.26539336534;31157.8442107788;31489.685103148677;31577.21056973594;20178.394648160578;21268.40486963453;23124.844997768236;23916.64125038091;23945.640815387436 04:42:39 INFO - PROCESS | 1636 | |2;jquery.html;340.88409940620187;359.6978538028059;390.47676114007226;399.82407740594147;423.2064878353088;14470.348834302455;14878.628034299134;16642.75035874463;20352.98235088247;21034.368968930918;20907.268245611405;21287.68068478974;21320.893395533;21474;21637.91810409481;5820.679862607555;5899.085641725539;6001.939980600204;6164.784232551861;6179.289381721114;4042;5917.260342457193;6026.668533230671;6028.39716028398;6033.245844269466;4240.257954857903;6357.809265722036;6622.9006564901565;6663.133792606955;6724.831879203035;6379.71291291893;6591.505637077191;6639.2032956045105;6678.56589321695;6732.865342693119;5864.794732184396;5868.061110222344;5872.324682661506;5872.500837428824;5876.3242227144;23655.76344236553;24781.008759649594;25360;26125.608115878276;26380.340491487867;2566.396896729267;2612.542805009135;2619.1356852238905;2626.4090579619433;2630.1714959787605;10399.504119011459;10417.218708596885;10794.946025269903;12466.439010244561;16279.918600406922;1850.7686539182603;1873.100911562444;1874.7656542932134;1883.5667796406904;1885.179946723166;130655;133743.33128334297;135630;137036.31481842528;142606;11580.536778528765;11861.288322700666;12130;12233.571824986082;12266.877331226573;16247.187640617922;16250.51248462548;16261.59346016359;16276.593085172966;16295;8194.09864914859;8242.381821363422;8375.916240837514;8580.871286930576;8647.394682372174;6906.723731050702;8140.75577732669;8152.551609661407;8162.510249385056;8306.210909963544;4865.270209468608;4873.951260487421;4880.145974454456;4898.191798353232;4971.676841005323;1985.2456066694563;2046.8055534724178;2047.3141497598472;2048.6683655623965;2059.4954236212225;1006.6677996261217;1013.8175128476797;1024.2369434771142;1028.4600584692946;1062.16089289462;21083.262085826922;21621.35135945924;21626.567468650537;21653.350399487725;21679.458013549473;911.5077857956762;1002.2382988928467;1051.600391851107;1061.527620209015;1072.399456304472;9422.869255689362;10686.57253709843;11160;11266.887331126583;11312.434378281054;670350;685763;713676;736791;739956;135995.32002339925;142809;148170;155672;167715;1028.5680014393972;1056.265895202842;1071.8767341755643;1076.2842709598185;1076.5747529725716;19684.803151968295;19889.502762431053;20027.29904453337;20566.177352905717;20603.4849128773;59249;59908;60762.696186518784;61721.38278617156;61982.0702689469;75920.24079759352;90760.63859041987;92440.53779731058;93264.06735932553;96234.55648165144;8426.915730842613;8475.279601233964;8639.568021599021;8733.606987685696;8972.327075469497;10197.337173083726;13402.26287554194;13441.327933603476;13872.4451021958;13889.861101388857;11590.18868679218;11768.823467648162;11802.76394472123;11840;11921.403929803648;9124.635014599342;11012;11597;11706.475795518038;11906.10704197206;11526;11586.246893951866;11765.470488836396;11912.761744764883;11913.285202887855;8422.5788710563;8555.272801811916;8581.570921454027;8591.183837535424;8649.1350864913;8505.659773608986;10122.696319110439;11037.503312350804;11358.716032098933;11845.933865952105 04:42:39 INFO - PROCESS | 1636 | |3;mootools.html;1422.6870088580508;1445.241248344619;1455.7597996330603;1460.685952520208;1471.3231913319873;8257.669693212272;8678.566071696405;8684.044755076937;8695.826083478327;8700.43447175934;8088.1103078661445;8507.276881465068;8534.231919127278;8602.397832151752;8611.095834937336;2274.1471948019453;2310.699609050821;2315.3632750993443;2316.3861576682193;2317.2237300504353;2286.862788232703;2308.90764369425;2318.7565305642916;2321.5937210988095;2323.512062466884;2252.5157091225383;2265.8980345884393;2291.106468477287;2293.839431239814;2301.4936713922993;2238.563480121385;2261.7512073671883;2270.2962081754627;2282.5663124006387;2291.4271432141963;2024.9595008099902;2215.4129155773735;2300.7584203658544;2306.9423264418356;2315.513742114158;8512.489250644981;8568.957155214246;8570.78573035679;8574.356923230782;8589.398742087993;5013.323201367805;5193.922091168635;5197.324347834758;5199.14214154664;5202.401723801773;2693.3939863530745;2911.199420159452;3291.226561757985;3455.481677748333;3492.7205823534055;1174.794410978084;1185.199990006742;1204.59646018583;1223.406647775828;1251.3117785218094;10931.890681093246;11021;11124.555017799279;11132.554697812158;11134.665960021212;4188.225178341955;4405.823767049282;4484.663650226246;4498.302763071729;4508.797104130322;8838.116188381238;8843.955780221058;8844.955775221082;8853.203211710848;8863.955680221687;5107.693538387709;5115.155999260155;5118.641695081308;5120.539151476385;5132.897342053138;4575.611073058761;4631.722096674211;4724.787384567703;4732.982408782128;4786.329913812069;3587.1749497615174;3624.52881125452;3655.8903232903053;3672.5225720656144;3690.2619476104887;1050.3015494696094;1082.9729256768487;1104.729561004851;1121.7419993401563;1125.0268517732102;823.3454403749034;881.8809460722848;899.0919171636564;903.6837107012493;943.8112377524523;4791.47293797682;4831.806727730922;5090.287359769635;5119.48805119485;5550.611457197957;566.3543560341215;568.1052342560483;568.6673296121733;571.3857603076709;579.7593998490663;3615.3492371373404;3633.709303255786;3715.6841668458483;3716.9814150929074;3778;9757.707268781947;10101.090901818725;10282.537285822007;10290.536925838354;10309.793804124025;9364.484953327632;9801.264905132144;10232.795344093225;10257.563716155022;10257.58969641221;7547.16981132075;7752.612369381508;7768.184340644264;7783.9610801946765;7818.452708310478;5453.727313634223;5544.584156188222;5583.944160558505;5640.605157638925;5666.206731057573;8941.955290223768;9357.672481463389;9370;9454.480003599869;9496.14534691881;9259.175951334908;9461.148496635336;9518.571664274985;9569.521523923915;9667.70996870095;7250.637468126678;7274.345308922222;7292.489525733148;7301.744438944612;7389.593572353358;7976.8005799853145;8054.677812887418;8058.717944871902;8061.234182752629;8079.3536517079665;7135.215126336203;7252.453255867274;7317.780466585798;7428.294312040348;7428.925710742823;6972.477064220102;7092.645367731696;7112.857742845217;7157.141143062866;7290.781276561712;6328.778492752732;6455.935440645721;6461.903071453838;6465.870682586416;6470.611763294218;4530.977345113253;4815.3258543803195;4845.1520983828395;4974.253861920741;4988.376452943382;4955.479674634112;4959.60323174138;4984.675996060245;5028.572571331331;5069.569086627678 04:42:39 INFO - PROCESS | 1636 | |4;prototype.html;287.51123090745733;315.6322883840326;343.48305799771333;349.70275266023884;367.6837919389325;4467.642588592913;4822.479589899375;4973.2540118982215;5065.620078494118;5147.408048074472;4896;5013.6490445668815;5028.5474307312425;5067.84796456106;5070.315507406499;3768.7361884668085;3774.075351538877;3953.9209215815667;3988.880333589997;4008.4388185653884;3266.370571689789;3431.9485207721905;3536.699380552643;3792.563855156651;4005.619466150711;3298.4062868683627;3798.54417469904;3960.782156981365;3976.1848820991772;4015.678745700337;3950.839328537173;3966.484357033567;3980.925150209456;3985.5815139410524;3996.7002474814503;3481.0079127448557;3538;3574.3566158091535;3590.612242858496;3600.369935261345;4469.7541635210055;4698.154332220199;4923.852284431473;4977.714708292546;4994.225894986283;4086.1816491660925;4225.957740422587;4240.618344348993;4580.488438815183;4601.400208881971;4502.864914052584;4798.280257961299;5270.735023594348;5383.838484845461;5405.891882162335;3470.2712430389647;3732.962670373289;3748.887533374003;3768.5289338832645;3935.468711723909;5323.467653234684;5608.523275521545;5644.604877658566;5666.914996275059;5673.255651270142;3778.9244215115546;3798.5631652360335;3802.790846503469;3802.9429558556644;3823.866164684223;3552.703263308874;3768.397056471006;3857.1514266861245;3866.284737323604;3868.96131038686;3587;3648.872289469856;4172.958270417318;4314;4315.07225946423;5195.739493615275;5389.272448219519;5399.595030372738;5419.268398766116;5428.782848686008;1084.4523515624717;1230.852297724261;1238.68413554543;1244.4026867103842;1265.7405231927432;753.8944547763317;769.819092513259;795.3120550723582;868.2446271743623;872.6029656506402;3832.597577254404;4303.6975218258085;4651.279051746985;4666.4633567139535;4708.458527269339;4649.674522783373;4770.165221086296;5129.64092513528;5189.42916279209;5190;4238.630288920316;4471.530489298641;4666.626669866402;4685.765711714401;4692.835750748707;2304.331743794314;2475.393528585508;2719.5920611907977;2728.4406696627143;2755.5395640310667;4554.950272377443;4559.863204103882;4763.595094417014;5030.170021946338;5374.731263436813;4968.627352948543;5164.896702066013;5325.946740532545;5363.570914326844;5398.676079435247;5295.364556253197;5678.574106941914;5742.7702891883855;5780.670445797406;5799.681017544076;5099.69401835891;5574.693391863487;5619.8876022480135;5628.296462942132;5678.68767217807;4232.513260975027;4865.416150062015;5285;5458.6724796512335;5473.890522189614;5630.493255607015;5733.512651424676;5739.339975902824;5765.192872997867;5774.265102615815;3764.247150569952;3800.486934263839;3815.618438156162;3826.1773718650625;3828.8659896903473;5138.332016837937;5397.865053373697;5500.42245564226;5594.69229192382;5656.377798442086;4517.50307466185;5208.73956302191;5261.5264626183825;5262.0793410405695;5384.380796208329;5343.305370301836;5347.973260133675;5355.758990845499;5366.436524164907;5385.3268341457315;4261.659067274549;4678.976605116953;4968.453470118357;5256.921146182887;5282.4717528246865;5261.842144735664;5357.330333708286;5388.73056347189;5398.4871437213405;5445.319335083114;5254.528731955059;5258.474152584711;5272.943552014641;5281.471852814688;5305.734713264244 04:42:39 INFO - PROCESS | 1636 | |5;yui.html;680.6290571638459;706.836043695319;766.4634755671032;816.4325793573466;831.8086840026796;14741.705165896688;15467.845321546782;15503.379864805394;15550.766738498918;15550.84449155508;14069.71860562788;14209.573712788633;14211.289435528233;14216.218108004055;14254;843.6836186430089;849.7280870121548;858.6651206029653;863.3093525179862;867.4578388507183;870.6256309786789;872.6378552900538;875.2210532625936;875.4353442029887;875.9343049271298;924.2051835421565;924.3344791749963;924.5007695844236;924.8982611912684;925.1765928323764;921.4747593871496;923.2706162131908;924.2605915267826;925.0980294213156;926.2080920812675;638.7668500997149;651.993480065198;682.6962001909103;685.0751485494592;688.2532452289272;14853.554393368217;15322.387104515807;15344.693106137925;15497.147656878868;15765.763513547307;13873.983294672897;14094.124115868219;14143.292835358294;14262.28688565578;14272.072315299472;11746.412679365998;11751.353675547842;11809.881901180965;12188.51245950161;12326.32205228712;3648.3068217038676;3652.689521390685;3666.17511060012;3675.3568125578186;3790.8294126764363;51401;55158.62103447446;63105.628496289646;100942.49528752382;104896.47551762269;9558.856617150748;10819.459027048617;11058.889411105947;11066.889331106748;11503.137264705181;23361.41596460102;24018.15936442216;24079.398015049763;24284.75715242825;24309.149179778622;7762.417818663623;7809.258120478546;7820.804479887935;7883.921160788319;7897.104694873189;4127.855525056609;4142.026623743418;4166.187593419315;4204.957950420457;4284.464441944757;3609.6931760800626;3625.728070394731;3675.4854320395166;3698.0754811297174;3711.387621042552;668.4017804065431;672.1027428383065;681.1077488490093;681.8772620928181;688.3116883116883;655.885220086483;670.8457054877308;690.1614538335846;694.9965250173717;699.1505321034967;9175.724728258163;10024.398536087858;10265.846012309821;10297.433641149808;10394.01256880595;585.9179714839926;614.532954954238;630.760311081786;634.2072409488139;639.6993413095837;4846.442659094178;4942.629302802305;4958.454569997298;4960.553550180501;4962.776675049631;81667.5916620425;82294.24439211932;84728.30543389097;92239;103452.48273758583;72416;73161.56253436928;73593.2640673597;82779.58610206911;85055.574722126;63504.682476587324;72982.27017729754;74360.51278974499;74881.87677184846;76561;8873.778155546162;10446.320989135682;10793.946030270112;10926.344419334866;11122.721931951766;40772;45523;49073.75463122661;49191.01617967549;49817.25274120818;64065;64993.35006649873;65240;66028;67486.325136748;12631.494740210288;12899.93550032244;12926.482940682268;13297.534586289434;13499.39252733649;13505.527306544223;14529.564113076625;14541.272936352929;14606.488772892897;14644.853551464348;9542.379745316532;11797.997170240282;12171.756564868476;12246.632601021984;12279.631611051684;11557.364344961108;11891.762164756483;12032.27806331623;12316.630501084981;12435.564755233523;11545.595904143314;11818.940905295418;11878.227915185485;12194.51221951112;12229.266244025386;11049.558017679203;11275.801218762263;11408.828867566826;11486.19596628228;11502.13733969939;10168.542415591168;10555.683329500127;11072.501737421997;11300.9434952828;11518.942405288255 04:42:39 INFO - PROCESS | 1636 | __end_tp_report 04:42:39 INFO - PROCESS | 1636 | __start_cc_report 04:42:39 INFO - PROCESS | 1636 | _x_x_mozilla_cycle_collect,1712 04:42:39 INFO - PROCESS | 1636 | __end_cc_report 04:42:39 INFO - PROCESS | 1636 | __startTimestamp1486212158999__endTimestamp 04:42:39 INFO - PROCESS | 1636 | 04:42:39 INFO - PROCESS | 1636 | ------- Summary: start ------- 04:42:39 INFO - PROCESS | 1636 | Number of tests: 6 04:42:39 INFO - PROCESS | 1636 | 04:42:39 INFO - PROCESS | 1636 | [#0] dojo.html Cycles:165 Average:5529.81 Median:5701.40 stddev:652.16 (11.4%) stddev-sans-first:629.21 04:42:39 INFO - PROCESS | 1636 | Values: 3252.5 3259.8 3282.6 3293.8 3337.6 5314.5 5386.3 5462.6 5512.0 5528.7 4510.5 5041.3 5289.1 5386.1 5527.1 4250.6 4964.5 4990.3 5014.1 5042.3 4524.6 4581.9 5129.0 5157.5 5190.9 4220.0 4901.9 4998.6 5080.9 5098.8 4971.6 5010.6 5053.4 5128.7 5135.5 4980.3 5021.6 5033.3 5038.8 5080.6 5498.6 5514.0 5518.3 5526.7 5530.2 5802.6 5856.5 5856.6 5944.9 6008.4 6111.2 6138.5 6142.2 6152.5 6162.5 4760.4 5645.2 5654.3 5666.9 5682.6 6028.8 6091.7 6177.4 6258.0 6271.1 5798.9 5807.6 5898.8 5914.3 6062.2 6170.6 6170.8 6177.1 6180.4 6183.0 6005.4 6084.4 6095.0 6098.7 6106.5 4894.1 5849.2 5866.5 6111.2 6115.8 4799.8 4836.5 4847.0 4849.6 4904.8 4326.2 4327.2 4338.2 4348.4 4360.9 5381.4 5534.3 5561.6 5569.4 5608.2 5903.4 5977.3 6012.4 6037.9 6038.5 5506.1 5513.7 5514.8 5546.6 5563.9 5239.0 5259.1 5266.0 5271.1 5311.6 5962.4 6095.3 6191.8 6255.6 6261.8 5050.3 6109.0 6109.1 6113.6 6204.9 6076.1 6095.0 6125.0 6187.8 6200.8 5948.2 6080.9 6115.4 6118.7 6127.4 6135.1 6167.4 6167.6 6169.5 6175.6 5565.9 5890.3 5936.2 5938.7 5962.6 5130.1 5701.4 5793.3 5795.6 5799.7 5691.9 5701.2 5713.3 5773.6 5812.6 5817.4 5860.9 5877.3 5886.3 5891.6 5551.4 5733.5 5733.7 5738.5 5746.6 04:42:39 INFO - PROCESS | 1636 | 04:42:39 INFO - PROCESS | 1636 | [#1] ext.html Cycles:180 Average:64158.37 Median:35840.37 stddev:136554.40 (381.0%) stddev-sans-first:136853.73 04:42:39 INFO - PROCESS | 1636 | Values: 464.3 488.1 493.8 518.1 524.8 29235.6 38782.2 40412.8 40498.6 40554.6 40815.4 41197.6 41207.6 41253.6 41262.4 7925.5 7956.0 9392.0 9398.9 9412.8 7981.4 10983.7 11241.6 11253.7 11378.7 11132.3 11191.5 11274.3 11278.2 11304.5 7947.5 11203.0 11259.5 11280.5 11288.2 8101.8 10955.3 11159.5 11203.5 11372.7 39446.4 39810.4 39871.0 39882.6 39918.0 4007.9 4015.6 4019.7 4020.2 4157.7 24995.3 25950.7 29494.3 30330.7 30477.7 3042.3 3216.0 3222.8 3260.3 3262.8 449063.0 455463.0 456048.0 458367.0 467915.0 50477.2 52218.0 61106.7 61422.0 61532.4 54264.7 54508.7 54536.0 54546.0 61731.0 15305.9 15802.6 15803.8 15807.2 15820.1 12223.8 12339.2 12366.4 12377.3 12394.5 16738.9 16804.3 16972.6 16988.2 16989.9 6780.5 6920.6 6935.1 6983.4 6996.6 839.9 854.3 864.1 869.2 887.2 24867.4 28263.9 29053.7 29209.6 29899.1 871.4 1045.4 1061.5 1062.6 1085.3 14659.7 15140.3 15319.0 15398.2 15524.1 522197.0 526831.0 593048.0 593840.0 594127.0 58008.7 58048.7 58094.4 58211.1 58273.0 1373.6 1423.5 1437.0 1440.9 1445.8 27861.6 28534.0 28671.8 29136.9 29538.0 49413.0 49797.8 50115.7 50131.0 50143.5 503710.0 505927.0 506756.0 507078.0 508632.0 21691.5 22078.0 26028.0 26058.3 26138.2 41181.4 41430.0 42446.8 44059.9 46642.3 31540.4 34373.7 34832.5 35121.1 36042.0 29463.0 33641.7 34713.5 35966.6 36295.5 34831.3 35792.3 35888.5 35890.0 35997.6 28759.7 29384.3 31157.8 31489.7 31577.2 20178.4 21268.4 23124.8 23916.6 23945.6 04:42:39 INFO - PROCESS | 1636 | 04:42:39 INFO - PROCESS | 1636 | [#2] jquery.html Cycles:180 Average:39984.58 Median:21547.68 stddev:118579.59 (550.3%) stddev-sans-first:118874.87 04:42:39 INFO - PROCESS | 1636 | Values: 340.9 359.7 390.5 399.8 423.2 14470.3 14878.6 16642.8 20353.0 21034.4 20907.3 21287.7 21320.9 21474.0 21637.9 5820.7 5899.1 6001.9 6164.8 6179.3 4042.0 5917.3 6026.7 6028.4 6033.2 4240.3 6357.8 6622.9 6663.1 6724.8 6379.7 6591.5 6639.2 6678.6 6732.9 5864.8 5868.1 5872.3 5872.5 5876.3 23655.8 24781.0 25360.0 26125.6 26380.3 2566.4 2612.5 2619.1 2626.4 2630.2 10399.5 10417.2 10794.9 12466.4 16279.9 1850.8 1873.1 1874.8 1883.6 1885.2 130655.0 133743.3 135630.0 137036.3 142606.0 11580.5 11861.3 12130.0 12233.6 12266.9 16247.2 16250.5 16261.6 16276.6 16295.0 8194.1 8242.4 8375.9 8580.9 8647.4 6906.7 8140.8 8152.6 8162.5 8306.2 4865.3 4874.0 4880.1 4898.2 4971.7 1985.2 2046.8 2047.3 2048.7 2059.5 1006.7 1013.8 1024.2 1028.5 1062.2 21083.3 21621.4 21626.6 21653.4 21679.5 911.5 1002.2 1051.6 1061.5 1072.4 9422.9 10686.6 11160.0 11266.9 11312.4 670350.0 685763.0 713676.0 736791.0 739956.0 135995.3 142809.0 148170.0 155672.0 167715.0 1028.6 1056.3 1071.9 1076.3 1076.6 19684.8 19889.5 20027.3 20566.2 20603.5 59249.0 59908.0 60762.7 61721.4 61982.1 75920.2 90760.6 92440.5 93264.1 96234.6 8426.9 8475.3 8639.6 8733.6 8972.3 10197.3 13402.3 13441.3 13872.4 13889.9 11590.2 11768.8 11802.8 11840.0 11921.4 9124.6 11012.0 11597.0 11706.5 11906.1 11526.0 11586.2 11765.5 11912.8 11913.3 8422.6 8555.3 8581.6 8591.2 8649.1 8505.7 10122.7 11037.5 11358.7 11845.9 04:42:39 INFO - PROCESS | 1636 | 04:42:39 INFO - PROCESS | 1636 | [#3] mootools.html Cycles:180 Average:5429.37 Median:5098.99 stddev:3040.59 (59.6%) stddev-sans-first:3034.22 04:42:39 INFO - PROCESS | 1636 | Values: 1422.7 1445.2 1455.8 1460.7 1471.3 8257.7 8678.6 8684.0 8695.8 8700.4 8088.1 8507.3 8534.2 8602.4 8611.1 2274.1 2310.7 2315.4 2316.4 2317.2 2286.9 2308.9 2318.8 2321.6 2323.5 2252.5 2265.9 2291.1 2293.8 2301.5 2238.6 2261.8 2270.3 2282.6 2291.4 2025.0 2215.4 2300.8 2306.9 2315.5 8512.5 8569.0 8570.8 8574.4 8589.4 5013.3 5193.9 5197.3 5199.1 5202.4 2693.4 2911.2 3291.2 3455.5 3492.7 1174.8 1185.2 1204.6 1223.4 1251.3 10931.9 11021.0 11124.6 11132.6 11134.7 4188.2 4405.8 4484.7 4498.3 4508.8 8838.1 8844.0 8845.0 8853.2 8864.0 5107.7 5115.2 5118.6 5120.5 5132.9 4575.6 4631.7 4724.8 4733.0 4786.3 3587.2 3624.5 3655.9 3672.5 3690.3 1050.3 1083.0 1104.7 1121.7 1125.0 823.3 881.9 899.1 903.7 943.8 4791.5 4831.8 5090.3 5119.5 5550.6 566.4 568.1 568.7 571.4 579.8 3615.3 3633.7 3715.7 3717.0 3778.0 9757.7 10101.1 10282.5 10290.5 10309.8 9364.5 9801.3 10232.8 10257.6 10257.6 7547.2 7752.6 7768.2 7784.0 7818.5 5453.7 5544.6 5583.9 5640.6 5666.2 8942.0 9357.7 9370.0 9454.5 9496.1 9259.2 9461.1 9518.6 9569.5 9667.7 7250.6 7274.3 7292.5 7301.7 7389.6 7976.8 8054.7 8058.7 8061.2 8079.4 7135.2 7252.5 7317.8 7428.3 7428.9 6972.5 7092.6 7112.9 7157.1 7290.8 6328.8 6455.9 6461.9 6465.9 6470.6 4531.0 4815.3 4845.2 4974.3 4988.4 4955.5 4959.6 4984.7 5028.6 5069.6 04:42:39 INFO - PROCESS | 1636 | 04:42:39 INFO - PROCESS | 1636 | [#4] prototype.html Cycles:180 Average:4320.71 Median:4736.03 stddev:1320.00 (27.9%) stddev-sans-first:1288.52 04:42:39 INFO - PROCESS | 1636 | Values: 287.5 315.6 343.5 349.7 367.7 4467.6 4822.5 4973.3 5065.6 5147.4 4896.0 5013.6 5028.5 5067.8 5070.3 3768.7 3774.1 3953.9 3988.9 4008.4 3266.4 3431.9 3536.7 3792.6 4005.6 3298.4 3798.5 3960.8 3976.2 4015.7 3950.8 3966.5 3980.9 3985.6 3996.7 3481.0 3538.0 3574.4 3590.6 3600.4 4469.8 4698.2 4923.9 4977.7 4994.2 4086.2 4226.0 4240.6 4580.5 4601.4 4502.9 4798.3 5270.7 5383.8 5405.9 3470.3 3733.0 3748.9 3768.5 3935.5 5323.5 5608.5 5644.6 5666.9 5673.3 3778.9 3798.6 3802.8 3802.9 3823.9 3552.7 3768.4 3857.2 3866.3 3869.0 3587.0 3648.9 4173.0 4314.0 4315.1 5195.7 5389.3 5399.6 5419.3 5428.8 1084.5 1230.9 1238.7 1244.4 1265.7 753.9 769.8 795.3 868.2 872.6 3832.6 4303.7 4651.3 4666.5 4708.5 4649.7 4770.2 5129.6 5189.4 5190.0 4238.6 4471.5 4666.6 4685.8 4692.8 2304.3 2475.4 2719.6 2728.4 2755.5 4555.0 4559.9 4763.6 5030.2 5374.7 4968.6 5164.9 5325.9 5363.6 5398.7 5295.4 5678.6 5742.8 5780.7 5799.7 5099.7 5574.7 5619.9 5628.3 5678.7 4232.5 4865.4 5285.0 5458.7 5473.9 5630.5 5733.5 5739.3 5765.2 5774.3 3764.2 3800.5 3815.6 3826.2 3828.9 5138.3 5397.9 5500.4 5594.7 5656.4 4517.5 5208.7 5261.5 5262.1 5384.4 5343.3 5348.0 5355.8 5366.4 5385.3 4261.7 4679.0 4968.5 5256.9 5282.5 5261.8 5357.3 5388.7 5398.5 5445.3 5254.5 5258.5 5272.9 5281.5 5305.7 04:42:39 INFO - PROCESS | 1636 | 04:42:39 INFO - PROCESS | 1636 | [#5] yui.html Cycles:180 Average:18586.76 Median:4134.94 stddev:25128.94 (607.7%) stddev-sans-first:25163.47 04:42:39 INFO - PROCESS | 1636 | Values: 680.6 706.8 766.5 816.4 831.8 14741.7 15467.8 15503.4 15550.8 15550.8 14069.7 14209.6 14211.3 14216.2 14254.0 843.7 849.7 858.7 863.3 867.5 870.6 872.6 875.2 875.4 875.9 924.2 924.3 924.5 924.9 925.2 921.5 923.3 924.3 925.1 926.2 638.8 652.0 682.7 685.1 688.3 14853.6 15322.4 15344.7 15497.1 15765.8 13874.0 14094.1 14143.3 14262.3 14272.1 11746.4 11751.4 11809.9 12188.5 12326.3 3648.3 3652.7 3666.2 3675.4 3790.8 51401.0 55158.6 63105.6 100942.5 104896.5 9558.9 10819.5 11058.9 11066.9 11503.1 23361.4 24018.2 24079.4 24284.8 24309.1 7762.4 7809.3 7820.8 7883.9 7897.1 4127.9 4142.0 4166.2 4205.0 4284.5 3609.7 3625.7 3675.5 3698.1 3711.4 668.4 672.1 681.1 681.9 688.3 655.9 670.8 690.2 695.0 699.2 9175.7 10024.4 10265.8 10297.4 10394.0 585.9 614.5 630.8 634.2 639.7 4846.4 4942.6 4958.5 4960.6 4962.8 81667.6 82294.2 84728.3 92239.0 103452.5 72416.0 73161.6 73593.3 82779.6 85055.6 63504.7 72982.3 74360.5 74881.9 76561.0 8873.8 10446.3 10793.9 10926.3 11122.7 40772.0 45523.0 49073.8 49191.0 49817.3 64065.0 64993.4 65240.0 66028.0 67486.3 12631.5 12899.9 12926.5 13297.5 13499.4 13505.5 14529.6 14541.3 14606.5 14644.9 9542.4 11798.0 12171.8 12246.6 12279.6 11557.4 11891.8 12032.3 12316.6 12435.6 11545.6 11818.9 11878.2 12194.5 12229.3 11049.6 11275.8 11408.8 11486.2 11502.1 10168.5 10555.7 11072.5 11300.9 11518.9 04:42:39 INFO - PROCESS | 1636 | -------- Summary: end -------- 04:42:39 INFO - PROCESS | 1636 | 04:42:39 INFO - TEST-INFO | 1636: exit 0 04:42:39 INFO - TEST-OK | dromaeo_css | took 1119171ms 04:42:39 INFO - TEST-START | kraken 04:42:39 INFO - operating with platform_type : mac_ 04:42:39 INFO - Initialising browser for kraken test... 04:42:39 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/tmpYp_2OF/profile http://localhost:49243/getInfo.html) 04:42:41 INFO - PROCESS | 1643 | 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:42:42 INFO - PROCESS | 1643 | __metrics Screen width/height:1600/1200 04:42:42 INFO - PROCESS | 1643 | colorDepth:24 04:42:42 INFO - PROCESS | 1643 | Browser inner width/height: 1024/689 04:42:42 INFO - PROCESS | 1643 | __metrics 04:42:42 INFO - PROCESS | 1643 | 1486212162242 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:42:42 INFO - PROCESS | 1643 | 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:42:42 INFO - PROCESS | 1643 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:42:43 INFO - TEST-INFO | 1643: exit 0 04:42:43 INFO - Browser initialized. 04:42:43 INFO - Running cycle 1/1 for kraken test... 04:42:43 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/tmpYp_2OF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:42:55 INFO - PROCESS | 1645 | RSS: Main: 228552704 04:42:55 INFO - PROCESS | 1645 | 04:42:58 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection) 04:42:58 INFO - PROCESS | 1645 | RSS: Main: 288460800 04:42:58 INFO - PROCESS | 1645 | 04:43:01 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-dft) 04:43:02 INFO - PROCESS | 1645 | RSS: Main: 293732352 04:43:02 INFO - PROCESS | 1645 | 04:43:05 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-fft) 04:43:06 INFO - PROCESS | 1645 | RSS: Main: 383778816 04:43:06 INFO - PROCESS | 1645 | 04:43:08 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator) 04:43:08 INFO - PROCESS | 1645 | RSS: Main: 284917760 04:43:08 INFO - PROCESS | 1645 | 04:43:10 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:43:11 INFO - PROCESS | 1645 | RSS: Main: 286162944 04:43:11 INFO - PROCESS | 1645 | 04:43:14 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom) 04:43:14 INFO - PROCESS | 1645 | RSS: Main: 330223616 04:43:14 INFO - PROCESS | 1645 | 04:43:18 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate) 04:43:18 INFO - PROCESS | 1645 | RSS: Main: 331198464 04:43:18 INFO - PROCESS | 1645 | 04:43:21 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial) 04:43:22 INFO - PROCESS | 1645 | RSS: Main: 330637312 04:43:22 INFO - PROCESS | 1645 | 04:43:23 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:43:24 INFO - PROCESS | 1645 | RSS: Main: 305065984 04:43:24 INFO - PROCESS | 1645 | 04:43:26 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:43:27 INFO - PROCESS | 1645 | RSS: Main: 309772288 04:43:27 INFO - PROCESS | 1645 | 04:43:29 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:43:29 INFO - PROCESS | 1645 | RSS: Main: 311562240 04:43:29 INFO - PROCESS | 1645 | 04:43:32 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:43:33 INFO - PROCESS | 1645 | RSS: Main: 330764288 04:43:33 INFO - PROCESS | 1645 | 04:43:36 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:43:37 INFO - PROCESS | 1645 | RSS: Main: 324435968 04:43:37 INFO - PROCESS | 1645 | 04:43:39 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49243/tests/kraken/driver.html?testName=ai-astar) 04:43:39 INFO - PROCESS | 1645 | RSS: Main: 368840704 04:43:39 INFO - PROCESS | 1645 | 04:43:39 INFO - PROCESS | 1645 | __start_tp_report 04:43:39 INFO - PROCESS | 1645 | _x_x_mozilla_page_load 04:43:39 INFO - PROCESS | 1645 | _x_x_mozilla_page_load_details 04:43:39 INFO - PROCESS | 1645 | |i|pagename|runs| 04:43:39 INFO - PROCESS | 1645 | |0;ai-astar;111;113;114;116;113;114;114;114;114;113 04:43:39 INFO - PROCESS | 1645 | |1;audio-beat-detection;155;133;131;172;132;107;110;111;131;110 04:43:39 INFO - PROCESS | 1645 | |2;audio-dft;185;184;183;187;184;184;183;184;183;185 04:43:39 INFO - PROCESS | 1645 | |3;audio-fft;80;59;59;89;61;60;59;59;76;59 04:43:39 INFO - PROCESS | 1645 | |4;audio-oscillator;88;70;89;72;68;69;86;68;69;83 04:43:39 INFO - PROCESS | 1645 | |5;imaging-gaussian-blur;90;87;90;88;90;87;91;88;91;87 04:43:39 INFO - PROCESS | 1645 | |6;imaging-darkroom;98;98;99;99;98;113;154;158;97;97 04:43:39 INFO - PROCESS | 1645 | |7;imaging-desaturate;88;113;129;128;86;86;86;85;88;94 04:43:39 INFO - PROCESS | 1645 | |8;json-parse-financial;61;59;58;58;59;59;58;61;59;60 04:43:39 INFO - PROCESS | 1645 | |9;json-stringify-tinderbox;65;67;81;78;72;68;69;70;70;71 04:43:39 INFO - PROCESS | 1645 | |10;stanford-crypto-aes;90;87;85;89;77;85;100;101;100;80 04:43:39 INFO - PROCESS | 1645 | |11;stanford-crypto-ccm;139;140;138;139;146;131;141;135;193;158 04:43:39 INFO - PROCESS | 1645 | |12;stanford-crypto-pbkdf2;222;179;173;174;178;171;174;173;172;172 04:43:39 INFO - PROCESS | 1645 | |13;stanford-crypto-sha256-iterative;66;75;82;82;83;63;67;85;83;80 04:43:39 INFO - PROCESS | 1645 | __end_tp_report 04:43:39 INFO - PROCESS | 1645 | __start_cc_report 04:43:39 INFO - PROCESS | 1645 | _x_x_mozilla_cycle_collect,2093 04:43:39 INFO - PROCESS | 1645 | __end_cc_report 04:43:39 INFO - PROCESS | 1645 | __startTimestamp1486212219402__endTimestamp 04:43:39 INFO - PROCESS | 1645 | 04:43:39 INFO - PROCESS | 1645 | ------- Summary: start ------- 04:43:39 INFO - PROCESS | 1645 | Number of tests: 14 04:43:39 INFO - PROCESS | 1645 | 04:43:39 INFO - PROCESS | 1645 | [#0] ai-astar Cycles:10 Average:113.60 Median:114.00 stddev:1.26 (1.1%) stddev-sans-first:0.93 04:43:39 INFO - PROCESS | 1645 | Values: 111.0 113.0 114.0 116.0 113.0 114.0 114.0 114.0 114.0 113.0 04:43:39 INFO - PROCESS | 1645 | 04:43:39 INFO - PROCESS | 1645 | [#1] audio-beat-detection Cycles:10 Average:129.20 Median:131.50 stddev:21.31 (16.2%) stddev-sans-first:20.46 04:43:39 INFO - PROCESS | 1645 | Values: 155.0 133.0 131.0 172.0 132.0 107.0 110.0 111.0 131.0 110.0 04:43:39 INFO - PROCESS | 1645 | 04:43:39 INFO - PROCESS | 1645 | [#2] audio-dft Cycles:10 Average:184.20 Median:184.00 stddev:1.23 (0.7%) stddev-sans-first:1.27 04:43:39 INFO - PROCESS | 1645 | Values: 185.0 184.0 183.0 187.0 184.0 184.0 183.0 184.0 183.0 185.0 04:43:39 INFO - PROCESS | 1645 | 04:43:39 INFO - PROCESS | 1645 | [#3] audio-fft Cycles:10 Average:66.10 Median:60.50 stddev:11.21 (18.5%) stddev-sans-first:10.70 04:43:39 INFO - PROCESS | 1645 | Values: 80.0 59.0 59.0 89.0 61.0 60.0 59.0 59.0 76.0 59.0 04:43:39 INFO - PROCESS | 1645 | 04:43:39 INFO - PROCESS | 1645 | [#4] audio-oscillator Cycles:10 Average:76.20 Median:77.50 stddev:9.07 (11.7%) stddev-sans-first:8.55 04:43:39 INFO - PROCESS | 1645 | Values: 88.0 70.0 89.0 72.0 68.0 69.0 86.0 68.0 69.0 83.0 04:43:39 INFO - PROCESS | 1645 | 04:43:39 INFO - PROCESS | 1645 | [#5] imaging-gaussian-blur Cycles:10 Average:88.90 Median:90.00 stddev:1.66 (1.8%) stddev-sans-first:1.72 04:43:39 INFO - PROCESS | 1645 | Values: 90.0 87.0 90.0 88.0 90.0 87.0 91.0 88.0 91.0 87.0 04:43:39 INFO - PROCESS | 1645 | 04:43:39 INFO - PROCESS | 1645 | [#6] imaging-darkroom Cycles:10 Average:111.10 Median:98.00 stddev:24.15 (24.6%) stddev-sans-first:25.15 04:43:39 INFO - PROCESS | 1645 | Values: 98.0 98.0 99.0 99.0 98.0 113.0 154.0 158.0 97.0 97.0 04:43:39 INFO - PROCESS | 1645 | 04:43:39 INFO - PROCESS | 1645 | [#7] imaging-desaturate Cycles:10 Average:98.30 Median:86.00 stddev:17.96 (20.9%) stddev-sans-first:18.66 04:43:39 INFO - PROCESS | 1645 | Values: 88.0 113.0 129.0 128.0 86.0 86.0 86.0 85.0 88.0 94.0 04:43:39 INFO - PROCESS | 1645 | 04:43:39 INFO - PROCESS | 1645 | [#8] json-parse-financial Cycles:10 Average:59.20 Median:59.00 stddev:1.14 (1.9%) stddev-sans-first:1.00 04:43:39 INFO - PROCESS | 1645 | Values: 61.0 59.0 58.0 58.0 59.0 59.0 58.0 61.0 59.0 60.0 04:43:39 INFO - PROCESS | 1645 | 04:43:39 INFO - PROCESS | 1645 | [#9] json-stringify-tinderbox Cycles:10 Average:71.10 Median:70.50 stddev:4.91 (7.0%) stddev-sans-first:4.68 04:43:39 INFO - PROCESS | 1645 | Values: 65.0 67.0 81.0 78.0 72.0 68.0 69.0 70.0 70.0 71.0 04:43:39 INFO - PROCESS | 1645 | 04:43:39 INFO - PROCESS | 1645 | [#10] stanford-crypto-aes Cycles:10 Average:89.40 Median:85.00 stddev:8.47 (10.0%) stddev-sans-first:8.99 04:43:39 INFO - PROCESS | 1645 | Values: 90.0 87.0 85.0 89.0 77.0 85.0 100.0 101.0 100.0 80.0 04:43:39 INFO - PROCESS | 1645 | 04:43:39 INFO - PROCESS | 1645 | [#11] stanford-crypto-ccm Cycles:10 Average:146.00 Median:140.50 stddev:18.02 (12.8%) stddev-sans-first:18.93 04:43:39 INFO - PROCESS | 1645 | Values: 139.0 140.0 138.0 139.0 146.0 131.0 141.0 135.0 193.0 158.0 04:43:39 INFO - PROCESS | 1645 | 04:43:39 INFO - PROCESS | 1645 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.80 Median:174.00 stddev:15.40 (8.8%) stddev-sans-first:2.74 04:43:39 INFO - PROCESS | 1645 | Values: 222.0 179.0 173.0 174.0 178.0 171.0 174.0 173.0 172.0 172.0 04:43:39 INFO - PROCESS | 1645 | 04:43:39 INFO - PROCESS | 1645 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.60 Median:82.00 stddev:8.26 (10.1%) stddev-sans-first:7.82 04:43:39 INFO - PROCESS | 1645 | Values: 66.0 75.0 82.0 82.0 83.0 63.0 67.0 85.0 83.0 80.0 04:43:39 INFO - PROCESS | 1645 | -------- Summary: end -------- 04:43:39 INFO - PROCESS | 1645 | 04:43:40 INFO - TEST-INFO | 1645: exit 0 04:43:40 INFO - TEST-OK | kraken | took 60794ms 04:43:40 INFO - SUITE-END | took 1179s 04:43:40 INFO - Completed test suite (00:19:40) 04:43:40 INFO - javascript benchmark 04:43:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7137.673581097161, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3252.4633435483147, 3259.837008149592, 3282.6224984126816, 3293.752968527361, 3337.566116404867, 5314.468553144684, 5386.272853164822, 5462.562994960399, 5512.0, 5528.695921724304, 4510.503844577094, 5041.344625198719, 5289.074411977907, 5386.057439948013, 5527.060399732045, 4250.617444429993, 4964.528369804862, 4990.276409920578, 5014.147594908856, 5042.319286896277, 4524.638028957692, 4581.862544123682, 5129.0, 5157.5358217760395, 5190.870228244286, 4220.0, 4901.872569309043, 4998.550130488274, 5080.923786143211, 5098.796048158069, 4971.552560269593, 5010.599152067826, 5053.418856831475, 5128.692278463304, 5135.460776618439, 4980.302757613937, 5021.648484606079, 5033.320501732256, 5038.84883453497, 5080.644354895159, 5498.5876059295715, 5513.972430137824, 5518.310211223597, 5526.696031718254, 5530.170474428827, 5802.5938184327115, 5856.502197313191, 5856.619319744203, 5944.940550594482, 6008.429199226067, 6111.205543279345, 6138.51101655979, 6142.201513803222, 6152.538559608047, 6162.506999440034, 4760.381150450454, 5645.1532270159805, 5654.349749778787, 5666.858328541737, 5682.573806964494, 6028.849278768066, 6091.664958427329, 6177.413145751147, 6257.9687101564205, 6271.122042913996, 5798.9420105799245, 5807.622504537191, 5898.8230353089475, 5914.290285165722, 6062.242219722535, 6170.629762214281, 6170.81487555374, 6177.073438984188, 6180.443760061616, 6183.0, 6005.39946005396, 6084.421979911901, 6094.969525152346, 6098.664573448415, 6106.4809491192855, 4894.143524883131, 5849.181114643953, 5866.501347385435, 6111.174991376196, 6115.755369785159, 4799.808007679723, 4836.516348365135, 4846.975765121152, 4849.636277279218, 4904.8038078477175, 4326.221280169537, 4327.199468098349, 4338.197433474817, 4348.369486424451, 4360.890977725582, 5381.4349493303425, 5534.308211473566, 5561.63849349791, 5569.415211402826, 5608.242887210175, 5903.3801450847905, 5977.342492325841, 6012.398760123952, 6037.909431358533, 6038.486728628028, 5506.091494903376, 5513.696746678891, 5514.834554963358, 5546.55627549795, 5563.860903477445, 5239.0, 5259.0796610593, 5265.973135238592, 5271.077561426735, 5311.575073994071, 5962.373950735284, 6095.329513753572, 6191.824367953058, 6255.593386429868, 6261.843453913598, 5050.267711181931, 6109.009944784373, 6109.053096770008, 6113.628337950474, 6204.937950620436, 6076.118962750288, 6095.0, 6124.991279909952, 6187.783427580013, 6200.844978875564, 5948.226730525004, 6080.90878636812, 6115.3578874217565, 6118.694065296629, 6127.387261273837, 6135.079468778513, 6167.383261673976, 6167.599106058092, 6169.475594574512, 6175.567710260419, 5565.860853478695, 5890.263717035371, 5936.198613187337, 5938.703064846654, 5962.612430192024, 5130.102232109291, 5701.40135285789, 5793.304803423616, 5795.565332599987, 5799.681017543907, 5691.8861622768145, 5701.173329867229, 5713.285839270091, 5773.595848290579, 5812.564057695774, 5817.447342502455, 5860.9450298946595, 5877.265341832271, 5886.293644762656, 5891.646501209942, 5551.361593416808, 5733.541316694571, 5733.71331433435, 5738.454846789548, 5746.597738158456], "value": 5488.808998591273, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [464.3267262469419, 488.0702262190526, 493.79260710501586, 518.1062666899599, 524.8215606693724, 29235.56146657802, 38782.22435551287, 40412.79793601031, 40498.59501404985, 40554.59445405545, 40815.387769183486, 41197.58802411975, 41207.58792412075, 41253.58746412543, 41262.38106428406, 7925.524468531878, 7955.960220198891, 9392.0, 9398.906010939905, 9412.811743765154, 7981.441299109065, 10983.725406864853, 11241.60654377097, 11253.66239012831, 11378.658640240807, 11132.332060076422, 11191.496382662759, 11274.32354058755, 11278.15413843961, 11304.547818087307, 7947.483413578157, 11203.04774094203, 11259.54961801527, 11280.492377842977, 11288.209825312231, 8101.837963240702, 10955.287906286067, 11159.497822597921, 11203.495842687018, 11372.658820235407, 39446.40830387546, 39810.40284395737, 39871.0, 39882.60117398818, 39918.00204994869, 4007.919841603181, 4015.574471062789, 4019.718619696622, 4020.2160578687167, 4157.688173386978, 24995.25071239329, 25950.74049259502, 29494.26264343387, 30330.69669303301, 30477.69522304793, 3042.34589563245, 3215.983920080408, 3222.822744749038, 3260.3479304139028, 3262.787918785295, 449063.0, 455463.0, 456048.0, 458367.0, 467915.0, 50477.24284135741, 52217.95564088697, 61106.694466527384, 61422.0, 61532.38467615356, 54264.72867635637, 54508.72745636326, 54536.0, 54546.0, 61731.0, 15305.923470382577, 15802.60493487672, 15803.841961580238, 15807.209639517978, 15820.05079695222, 12223.755524889453, 12339.197952133261, 12366.44351004207, 12377.319247441477, 12394.504219831106, 16738.916305418395, 16804.32782688703, 16972.575685607957, 16988.235529401205, 16989.915050424916, 6780.525363224527, 6920.550164239308, 6935.133108361455, 6983.406410455168, 6996.580205187705, 839.8824164616958, 854.2525290370926, 864.0927026622022, 869.1742844297816, 887.2458410351279, 24867.378315542257, 28263.858680706464, 29053.692583833777, 29209.561856572167, 29899.103026909226, 871.3987348729356, 1045.414567842011, 1061.5329255127874, 1062.6280801719336, 1085.2837127495816, 14659.706805863823, 15140.318685658951, 15318.979067388405, 15398.230088495531, 15524.068555886683, 522197.0, 526831.0, 593048.0, 593840.0, 594127.0, 58008.709956450795, 58048.70975645179, 58094.4190558089, 58211.12683309669, 58273.0, 1373.6085215713674, 1423.4804296431935, 1437.0, 1440.9351579178751, 1445.7542217822995, 27861.58207626928, 28534.0, 28671.794260274863, 29136.854315728287, 29538.0, 49413.0, 49797.75101124616, 50115.74479148374, 50131.0, 50143.498565013884, 503710.0, 505927.0, 506756.0, 507078.0, 508632.0, 21691.457713557287, 22078.0, 26028.0, 26058.34854128662, 26138.215853524423, 41181.382279266436, 41429.96425089397, 42446.787766060974, 44059.89850253769, 46642.300365493866, 31540.36919261556, 34373.65626343704, 34832.47751283682, 35121.121971949884, 36042.0, 29463.0, 33641.66358336385, 34713.479297811056, 35966.640333596326, 36295.45556816597, 34831.30337393187, 35792.28415431729, 35888.4616730744, 35890.0, 35997.5600975958, 28759.712402875703, 29384.26539336534, 31157.8442107788, 31489.685103148677, 31577.21056973594, 20178.394648160578, 21268.40486963453, 23124.844997768236, 23916.64125038091, 23945.640815387436], "value": 19246.282901226543, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [340.88409940620187, 359.6978538028059, 390.47676114007226, 399.82407740594147, 423.2064878353088, 14470.348834302455, 14878.628034299134, 16642.75035874463, 20352.98235088247, 21034.368968930918, 20907.268245611405, 21287.68068478974, 21320.893395533, 21474.0, 21637.91810409481, 5820.679862607555, 5899.085641725539, 6001.939980600204, 6164.784232551861, 6179.289381721114, 4042.0, 5917.260342457193, 6026.668533230671, 6028.39716028398, 6033.245844269466, 4240.257954857903, 6357.809265722036, 6622.9006564901565, 6663.133792606955, 6724.831879203035, 6379.71291291893, 6591.505637077191, 6639.2032956045105, 6678.56589321695, 6732.865342693119, 5864.794732184396, 5868.061110222344, 5872.324682661506, 5872.500837428824, 5876.3242227144, 23655.76344236553, 24781.008759649594, 25360.0, 26125.608115878276, 26380.340491487867, 2566.396896729267, 2612.542805009135, 2619.1356852238905, 2626.4090579619433, 2630.1714959787605, 10399.504119011459, 10417.218708596885, 10794.946025269903, 12466.439010244561, 16279.918600406922, 1850.7686539182603, 1873.100911562444, 1874.7656542932134, 1883.5667796406904, 1885.179946723166, 130655.0, 133743.33128334297, 135630.0, 137036.31481842528, 142606.0, 11580.536778528765, 11861.288322700666, 12130.0, 12233.571824986082, 12266.877331226573, 16247.187640617922, 16250.51248462548, 16261.59346016359, 16276.593085172966, 16295.0, 8194.09864914859, 8242.381821363422, 8375.916240837514, 8580.871286930576, 8647.394682372174, 6906.723731050702, 8140.75577732669, 8152.551609661407, 8162.510249385056, 8306.210909963544, 4865.270209468608, 4873.951260487421, 4880.145974454456, 4898.191798353232, 4971.676841005323, 1985.2456066694563, 2046.8055534724178, 2047.3141497598472, 2048.6683655623965, 2059.4954236212225, 1006.6677996261217, 1013.8175128476797, 1024.2369434771142, 1028.4600584692946, 1062.16089289462, 21083.262085826922, 21621.35135945924, 21626.567468650537, 21653.350399487725, 21679.458013549473, 911.5077857956762, 1002.2382988928467, 1051.600391851107, 1061.527620209015, 1072.399456304472, 9422.869255689362, 10686.57253709843, 11160.0, 11266.887331126583, 11312.434378281054, 670350.0, 685763.0, 713676.0, 736791.0, 739956.0, 135995.32002339925, 142809.0, 148170.0, 155672.0, 167715.0, 1028.5680014393972, 1056.265895202842, 1071.8767341755643, 1076.2842709598185, 1076.5747529725716, 19684.803151968295, 19889.502762431053, 20027.29904453337, 20566.177352905717, 20603.4849128773, 59249.0, 59908.0, 60762.696186518784, 61721.38278617156, 61982.0702689469, 75920.24079759352, 90760.63859041987, 92440.53779731058, 93264.06735932553, 96234.55648165144, 8426.915730842613, 8475.279601233964, 8639.568021599021, 8733.606987685696, 8972.327075469497, 10197.337173083726, 13402.26287554194, 13441.327933603476, 13872.4451021958, 13889.861101388857, 11590.18868679218, 11768.823467648162, 11802.76394472123, 11840.0, 11921.403929803648, 9124.635014599342, 11012.0, 11597.0, 11706.475795518038, 11906.10704197206, 11526.0, 11586.246893951866, 11765.470488836396, 11912.761744764883, 11913.285202887855, 8422.5788710563, 8555.272801811916, 8581.570921454027, 8591.183837535424, 8649.1350864913, 8505.659773608986, 10122.696319110439, 11037.503312350804, 11358.716032098933, 11845.933865952105], "value": 10097.91904918281, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1422.6870088580508, 1445.241248344619, 1455.7597996330603, 1460.685952520208, 1471.3231913319873, 8257.669693212272, 8678.566071696405, 8684.044755076937, 8695.826083478327, 8700.43447175934, 8088.1103078661445, 8507.276881465068, 8534.231919127278, 8602.397832151752, 8611.095834937336, 2274.1471948019453, 2310.699609050821, 2315.3632750993443, 2316.3861576682193, 2317.2237300504353, 2286.862788232703, 2308.90764369425, 2318.7565305642916, 2321.5937210988095, 2323.512062466884, 2252.5157091225383, 2265.8980345884393, 2291.106468477287, 2293.839431239814, 2301.4936713922993, 2238.563480121385, 2261.7512073671883, 2270.2962081754627, 2282.5663124006387, 2291.4271432141963, 2024.9595008099902, 2215.4129155773735, 2300.7584203658544, 2306.9423264418356, 2315.513742114158, 8512.489250644981, 8568.957155214246, 8570.78573035679, 8574.356923230782, 8589.398742087993, 5013.323201367805, 5193.922091168635, 5197.324347834758, 5199.14214154664, 5202.401723801773, 2693.3939863530745, 2911.199420159452, 3291.226561757985, 3455.481677748333, 3492.7205823534055, 1174.794410978084, 1185.199990006742, 1204.59646018583, 1223.406647775828, 1251.3117785218094, 10931.890681093246, 11021.0, 11124.555017799279, 11132.554697812158, 11134.665960021212, 4188.225178341955, 4405.823767049282, 4484.663650226246, 4498.302763071729, 4508.797104130322, 8838.116188381238, 8843.955780221058, 8844.955775221082, 8853.203211710848, 8863.955680221687, 5107.693538387709, 5115.155999260155, 5118.641695081308, 5120.539151476385, 5132.897342053138, 4575.611073058761, 4631.722096674211, 4724.787384567703, 4732.982408782128, 4786.329913812069, 3587.1749497615174, 3624.52881125452, 3655.8903232903053, 3672.5225720656144, 3690.2619476104887, 1050.3015494696094, 1082.9729256768487, 1104.729561004851, 1121.7419993401563, 1125.0268517732102, 823.3454403749034, 881.8809460722848, 899.0919171636564, 903.6837107012493, 943.8112377524523, 4791.47293797682, 4831.806727730922, 5090.287359769635, 5119.48805119485, 5550.611457197957, 566.3543560341215, 568.1052342560483, 568.6673296121733, 571.3857603076709, 579.7593998490663, 3615.3492371373404, 3633.709303255786, 3715.6841668458483, 3716.9814150929074, 3778.0, 9757.707268781947, 10101.090901818725, 10282.537285822007, 10290.536925838354, 10309.793804124025, 9364.484953327632, 9801.264905132144, 10232.795344093225, 10257.563716155022, 10257.58969641221, 7547.16981132075, 7752.612369381508, 7768.184340644264, 7783.9610801946765, 7818.452708310478, 5453.727313634223, 5544.584156188222, 5583.944160558505, 5640.605157638925, 5666.206731057573, 8941.955290223768, 9357.672481463389, 9370.0, 9454.480003599869, 9496.14534691881, 9259.175951334908, 9461.148496635336, 9518.571664274985, 9569.521523923915, 9667.70996870095, 7250.637468126678, 7274.345308922222, 7292.489525733148, 7301.744438944612, 7389.593572353358, 7976.8005799853145, 8054.677812887418, 8058.717944871902, 8061.234182752629, 8079.3536517079665, 7135.215126336203, 7252.453255867274, 7317.780466585798, 7428.294312040348, 7428.925710742823, 6972.477064220102, 7092.645367731696, 7112.857742845217, 7157.141143062866, 7290.781276561712, 6328.778492752732, 6455.935440645721, 6461.903071453838, 6465.870682586416, 6470.611763294218, 4530.977345113253, 4815.3258543803195, 4845.1520983828395, 4974.253861920741, 4988.376452943382, 4955.479674634112, 4959.60323174138, 4984.675996060245, 5028.572571331331, 5069.569086627678], "value": 4327.992748720262, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [287.51123090745733, 315.6322883840326, 343.48305799771333, 349.70275266023884, 367.6837919389325, 4467.642588592913, 4822.479589899375, 4973.2540118982215, 5065.620078494118, 5147.408048074472, 4896.0, 5013.6490445668815, 5028.5474307312425, 5067.84796456106, 5070.315507406499, 3768.7361884668085, 3774.075351538877, 3953.9209215815667, 3988.880333589997, 4008.4388185653884, 3266.370571689789, 3431.9485207721905, 3536.699380552643, 3792.563855156651, 4005.619466150711, 3298.4062868683627, 3798.54417469904, 3960.782156981365, 3976.1848820991772, 4015.678745700337, 3950.839328537173, 3966.484357033567, 3980.925150209456, 3985.5815139410524, 3996.7002474814503, 3481.0079127448557, 3538.0, 3574.3566158091535, 3590.612242858496, 3600.369935261345, 4469.7541635210055, 4698.154332220199, 4923.852284431473, 4977.714708292546, 4994.225894986283, 4086.1816491660925, 4225.957740422587, 4240.618344348993, 4580.488438815183, 4601.400208881971, 4502.864914052584, 4798.280257961299, 5270.735023594348, 5383.838484845461, 5405.891882162335, 3470.2712430389647, 3732.962670373289, 3748.887533374003, 3768.5289338832645, 3935.468711723909, 5323.467653234684, 5608.523275521545, 5644.604877658566, 5666.914996275059, 5673.255651270142, 3778.9244215115546, 3798.5631652360335, 3802.790846503469, 3802.9429558556644, 3823.866164684223, 3552.703263308874, 3768.397056471006, 3857.1514266861245, 3866.284737323604, 3868.96131038686, 3587.0, 3648.872289469856, 4172.958270417318, 4314.0, 4315.07225946423, 5195.739493615275, 5389.272448219519, 5399.595030372738, 5419.268398766116, 5428.782848686008, 1084.4523515624717, 1230.852297724261, 1238.68413554543, 1244.4026867103842, 1265.7405231927432, 753.8944547763317, 769.819092513259, 795.3120550723582, 868.2446271743623, 872.6029656506402, 3832.597577254404, 4303.6975218258085, 4651.279051746985, 4666.4633567139535, 4708.458527269339, 4649.674522783373, 4770.165221086296, 5129.64092513528, 5189.42916279209, 5190.0, 4238.630288920316, 4471.530489298641, 4666.626669866402, 4685.765711714401, 4692.835750748707, 2304.331743794314, 2475.393528585508, 2719.5920611907977, 2728.4406696627143, 2755.5395640310667, 4554.950272377443, 4559.863204103882, 4763.595094417014, 5030.170021946338, 5374.731263436813, 4968.627352948543, 5164.896702066013, 5325.946740532545, 5363.570914326844, 5398.676079435247, 5295.364556253197, 5678.574106941914, 5742.7702891883855, 5780.670445797406, 5799.681017544076, 5099.69401835891, 5574.693391863487, 5619.8876022480135, 5628.296462942132, 5678.68767217807, 4232.513260975027, 4865.416150062015, 5285.0, 5458.6724796512335, 5473.890522189614, 5630.493255607015, 5733.512651424676, 5739.339975902824, 5765.192872997867, 5774.265102615815, 3764.247150569952, 3800.486934263839, 3815.618438156162, 3826.1773718650625, 3828.8659896903473, 5138.332016837937, 5397.865053373697, 5500.42245564226, 5594.69229192382, 5656.377798442086, 4517.50307466185, 5208.73956302191, 5261.5264626183825, 5262.0793410405695, 5384.380796208329, 5343.305370301836, 5347.973260133675, 5355.758990845499, 5366.436524164907, 5385.3268341457315, 4261.659067274549, 4678.976605116953, 4968.453470118357, 5256.921146182887, 5282.4717528246865, 5261.842144735664, 5357.330333708286, 5388.73056347189, 5398.4871437213405, 5445.319335083114, 5254.528731955059, 5258.474152584711, 5272.943552014641, 5281.471852814688, 5305.734713264244], "value": 3905.285894667471, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [680.6290571638459, 706.836043695319, 766.4634755671032, 816.4325793573466, 831.8086840026796, 14741.705165896688, 15467.845321546782, 15503.379864805394, 15550.766738498918, 15550.84449155508, 14069.71860562788, 14209.573712788633, 14211.289435528233, 14216.218108004055, 14254.0, 843.6836186430089, 849.7280870121548, 858.6651206029653, 863.3093525179862, 867.4578388507183, 870.6256309786789, 872.6378552900538, 875.2210532625936, 875.4353442029887, 875.9343049271298, 924.2051835421565, 924.3344791749963, 924.5007695844236, 924.8982611912684, 925.1765928323764, 921.4747593871496, 923.2706162131908, 924.2605915267826, 925.0980294213156, 926.2080920812675, 638.7668500997149, 651.993480065198, 682.6962001909103, 685.0751485494592, 688.2532452289272, 14853.554393368217, 15322.387104515807, 15344.693106137925, 15497.147656878868, 15765.763513547307, 13873.983294672897, 14094.124115868219, 14143.292835358294, 14262.28688565578, 14272.072315299472, 11746.412679365998, 11751.353675547842, 11809.881901180965, 12188.51245950161, 12326.32205228712, 3648.3068217038676, 3652.689521390685, 3666.17511060012, 3675.3568125578186, 3790.8294126764363, 51401.0, 55158.62103447446, 63105.628496289646, 100942.49528752382, 104896.47551762269, 9558.856617150748, 10819.459027048617, 11058.889411105947, 11066.889331106748, 11503.137264705181, 23361.41596460102, 24018.15936442216, 24079.398015049763, 24284.75715242825, 24309.149179778622, 7762.417818663623, 7809.258120478546, 7820.804479887935, 7883.921160788319, 7897.104694873189, 4127.855525056609, 4142.026623743418, 4166.187593419315, 4204.957950420457, 4284.464441944757, 3609.6931760800626, 3625.728070394731, 3675.4854320395166, 3698.0754811297174, 3711.387621042552, 668.4017804065431, 672.1027428383065, 681.1077488490093, 681.8772620928181, 688.3116883116883, 655.885220086483, 670.8457054877308, 690.1614538335846, 694.9965250173717, 699.1505321034967, 9175.724728258163, 10024.398536087858, 10265.846012309821, 10297.433641149808, 10394.01256880595, 585.9179714839926, 614.532954954238, 630.760311081786, 634.2072409488139, 639.6993413095837, 4846.442659094178, 4942.629302802305, 4958.454569997298, 4960.553550180501, 4962.776675049631, 81667.5916620425, 82294.24439211932, 84728.30543389097, 92239.0, 103452.48273758583, 72416.0, 73161.56253436928, 73593.2640673597, 82779.58610206911, 85055.574722126, 63504.682476587324, 72982.27017729754, 74360.51278974499, 74881.87677184846, 76561.0, 8873.778155546162, 10446.320989135682, 10793.946030270112, 10926.344419334866, 11122.721931951766, 40772.0, 45523.0, 49073.75463122661, 49191.01617967549, 49817.25274120818, 64065.0, 64993.35006649873, 65240.0, 66028.0, 67486.325136748, 12631.494740210288, 12899.93550032244, 12926.482940682268, 13297.534586289434, 13499.39252733649, 13505.527306544223, 14529.564113076625, 14541.272936352929, 14606.488772892897, 14644.853551464348, 9542.379745316532, 11797.997170240282, 12171.756564868476, 12246.632601021984, 12279.631611051684, 11557.364344961108, 11891.762164756483, 12032.27806331623, 12316.630501084981, 12435.564755233523, 11545.595904143314, 11818.940905295418, 11878.227915185485, 12194.51221951112, 12229.266244025386, 11049.558017679203, 11275.801218762263, 11408.828867566826, 11486.19596628228, 11502.13733969939, 10168.542415591168, 10555.683329500127, 11072.501737421997, 11300.9434952828, 11518.942405288255], "value": 7333.199268011205, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1488.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 113.0, 114.0, 116.0, 113.0, 114.0, 114.0, 114.0, 114.0, 113.0], "value": 113.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 133.0, 131.0, 172.0, 132.0, 107.0, 110.0, 111.0, 131.0, 110.0], "value": 129.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 184.0, 183.0, 187.0, 184.0, 184.0, 183.0, 184.0, 183.0, 185.0], "value": 184.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 59.0, 59.0, 89.0, 61.0, 60.0, 59.0, 59.0, 76.0, 59.0], "value": 66.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 70.0, 89.0, 72.0, 68.0, 69.0, 86.0, 68.0, 69.0, 83.0], "value": 76.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 90.0, 88.0, 90.0, 87.0, 91.0, 88.0, 91.0, 87.0], "value": 88.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 98.0, 99.0, 99.0, 98.0, 113.0, 154.0, 158.0, 97.0, 97.0], "value": 111.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 113.0, 129.0, 128.0, 86.0, 86.0, 86.0, 85.0, 88.0, 94.0], "value": 98.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 59.0, 58.0, 58.0, 59.0, 59.0, 58.0, 61.0, 59.0, 60.0], "value": 59.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 81.0, 78.0, 72.0, 68.0, 69.0, 70.0, 70.0, 71.0], "value": 71.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 85.0, 89.0, 77.0, 85.0, 100.0, 101.0, 100.0, 80.0], "value": 89.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 140.0, 138.0, 139.0, 146.0, 131.0, 141.0, 135.0, 193.0, 158.0], "value": 146.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [222.0, 179.0, 173.0, 174.0, 178.0, 171.0, 174.0, 173.0, 172.0, 172.0], "value": 178.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 75.0, 82.0, 82.0, 83.0, 63.0, 67.0, 85.0, 83.0, 80.0], "value": 76.6, "unit": "score"}]}]} 04:43:40 INFO - Return code: 0 04:43:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:43:40 ERROR - # TBPL SUCCESS # 04:43:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:43:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:43:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:43:40 INFO - 2017-02-04 04:43:40.682 screenresolution[1649:10171] starting screenresolution argv=screenresolution get 04:43:40 INFO - 2017-02-04 04:43:40.684 screenresolution[1649:10171] Display 0: 1600x1200x32@60 04:43:40 INFO - 2017-02-04 04:43:40.690 screenresolution[1650:10174] starting screenresolution argv=screenresolution list 04:43:40 INFO - Available Modes on Display 0 04:43:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:43:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:43:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:43:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:43:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:43:40 INFO - Intel Iris: 04:43:40 INFO - Chipset Model: Intel Iris 04:43:40 INFO - Type: GPU 04:43:40 INFO - Bus: Built-In 04:43:40 INFO - VRAM (Dynamic, Max): 1536 MB 04:43:40 INFO - Vendor: Intel (0x8086) 04:43:40 INFO - Device ID: 0x0a2e 04:43:40 INFO - Revision ID: 0x0009 04:43:40 INFO - Displays: 04:43:40 INFO - Display: 04:43:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:43:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:43:40 INFO - Display Serial Number: 1600x1200 60 04:43:40 INFO - Main Display: Yes 04:43:40 INFO - Mirror: Off 04:43:40 INFO - Online: Yes 04:43:40 INFO - Rotation: Supported 04:43:40 INFO - Return code: 0 04:43:40 INFO - Running post-action listener: _resource_record_post_action 04:43:40 INFO - [mozharness: 2017-02-04 12:43:40.933198Z] Finished run-tests step (success) 04:43:40 INFO - Running post-run listener: _resource_record_post_run 04:43:40 INFO - Total resource usage - Wall time: 1204s; CPU: 54.0%; Read bytes: 34712576; Write bytes: 554107392; Read time: 332; Write time: 1661 04:43:40 INFO - TinderboxPrint: CPU usage
53.6% 04:43:40 INFO - TinderboxPrint: I/O read bytes / time
34,712,576 / 332 04:43:40 INFO - TinderboxPrint: I/O write bytes / time
554,107,392 / 1,661 04:43:40 INFO - TinderboxPrint: CPU idle
2,237.3 (46.4%) 04:43:40 INFO - TinderboxPrint: CPU system
956.2 (19.8%) 04:43:40 INFO - TinderboxPrint: CPU user
1,624.1 (33.7%) 04:43:40 INFO - TinderboxPrint: Swap in / out
281,030,656 / 0 04:43:40 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 244220416; Write bytes: 238872064; Read time: 18636; Write time: 493 04:43:41 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 32582656; Write bytes: 297831424; Read time: 307; Write time: 1122 04:43:41 INFO - Running post-run listener: _upload_blobber_files 04:43:41 INFO - Blob upload gear active. 04:43:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:43:41 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:43:41 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:43:41 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:43:41 INFO - (blobuploader) - INFO - Open directory for files ... 04:43:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:43:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:43:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:43:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:43:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:43:42 INFO - (blobuploader) - INFO - Done attempting. 04:43:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:43:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:43:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:43:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:43:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:43:43 INFO - (blobuploader) - INFO - Done attempting. 04:43:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:43:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:43:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:43:44 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:43:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:43:44 INFO - (blobuploader) - INFO - Done attempting. 04:43:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:43:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:43:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:43:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:43:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:43:47 INFO - (blobuploader) - INFO - Done attempting. 04:43:47 INFO - (blobuploader) - INFO - Iteration through files over. 04:43:47 INFO - Return code: 0 04:43:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:43:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:43:47 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dfe52a5aecfd7c0adf94328c596afaf8beadf54378f0ee168c374d9fd41c12e4ebcb1741577629d9bb75bcab7291202694e0ddf445580bb2adce4c577cda00bd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e9fde4350347cca19d874b5bd59f92a952db48c4f6d7a76f6f365050ac38358f94f9658d8ae0e6d2c51d87f265ac9c0ac223724609930be7aa91629743ba1d2d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cdbe71e760d85e337f0166a4329c644538a4d5a87feb95104511a37f6b3a19a623fac11d890ad1354f8abe5b941fcf5b8fa51d40a4637959762628752a762ffb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/476c80156dfa913cb55de6f7eef3b77fbe07b696c253a06ea30a73a292e4cafd5430569b9c440afc0d781afd8642dd4f5da4532c71172d2a9d24bdb683832324"} 04:43:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:43:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:43:47 INFO - Contents: 04:43:47 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dfe52a5aecfd7c0adf94328c596afaf8beadf54378f0ee168c374d9fd41c12e4ebcb1741577629d9bb75bcab7291202694e0ddf445580bb2adce4c577cda00bd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e9fde4350347cca19d874b5bd59f92a952db48c4f6d7a76f6f365050ac38358f94f9658d8ae0e6d2c51d87f265ac9c0ac223724609930be7aa91629743ba1d2d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cdbe71e760d85e337f0166a4329c644538a4d5a87feb95104511a37f6b3a19a623fac11d890ad1354f8abe5b941fcf5b8fa51d40a4637959762628752a762ffb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/476c80156dfa913cb55de6f7eef3b77fbe07b696c253a06ea30a73a292e4cafd5430569b9c440afc0d781afd8642dd4f5da4532c71172d2a9d24bdb683832324"} 04:43:47 INFO - Running post-run listener: copy_logs_to_upload_dir 04:43:47 INFO - Copying logs to upload dir... 04:43:47 INFO - mkdir: /builds/slave/test/build/upload/logs 04:43:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.701541 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2017-02-04 04:43:47.111731) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-04 04:43:47.114961) ========= 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.me28Byi9vq/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.iItRurIuej/Listeners TMPDIR=/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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/dfe52a5aecfd7c0adf94328c596afaf8beadf54378f0ee168c374d9fd41c12e4ebcb1741577629d9bb75bcab7291202694e0ddf445580bb2adce4c577cda00bd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e9fde4350347cca19d874b5bd59f92a952db48c4f6d7a76f6f365050ac38358f94f9658d8ae0e6d2c51d87f265ac9c0ac223724609930be7aa91629743ba1d2d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cdbe71e760d85e337f0166a4329c644538a4d5a87feb95104511a37f6b3a19a623fac11d890ad1354f8abe5b941fcf5b8fa51d40a4637959762628752a762ffb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/476c80156dfa913cb55de6f7eef3b77fbe07b696c253a06ea30a73a292e4cafd5430569b9c440afc0d781afd8642dd4f5da4532c71172d2a9d24bdb683832324"} build_url:https://queue.taskcluster.net/v1/task/NS6fQSoISISw3FvvWU7BUA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010993 build_url: 'https://queue.taskcluster.net/v1/task/NS6fQSoISISw3FvvWU7BUA/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/dfe52a5aecfd7c0adf94328c596afaf8beadf54378f0ee168c374d9fd41c12e4ebcb1741577629d9bb75bcab7291202694e0ddf445580bb2adce4c577cda00bd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e9fde4350347cca19d874b5bd59f92a952db48c4f6d7a76f6f365050ac38358f94f9658d8ae0e6d2c51d87f265ac9c0ac223724609930be7aa91629743ba1d2d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cdbe71e760d85e337f0166a4329c644538a4d5a87feb95104511a37f6b3a19a623fac11d890ad1354f8abe5b941fcf5b8fa51d40a4637959762628752a762ffb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/476c80156dfa913cb55de6f7eef3b77fbe07b696c253a06ea30a73a292e4cafd5430569b9c440afc0d781afd8642dd4f5da4532c71172d2a9d24bdb683832324"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-04 04:43:47.148795) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-04 04:43:47.149123) ========= 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.me28Byi9vq/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.iItRurIuej/Listeners TMPDIR=/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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.005103 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-04 04:43:47.197536) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-04 04:43:47.197857) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-04 04:43:47.198169) ========= ========= Total master_lag: 0.18 =========