builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0023 starttime: 1486643291.14 results: success (0) buildid: 20170209030214 builduid: 7cc43ce136944e6cb14b78737e5b7042 revision: b772e0f4138540113e91a46c99bb0d14ecc8acca ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-09 04:28:11.143588) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-09 04:28:11.144062) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-09 04:28:11.144355) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-09 04:28:11.169307) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 04:28:11.169705) ========= 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.Q4nofNE2zL/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.ABVtj1RE5R/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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-09 04:28:11-- 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.4M=0.001s 2017-02-09 04:28:11 (20.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.145863 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 04:28:11.339309) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 04:28:11.339626) ========= 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.Q4nofNE2zL/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.ABVtj1RE5R/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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.020992 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 04:28:11.388046) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-09 04:28:11.388402) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b772e0f4138540113e91a46c99bb0d14ecc8acca --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 b772e0f4138540113e91a46c99bb0d14ecc8acca --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Q4nofNE2zL/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.ABVtj1RE5R/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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-09 04:28:11,473 truncating revision to first 12 chars 2017-02-09 04:28:11,473 Setting DEBUG logging. 2017-02-09 04:28:11,473 attempt 1/10 2017-02-09 04:28:11,473 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b772e0f41385?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-09 04:28:12,678 unpacking tar archive at: mozilla-central-b772e0f41385/testing/mozharness/ program finished with exit code 0 elapsedTime=1.472934 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-09 04:28:12.884868) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-09 04:28:12.885237) ========= script_repo_revision: b772e0f4138540113e91a46c99bb0d14ecc8acca ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-09 04:28:12.885620) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-09 04:28:12.885886) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-09 04:28:12.905774) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-02-09 04:28:12.906111) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.Q4nofNE2zL/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.ABVtj1RE5R/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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:28:13 INFO - MultiFileLogger online at 20170209 04:28:13 in /builds/slave/test 04:28:13 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:28:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:28:13 INFO - {'append_to_log': False, 04:28:13 INFO - 'base_work_dir': '/builds/slave/test', 04:28:13 INFO - 'blob_upload_branch': 'Firefox', 04:28:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:28:13 INFO - 'branch': 'Firefox', 04:28:13 INFO - 'buildbot_json_path': 'buildprops.json', 04:28:13 INFO - 'config_files': ('talos/mac_config.py',), 04:28:13 INFO - 'default_actions': ('clobber', 04:28:13 INFO - 'read-buildbot-config', 04:28:13 INFO - 'download-and-extract', 04:28:13 INFO - 'populate-webroot', 04:28:13 INFO - 'create-virtualenv', 04:28:13 INFO - 'install', 04:28:13 INFO - 'run-tests'), 04:28:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:28:13 INFO - 'download_minidump_stackwalk': True, 04:28:13 INFO - 'download_symbols': 'ondemand', 04:28:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:28:13 INFO - 'tooltool.py': '/tools/tooltool.py', 04:28:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:28:13 INFO - '/tools/misc-python/virtualenv.py')}, 04:28:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:28:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:28:13 INFO - 'installer_path': 'installer.exe', 04:28:13 INFO - 'log_level': 'info', 04:28:13 INFO - 'log_name': 'talos', 04:28:13 INFO - 'log_to_console': True, 04:28:13 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:28:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:28:13 INFO - 'opt_config_files': (), 04:28:13 INFO - 'pip_index': False, 04:28:13 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:28:13 INFO - 'cmd': ('bash', 04:28:13 INFO - '-c', 04:28:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:28:13 INFO - 'enabled': True, 04:28:13 INFO - 'halt_on_failure': False, 04:28:13 INFO - 'name': 'check_screen_resolution'},), 04:28:13 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:28:13 INFO - 'cmd': ('bash', 04:28:13 INFO - '-c', 04:28:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:28:13 INFO - 'enabled': True, 04:28:13 INFO - 'halt_on_failure': False, 04:28:13 INFO - 'name': 'check_screen_resolution'},), 04:28:13 INFO - 'run_cmd_checks_enabled': True, 04:28:13 INFO - 'sps_profile': False, 04:28:13 INFO - 'sps_profile_interval': 0, 04:28:13 INFO - 'suite': 'dromaeojs-e10s', 04:28:13 INFO - 'system_bits': '32', 04:28:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:28:13 INFO - 'title': 't-yosemite-r7-0023', 04:28:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:28:13 INFO - 'use_talos_json': True, 04:28:13 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:28:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:28:13 INFO - 'work_dir': 'build'} 04:28:13 INFO - [mozharness: 2017-02-09 12:28:13.114309Z] Running clobber step. 04:28:13 INFO - Running pre-action listener: _resource_record_pre_action 04:28:13 INFO - Running main action method: clobber 04:28:13 INFO - rmtree: /builds/slave/test/build 04:28:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:28:13 INFO - Running post-action listener: _resource_record_post_action 04:28:13 INFO - [mozharness: 2017-02-09 12:28:13.310121Z] Finished clobber step (success) 04:28:13 INFO - [mozharness: 2017-02-09 12:28:13.310240Z] Running read-buildbot-config step. 04:28:13 INFO - Running pre-action listener: _resource_record_pre_action 04:28:13 INFO - Running main action method: read_buildbot_config 04:28:13 INFO - Using buildbot properties: 04:28:13 INFO - { 04:28:13 INFO - "project": "", 04:28:13 INFO - "product": "firefox", 04:28:13 INFO - "script_repo_revision": "production", 04:28:13 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 04:28:13 INFO - "repository": "", 04:28:13 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 04:28:13 INFO - "buildid": "20170209030214", 04:28:13 INFO - "stage_platform": "macosx64", 04:28:13 INFO - "basedir": "/builds/slave/test", 04:28:13 INFO - "nightly_build": "True", 04:28:13 INFO - "buildnumber": 79, 04:28:13 INFO - "slavename": "t-yosemite-r7-0023", 04:28:13 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 04:28:13 INFO - "platform": "yosemite_r7", 04:28:13 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:28:13 INFO - "branch": "mozilla-central", 04:28:13 INFO - "repo_path": "mozilla-central", 04:28:13 INFO - "revision": "b772e0f4138540113e91a46c99bb0d14ecc8acca", 04:28:13 INFO - "pgo_build": "False", 04:28:13 INFO - "builduid": "7cc43ce136944e6cb14b78737e5b7042", 04:28:13 INFO - "slavebuilddir": "test" 04:28:13 INFO - } 04:28:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/DaDNVud2SSWuqkyX4aZNPw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 04:28:13 INFO - Running post-action listener: _resource_record_post_action 04:28:13 INFO - [mozharness: 2017-02-09 12:28:13.313279Z] Finished read-buildbot-config step (success) 04:28:13 INFO - [mozharness: 2017-02-09 12:28:13.313385Z] Running download-and-extract step. 04:28:13 INFO - Running pre-action listener: _resource_record_pre_action 04:28:13 INFO - Running main action method: download_and_extract 04:28:13 INFO - mkdir: /builds/slave/test/build/tests 04:28: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:28:13 INFO - trying https://queue.taskcluster.net/v1/task/DaDNVud2SSWuqkyX4aZNPw/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 04:28:13 INFO - Downloading https://queue.taskcluster.net/v1/task/DaDNVud2SSWuqkyX4aZNPw/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:28:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DaDNVud2SSWuqkyX4aZNPw/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:28:14 INFO - Downloaded 1199 bytes. 04:28:14 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 04:28:14 INFO - Using the following test package requirements: 04:28:14 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 04:28:14 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:28:14 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 04:28:14 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:28:14 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 04:28:14 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:28:14 INFO - u'jsshell-mac.zip'], 04:28:14 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:28:14 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 04:28:14 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 04:28:14 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:28:14 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 04:28:14 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:28:14 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 04:28:14 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:28:14 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 04:28:14 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:28:14 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 04:28:14 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:28:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DaDNVud2SSWuqkyX4aZNPw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 04:28:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DaDNVud2SSWuqkyX4aZNPw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:28:14 INFO - Fetch https://queue.taskcluster.net/v1/task/DaDNVud2SSWuqkyX4aZNPw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 04:28:16 INFO - Expected file size: 34939821 04:28:16 INFO - Obtained file size: 34939821 04:28:23 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:28:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DaDNVud2SSWuqkyX4aZNPw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 04:28:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DaDNVud2SSWuqkyX4aZNPw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:28:23 INFO - Fetch https://queue.taskcluster.net/v1/task/DaDNVud2SSWuqkyX4aZNPw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 04:28:24 INFO - Expected file size: 34939821 04:28:24 INFO - Obtained file size: 34939821 04:28:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DaDNVud2SSWuqkyX4aZNPw/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 04:28:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DaDNVud2SSWuqkyX4aZNPw/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:28:30 INFO - Fetch https://queue.taskcluster.net/v1/task/DaDNVud2SSWuqkyX4aZNPw/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 04:28:31 INFO - Expected file size: 14403454 04:28:31 INFO - Obtained file size: 14403454 04:28:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:28:32 INFO - trying https://queue.taskcluster.net/v1/task/DaDNVud2SSWuqkyX4aZNPw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 04:28:32 INFO - Downloading https://queue.taskcluster.net/v1/task/DaDNVud2SSWuqkyX4aZNPw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 04:28:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DaDNVud2SSWuqkyX4aZNPw/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:28:34 INFO - Downloaded 70372404 bytes. 04:28:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DaDNVud2SSWuqkyX4aZNPw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 04:28:34 INFO - mkdir: /builds/slave/test/properties 04:28:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:28:34 INFO - Writing to file /builds/slave/test/properties/build_url 04:28:34 INFO - Contents: 04:28:34 INFO - build_url:https://queue.taskcluster.net/v1/task/DaDNVud2SSWuqkyX4aZNPw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 04:28:34 INFO - Running post-action listener: _resource_record_post_action 04:28:34 INFO - Running post-action listener: set_extra_try_arguments 04:28:34 INFO - [mozharness: 2017-02-09 12:28:34.784104Z] Finished download-and-extract step (success) 04:28:34 INFO - [mozharness: 2017-02-09 12:28:34.784213Z] Running populate-webroot step. 04:28:34 INFO - Running pre-action listener: _resource_record_pre_action 04:28:34 INFO - Running main action method: populate_webroot 04:28:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:28:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:28:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:28:34 INFO - '--tppagecycles', 04:28:34 INFO - '7'], 04:28:34 INFO - 'tests': ['tsvgm']}}, 04:28:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:28:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:28:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:28:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:28:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:28:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:28:34 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:28:34 INFO - 'talos_options': ['--disable-e10s'], 04:28:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:28:34 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:28:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:28:34 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:28:34 INFO - 'talos_options': ['--disable-e10s'], 04:28:34 INFO - 'tests': ['damp', 'tps']}, 04:28:34 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:28:34 INFO - 'tests': ['damp', 'tps']}, 04:28:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:28:34 INFO - 'tests': ['dromaeo_dom']}, 04:28:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:28:34 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:28:34 INFO - 'tests': ['basic_compositor_video']}, 04:28:34 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:28:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:28:34 INFO - 'tests': ['a11yr', 04:28:34 INFO - 'ts_paint', 04:28:34 INFO - 'tpaint', 04:28:34 INFO - 'sessionrestore', 04:28:34 INFO - 'sessionrestore_no_auto_restore', 04:28:34 INFO - 'tabpaint']}, 04:28:34 INFO - 'other-e10s': {'tests': ['a11yr', 04:28:34 INFO - 'ts_paint', 04:28:34 INFO - 'tpaint', 04:28:34 INFO - 'sessionrestore', 04:28:34 INFO - 'sessionrestore_no_auto_restore', 04:28:34 INFO - 'tabpaint']}, 04:28:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:28:34 INFO - 'ts_paint', 04:28:34 INFO - 'tpaint', 04:28:34 INFO - 'sessionrestore', 04:28:34 INFO - 'sessionrestore_no_auto_restore', 04:28:34 INFO - 'tabpaint']}, 04:28:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:28:34 INFO - 'ts_paint', 04:28:34 INFO - 'tpaint', 04:28:34 INFO - 'sessionrestore', 04:28:34 INFO - 'sessionrestore_no_auto_restore', 04:28:34 INFO - 'tabpaint']}, 04:28:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:28:34 INFO - 'tests': ['a11yr', 04:28:34 INFO - 'ts_paint', 04:28:34 INFO - 'tpaint', 04:28:34 INFO - 'sessionrestore', 04:28:34 INFO - 'sessionrestore_no_auto_restore', 04:28:34 INFO - 'tabpaint']}, 04:28:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:28:34 INFO - 'tests': ['a11yr', 04:28:34 INFO - 'ts_paint', 04:28:34 INFO - 'tpaint', 04:28:34 INFO - 'sessionrestore', 04:28:34 INFO - 'sessionrestore_no_auto_restore', 04:28:34 INFO - 'tabpaint']}, 04:28:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:28:34 INFO - 'tests': ['tsvgx', 04:28:34 INFO - 'tsvgr_opacity', 04:28:34 INFO - 'tart', 04:28:34 INFO - 'tscrollx', 04:28:34 INFO - 'cart', 04:28:34 INFO - 'tsvg_static']}, 04:28:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:28:34 INFO - 'tsvgr_opacity', 04:28:34 INFO - 'tart', 04:28:34 INFO - 'tscrollx', 04:28:34 INFO - 'cart', 04:28:34 INFO - 'tsvg_static']}, 04:28:34 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:28:34 INFO - 'talos_options': ['--disable-e10s'], 04:28:34 INFO - 'tests': ['tp5o']}, 04:28:34 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:28:34 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:28:34 INFO - 'talos_options': ['--disable-e10s', 04:28:34 INFO - '--xperf_path', 04:28:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:28:34 INFO - 'tests': ['tp5n']}, 04:28:34 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:28:34 INFO - 'talos_options': ['--xperf_path', 04:28:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:28:34 INFO - 'tests': ['tp5n']}}, 04:28:34 INFO - 'talos.zip': {'path': '', 04:28:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:28:34 INFO - Running post-action listener: _resource_record_post_action 04:28:34 INFO - [mozharness: 2017-02-09 12:28:34.802611Z] Finished populate-webroot step (success) 04:28:34 INFO - [mozharness: 2017-02-09 12:28:34.802742Z] Running create-virtualenv step. 04:28:34 INFO - Running pre-action listener: _resource_record_pre_action 04:28:34 INFO - Running main action method: create_virtualenv 04:28:34 INFO - Creating virtualenv /builds/slave/test/build/venv 04:28:34 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:28:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:28:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:28:34 INFO - Using real prefix '/tools/python27' 04:28:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:28:35 INFO - Installing distribute.............................................................................................................................................................................................done. 04:28:38 INFO - Installing pip.................done. 04:28:38 INFO - Return code: 0 04:28:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:28:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:28:38 INFO - Reading from file tmpfile_stdout 04:28:38 INFO - Output received: 04:28:38 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:28:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:28:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:28:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:28:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:28:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:28:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:28:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:28:38 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 0x102e3cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e3f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96394c0bb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102c1ad30>, '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 0x102b41db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102c0fbe8>, '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.Q4nofNE2zL/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.ABVtj1RE5R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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:28:38 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:28:38 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:28:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q4nofNE2zL/Render', 04:28:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:28:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:28:38 INFO - 'HOME': '/Users/cltbld', 04:28:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:28:38 INFO - 'LOGNAME': 'cltbld', 04:28:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:28:38 INFO - 'MOZ_NO_REMOTE': '1', 04:28:38 INFO - 'NO_EM_RESTART': '1', 04:28:38 INFO - 'PAGER': '/bin/cat', 04:28:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:28:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:28:38 INFO - 'PWD': '/builds/slave/test', 04:28:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:28:38 INFO - 'SHELL': '/bin/bash', 04:28:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ABVtj1RE5R/Listeners', 04:28:38 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 04:28:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:28:38 INFO - 'USER': 'cltbld', 04:28:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:28:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:28:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:28:38 INFO - 'XPC_FLAGS': '0x0', 04:28:38 INFO - 'XPC_SERVICE_NAME': '0', 04:28:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:28:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:28:39 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:28:39 INFO - Cleaning up... 04:28:39 INFO - Return code: 0 04:28:39 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:28:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:28:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:28:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:28:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:28:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:28:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:28:39 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 0x102e3cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e3f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96394c0bb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102c1ad30>, '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 0x102b41db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102c0fbe8>, '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.Q4nofNE2zL/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.ABVtj1RE5R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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:28:39 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:28:39 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:28:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q4nofNE2zL/Render', 04:28:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:28:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:28:39 INFO - 'HOME': '/Users/cltbld', 04:28:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:28:39 INFO - 'LOGNAME': 'cltbld', 04:28:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:28:39 INFO - 'MOZ_NO_REMOTE': '1', 04:28:39 INFO - 'NO_EM_RESTART': '1', 04:28:39 INFO - 'PAGER': '/bin/cat', 04:28:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:28:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:28:39 INFO - 'PWD': '/builds/slave/test', 04:28:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:28:39 INFO - 'SHELL': '/bin/bash', 04:28:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ABVtj1RE5R/Listeners', 04:28:39 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 04:28:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:28:39 INFO - 'USER': 'cltbld', 04:28:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:28:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:28:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:28:39 INFO - 'XPC_FLAGS': '0x0', 04:28:39 INFO - 'XPC_SERVICE_NAME': '0', 04:28:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:28:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:28:39 INFO - Downloading/unpacking psutil>=3.1.1 04:28:39 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:28:39 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:28:39 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:28:39 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:28:39 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:28:39 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:28:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:28:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:28:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:28:42 INFO - Installing collected packages: psutil 04:28:42 INFO - Running setup.py install for psutil 04:28:43 INFO - building 'psutil._psutil_osx' extension 04:28:43 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:28:43 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:28:43 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:28:43 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:28:43 INFO - building 'psutil._psutil_posix' extension 04:28:43 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:28:43 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:28:43 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:28:43 INFO - ^ 04:28:43 INFO - 1 warning generated. 04:28:43 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:28:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:28:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:28:43 INFO - Successfully installed psutil 04:28:43 INFO - Cleaning up... 04:28:43 INFO - Return code: 0 04:28:43 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:28:43 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:28:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:28:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:28:43 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:28:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:28:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:28:43 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 0x102e3cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e3f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96394c0bb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102c1ad30>, '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 0x102b41db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102c0fbe8>, '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.Q4nofNE2zL/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.ABVtj1RE5R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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:28:43 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:28:43 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:28:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q4nofNE2zL/Render', 04:28:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:28:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:28:43 INFO - 'HOME': '/Users/cltbld', 04:28:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:28:43 INFO - 'LOGNAME': 'cltbld', 04:28:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:28:43 INFO - 'MOZ_NO_REMOTE': '1', 04:28:43 INFO - 'NO_EM_RESTART': '1', 04:28:43 INFO - 'PAGER': '/bin/cat', 04:28:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:28:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:28:43 INFO - 'PWD': '/builds/slave/test', 04:28:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:28:43 INFO - 'SHELL': '/bin/bash', 04:28:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ABVtj1RE5R/Listeners', 04:28:43 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 04:28:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:28:43 INFO - 'USER': 'cltbld', 04:28:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:28:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:28:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:28:43 INFO - 'XPC_FLAGS': '0x0', 04:28:43 INFO - 'XPC_SERVICE_NAME': '0', 04:28:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:28:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:28:44 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:28:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:28:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:28:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:28:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:28:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:28:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:28:46 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:28:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:28:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:28:46 INFO - Installing collected packages: mozsystemmonitor 04:28:46 INFO - Running setup.py install for mozsystemmonitor 04:28:46 INFO - Successfully installed mozsystemmonitor 04:28:46 INFO - Cleaning up... 04:28:47 INFO - Return code: 0 04:28:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:28:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:28:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:28:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:28:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:28:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:28:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:28:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x102e3cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e3f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96394c0bb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102c1ad30>, '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 0x102b41db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102c0fbe8>, '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.Q4nofNE2zL/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.ABVtj1RE5R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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:28:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:28:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:28:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q4nofNE2zL/Render', 04:28:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:28:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:28:47 INFO - 'HOME': '/Users/cltbld', 04:28:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:28:47 INFO - 'LOGNAME': 'cltbld', 04:28:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:28:47 INFO - 'MOZ_NO_REMOTE': '1', 04:28:47 INFO - 'NO_EM_RESTART': '1', 04:28:47 INFO - 'PAGER': '/bin/cat', 04:28:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:28:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:28:47 INFO - 'PWD': '/builds/slave/test', 04:28:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:28:47 INFO - 'SHELL': '/bin/bash', 04:28:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ABVtj1RE5R/Listeners', 04:28:47 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 04:28:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:28:47 INFO - 'USER': 'cltbld', 04:28:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:28:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:28:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:28:47 INFO - 'XPC_FLAGS': '0x0', 04:28:47 INFO - 'XPC_SERVICE_NAME': '0', 04:28:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:28:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:28:47 INFO - Downloading/unpacking jsonschema==2.5.1 04:28:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:28:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:28:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:28:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:28:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:28:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:28:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:28:49 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:28:49 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:28:49 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:28:49 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:28:49 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:28:49 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:28:49 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:28:50 INFO - Downloading functools32-3.2.3-2.tar.gz 04:28:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:28:50 INFO - warning: no files found matching '*.txt' 04:28:50 INFO - no previously-included directories found matching 'build' 04:28:50 INFO - no previously-included directories found matching 'dist' 04:28:50 INFO - no previously-included directories found matching '.git*' 04:28:50 INFO - Installing collected packages: jsonschema, functools32 04:28:50 INFO - Running setup.py install for functools32 04:28:50 INFO - warning: no files found matching '*.txt' 04:28:50 INFO - no previously-included directories found matching 'build' 04:28:50 INFO - no previously-included directories found matching 'dist' 04:28:50 INFO - no previously-included directories found matching '.git*' 04:28:50 INFO - Successfully installed jsonschema functools32 04:28:50 INFO - Cleaning up... 04:28:50 INFO - Return code: 0 04:28:50 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:28:50 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:28:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:28:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:28:50 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:28:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:28:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:28:50 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 0x102e3cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e3f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96394c0bb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102c1ad30>, '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 0x102b41db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102c0fbe8>, '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.Q4nofNE2zL/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.ABVtj1RE5R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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:28:50 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:28:50 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:28:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q4nofNE2zL/Render', 04:28:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:28:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:28:50 INFO - 'HOME': '/Users/cltbld', 04:28:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:28:50 INFO - 'LOGNAME': 'cltbld', 04:28:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:28:50 INFO - 'MOZ_NO_REMOTE': '1', 04:28:50 INFO - 'NO_EM_RESTART': '1', 04:28:50 INFO - 'PAGER': '/bin/cat', 04:28:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:28:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:28:50 INFO - 'PWD': '/builds/slave/test', 04:28:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:28:50 INFO - 'SHELL': '/bin/bash', 04:28:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ABVtj1RE5R/Listeners', 04:28:50 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 04:28:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:28:50 INFO - 'USER': 'cltbld', 04:28:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:28:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:28:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:28:50 INFO - 'XPC_FLAGS': '0x0', 04:28:50 INFO - 'XPC_SERVICE_NAME': '0', 04:28:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:28:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:28:50 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:28:50 INFO - Cleaning up... 04:28:50 INFO - Return code: 0 04:28:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:28:50 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:28:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:28:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:28:50 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:28:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:28:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:28:50 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 0x102e3cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e3f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96394c0bb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102c1ad30>, '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 0x102b41db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102c0fbe8>, '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.Q4nofNE2zL/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.ABVtj1RE5R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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:28:50 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:28:50 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:28:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q4nofNE2zL/Render', 04:28:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:28:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:28:50 INFO - 'HOME': '/Users/cltbld', 04:28:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:28:50 INFO - 'LOGNAME': 'cltbld', 04:28:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:28:50 INFO - 'MOZ_NO_REMOTE': '1', 04:28:50 INFO - 'NO_EM_RESTART': '1', 04:28:50 INFO - 'PAGER': '/bin/cat', 04:28:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:28:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:28:50 INFO - 'PWD': '/builds/slave/test', 04:28:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:28:50 INFO - 'SHELL': '/bin/bash', 04:28:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ABVtj1RE5R/Listeners', 04:28:50 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 04:28:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:28:50 INFO - 'USER': 'cltbld', 04:28:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:28:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:28:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:28:50 INFO - 'XPC_FLAGS': '0x0', 04:28:50 INFO - 'XPC_SERVICE_NAME': '0', 04:28:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:28:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:28:51 INFO - Downloading/unpacking blobuploader==1.2.4 04:28:51 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:28:51 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:28:51 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:28:51 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:28:51 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:28:51 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:28:53 INFO - Downloading blobuploader-1.2.4.tar.gz 04:28:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:28:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:28:53 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:28:53 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:28:53 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:28:53 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:28:53 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:28:53 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:28:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:28:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:28:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:28:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:28:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:28:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:28:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:28:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:28:55 INFO - Downloading docopt-0.6.1.tar.gz 04:28:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:28:55 INFO - Installing collected packages: blobuploader, requests, docopt 04:28:55 INFO - Running setup.py install for blobuploader 04:28:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:28:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:28:55 INFO - Running setup.py install for requests 04:28:55 INFO - Running setup.py install for docopt 04:28:55 INFO - Successfully installed blobuploader requests docopt 04:28:55 INFO - Cleaning up... 04:28:55 INFO - Return code: 0 04:28:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:28:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:28:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:28:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:28:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:28:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:28:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:28:55 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 0x102e3cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e3f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96394c0bb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102c1ad30>, '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 0x102b41db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102c0fbe8>, '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.Q4nofNE2zL/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.ABVtj1RE5R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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:28:55 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:28:55 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:28:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q4nofNE2zL/Render', 04:28:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:28:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:28:55 INFO - 'HOME': '/Users/cltbld', 04:28:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:28:55 INFO - 'LOGNAME': 'cltbld', 04:28:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:28:55 INFO - 'MOZ_NO_REMOTE': '1', 04:28:55 INFO - 'NO_EM_RESTART': '1', 04:28:55 INFO - 'PAGER': '/bin/cat', 04:28:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:28:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:28:55 INFO - 'PWD': '/builds/slave/test', 04:28:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:28:55 INFO - 'SHELL': '/bin/bash', 04:28:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ABVtj1RE5R/Listeners', 04:28:55 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 04:28:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:28:55 INFO - 'USER': 'cltbld', 04:28:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:28:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:28:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:28:55 INFO - 'XPC_FLAGS': '0x0', 04:28:55 INFO - 'XPC_SERVICE_NAME': '0', 04:28:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:28:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:28:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:28:56 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-N9L6Hp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:28:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:28:56 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-Q09fs5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:28:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:28:56 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-TVdtjD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:28:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:28:56 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-wk7gAb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:28:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:28:56 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-3j9oxV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:28:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:28:56 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-nG8A5I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:28:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:28:56 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-PoLdYv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:28:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:28:56 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-xpFvCQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:28:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:28:57 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-FSsqYg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:28:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:28:57 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-MVspnK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:28:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:28:57 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-RbPse_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:28:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:28:57 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-Z33sM6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:28:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:28:57 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-O0Uarf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:28:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:28:57 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-NraWSn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:28:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:28:57 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-QtzUoE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:28:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:28:57 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-7iXxen-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:28:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:28:57 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-HpmRDE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:28:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:28:58 INFO - Running setup.py install for manifestparser 04:28:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:28:58 INFO - Running setup.py install for mozcrash 04:28:58 INFO - Running setup.py install for mozdebug 04:28:58 INFO - Running setup.py install for mozdevice 04:28:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:28:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:28:58 INFO - Running setup.py install for mozfile 04:28:58 INFO - Running setup.py install for mozhttpd 04:28:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:28:58 INFO - Running setup.py install for mozinfo 04:28:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:28:59 INFO - Running setup.py install for mozInstall 04:28:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:28:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:28:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:28:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:28:59 INFO - Running setup.py install for mozleak 04:28:59 INFO - Running setup.py install for mozlog 04:28:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:28:59 INFO - Running setup.py install for moznetwork 04:28:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:28:59 INFO - Running setup.py install for mozprocess 04:28:59 INFO - Running setup.py install for mozprofile 04:28:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:28:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:28:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:28:59 INFO - Running setup.py install for mozrunner 04:29:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:29:00 INFO - Running setup.py install for mozscreenshot 04:29:00 INFO - Running setup.py install for moztest 04:29:00 INFO - Running setup.py install for mozversion 04:29:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:29:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:29:00 INFO - Cleaning up... 04:29:00 INFO - Return code: 0 04:29:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:29: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:29:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:29:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29: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:29:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:29:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:00 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 0x102e3cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e3f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96394c0bb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102c1ad30>, '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 0x102b41db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102c0fbe8>, '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.Q4nofNE2zL/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.ABVtj1RE5R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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:29:00 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:29:00 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:29:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q4nofNE2zL/Render', 04:29:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:29:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:29:00 INFO - 'HOME': '/Users/cltbld', 04:29:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:29:00 INFO - 'LOGNAME': 'cltbld', 04:29:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:29:00 INFO - 'MOZ_NO_REMOTE': '1', 04:29:00 INFO - 'NO_EM_RESTART': '1', 04:29:00 INFO - 'PAGER': '/bin/cat', 04:29:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:29:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:29:00 INFO - 'PWD': '/builds/slave/test', 04:29:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:29:00 INFO - 'SHELL': '/bin/bash', 04:29:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ABVtj1RE5R/Listeners', 04:29:00 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 04:29:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:29:00 INFO - 'USER': 'cltbld', 04:29:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:29:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:29:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:29:00 INFO - 'XPC_FLAGS': '0x0', 04:29:00 INFO - 'XPC_SERVICE_NAME': '0', 04:29:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:29:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:29:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:29:00 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-HZ0Oc3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:29:00 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:29:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:29:00 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-UJ9zDr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:29:01 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:29:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:29:01 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-Bzd_cZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:29:01 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:29:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:29:01 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-Jfw_4U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:29:01 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:29:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:29:01 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-4FqW4Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:29:01 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:29:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:29:01 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-gi3b7I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:29:01 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:29:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:29:01 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-bAT4u6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:29:01 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:29:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:29:01 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-03vDWo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:29:01 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:29:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:29:01 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-oETEAI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:29:01 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:29:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:29:01 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-nCIqwt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:29:01 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:29:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:29:01 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-iGDURo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:29:02 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:29:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:29:02 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-OheDjx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:29:02 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:29:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:29:02 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-f7m6iX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:29:02 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:29:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:29:02 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-zA0aY5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:29:02 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:29:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:29:02 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-M0kh9k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:29:02 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:29:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:29:02 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-0YNDuT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:29:02 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:29:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:29:02 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-u6JMt6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:29:02 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:29:02 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:29:02 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:29:02 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:29:02 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:29:02 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:29:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:29:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:29:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:29:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:29:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:29:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:29:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:29:05 INFO - Downloading blessings-1.6.tar.gz 04:29:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:29:05 INFO - Installing collected packages: blessings 04:29:05 INFO - Running setup.py install for blessings 04:29:05 INFO - Successfully installed blessings 04:29:05 INFO - Cleaning up... 04:29:05 INFO - Return code: 0 04:29:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:29:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:29:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:29:05 INFO - Reading from file tmpfile_stdout 04:29:05 INFO - Current package versions: 04:29:05 INFO - blessings == 1.6 04:29:05 INFO - blobuploader == 1.2.4 04:29:05 INFO - docopt == 0.6.1 04:29:05 INFO - functools32 == 3.2.3-2 04:29:05 INFO - jsonschema == 2.5.1 04:29:05 INFO - manifestparser == 1.1 04:29:05 INFO - mozInstall == 1.12 04:29:05 INFO - mozcrash == 1.0 04:29:05 INFO - mozdebug == 0.1 04:29:05 INFO - mozdevice == 0.49 04:29:05 INFO - mozfile == 1.2 04:29:05 INFO - mozhttpd == 0.7 04:29:05 INFO - mozinfo == 0.9 04:29:05 INFO - mozleak == 0.1 04:29:05 INFO - mozlog == 3.4 04:29:05 INFO - moznetwork == 0.27 04:29:05 INFO - mozprocess == 0.24 04:29:05 INFO - mozprofile == 0.28 04:29:05 INFO - mozrunner == 6.13 04:29:05 INFO - mozscreenshot == 0.1 04:29:05 INFO - mozsystemmonitor == 0.3 04:29:05 INFO - moztest == 0.8 04:29:05 INFO - mozversion == 1.4 04:29:05 INFO - psutil == 3.1.1 04:29:05 INFO - requests == 1.2.3 04:29:05 INFO - wsgiref == 0.1.2 04:29:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:29:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:29:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:29:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:29:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:29:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:05 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 0x102e3cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e3f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96394c0bb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102c1ad30>, '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 0x102b41db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102c0fbe8>, '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.Q4nofNE2zL/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.ABVtj1RE5R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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:29:05 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:29:05 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:29:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q4nofNE2zL/Render', 04:29:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:29:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:29:05 INFO - 'HOME': '/Users/cltbld', 04:29:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:29:05 INFO - 'LOGNAME': 'cltbld', 04:29:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:29:05 INFO - 'MOZ_NO_REMOTE': '1', 04:29:05 INFO - 'NO_EM_RESTART': '1', 04:29:05 INFO - 'PAGER': '/bin/cat', 04:29:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:29:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:29:05 INFO - 'PWD': '/builds/slave/test', 04:29:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:29:05 INFO - 'SHELL': '/bin/bash', 04:29:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ABVtj1RE5R/Listeners', 04:29:05 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 04:29:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:29:05 INFO - 'USER': 'cltbld', 04:29:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:29:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:29:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:29:05 INFO - 'XPC_FLAGS': '0x0', 04:29:05 INFO - 'XPC_SERVICE_NAME': '0', 04:29:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:29:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:29:06 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:29:06 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:29:06 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:29:06 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:29:06 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:29:06 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:29:06 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:29:06 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:29:06 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:29:06 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:29:06 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:29:06 INFO - Cleaning up... 04:29:06 INFO - Return code: 0 04:29:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:29:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:29:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:29:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:29:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:29:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102e3cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e3f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96394c0bb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102c1ad30>, '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 0x102b41db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102c0fbe8>, '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.Q4nofNE2zL/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.ABVtj1RE5R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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:29:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:29:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:29:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q4nofNE2zL/Render', 04:29:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:29:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:29:06 INFO - 'HOME': '/Users/cltbld', 04:29:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:29:06 INFO - 'LOGNAME': 'cltbld', 04:29:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:29:06 INFO - 'MOZ_NO_REMOTE': '1', 04:29:06 INFO - 'NO_EM_RESTART': '1', 04:29:06 INFO - 'PAGER': '/bin/cat', 04:29:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:29:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:29:06 INFO - 'PWD': '/builds/slave/test', 04:29:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:29:06 INFO - 'SHELL': '/bin/bash', 04:29:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ABVtj1RE5R/Listeners', 04:29:06 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 04:29:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:29:06 INFO - 'USER': 'cltbld', 04:29:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:29:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:29:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:29:06 INFO - 'XPC_FLAGS': '0x0', 04:29:06 INFO - 'XPC_SERVICE_NAME': '0', 04:29:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:29:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:29:06 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:29:06 INFO - Cleaning up... 04:29:06 INFO - Return code: 0 04:29:06 INFO - Running post-action listener: _resource_record_post_action 04:29:06 INFO - Running post-action listener: _start_resource_monitoring 04:29:06 INFO - Starting resource monitoring. 04:29:06 INFO - [mozharness: 2017-02-09 12:29:06.522354Z] Finished create-virtualenv step (success) 04:29:06 INFO - [mozharness: 2017-02-09 12:29:06.523299Z] Running install step. 04:29:06 INFO - Running pre-action listener: _resource_record_pre_action 04:29:06 INFO - Running main action method: install 04:29:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:29:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:29:06 INFO - Reading from file tmpfile_stdout 04:29:06 INFO - Detecting whether we're running mozinstall >=1.0... 04:29:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:29:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:29:06 INFO - Reading from file tmpfile_stdout 04:29:06 INFO - Output received: 04:29:06 INFO - Usage: mozinstall [options] installer 04:29:06 INFO - Options: 04:29:06 INFO - -h, --help show this help message and exit 04:29:06 INFO - -d DEST, --destination=DEST 04:29:06 INFO - Directory to install application into. [default: 04:29:06 INFO - "/builds/slave/test"] 04:29:06 INFO - --app=APP Application being installed. [default: firefox] 04:29:06 INFO - mkdir: /builds/slave/test/build/application 04:29:06 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:29:06 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:29:28 INFO - Reading from file tmpfile_stdout 04:29:28 INFO - Output received: 04:29:28 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 04:29:28 INFO - Running post-action listener: _resource_record_post_action 04:29:28 INFO - [mozharness: 2017-02-09 12:29:28.697441Z] Finished install step (success) 04:29:28 INFO - [mozharness: 2017-02-09 12:29:28.697592Z] Running run-tests step. 04:29:28 INFO - Running pre-action listener: _resource_record_pre_action 04:29:28 INFO - Running main action method: run_tests 04:29:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:29:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:29:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:29:28 INFO - 2017-02-09 04:29:28.712 screenresolution[1882:8676] starting screenresolution argv=screenresolution get 04:29:28 INFO - 2017-02-09 04:29:28.714 screenresolution[1882:8676] Display 0: 1600x1200x32@60 04:29:28 INFO - 2017-02-09 04:29:28.720 screenresolution[1883:8678] starting screenresolution argv=screenresolution list 04:29:28 INFO - Available Modes on Display 0 04:29:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:29:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:29:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:29:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:29:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:29:28 INFO - Intel Iris: 04:29:28 INFO - Chipset Model: Intel Iris 04:29:28 INFO - Type: GPU 04:29:28 INFO - Bus: Built-In 04:29:28 INFO - VRAM (Dynamic, Max): 1536 MB 04:29:28 INFO - Vendor: Intel (0x8086) 04:29:28 INFO - Device ID: 0x0a2e 04:29:28 INFO - Revision ID: 0x0009 04:29:28 INFO - Displays: 04:29:28 INFO - Display: 04:29:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:29:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:29:28 INFO - Display Serial Number: 1600x1200 60 04:29:28 INFO - Main Display: Yes 04:29:28 INFO - Mirror: Off 04:29:28 INFO - Online: Yes 04:29:28 INFO - Rotation: Supported 04:29:28 INFO - Return code: 0 04:29:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:29:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:29:28 INFO - Python 2.7.3 04:29:28 INFO - Return code: 0 04:29:28 INFO - grabbing minidump binary from tooltool 04:29:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:29:28 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 0x102c1ad30>, '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 0x102b41db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102c0fbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:29:28 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:29:28 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:29:28 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:29:29 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:29:29 INFO - Return code: 0 04:29:29 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:29:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:29:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:29:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:29:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:29:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DaDNVud2SSWuqkyX4aZNPw/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0023', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 04:29:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/DaDNVud2SSWuqkyX4aZNPw/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0023 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 04:29:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q4nofNE2zL/Render', 04:29:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:29:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:29:29 INFO - 'HOME': '/Users/cltbld', 04:29:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:29:29 INFO - 'LOGNAME': 'cltbld', 04:29:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:29:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:29:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:29:29 INFO - 'MOZ_NO_REMOTE': '1', 04:29:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:29:29 INFO - 'NO_EM_RESTART': '1', 04:29:29 INFO - 'PAGER': '/bin/cat', 04:29:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:29:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:29:29 INFO - 'PWD': '/builds/slave/test', 04:29:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:29:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:29:29 INFO - 'SHELL': '/bin/bash', 04:29:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ABVtj1RE5R/Listeners', 04:29:29 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 04:29:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:29:29 INFO - 'USER': 'cltbld', 04:29:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:29:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:29:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:29:29 INFO - 'XPC_FLAGS': '0x0', 04:29:29 INFO - 'XPC_SERVICE_NAME': '0', 04:29:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:29:29 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DaDNVud2SSWuqkyX4aZNPw/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0023', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 04:29:29 INFO - mozversion application_buildid: 20170209030214 04:29:29 INFO - mozversion application_changeset: b772e0f4138540113e91a46c99bb0d14ecc8acca 04:29:29 INFO - mozversion application_display_name: Nightly 04:29:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:29:29 INFO - mozversion application_name: Firefox 04:29:29 INFO - mozversion application_remotingname: firefox 04:29:29 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:29:29 INFO - mozversion application_vendor: Mozilla 04:29:29 INFO - mozversion application_version: 54.0a1 04:29:29 INFO - mozversion platform_buildid: 20170209030214 04:29:29 INFO - mozversion platform_changeset: b772e0f4138540113e91a46c99bb0d14ecc8acca 04:29:29 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:29:29 INFO - mozversion platform_version: 54.0a1 04:29:29 INFO - using testdate: 1486643369 04:29:29 INFO - actual date: 1486643369 04:29:29 INFO - starting webserver on 'localhost:49377' 04:29:29 INFO - SUITE-START | Running 2 tests 04:29:29 INFO - TEST-START | dromaeo_css 04:29:29 INFO - operating with platform_type : mac_ 04:29:29 INFO - Initialising browser for dromaeo_css test... 04:29:29 INFO - TEST-INFO | started process 1893 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/tmp3Mzfyf/profile http://localhost:49377/getInfo.html) 04:29:32 INFO - PROCESS | 1893 | 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:29:32 INFO - PROCESS | 1893 | 2017-02-09 04:29:32.411 plugin-container[1896:8840] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4d47, name = 'com.apple.tsm.portname' 04:29:32 INFO - PROCESS | 1893 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:29:32 INFO - PROCESS | 1893 | 2017-02-09 04:29:32.412 plugin-container[1896:8840] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x970b, name = 'com.apple.CFPasteboardClient' 04:29:32 INFO - PROCESS | 1893 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:29:32 INFO - PROCESS | 1893 | 2017-02-09 04:29:32.412 plugin-container[1896:8840] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:29:32 INFO - PROCESS | 1893 | __metrics Screen width/height:1600/1200 04:29:32 INFO - PROCESS | 1893 | colorDepth:24 04:29:32 INFO - PROCESS | 1893 | Browser inner width/height: 1024/689 04:29:32 INFO - PROCESS | 1893 | __metrics 04:29:32 ERROR - PROCESS | 1893 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3283: TypeError: this.tabs is undefined 04:29:33 INFO - TEST-INFO | 1893: exit 0 04:29:33 INFO - Browser initialized. 04:29:33 INFO - Running cycle 1/1 for dromaeo_css test... 04:29:33 INFO - TEST-INFO | started process 1897 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/tmp3Mzfyf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:29:35 INFO - PROCESS | 1897 | 2017-02-09 04:29:35.992 plugin-container[1898:8962] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 04:29:35 INFO - PROCESS | 1897 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:29:35 INFO - PROCESS | 1897 | 2017-02-09 04:29:35.993 plugin-container[1898:8962] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b2b, name = 'com.apple.CFPasteboardClient' 04:29:35 INFO - PROCESS | 1897 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:29:35 INFO - PROCESS | 1897 | 2017-02-09 04:29:35.993 plugin-container[1898:8962] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:29:45 INFO - PROCESS | 1897 | RSS: Main: 232677376 04:29:45 INFO - PROCESS | 1897 | 04:32:48 INFO - PROCESS | 1897 | Cycle 1(1): loaded http://localhost:49377/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49377/tests/dromaeo/cssquery-ext.html) 04:32:50 INFO - PROCESS | 1897 | RSS: Main: 256401408 04:32:50 INFO - PROCESS | 1897 | 04:34:33 INFO - PROCESS | 1897 | ************************* 04:34:33 INFO - PROCESS | 1897 | A coding exception was thrown and uncaught in a Task. 04:34:33 INFO - PROCESS | 1897 | 04:34:33 ERROR - PROCESS | 1897 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:34:33 INFO - PROCESS | 1897 | Full stack: 04:34:33 INFO - PROCESS | 1897 | ************************* 04:35:52 INFO - PROCESS | 1897 | Cycle 1(1): loaded http://localhost:49377/tests/dromaeo/cssquery-ext.html (next: http://localhost:49377/tests/dromaeo/cssquery-jquery.html) 04:35:53 INFO - PROCESS | 1897 | RSS: Main: 264364032 04:35:53 INFO - PROCESS | 1897 | 04:38:55 INFO - PROCESS | 1897 | Cycle 1(1): loaded http://localhost:49377/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49377/tests/dromaeo/cssquery-mootools.html) 04:38:55 INFO - PROCESS | 1897 | RSS: Main: 275308544 04:38:55 INFO - PROCESS | 1897 | 04:41:57 INFO - PROCESS | 1897 | Cycle 1(1): loaded http://localhost:49377/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49377/tests/dromaeo/cssquery-prototype.html) 04:41:58 INFO - PROCESS | 1897 | RSS: Main: 268091392 04:41:58 INFO - PROCESS | 1897 | 04:45:01 INFO - PROCESS | 1897 | Cycle 1(1): loaded http://localhost:49377/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49377/tests/dromaeo/cssquery-yui.html) 04:45:02 INFO - PROCESS | 1897 | RSS: Main: 273203200 04:45:02 INFO - PROCESS | 1897 | 04:48:05 INFO - PROCESS | 1897 | Cycle 1(1): loaded http://localhost:49377/tests/dromaeo/cssquery-yui.html (next: http://localhost:49377/tests/dromaeo/cssquery-dojo.html) 04:48:05 INFO - PROCESS | 1897 | RSS: Main: 275316736 04:48:05 INFO - PROCESS | 1897 | 04:48:05 INFO - PROCESS | 1897 | __start_tp_report 04:48:05 INFO - PROCESS | 1897 | _x_x_mozilla_page_load 04:48:05 INFO - PROCESS | 1897 | _x_x_mozilla_page_load_details 04:48:05 INFO - PROCESS | 1897 | |i|pagename|runs| 04:48:05 INFO - PROCESS | 1897 | |0;dojo.html;3295.5715756951595;3309.8841540546077;3332.2169290216793;3359.2609625882296;3364.798112113275;5402;5529.087700529413;5534.833954981352;5576.414476479972;5603.579731520143;5407.756650950707;5524.723763811813;5539.556835453164;5558.388577256509;5638.774449022045;4979.352684151056;5058.822941197059;5059;5144.61415393847;5145.922811157836;4976.054549635558;5131.153359695646;5169.40551836538;5177;5205.115130427836;4961.578265847407;5138.614603904722;5174.715390653512;5190.377154741455;5213.869653258661;4985.252212168168;5033.370828646419;5035.3454050973505;5064.974675126601;5150.690958542499;4511.233090374644;5004.549590536869;5060.569851562622;5067.214581739836;5080.085584594808;4987.576056035241;5508.44915508446;5557.916631250535;5566.805161819358;5571.275734154575;5786.797462088806;5870.412958704138;6079.939200608026;6120.387961203888;6122.326544080148;6116.296625888035;6176.104464852616;6176.96911515444;6191.84520386994;6208.5033197344555;4754.358161648202;5682.914756278659;5700.971495142498;5702.201691763156;5710.972025035533;6039.577229593974;6143.262808462924;6157.076438534255;6180.165677633552;6208.875822483525;5074.974625126924;6000.909986350208;6040.969795151084;6063.120847477091;6101.938980610226;6077.726502307407;6107.633541987495;6182.536309776785;6209.59637623553;6222.315545290015;5758.568107391962;5818.796342128006;5829.650220986669;5909.645421274738;5984.431479009487;6123.724432400463;6128.778133445034;6141.508679305645;6157.0456579230295;6180.165677633552;4857.392825896763;4860.270959356054;4882.707037577757;4904.534069263485;4928.901421971612;4297.978510107429;4324.17840610283;4394.626456751212;4398.142362239332;4403.691741578059;5645.576581756384;5649.576281778883;5666.433356664301;5685.431456854281;5703.600747947604;6149.200603921461;6163.260408750979;6186.783462578878;6196.628202307876;6202.56582039262;5414.377346605112;5583;5661.037623603915;5677.659340439504;5682.147677848273;5197.948020519822;5286.524212820787;5298.602604804655;5339.092354299701;5385.246065550826;6258.937410625836;6289;6303.558750887394;6312.589681670676;6345.206849143857;6167.383261673976;6192.287886893065;6194.876102477835;6202.751889924533;6215.223097112861;5792.464996775773;5930.703464826827;6090.269167699905;6169.198004259418;6171.352008039271;6034.517238620988;6050.969745151246;6053.243344581927;6060.363661815446;6077;6141.938580614136;6142;6144.170536977451;6165.753369865155;6168.19813424252;4310.655147588248;5622.353429355677;5854.677992710442;5880.117982302689;5937.584369094295;5903.409659034062;5961.254843144607;5973.462388385066;5998;6016.067509536029;5569.860753481195;5638.548916086621;5676.20533125371;5781.913271300849;5787.681677507778;5707.6575405475805;5843.444872737083;5910.231669882888;5914.822555323347;6033.396660333931;5743.282089738783;5776.480116789509;5786.363500014911;5791.82624521265;5801.94198058014 04:48:05 INFO - PROCESS | 1897 | |1;ext.html;430.709271241912;434.495984657797;471.4648873528544;477.4366247827563;521.7260938007546;38126.618733812655;39088.60911390885;39153;40945;40970.59029409713;39477.802610986975;39976.800695979095;40027;40166.7991660042;40359.19281614366;8978.775530611721;9144.8628270576;9182.908170918305;9218.723438296829;10409.687709368693;10302.433366164858;10520.263581549294;10521.263511554194;10527.63153289635;10537.209709271798;10507.842382364272;10563.049325560698;10584.417857017861;10584.682459526226;10602.575896964112;10515.053645171933;10556.261061725681;10561.155107591374;10561.366318020866;10568.313059651098;10472.738181545523;10515.84226236607;10547.525361358681;10553.366797992145;10617.203709721802;34384.656153438395;34919.82540087309;34971.9508414748;35330;35420.468692969625;3751.624837516254;3790.753601015953;3855.5373355197557;3856.8842934712;3857.787821669807;26688.332791680168;27714.168574942785;27729.722702773117;27818.3045423864;27853.442931141468;3033.362993771311;3073.1548824073334;3179.7456203503893;3213.3412650406613;3222.7744057915957;443834;445300;445598.5440145622;446301;448235;45110;46013.309800353025;53017;53378.932421351354;53412.46587534153;56919.430805691416;57702.7114864423;57947.71026144927;58002;58301.70849145727;14453.205073720836;14594.489192878198;14621.122732636077;14633.048851824597;14639.268036598127;10991.06575941038;11156.16328775345;11235.66293011211;11271.830922536168;11271.830922536168;16610.335586576402;16684.082375469465;16728.665426691397;16794.496165115066;16836.915815420845;7701.961490192625;7791.649375778104;7801.180876008051;7813.570253636105;7872.842543149105;823.9917600824035;834.7161964931831;842.3766412854444;853.5732133933034;859.239572977919;27754.167374978784;28940;29037.7096229035;29306.267343316587;29693.960711375;864.5115509736978;977.0620204603518;977.4477420257532;987.4815721745997;1185.8932696057395;11359.943200284111;13620.318984050758;13780.655483612789;13885.86114138866;13918;536519;570950;589567;613413;614889;47341.76329118401;47862;47953.520464795605;47976.7601161992;48089;1285.8328417305877;1294.9417276222594;1327.634900402397;1348.6089034179977;1357.5045108535512;22055.889720551295;23454;23646.763532365145;26044.609330859672;26320.010760348177;41868.79065604774;42054.7897260524;42386.36420453634;42432;42547;570960;571221;571252;571341;573278.1336093292;20635.690464643343;21166.576668466852;23022.539549208588;23249.88375058114;23678.408039798454;38012;38147.618523815516;38504;38622.80688596539;38752.418713718755;27388.904443822023;33224.687309411136;33392.332153356314;33498.33003339968;33730.831345843115;32068.51897221497;32421.675783241866;32639;33244.00267991964;33467.83266083654;32038.83980580082;32287.838560807046;32388.514172286963;32559.511607325436;32604.347913042082;28005.299867503476;29598.40803183881;29756.851215743784;29763.958261460746;29897.240675773355;22437.61497240618;22680;22872.771272287064;22896.427589309733;22951.540969180856 04:48:05 INFO - PROCESS | 1897 | |2;jquery.html;369.96670299673025;380.46164676982073;385.58549559223843;397.87466947911423;414.82577317526636;18652.067396630166;19295.807041929576;20668.48328791781;20892.89553552234;20927.05828237727;10701.411422371766;11833;12021.579244726436;12183.451744671489;12462.875371246284;5080.923786143201;5368.30212072432;5372.597055220855;5395.163749618815;5415.214793854889;5248.790048398059;5561.138023606327;5866.296044474669;5914.2015827863115;6005.879882402372;5974.103884417329;5981.043033114703;5988.101784732282;5990.951583472896;5998.7600495980105;5771.740271687784;5972.134040564116;6024.126501657235;6038.788642397539;6050.485708714765;5563.165525171216;5710.971445142789;5839.883202335929;5851.648901065907;5859.326177489601;10839.566417343296;13112.344382780919;13285.269310187936;13313.467461301632;16091.83908160915;2410.337157281744;2541.1995221505167;2544.5038217547585;2547.1467058535413;2570.5244529762053;8351.58242087893;9946.204303655762;10395.376277423378;10442.634507792267;15133.394664213418;1439.7048605035943;1833.8899666020081;1847.122616757043;1856;1869.7943226245102;96563;116313;119366.17964882843;130575.6942430573;136053;10191.54138063789;11923.165378423428;12111.697207569705;12157.148999569945;12303.507859685691;15618;15773.921130394274;15791.21043947798;15792.68414631701;15809.367625294859;7852;8125.187481251827;8160.673573057011;8203.548804815673;8232.464889782144;7541.981832452549;7594.088709354913;7605.0226940995735;7699.961500192463;7747.109082455477;4775.355327030807;4835.975820120947;4869.488703686062;4885.609151267961;4938.8271410501175;2058.9794102058786;2091.7385326834146;2098.790120987889;2103.6528972719634;2104.1793700456833;845.5011543189547;929.4980710416299;999.5851721535481;1014.3102690170607;1047.3035431438009;19005.524861878563;20661.896690516453;21113.472163195736;21577.56844863094;21607.783922160575;1010.3634710132569;1012.9544170512345;1013.8580598716186;1040.7502199472071;1054.836500342448;11034.393108378956;11226.989570938655;11322.037626801814;11336.943315283535;11341.489632966537;662456.6877165583;673421;675497;678754;681542.5922870354;105489;106960;130807;132809.33595332154;133937;957.5212393803098;975.4439969217619;1008.2236677758085;1025.1337619711333;1037.0200160848076;14438.639034024234;17343.913280433517;19210.903945480182;19404.708929366352;19648.508787280432;48641;55344;58572.70713646404;58600.70699646645;59885;58391;83993.74009389742;85314;85409.88606082412;86558.56720716356;7519.834425664031;7560.697572097055;7732.033495813023;8162.551059691774;8169.0605580359015;10961.342319460859;12512.499500020262;12909.741805163656;12926.806097908728;12968.740625187254;10754.784904302027;10861.02250797432;11056.07014141237;11057.22599418033;11072.944635276772;10563.94718026405;10695.679129626124;10872.728181795519;10955.50700218508;11124.555017799197;10837;10905.073068789241;11141.777164456504;11148.331100134017;11254.718632034264;8141.185881412049;8334.374921880762;8350.624221909908;8451.1548845115;8584.613692383982;10394.896051039394;10617.256792024484;10928.01647851697;11110.888891110986;11348.602798901999 04:48:05 INFO - PROCESS | 1897 | |3;mootools.html;1218.951241950322;1328.7541804766124;1397.2245403800887;1414.7055444268494;1432.7349440353535;6272.247330320357;8178.263956243937;8408.495490270572;8580.442271252365;8594.570271486424;8394.286485648727;8435.24082832545;8469.153084691528;8488.108748581402;8543.01755298141;2232.162938897909;2267.875266860322;2275.8975846086964;2276.9886150569223;2288.187693368855;2193.6618662615792;2265.7394399644086;2278.5784629843533;2304.573653874039;2307.792298693117;2039.5614942787297;2257.4694946687514;2287.7254729432493;2287.816974642033;2304.2280835919987;2180.258712037899;2238.630625946725;2269.897854596547;2274.5337205872756;2274.5678321118935;1965.764108306998;2058.3824852544326;2245.371296037112;2318.258157389636;2338.7661233876647;8307.626156822897;8487.278581320596;8521.531315777629;8572.571371431404;8609.913900860975;5094.1849304111165;5247.86880327991;5250.264962905197;5253.737313134328;5260.158374660036;2864.283929017746;3379.662033796625;3411.4541673332396;3465.8093804840723;3535.2399234164777;1206.4209179593756;1206.867244603093;1240.894523965464;1241.5592464675;1268.0109514578644;10988;11031.503582338813;11032.951869572458;11085.168612354106;11086.556537738561;4177.352510360899;4470.597646211857;4545.36364908913;4561.361409402686;4570.977145114253;8643.438176518506;8789.736307910773;8790.736277911672;8791.208791208823;8810.339224558184;5119.948800511947;5136.998285334399;5150.227465880073;5153.613478989091;5157.406898206679;4380.123975204971;4432.113577284556;4634.073185362941;4644.883877903012;4769.117713223065;3599.4960705501253;3607.567091948983;3649.306631740014;3680.1535646801385;3696.963030369716;954;1078.3691540448926;1144.2047776795214;1145.5933143733855;1160.1356989043218;861.5304658960881;870.6909047288248;876.2770714160843;911.49530056931;912.3385545479607;4841.806327746851;5473.562115030788;5714.628549144292;5717.256756621613;5728.341240757366;535.9115745901961;563.2030676592576;565.3682109778904;570.6576054367347;576.5560518400808;3779.829907654162;3786.734928554975;3790.450384694204;3876.6704830089757;3900.6737709179015;9603.807923841485;10079.395236285847;10197.082262596254;10213.285070045175;10214.387136771817;9913.801723965624;10095.949520252352;10136.290459667753;10150.644727434505;10156.796864062677;7804.843903121906;7816.49192802466;7871.763847084597;7880.960595196987;7893.921060789318;4656.534346565452;5269.735263536801;5550.750216240198;5591.245181900392;5617.466340697627;9076.909230907606;9120.726378208665;9190.770230744018;9333.439993600407;9405.106514881076;7860.344939603112;8218.424710270463;9396.295277854051;9443.102905223992;9542.713718588453;6594.307597702364;6679.298673639198;6682.498812588978;6694.895342268471;6708.563943343667;7706.267904549059;7737.226277372218;7802.258785415376;7873.448858579844;7889.921100788919;7020.964895175492;7098.112735908012;7099.2190859006505;7120.359167674934;7160.248173941661;6696.832579185364;7002.054722612382;7010.948357746379;7224.169220539705;7258.3104605062435;6220.315765265907;6358.1734374531015;6487.967560162169;6503.349665033459;6512.479001679949;4863.270509423614;4913.97543012297;4952.603791696728;4999.975000124976;5134.409542902614;4398.538153493837;4912.1649319615035;4978.1288274552535;4980.32765576657;5079.212722028092 04:48:05 INFO - PROCESS | 1897 | |4;prototype.html;326.5999151039976;346.5425638157631;360.81057444841457;363.4530032301386;369.2227860353955;4462.866114016577;4821.228603423453;5162.199859021848;5385.730713464326;5391.487808658176;4497.482789479211;5069.732566858285;5285.524302812746;5317.175837745146;5331.306930099082;3700.9814950925206;4186.183694179636;4215.262329092404;4227.471566054243;4231.1114665920195;3339.699427051577;3994.900127496807;4198.068888311365;4211.894702632443;4221.113566151096;3732.973432306124;3959.366501359783;3960.2673505401594;3985.940210896839;4042.333015052513;3333.783304085251;3554.3246783111254;3828.9279001879518;3992.6606238469767;4036.4348991141264;3233.4503134466963;3484.285721427101;3493.7205023598053;3498.4227602445567;3501.28223714138;4275.679324050678;4283.714885534328;4804;4935.5804756595735;5156.685045313458;4564.2012647786505;4587.8623641290815;4595.356650068993;4602.424696912886;4611.21609326412;5586.190002449622;5613.522850557708;5614;5650.406707295716;5668.858278543069;3212.775105742599;3788.5074940257864;3805.6765174959883;3851.9229615407535;3859.4789703389965;4692.741899195543;5470.452954704538;5545.334559852843;5744.219292019446;5928.970355148197;3666.5416822897137;3783.829727662262;3856.537215534154;3868.7291889568023;3887.378019516864;3389.7627166098137;3859.938516907873;3905.902352441155;3918.6669133123432;3976.960230397659;4547.3179023146795;4570.702904311209;4571.245744452194;4586.403767510269;4599.2871104978785;4711.575958163782;5256.92114618281;5449.3188351456065;5715.342735585377;5737.3688894221605;1212.8120141378101;1274.3373445808313;1279.8848103670528;1287.6265882894043;1292.2827830553945;807.5921659561884;865.0786911938765;868.9522076285739;880.7845173660307;888.7911340834899;4476.395686582335;4990.7523119220195;5038.571721403649;5045.192769156917;5086.372360844568;4992.152903425683;5613.691246981373;5634.971825140848;5641;5647.435256474319;4474.373587697725;4666.85662012809;4688.742119183409;4711.811527538928;4757.26262429324;2847.985760071228;2910;2911.7333959727584;2917.7811664125275;2922.941541169165;5019.899602007939;5254.896471740977;5349.866253343697;5668.7121469547565;5731.742071606787;5273.604479664041;5419.403865574784;5621.5221706155435;5711.286089238845;5711.628744131618;4753.155456143281;5509.724513774296;5701.92312496567;5740.913886291709;5750.827475175752;5038.672486288379;5214.556762675215;5498.532624726783;5568.49883510486;5616.69108199053;5209.869753256199;5452.727363631723;5712.200291959046;5775.9133612995;5827.59206855516;5074.010567939216;5710.0029979014025;5896;6030.668313242638;6063.5452341073715;3547.7516573839584;3586.659267369596;3592.4431713084514;3877;3877.2827027000094;5200.5059519345605;5791.48070589295;5799.681017544076;5814.069748840166;5898.734556945034;4589.751021999263;5349.946500534945;5584.459071122711;5611.046122159324;5628.577856660849;4670.416197975253;5164.431912489698;5609.551235901036;5625.634333768292;5645.548356131418;4621.491635920113;5523.8895222094525;5526.47498487643;5548.833534993957;5556.277683901067;5261.921071184011;5522.5581953442825;5554.805581804618;5611.971940140274;5815.41845815415;4727.409073865767;5461.617686761889;5484.424135465719;5490.835274941598;5520.702634880719 04:48:05 INFO - PROCESS | 1897 | |5;yui.html;549.7938273147572;674.7469698862926;678.1150598468996;712.6971037309144;795.0657976877168;12760.55338063168;13285.93357033216;13324.933375333121;14037.736603705664;14514.346854391551;13953.790693139586;14033.859661403383;14104.64738381541;14118.64703382416;14179.291035448212;857.1728282207683;864.1229152410309;870.9390342676015;884.4914174349742;887.7647423432795;883.0992387764477;883.8409086364452;884.7876509637705;887.5118684723409;897.4660077254024;929.5166513413055;932.0306880843914;935.8081593273397;937.2642475656611;938.1744065222595;741.2587412587412;906.9047749986222;924.4176169013512;926.6895589977365;930.1907340613642;687.2405991379503;689.3313485918701;689.582802404548;691.9515633905629;693.223589579674;14870.553883383516;15040.774388384183;15142.848571514254;15467.5359739208;15503.302351394213;12040.026950814798;13651.11267767592;13680.042397032212;13822.447102115902;13976.720465590734;11408.372539510323;11658.941705291503;11696.47365868538;11696.590619328283;12193.878061219362;3583.2475180212186;3584.22939068099;3601.117726157082;3603.4414665726854;3737.8878633641034;80226;89147;100963.9903600962;104449.47775261075;107476;9184.816303674022;10538.683839484827;10568.947155264175;10865.293755905841;10883.619073332395;21773.34679959604;21934.561308774053;22113.44716382071;22130.004149813296;22239.22162724297;7489.363404110712;7522.548647081193;7565.6217189140325;7604;7640.808979775439;3380.763346565766;4061.2080644274674;4165.8333666653;4193.161367726467;4305.526392096866;3495.632958539368;3559.928801423957;3578.7673801202836;3584.5698516178227;3587.838547265327;557.4509108528097;657.1588366890388;669.568128557078;675.7668604331498;685.2496516314627;595.6835393779709;607.6475644126396;619.5013014523262;642.7364780439997;658.6245839871227;9527.09492598202;9610.279229057849;9660.613575456902;9697.078777516124;9881.851772223423;598.643806934873;649.4998850884792;651.8761435327273;654.1057256516359;665.8102440804347;4571.268597024527;4691.554302341272;4704.717716936994;4730.075079769148;4739.3601863748645;87504;106876.46561767296;107314;107754;109171;52405.7379713099;53638.73180634072;76185.42887426772;76344;94235;58117;77063.61468192624;77593.89624415073;77768;78175;10472.68581942543;10507.211959102944;10652.520636571218;10723.785524289626;10761.381944029987;40878.38682419826;46860.53139468698;47950.56148315556;48303;50571.74714126406;47640;61553;61995.690021549606;62224;63137;12626.621201363974;12640.55758048464;12678.556250531186;12747.490100396251;13083.150624041591;11789.646310610695;12595.74042595733;12745.61763147107;13683;14181.858181418467;11822.817718228109;12002.459889304828;12705.809412859;12717.236965782082;13761.931190343985;10906.781864362827;13030.478780849038;13350.163646523462;13444.865551344361;13471.865281347062;12878.484860605471;12894.226346419246;12935.223886566837;13459.79810302866;13769.862301376857;8147.3482121431325;10159.79680406373;10618.787624247627;11136.777264454828;11638.41807909618;10426.530806113591;10501.9474902625;10512.526936287732;10514.106300964198;10568.365898046142 04:48:05 INFO - PROCESS | 1897 | __end_tp_report 04:48:05 INFO - PROCESS | 1897 | __start_cc_report 04:48:05 INFO - PROCESS | 1897 | _x_x_mozilla_cycle_collect,408 04:48:05 INFO - PROCESS | 1897 | __end_cc_report 04:48:05 INFO - PROCESS | 1897 | __startTimestamp1486644485352__endTimestamp 04:48:05 INFO - PROCESS | 1897 | 04:48:05 INFO - PROCESS | 1897 | ------- Summary: start ------- 04:48:05 INFO - PROCESS | 1897 | Number of tests: 6 04:48:05 INFO - PROCESS | 1897 | 04:48:05 INFO - PROCESS | 1897 | [#0] dojo.html Cycles:165 Average:5594.27 Median:5743.28 stddev:637.39 (11.1%) stddev-sans-first:613.30 04:48:05 INFO - PROCESS | 1897 | Values: 3295.6 3309.9 3332.2 3359.3 3364.8 5402.0 5529.1 5534.8 5576.4 5603.6 5407.8 5524.7 5539.6 5558.4 5638.8 4979.4 5058.8 5059.0 5144.6 5145.9 4976.1 5131.2 5169.4 5177.0 5205.1 4961.6 5138.6 5174.7 5190.4 5213.9 4985.3 5033.4 5035.3 5065.0 5150.7 4511.2 5004.5 5060.6 5067.2 5080.1 4987.6 5508.4 5557.9 5566.8 5571.3 5786.8 5870.4 6079.9 6120.4 6122.3 6116.3 6176.1 6177.0 6191.8 6208.5 4754.4 5682.9 5701.0 5702.2 5711.0 6039.6 6143.3 6157.1 6180.2 6208.9 5075.0 6000.9 6041.0 6063.1 6101.9 6077.7 6107.6 6182.5 6209.6 6222.3 5758.6 5818.8 5829.7 5909.6 5984.4 6123.7 6128.8 6141.5 6157.0 6180.2 4857.4 4860.3 4882.7 4904.5 4928.9 4298.0 4324.2 4394.6 4398.1 4403.7 5645.6 5649.6 5666.4 5685.4 5703.6 6149.2 6163.3 6186.8 6196.6 6202.6 5414.4 5583.0 5661.0 5677.7 5682.1 5197.9 5286.5 5298.6 5339.1 5385.2 6258.9 6289.0 6303.6 6312.6 6345.2 6167.4 6192.3 6194.9 6202.8 6215.2 5792.5 5930.7 6090.3 6169.2 6171.4 6034.5 6051.0 6053.2 6060.4 6077.0 6141.9 6142.0 6144.2 6165.8 6168.2 4310.7 5622.4 5854.7 5880.1 5937.6 5903.4 5961.3 5973.5 5998.0 6016.1 5569.9 5638.5 5676.2 5781.9 5787.7 5707.7 5843.4 5910.2 5914.8 6033.4 5743.3 5776.5 5786.4 5791.8 5801.9 04:48:05 INFO - PROCESS | 1897 | 04:48:05 INFO - PROCESS | 1897 | [#1] ext.html Cycles:180 Average:64781.52 Median:32931.84 stddev:143974.71 (437.2%) stddev-sans-first:144297.53 04:48:05 INFO - PROCESS | 1897 | Values: 430.7 434.5 471.5 477.4 521.7 38126.6 39088.6 39153.0 40945.0 40970.6 39477.8 39976.8 40027.0 40166.8 40359.2 8978.8 9144.9 9182.9 9218.7 10409.7 10302.4 10520.3 10521.3 10527.6 10537.2 10507.8 10563.0 10584.4 10584.7 10602.6 10515.1 10556.3 10561.2 10561.4 10568.3 10472.7 10515.8 10547.5 10553.4 10617.2 34384.7 34919.8 34972.0 35330.0 35420.5 3751.6 3790.8 3855.5 3856.9 3857.8 26688.3 27714.2 27729.7 27818.3 27853.4 3033.4 3073.2 3179.7 3213.3 3222.8 443834.0 445300.0 445598.5 446301.0 448235.0 45110.0 46013.3 53017.0 53378.9 53412.5 56919.4 57702.7 57947.7 58002.0 58301.7 14453.2 14594.5 14621.1 14633.0 14639.3 10991.1 11156.2 11235.7 11271.8 11271.8 16610.3 16684.1 16728.7 16794.5 16836.9 7702.0 7791.6 7801.2 7813.6 7872.8 824.0 834.7 842.4 853.6 859.2 27754.2 28940.0 29037.7 29306.3 29694.0 864.5 977.1 977.4 987.5 1185.9 11359.9 13620.3 13780.7 13885.9 13918.0 536519.0 570950.0 589567.0 613413.0 614889.0 47341.8 47862.0 47953.5 47976.8 48089.0 1285.8 1294.9 1327.6 1348.6 1357.5 22055.9 23454.0 23646.8 26044.6 26320.0 41868.8 42054.8 42386.4 42432.0 42547.0 570960.0 571221.0 571252.0 571341.0 573278.1 20635.7 21166.6 23022.5 23249.9 23678.4 38012.0 38147.6 38504.0 38622.8 38752.4 27388.9 33224.7 33392.3 33498.3 33730.8 32068.5 32421.7 32639.0 33244.0 33467.8 32038.8 32287.8 32388.5 32559.5 32604.3 28005.3 29598.4 29756.9 29764.0 29897.2 22437.6 22680.0 22872.8 22896.4 22951.5 04:48:05 INFO - PROCESS | 1897 | 04:48:05 INFO - PROCESS | 1897 | [#2] jquery.html Cycles:180 Average:36596.05 Median:11512.92 stddev:111995.76 (972.8%) stddev-sans-first:112276.90 04:48:05 INFO - PROCESS | 1897 | Values: 370.0 380.5 385.6 397.9 414.8 18652.1 19295.8 20668.5 20892.9 20927.1 10701.4 11833.0 12021.6 12183.5 12462.9 5080.9 5368.3 5372.6 5395.2 5415.2 5248.8 5561.1 5866.3 5914.2 6005.9 5974.1 5981.0 5988.1 5991.0 5998.8 5771.7 5972.1 6024.1 6038.8 6050.5 5563.2 5711.0 5839.9 5851.6 5859.3 10839.6 13112.3 13285.3 13313.5 16091.8 2410.3 2541.2 2544.5 2547.1 2570.5 8351.6 9946.2 10395.4 10442.6 15133.4 1439.7 1833.9 1847.1 1856.0 1869.8 96563.0 116313.0 119366.2 130575.7 136053.0 10191.5 11923.2 12111.7 12157.1 12303.5 15618.0 15773.9 15791.2 15792.7 15809.4 7852.0 8125.2 8160.7 8203.5 8232.5 7542.0 7594.1 7605.0 7700.0 7747.1 4775.4 4836.0 4869.5 4885.6 4938.8 2059.0 2091.7 2098.8 2103.7 2104.2 845.5 929.5 999.6 1014.3 1047.3 19005.5 20661.9 21113.5 21577.6 21607.8 1010.4 1013.0 1013.9 1040.8 1054.8 11034.4 11227.0 11322.0 11336.9 11341.5 662456.7 673421.0 675497.0 678754.0 681542.6 105489.0 106960.0 130807.0 132809.3 133937.0 957.5 975.4 1008.2 1025.1 1037.0 14438.6 17343.9 19210.9 19404.7 19648.5 48641.0 55344.0 58572.7 58600.7 59885.0 58391.0 83993.7 85314.0 85409.9 86558.6 7519.8 7560.7 7732.0 8162.6 8169.1 10961.3 12512.5 12909.7 12926.8 12968.7 10754.8 10861.0 11056.1 11057.2 11072.9 10563.9 10695.7 10872.7 10955.5 11124.6 10837.0 10905.1 11141.8 11148.3 11254.7 8141.2 8334.4 8350.6 8451.2 8584.6 10394.9 10617.3 10928.0 11110.9 11348.6 04:48:05 INFO - PROCESS | 1897 | 04:48:05 INFO - PROCESS | 1897 | [#3] mootools.html Cycles:180 Average:5365.53 Median:5135.70 stddev:2986.38 (58.1%) stddev-sans-first:2978.49 04:48:05 INFO - PROCESS | 1897 | Values: 1219.0 1328.8 1397.2 1414.7 1432.7 6272.2 8178.3 8408.5 8580.4 8594.6 8394.3 8435.2 8469.2 8488.1 8543.0 2232.2 2267.9 2275.9 2277.0 2288.2 2193.7 2265.7 2278.6 2304.6 2307.8 2039.6 2257.5 2287.7 2287.8 2304.2 2180.3 2238.6 2269.9 2274.5 2274.6 1965.8 2058.4 2245.4 2318.3 2338.8 8307.6 8487.3 8521.5 8572.6 8609.9 5094.2 5247.9 5250.3 5253.7 5260.2 2864.3 3379.7 3411.5 3465.8 3535.2 1206.4 1206.9 1240.9 1241.6 1268.0 10988.0 11031.5 11033.0 11085.2 11086.6 4177.4 4470.6 4545.4 4561.4 4571.0 8643.4 8789.7 8790.7 8791.2 8810.3 5119.9 5137.0 5150.2 5153.6 5157.4 4380.1 4432.1 4634.1 4644.9 4769.1 3599.5 3607.6 3649.3 3680.2 3697.0 954.0 1078.4 1144.2 1145.6 1160.1 861.5 870.7 876.3 911.5 912.3 4841.8 5473.6 5714.6 5717.3 5728.3 535.9 563.2 565.4 570.7 576.6 3779.8 3786.7 3790.5 3876.7 3900.7 9603.8 10079.4 10197.1 10213.3 10214.4 9913.8 10095.9 10136.3 10150.6 10156.8 7804.8 7816.5 7871.8 7881.0 7893.9 4656.5 5269.7 5550.8 5591.2 5617.5 9076.9 9120.7 9190.8 9333.4 9405.1 7860.3 8218.4 9396.3 9443.1 9542.7 6594.3 6679.3 6682.5 6694.9 6708.6 7706.3 7737.2 7802.3 7873.4 7889.9 7021.0 7098.1 7099.2 7120.4 7160.2 6696.8 7002.1 7010.9 7224.2 7258.3 6220.3 6358.2 6488.0 6503.3 6512.5 4863.3 4914.0 4952.6 5000.0 5134.4 4398.5 4912.2 4978.1 4980.3 5079.2 04:48:05 INFO - PROCESS | 1897 | 04:48:05 INFO - PROCESS | 1897 | [#4] prototype.html Cycles:180 Average:4473.34 Median:4991.45 stddev:1369.26 (27.4%) stddev-sans-first:1337.26 04:48:05 INFO - PROCESS | 1897 | Values: 326.6 346.5 360.8 363.5 369.2 4462.9 4821.2 5162.2 5385.7 5391.5 4497.5 5069.7 5285.5 5317.2 5331.3 3701.0 4186.2 4215.3 4227.5 4231.1 3339.7 3994.9 4198.1 4211.9 4221.1 3733.0 3959.4 3960.3 3985.9 4042.3 3333.8 3554.3 3828.9 3992.7 4036.4 3233.5 3484.3 3493.7 3498.4 3501.3 4275.7 4283.7 4804.0 4935.6 5156.7 4564.2 4587.9 4595.4 4602.4 4611.2 5586.2 5613.5 5614.0 5650.4 5668.9 3212.8 3788.5 3805.7 3851.9 3859.5 4692.7 5470.5 5545.3 5744.2 5929.0 3666.5 3783.8 3856.5 3868.7 3887.4 3389.8 3859.9 3905.9 3918.7 3977.0 4547.3 4570.7 4571.2 4586.4 4599.3 4711.6 5256.9 5449.3 5715.3 5737.4 1212.8 1274.3 1279.9 1287.6 1292.3 807.6 865.1 869.0 880.8 888.8 4476.4 4990.8 5038.6 5045.2 5086.4 4992.2 5613.7 5635.0 5641.0 5647.4 4474.4 4666.9 4688.7 4711.8 4757.3 2848.0 2910.0 2911.7 2917.8 2922.9 5019.9 5254.9 5349.9 5668.7 5731.7 5273.6 5419.4 5621.5 5711.3 5711.6 4753.2 5509.7 5701.9 5740.9 5750.8 5038.7 5214.6 5498.5 5568.5 5616.7 5209.9 5452.7 5712.2 5775.9 5827.6 5074.0 5710.0 5896.0 6030.7 6063.5 3547.8 3586.7 3592.4 3877.0 3877.3 5200.5 5791.5 5799.7 5814.1 5898.7 4589.8 5349.9 5584.5 5611.0 5628.6 4670.4 5164.4 5609.6 5625.6 5645.5 4621.5 5523.9 5526.5 5548.8 5556.3 5261.9 5522.6 5554.8 5612.0 5815.4 4727.4 5461.6 5484.4 5490.8 5520.7 04:48:05 INFO - PROCESS | 1897 | 04:48:05 INFO - PROCESS | 1897 | [#5] yui.html Cycles:180 Average:19084.43 Median:3899.55 stddev:27218.17 (698.0%) stddev-sans-first:27258.95 04:48:05 INFO - PROCESS | 1897 | Values: 549.8 674.7 678.1 712.7 795.1 12760.6 13285.9 13324.9 14037.7 14514.3 13953.8 14033.9 14104.6 14118.6 14179.3 857.2 864.1 870.9 884.5 887.8 883.1 883.8 884.8 887.5 897.5 929.5 932.0 935.8 937.3 938.2 741.3 906.9 924.4 926.7 930.2 687.2 689.3 689.6 692.0 693.2 14870.6 15040.8 15142.8 15467.5 15503.3 12040.0 13651.1 13680.0 13822.4 13976.7 11408.4 11658.9 11696.5 11696.6 12193.9 3583.2 3584.2 3601.1 3603.4 3737.9 80226.0 89147.0 100964.0 104449.5 107476.0 9184.8 10538.7 10568.9 10865.3 10883.6 21773.3 21934.6 22113.4 22130.0 22239.2 7489.4 7522.5 7565.6 7604.0 7640.8 3380.8 4061.2 4165.8 4193.2 4305.5 3495.6 3559.9 3578.8 3584.6 3587.8 557.5 657.2 669.6 675.8 685.2 595.7 607.6 619.5 642.7 658.6 9527.1 9610.3 9660.6 9697.1 9881.9 598.6 649.5 651.9 654.1 665.8 4571.3 4691.6 4704.7 4730.1 4739.4 87504.0 106876.5 107314.0 107754.0 109171.0 52405.7 53638.7 76185.4 76344.0 94235.0 58117.0 77063.6 77593.9 77768.0 78175.0 10472.7 10507.2 10652.5 10723.8 10761.4 40878.4 46860.5 47950.6 48303.0 50571.7 47640.0 61553.0 61995.7 62224.0 63137.0 12626.6 12640.6 12678.6 12747.5 13083.2 11789.6 12595.7 12745.6 13683.0 14181.9 11822.8 12002.5 12705.8 12717.2 13761.9 10906.8 13030.5 13350.2 13444.9 13471.9 12878.5 12894.2 12935.2 13459.8 13769.9 8147.3 10159.8 10618.8 11136.8 11638.4 10426.5 10501.9 10512.5 10514.1 10568.4 04:48:05 INFO - PROCESS | 1897 | -------- Summary: end -------- 04:48:05 INFO - PROCESS | 1897 | 04:48:06 INFO - TEST-INFO | 1897: exit 0 04:48:06 INFO - TEST-OK | dromaeo_css | took 1116730ms 04:48:06 INFO - TEST-START | kraken 04:48:06 INFO - operating with platform_type : mac_ 04:48:06 INFO - Initialising browser for kraken test... 04:48:06 INFO - TEST-INFO | started process 1907 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/tmpPSMREi/profile http://localhost:49377/getInfo.html) 04:48:08 INFO - PROCESS | 1907 | 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:48:08 INFO - PROCESS | 1907 | 2017-02-09 04:48:08.873 plugin-container[1909:10867] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 04:48:08 INFO - PROCESS | 1907 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:48:08 INFO - PROCESS | 1907 | 2017-02-09 04:48:08.875 plugin-container[1909:10867] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9003, name = 'com.apple.CFPasteboardClient' 04:48:08 INFO - PROCESS | 1907 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:48:08 INFO - PROCESS | 1907 | 2017-02-09 04:48:08.875 plugin-container[1909:10867] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:48:08 INFO - PROCESS | 1907 | __metrics Screen width/height:1600/1200 04:48:08 INFO - PROCESS | 1907 | colorDepth:24 04:48:08 INFO - PROCESS | 1907 | Browser inner width/height: 1024/689 04:48:08 INFO - PROCESS | 1907 | __metrics 04:48:09 ERROR - PROCESS | 1907 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3283: TypeError: this.tabs is undefined 04:48:09 INFO - TEST-INFO | 1907: exit 0 04:48:09 INFO - Browser initialized. 04:48:09 INFO - Running cycle 1/1 for kraken test... 04:48:09 INFO - TEST-INFO | started process 1910 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/tmpPSMREi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:48:12 INFO - PROCESS | 1910 | 2017-02-09 04:48:12.045 plugin-container[1911:10981] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x882f, name = 'com.apple.tsm.portname' 04:48:12 INFO - PROCESS | 1910 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:48:12 INFO - PROCESS | 1910 | 2017-02-09 04:48:12.046 plugin-container[1911:10981] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x441b, name = 'com.apple.CFPasteboardClient' 04:48:12 INFO - PROCESS | 1910 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:48:12 INFO - PROCESS | 1910 | 2017-02-09 04:48:12.046 plugin-container[1911:10981] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:48:21 INFO - PROCESS | 1910 | RSS: Main: 233562112 04:48:21 INFO - PROCESS | 1910 | 04:48:25 INFO - PROCESS | 1910 | Cycle 1(1): loaded http://localhost:49377/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49377/tests/kraken/driver.html?testName=audio-beat-detection) 04:48:26 INFO - PROCESS | 1910 | RSS: Main: 247066624 04:48:26 INFO - PROCESS | 1910 | 04:48:28 INFO - PROCESS | 1910 | Cycle 1(1): loaded http://localhost:49377/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49377/tests/kraken/driver.html?testName=audio-dft) 04:48:29 INFO - PROCESS | 1910 | RSS: Main: 254914560 04:48:29 INFO - PROCESS | 1910 | 04:48:33 INFO - PROCESS | 1910 | Cycle 1(1): loaded http://localhost:49377/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49377/tests/kraken/driver.html?testName=audio-fft) 04:48:33 INFO - PROCESS | 1910 | RSS: Main: 257540096 04:48:33 INFO - PROCESS | 1910 | 04:48:35 INFO - PROCESS | 1910 | Cycle 1(1): loaded http://localhost:49377/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49377/tests/kraken/driver.html?testName=audio-oscillator) 04:48:36 INFO - PROCESS | 1910 | RSS: Main: 259731456 04:48:36 INFO - PROCESS | 1910 | 04:48:38 INFO - PROCESS | 1910 | Cycle 1(1): loaded http://localhost:49377/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49377/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:48:39 INFO - PROCESS | 1910 | RSS: Main: 260472832 04:48:39 INFO - PROCESS | 1910 | 04:48:42 INFO - PROCESS | 1910 | Cycle 1(1): loaded http://localhost:49377/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49377/tests/kraken/driver.html?testName=imaging-darkroom) 04:48:43 INFO - PROCESS | 1910 | RSS: Main: 266280960 04:48:43 INFO - PROCESS | 1910 | 04:48:46 INFO - PROCESS | 1910 | Cycle 1(1): loaded http://localhost:49377/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49377/tests/kraken/driver.html?testName=imaging-desaturate) 04:48:46 INFO - PROCESS | 1910 | RSS: Main: 265342976 04:48:46 INFO - PROCESS | 1910 | 04:48:49 INFO - PROCESS | 1910 | Cycle 1(1): loaded http://localhost:49377/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49377/tests/kraken/driver.html?testName=json-parse-financial) 04:48:50 INFO - PROCESS | 1910 | RSS: Main: 266047488 04:48:50 INFO - PROCESS | 1910 | 04:48:51 INFO - PROCESS | 1910 | Cycle 1(1): loaded http://localhost:49377/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49377/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:48:52 INFO - PROCESS | 1910 | RSS: Main: 265338880 04:48:52 INFO - PROCESS | 1910 | 04:48:54 INFO - PROCESS | 1910 | Cycle 1(1): loaded http://localhost:49377/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49377/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:48:55 INFO - PROCESS | 1910 | RSS: Main: 265342976 04:48:55 INFO - PROCESS | 1910 | 04:48:57 INFO - PROCESS | 1910 | Cycle 1(1): loaded http://localhost:49377/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49377/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:48:58 INFO - PROCESS | 1910 | RSS: Main: 265416704 04:48:58 INFO - PROCESS | 1910 | 04:49:01 INFO - PROCESS | 1910 | Cycle 1(1): loaded http://localhost:49377/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49377/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:49:02 INFO - PROCESS | 1910 | RSS: Main: 265764864 04:49:02 INFO - PROCESS | 1910 | 04:49:05 INFO - PROCESS | 1910 | Cycle 1(1): loaded http://localhost:49377/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49377/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:49:05 INFO - PROCESS | 1910 | RSS: Main: 265543680 04:49:05 INFO - PROCESS | 1910 | 04:49:07 INFO - PROCESS | 1910 | Cycle 1(1): loaded http://localhost:49377/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49377/tests/kraken/driver.html?testName=ai-astar) 04:49:07 INFO - PROCESS | 1910 | RSS: Main: 268816384 04:49:07 INFO - PROCESS | 1910 | 04:49:07 INFO - PROCESS | 1910 | __start_tp_report 04:49:07 INFO - PROCESS | 1910 | _x_x_mozilla_page_load 04:49:07 INFO - PROCESS | 1910 | _x_x_mozilla_page_load_details 04:49:07 INFO - PROCESS | 1910 | |i|pagename|runs| 04:49:07 INFO - PROCESS | 1910 | |0;ai-astar;107;167;176;137;117;142;147;119;115;120 04:49:07 INFO - PROCESS | 1910 | |1;audio-beat-detection;150;120;121;190;143;120;137;140;167;145 04:49:07 INFO - PROCESS | 1910 | |2;audio-dft;216;217;251;235;196;229;208;192;290;242 04:49:07 INFO - PROCESS | 1910 | |3;audio-fft;85;70;59;81;81;85;88;86;72;62 04:49:07 INFO - PROCESS | 1910 | |4;audio-oscillator;99;61;88;102;102;103;118;60;92;112 04:49:07 INFO - PROCESS | 1910 | |5;imaging-gaussian-blur;104;109;78;133;136;89;91;124;139;88 04:49:07 INFO - PROCESS | 1910 | |6;imaging-darkroom;95;98;97;97;107;142;95;96;97;105 04:49:07 INFO - PROCESS | 1910 | |7;imaging-desaturate;91;95;81;147;147;93;93;97;93;98 04:49:07 INFO - PROCESS | 1910 | |8;json-parse-financial;64;62;64;64;63;64;64;66;64;64 04:49:07 INFO - PROCESS | 1910 | |9;json-stringify-tinderbox;71;106;73;85;115;103;77;73;72;72 04:49:07 INFO - PROCESS | 1910 | |10;stanford-crypto-aes;92;91;96;90;87;80;111;110;109;108 04:49:07 INFO - PROCESS | 1910 | |11;stanford-crypto-ccm;145;169;143;152;168;162;155;159;187;162 04:49:07 INFO - PROCESS | 1910 | |12;stanford-crypto-pbkdf2;180;183;179;180;183;234;224;179;209;196 04:49:07 INFO - PROCESS | 1910 | |13;stanford-crypto-sha256-iterative;69;71;69;71;70;68;70;70;70;67 04:49:07 INFO - PROCESS | 1910 | __end_tp_report 04:49:07 INFO - PROCESS | 1910 | __start_cc_report 04:49:07 INFO - PROCESS | 1910 | _x_x_mozilla_cycle_collect,975 04:49:07 INFO - PROCESS | 1910 | __end_cc_report 04:49:07 INFO - PROCESS | 1910 | __startTimestamp1486644547835__endTimestamp 04:49:07 INFO - PROCESS | 1910 | 04:49:07 INFO - PROCESS | 1910 | ------- Summary: start ------- 04:49:07 INFO - PROCESS | 1910 | Number of tests: 14 04:49:07 INFO - PROCESS | 1910 | 04:49:07 INFO - PROCESS | 1910 | [#0] ai-astar Cycles:10 Average:134.70 Median:139.50 stddev:23.31 (16.7%) stddev-sans-first:22.47 04:49:07 INFO - PROCESS | 1910 | Values: 107.0 167.0 176.0 137.0 117.0 142.0 147.0 119.0 115.0 120.0 04:49:07 INFO - PROCESS | 1910 | 04:49:07 INFO - PROCESS | 1910 | [#1] audio-beat-detection Cycles:10 Average:143.30 Median:144.00 stddev:22.17 (15.4%) stddev-sans-first:23.38 04:49:07 INFO - PROCESS | 1910 | Values: 150.0 120.0 121.0 190.0 143.0 120.0 137.0 140.0 167.0 145.0 04:49:07 INFO - PROCESS | 1910 | 04:49:07 INFO - PROCESS | 1910 | [#2] audio-dft Cycles:10 Average:227.60 Median:232.00 stddev:29.06 (12.5%) stddev-sans-first:30.52 04:49:07 INFO - PROCESS | 1910 | Values: 216.0 217.0 251.0 235.0 196.0 229.0 208.0 192.0 290.0 242.0 04:49:07 INFO - PROCESS | 1910 | 04:49:07 INFO - PROCESS | 1910 | [#3] audio-fft Cycles:10 Average:76.90 Median:83.00 stddev:10.46 (12.6%) stddev-sans-first:10.68 04:49:07 INFO - PROCESS | 1910 | Values: 85.0 70.0 59.0 81.0 81.0 85.0 88.0 86.0 72.0 62.0 04:49:07 INFO - PROCESS | 1910 | 04:49:07 INFO - PROCESS | 1910 | [#4] audio-oscillator Cycles:10 Average:93.70 Median:60.50 stddev:19.49 (32.2%) stddev-sans-first:20.58 04:49:07 INFO - PROCESS | 1910 | Values: 99.0 61.0 88.0 102.0 102.0 103.0 118.0 60.0 92.0 112.0 04:49:07 INFO - PROCESS | 1910 | 04:49:07 INFO - PROCESS | 1910 | [#5] imaging-gaussian-blur Cycles:10 Average:109.10 Median:108.50 stddev:22.56 (20.8%) stddev-sans-first:23.85 04:49:07 INFO - PROCESS | 1910 | Values: 104.0 109.0 78.0 133.0 136.0 89.0 91.0 124.0 139.0 88.0 04:49:07 INFO - PROCESS | 1910 | 04:49:07 INFO - PROCESS | 1910 | [#6] imaging-darkroom Cycles:10 Average:102.90 Median:96.50 stddev:14.34 (14.9%) stddev-sans-first:14.92 04:49:07 INFO - PROCESS | 1910 | Values: 95.0 98.0 97.0 97.0 107.0 142.0 95.0 96.0 97.0 105.0 04:49:07 INFO - PROCESS | 1910 | 04:49:07 INFO - PROCESS | 1910 | [#7] imaging-desaturate Cycles:10 Average:103.50 Median:93.00 stddev:23.39 (25.1%) stddev-sans-first:24.36 04:49:07 INFO - PROCESS | 1910 | Values: 91.0 95.0 81.0 147.0 147.0 93.0 93.0 97.0 93.0 98.0 04:49:07 INFO - PROCESS | 1910 | 04:49:07 INFO - PROCESS | 1910 | [#8] json-parse-financial Cycles:10 Average:63.90 Median:64.00 stddev:0.99 (1.6%) stddev-sans-first:1.05 04:49:07 INFO - PROCESS | 1910 | Values: 64.0 62.0 64.0 64.0 63.0 64.0 64.0 66.0 64.0 64.0 04:49:07 INFO - PROCESS | 1910 | 04:49:07 INFO - PROCESS | 1910 | [#9] json-stringify-tinderbox Cycles:10 Average:84.70 Median:72.50 stddev:16.83 (23.2%) stddev-sans-first:17.11 04:49:07 INFO - PROCESS | 1910 | Values: 71.0 106.0 73.0 85.0 115.0 103.0 77.0 73.0 72.0 72.0 04:49:07 INFO - PROCESS | 1910 | 04:49:07 INFO - PROCESS | 1910 | [#10] stanford-crypto-aes Cycles:10 Average:97.40 Median:88.50 stddev:11.20 (12.7%) stddev-sans-first:11.70 04:49:07 INFO - PROCESS | 1910 | Values: 92.0 91.0 96.0 90.0 87.0 80.0 111.0 110.0 109.0 108.0 04:49:07 INFO - PROCESS | 1910 | 04:49:07 INFO - PROCESS | 1910 | [#11] stanford-crypto-ccm Cycles:10 Average:160.20 Median:162.00 stddev:12.85 (7.9%) stddev-sans-first:12.39 04:49:07 INFO - PROCESS | 1910 | Values: 145.0 169.0 143.0 152.0 168.0 162.0 155.0 159.0 187.0 162.0 04:49:07 INFO - PROCESS | 1910 | 04:49:07 INFO - PROCESS | 1910 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:194.70 Median:189.50 stddev:20.57 (10.9%) stddev-sans-first:21.12 04:49:07 INFO - PROCESS | 1910 | Values: 180.0 183.0 179.0 180.0 183.0 234.0 224.0 179.0 209.0 196.0 04:49:07 INFO - PROCESS | 1910 | 04:49:07 INFO - PROCESS | 1910 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.50 Median:70.00 stddev:1.27 (1.8%) stddev-sans-first:1.33 04:49:07 INFO - PROCESS | 1910 | Values: 69.0 71.0 69.0 71.0 70.0 68.0 70.0 70.0 70.0 67.0 04:49:07 INFO - PROCESS | 1910 | -------- Summary: end -------- 04:49:07 INFO - PROCESS | 1910 | 04:49:08 INFO - TEST-INFO | 1910: exit 0 04:49:08 INFO - TEST-OK | kraken | took 62954ms 04:49:08 INFO - SUITE-END | took 1179s 04:49:09 INFO - Completed test suite (00:19:39) 04:49:09 INFO - javascript benchmark 04:49:09 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7029.6307105449205, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3295.5715756951595, 3309.8841540546077, 3332.2169290216793, 3359.2609625882296, 3364.798112113275, 5402.0, 5529.087700529413, 5534.833954981352, 5576.414476479972, 5603.579731520143, 5407.756650950707, 5524.723763811813, 5539.556835453164, 5558.388577256509, 5638.774449022045, 4979.352684151056, 5058.822941197059, 5059.0, 5144.61415393847, 5145.922811157836, 4976.054549635558, 5131.153359695646, 5169.40551836538, 5177.0, 5205.115130427836, 4961.578265847407, 5138.614603904722, 5174.715390653512, 5190.377154741455, 5213.869653258661, 4985.252212168168, 5033.370828646419, 5035.3454050973505, 5064.974675126601, 5150.690958542499, 4511.233090374644, 5004.549590536869, 5060.569851562622, 5067.214581739836, 5080.085584594808, 4987.576056035241, 5508.44915508446, 5557.916631250535, 5566.805161819358, 5571.275734154575, 5786.797462088806, 5870.412958704138, 6079.939200608026, 6120.387961203888, 6122.326544080148, 6116.296625888035, 6176.104464852616, 6176.96911515444, 6191.84520386994, 6208.5033197344555, 4754.358161648202, 5682.914756278659, 5700.971495142498, 5702.201691763156, 5710.972025035533, 6039.577229593974, 6143.262808462924, 6157.076438534255, 6180.165677633552, 6208.875822483525, 5074.974625126924, 6000.909986350208, 6040.969795151084, 6063.120847477091, 6101.938980610226, 6077.726502307407, 6107.633541987495, 6182.536309776785, 6209.59637623553, 6222.315545290015, 5758.568107391962, 5818.796342128006, 5829.650220986669, 5909.645421274738, 5984.431479009487, 6123.724432400463, 6128.778133445034, 6141.508679305645, 6157.0456579230295, 6180.165677633552, 4857.392825896763, 4860.270959356054, 4882.707037577757, 4904.534069263485, 4928.901421971612, 4297.978510107429, 4324.17840610283, 4394.626456751212, 4398.142362239332, 4403.691741578059, 5645.576581756384, 5649.576281778883, 5666.433356664301, 5685.431456854281, 5703.600747947604, 6149.200603921461, 6163.260408750979, 6186.783462578878, 6196.628202307876, 6202.56582039262, 5414.377346605112, 5583.0, 5661.037623603915, 5677.659340439504, 5682.147677848273, 5197.948020519822, 5286.524212820787, 5298.602604804655, 5339.092354299701, 5385.246065550826, 6258.937410625836, 6289.0, 6303.558750887394, 6312.589681670676, 6345.206849143857, 6167.383261673976, 6192.287886893065, 6194.876102477835, 6202.751889924533, 6215.223097112861, 5792.464996775773, 5930.703464826827, 6090.269167699905, 6169.198004259418, 6171.352008039271, 6034.517238620988, 6050.969745151246, 6053.243344581927, 6060.363661815446, 6077.0, 6141.938580614136, 6142.0, 6144.170536977451, 6165.753369865155, 6168.19813424252, 4310.655147588248, 5622.353429355677, 5854.677992710442, 5880.117982302689, 5937.584369094295, 5903.409659034062, 5961.254843144607, 5973.462388385066, 5998.0, 6016.067509536029, 5569.860753481195, 5638.548916086621, 5676.20533125371, 5781.913271300849, 5787.681677507778, 5707.6575405475805, 5843.444872737083, 5910.231669882888, 5914.822555323347, 6033.396660333931, 5743.282089738783, 5776.480116789509, 5786.363500014911, 5791.82624521265, 5801.94198058014], "value": 5554.8237423336805, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [430.709271241912, 434.495984657797, 471.4648873528544, 477.4366247827563, 521.7260938007546, 38126.618733812655, 39088.60911390885, 39153.0, 40945.0, 40970.59029409713, 39477.802610986975, 39976.800695979095, 40027.0, 40166.7991660042, 40359.19281614366, 8978.775530611721, 9144.8628270576, 9182.908170918305, 9218.723438296829, 10409.687709368693, 10302.433366164858, 10520.263581549294, 10521.263511554194, 10527.63153289635, 10537.209709271798, 10507.842382364272, 10563.049325560698, 10584.417857017861, 10584.682459526226, 10602.575896964112, 10515.053645171933, 10556.261061725681, 10561.155107591374, 10561.366318020866, 10568.313059651098, 10472.738181545523, 10515.84226236607, 10547.525361358681, 10553.366797992145, 10617.203709721802, 34384.656153438395, 34919.82540087309, 34971.9508414748, 35330.0, 35420.468692969625, 3751.624837516254, 3790.753601015953, 3855.5373355197557, 3856.8842934712, 3857.787821669807, 26688.332791680168, 27714.168574942785, 27729.722702773117, 27818.3045423864, 27853.442931141468, 3033.362993771311, 3073.1548824073334, 3179.7456203503893, 3213.3412650406613, 3222.7744057915957, 443834.0, 445300.0, 445598.5440145622, 446301.0, 448235.0, 45110.0, 46013.309800353025, 53017.0, 53378.932421351354, 53412.46587534153, 56919.430805691416, 57702.7114864423, 57947.71026144927, 58002.0, 58301.70849145727, 14453.205073720836, 14594.489192878198, 14621.122732636077, 14633.048851824597, 14639.268036598127, 10991.06575941038, 11156.16328775345, 11235.66293011211, 11271.830922536168, 11271.830922536168, 16610.335586576402, 16684.082375469465, 16728.665426691397, 16794.496165115066, 16836.915815420845, 7701.961490192625, 7791.649375778104, 7801.180876008051, 7813.570253636105, 7872.842543149105, 823.9917600824035, 834.7161964931831, 842.3766412854444, 853.5732133933034, 859.239572977919, 27754.167374978784, 28940.0, 29037.7096229035, 29306.267343316587, 29693.960711375, 864.5115509736978, 977.0620204603518, 977.4477420257532, 987.4815721745997, 1185.8932696057395, 11359.943200284111, 13620.318984050758, 13780.655483612789, 13885.86114138866, 13918.0, 536519.0, 570950.0, 589567.0, 613413.0, 614889.0, 47341.76329118401, 47862.0, 47953.520464795605, 47976.7601161992, 48089.0, 1285.8328417305877, 1294.9417276222594, 1327.634900402397, 1348.6089034179977, 1357.5045108535512, 22055.889720551295, 23454.0, 23646.763532365145, 26044.609330859672, 26320.010760348177, 41868.79065604774, 42054.7897260524, 42386.36420453634, 42432.0, 42547.0, 570960.0, 571221.0, 571252.0, 571341.0, 573278.1336093292, 20635.690464643343, 21166.576668466852, 23022.539549208588, 23249.88375058114, 23678.408039798454, 38012.0, 38147.618523815516, 38504.0, 38622.80688596539, 38752.418713718755, 27388.904443822023, 33224.687309411136, 33392.332153356314, 33498.33003339968, 33730.831345843115, 32068.51897221497, 32421.675783241866, 32639.0, 33244.00267991964, 33467.83266083654, 32038.83980580082, 32287.838560807046, 32388.514172286963, 32559.511607325436, 32604.347913042082, 28005.299867503476, 29598.40803183881, 29756.851215743784, 29763.958261460746, 29897.240675773355, 22437.61497240618, 22680.0, 22872.771272287064, 22896.427589309733, 22951.540969180856], "value": 18456.049464590575, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [369.96670299673025, 380.46164676982073, 385.58549559223843, 397.87466947911423, 414.82577317526636, 18652.067396630166, 19295.807041929576, 20668.48328791781, 20892.89553552234, 20927.05828237727, 10701.411422371766, 11833.0, 12021.579244726436, 12183.451744671489, 12462.875371246284, 5080.923786143201, 5368.30212072432, 5372.597055220855, 5395.163749618815, 5415.214793854889, 5248.790048398059, 5561.138023606327, 5866.296044474669, 5914.2015827863115, 6005.879882402372, 5974.103884417329, 5981.043033114703, 5988.101784732282, 5990.951583472896, 5998.7600495980105, 5771.740271687784, 5972.134040564116, 6024.126501657235, 6038.788642397539, 6050.485708714765, 5563.165525171216, 5710.971445142789, 5839.883202335929, 5851.648901065907, 5859.326177489601, 10839.566417343296, 13112.344382780919, 13285.269310187936, 13313.467461301632, 16091.83908160915, 2410.337157281744, 2541.1995221505167, 2544.5038217547585, 2547.1467058535413, 2570.5244529762053, 8351.58242087893, 9946.204303655762, 10395.376277423378, 10442.634507792267, 15133.394664213418, 1439.7048605035943, 1833.8899666020081, 1847.122616757043, 1856.0, 1869.7943226245102, 96563.0, 116313.0, 119366.17964882843, 130575.6942430573, 136053.0, 10191.54138063789, 11923.165378423428, 12111.697207569705, 12157.148999569945, 12303.507859685691, 15618.0, 15773.921130394274, 15791.21043947798, 15792.68414631701, 15809.367625294859, 7852.0, 8125.187481251827, 8160.673573057011, 8203.548804815673, 8232.464889782144, 7541.981832452549, 7594.088709354913, 7605.0226940995735, 7699.961500192463, 7747.109082455477, 4775.355327030807, 4835.975820120947, 4869.488703686062, 4885.609151267961, 4938.8271410501175, 2058.9794102058786, 2091.7385326834146, 2098.790120987889, 2103.6528972719634, 2104.1793700456833, 845.5011543189547, 929.4980710416299, 999.5851721535481, 1014.3102690170607, 1047.3035431438009, 19005.524861878563, 20661.896690516453, 21113.472163195736, 21577.56844863094, 21607.783922160575, 1010.3634710132569, 1012.9544170512345, 1013.8580598716186, 1040.7502199472071, 1054.836500342448, 11034.393108378956, 11226.989570938655, 11322.037626801814, 11336.943315283535, 11341.489632966537, 662456.6877165583, 673421.0, 675497.0, 678754.0, 681542.5922870354, 105489.0, 106960.0, 130807.0, 132809.33595332154, 133937.0, 957.5212393803098, 975.4439969217619, 1008.2236677758085, 1025.1337619711333, 1037.0200160848076, 14438.639034024234, 17343.913280433517, 19210.903945480182, 19404.708929366352, 19648.508787280432, 48641.0, 55344.0, 58572.70713646404, 58600.70699646645, 59885.0, 58391.0, 83993.74009389742, 85314.0, 85409.88606082412, 86558.56720716356, 7519.834425664031, 7560.697572097055, 7732.033495813023, 8162.551059691774, 8169.0605580359015, 10961.342319460859, 12512.499500020262, 12909.741805163656, 12926.806097908728, 12968.740625187254, 10754.784904302027, 10861.02250797432, 11056.07014141237, 11057.22599418033, 11072.944635276772, 10563.94718026405, 10695.679129626124, 10872.728181795519, 10955.50700218508, 11124.555017799197, 10837.0, 10905.073068789241, 11141.777164456504, 11148.331100134017, 11254.718632034264, 8141.185881412049, 8334.374921880762, 8350.624221909908, 8451.1548845115, 8584.613692383982, 10394.896051039394, 10617.256792024484, 10928.01647851697, 11110.888891110986, 11348.602798901999], "value": 9367.89630802892, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1218.951241950322, 1328.7541804766124, 1397.2245403800887, 1414.7055444268494, 1432.7349440353535, 6272.247330320357, 8178.263956243937, 8408.495490270572, 8580.442271252365, 8594.570271486424, 8394.286485648727, 8435.24082832545, 8469.153084691528, 8488.108748581402, 8543.01755298141, 2232.162938897909, 2267.875266860322, 2275.8975846086964, 2276.9886150569223, 2288.187693368855, 2193.6618662615792, 2265.7394399644086, 2278.5784629843533, 2304.573653874039, 2307.792298693117, 2039.5614942787297, 2257.4694946687514, 2287.7254729432493, 2287.816974642033, 2304.2280835919987, 2180.258712037899, 2238.630625946725, 2269.897854596547, 2274.5337205872756, 2274.5678321118935, 1965.764108306998, 2058.3824852544326, 2245.371296037112, 2318.258157389636, 2338.7661233876647, 8307.626156822897, 8487.278581320596, 8521.531315777629, 8572.571371431404, 8609.913900860975, 5094.1849304111165, 5247.86880327991, 5250.264962905197, 5253.737313134328, 5260.158374660036, 2864.283929017746, 3379.662033796625, 3411.4541673332396, 3465.8093804840723, 3535.2399234164777, 1206.4209179593756, 1206.867244603093, 1240.894523965464, 1241.5592464675, 1268.0109514578644, 10988.0, 11031.503582338813, 11032.951869572458, 11085.168612354106, 11086.556537738561, 4177.352510360899, 4470.597646211857, 4545.36364908913, 4561.361409402686, 4570.977145114253, 8643.438176518506, 8789.736307910773, 8790.736277911672, 8791.208791208823, 8810.339224558184, 5119.948800511947, 5136.998285334399, 5150.227465880073, 5153.613478989091, 5157.406898206679, 4380.123975204971, 4432.113577284556, 4634.073185362941, 4644.883877903012, 4769.117713223065, 3599.4960705501253, 3607.567091948983, 3649.306631740014, 3680.1535646801385, 3696.963030369716, 954.0, 1078.3691540448926, 1144.2047776795214, 1145.5933143733855, 1160.1356989043218, 861.5304658960881, 870.6909047288248, 876.2770714160843, 911.49530056931, 912.3385545479607, 4841.806327746851, 5473.562115030788, 5714.628549144292, 5717.256756621613, 5728.341240757366, 535.9115745901961, 563.2030676592576, 565.3682109778904, 570.6576054367347, 576.5560518400808, 3779.829907654162, 3786.734928554975, 3790.450384694204, 3876.6704830089757, 3900.6737709179015, 9603.807923841485, 10079.395236285847, 10197.082262596254, 10213.285070045175, 10214.387136771817, 9913.801723965624, 10095.949520252352, 10136.290459667753, 10150.644727434505, 10156.796864062677, 7804.843903121906, 7816.49192802466, 7871.763847084597, 7880.960595196987, 7893.921060789318, 4656.534346565452, 5269.735263536801, 5550.750216240198, 5591.245181900392, 5617.466340697627, 9076.909230907606, 9120.726378208665, 9190.770230744018, 9333.439993600407, 9405.106514881076, 7860.344939603112, 8218.424710270463, 9396.295277854051, 9443.102905223992, 9542.713718588453, 6594.307597702364, 6679.298673639198, 6682.498812588978, 6694.895342268471, 6708.563943343667, 7706.267904549059, 7737.226277372218, 7802.258785415376, 7873.448858579844, 7889.921100788919, 7020.964895175492, 7098.112735908012, 7099.2190859006505, 7120.359167674934, 7160.248173941661, 6696.832579185364, 7002.054722612382, 7010.948357746379, 7224.169220539705, 7258.3104605062435, 6220.315765265907, 6358.1734374531015, 6487.967560162169, 6503.349665033459, 6512.479001679949, 4863.270509423614, 4913.97543012297, 4952.603791696728, 4999.975000124976, 5134.409542902614, 4398.538153493837, 4912.1649319615035, 4978.1288274552535, 4980.32765576657, 5079.212722028092], "value": 4287.046773854802, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [326.5999151039976, 346.5425638157631, 360.81057444841457, 363.4530032301386, 369.2227860353955, 4462.866114016577, 4821.228603423453, 5162.199859021848, 5385.730713464326, 5391.487808658176, 4497.482789479211, 5069.732566858285, 5285.524302812746, 5317.175837745146, 5331.306930099082, 3700.9814950925206, 4186.183694179636, 4215.262329092404, 4227.471566054243, 4231.1114665920195, 3339.699427051577, 3994.900127496807, 4198.068888311365, 4211.894702632443, 4221.113566151096, 3732.973432306124, 3959.366501359783, 3960.2673505401594, 3985.940210896839, 4042.333015052513, 3333.783304085251, 3554.3246783111254, 3828.9279001879518, 3992.6606238469767, 4036.4348991141264, 3233.4503134466963, 3484.285721427101, 3493.7205023598053, 3498.4227602445567, 3501.28223714138, 4275.679324050678, 4283.714885534328, 4804.0, 4935.5804756595735, 5156.685045313458, 4564.2012647786505, 4587.8623641290815, 4595.356650068993, 4602.424696912886, 4611.21609326412, 5586.190002449622, 5613.522850557708, 5614.0, 5650.406707295716, 5668.858278543069, 3212.775105742599, 3788.5074940257864, 3805.6765174959883, 3851.9229615407535, 3859.4789703389965, 4692.741899195543, 5470.452954704538, 5545.334559852843, 5744.219292019446, 5928.970355148197, 3666.5416822897137, 3783.829727662262, 3856.537215534154, 3868.7291889568023, 3887.378019516864, 3389.7627166098137, 3859.938516907873, 3905.902352441155, 3918.6669133123432, 3976.960230397659, 4547.3179023146795, 4570.702904311209, 4571.245744452194, 4586.403767510269, 4599.2871104978785, 4711.575958163782, 5256.92114618281, 5449.3188351456065, 5715.342735585377, 5737.3688894221605, 1212.8120141378101, 1274.3373445808313, 1279.8848103670528, 1287.6265882894043, 1292.2827830553945, 807.5921659561884, 865.0786911938765, 868.9522076285739, 880.7845173660307, 888.7911340834899, 4476.395686582335, 4990.7523119220195, 5038.571721403649, 5045.192769156917, 5086.372360844568, 4992.152903425683, 5613.691246981373, 5634.971825140848, 5641.0, 5647.435256474319, 4474.373587697725, 4666.85662012809, 4688.742119183409, 4711.811527538928, 4757.26262429324, 2847.985760071228, 2910.0, 2911.7333959727584, 2917.7811664125275, 2922.941541169165, 5019.899602007939, 5254.896471740977, 5349.866253343697, 5668.7121469547565, 5731.742071606787, 5273.604479664041, 5419.403865574784, 5621.5221706155435, 5711.286089238845, 5711.628744131618, 4753.155456143281, 5509.724513774296, 5701.92312496567, 5740.913886291709, 5750.827475175752, 5038.672486288379, 5214.556762675215, 5498.532624726783, 5568.49883510486, 5616.69108199053, 5209.869753256199, 5452.727363631723, 5712.200291959046, 5775.9133612995, 5827.59206855516, 5074.010567939216, 5710.0029979014025, 5896.0, 6030.668313242638, 6063.5452341073715, 3547.7516573839584, 3586.659267369596, 3592.4431713084514, 3877.0, 3877.2827027000094, 5200.5059519345605, 5791.48070589295, 5799.681017544076, 5814.069748840166, 5898.734556945034, 4589.751021999263, 5349.946500534945, 5584.459071122711, 5611.046122159324, 5628.577856660849, 4670.416197975253, 5164.431912489698, 5609.551235901036, 5625.634333768292, 5645.548356131418, 4621.491635920113, 5523.8895222094525, 5526.47498487643, 5548.833534993957, 5556.277683901067, 5261.921071184011, 5522.5581953442825, 5554.805581804618, 5611.971940140274, 5815.41845815415, 4727.409073865767, 5461.617686761889, 5484.424135465719, 5490.835274941598, 5520.702634880719], "value": 4049.118286491813, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [549.7938273147572, 674.7469698862926, 678.1150598468996, 712.6971037309144, 795.0657976877168, 12760.55338063168, 13285.93357033216, 13324.933375333121, 14037.736603705664, 14514.346854391551, 13953.790693139586, 14033.859661403383, 14104.64738381541, 14118.64703382416, 14179.291035448212, 857.1728282207683, 864.1229152410309, 870.9390342676015, 884.4914174349742, 887.7647423432795, 883.0992387764477, 883.8409086364452, 884.7876509637705, 887.5118684723409, 897.4660077254024, 929.5166513413055, 932.0306880843914, 935.8081593273397, 937.2642475656611, 938.1744065222595, 741.2587412587412, 906.9047749986222, 924.4176169013512, 926.6895589977365, 930.1907340613642, 687.2405991379503, 689.3313485918701, 689.582802404548, 691.9515633905629, 693.223589579674, 14870.553883383516, 15040.774388384183, 15142.848571514254, 15467.5359739208, 15503.302351394213, 12040.026950814798, 13651.11267767592, 13680.042397032212, 13822.447102115902, 13976.720465590734, 11408.372539510323, 11658.941705291503, 11696.47365868538, 11696.590619328283, 12193.878061219362, 3583.2475180212186, 3584.22939068099, 3601.117726157082, 3603.4414665726854, 3737.8878633641034, 80226.0, 89147.0, 100963.9903600962, 104449.47775261075, 107476.0, 9184.816303674022, 10538.683839484827, 10568.947155264175, 10865.293755905841, 10883.619073332395, 21773.34679959604, 21934.561308774053, 22113.44716382071, 22130.004149813296, 22239.22162724297, 7489.363404110712, 7522.548647081193, 7565.6217189140325, 7604.0, 7640.808979775439, 3380.763346565766, 4061.2080644274674, 4165.8333666653, 4193.161367726467, 4305.526392096866, 3495.632958539368, 3559.928801423957, 3578.7673801202836, 3584.5698516178227, 3587.838547265327, 557.4509108528097, 657.1588366890388, 669.568128557078, 675.7668604331498, 685.2496516314627, 595.6835393779709, 607.6475644126396, 619.5013014523262, 642.7364780439997, 658.6245839871227, 9527.09492598202, 9610.279229057849, 9660.613575456902, 9697.078777516124, 9881.851772223423, 598.643806934873, 649.4998850884792, 651.8761435327273, 654.1057256516359, 665.8102440804347, 4571.268597024527, 4691.554302341272, 4704.717716936994, 4730.075079769148, 4739.3601863748645, 87504.0, 106876.46561767296, 107314.0, 107754.0, 109171.0, 52405.7379713099, 53638.73180634072, 76185.42887426772, 76344.0, 94235.0, 58117.0, 77063.61468192624, 77593.89624415073, 77768.0, 78175.0, 10472.68581942543, 10507.211959102944, 10652.520636571218, 10723.785524289626, 10761.381944029987, 40878.38682419826, 46860.53139468698, 47950.56148315556, 48303.0, 50571.74714126406, 47640.0, 61553.0, 61995.690021549606, 62224.0, 63137.0, 12626.621201363974, 12640.55758048464, 12678.556250531186, 12747.490100396251, 13083.150624041591, 11789.646310610695, 12595.74042595733, 12745.61763147107, 13683.0, 14181.858181418467, 11822.817718228109, 12002.459889304828, 12705.809412859, 12717.236965782082, 13761.931190343985, 10906.781864362827, 13030.478780849038, 13350.163646523462, 13444.865551344361, 13471.865281347062, 12878.484860605471, 12894.226346419246, 12935.223886566837, 13459.79810302866, 13769.862301376857, 8147.3482121431325, 10159.79680406373, 10618.787624247627, 11136.777264454828, 11638.41807909618, 10426.530806113591, 10501.9474902625, 10512.526936287732, 10514.106300964198, 10568.365898046142], "value": 7237.345886566763, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1662.1000000000004, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 167.0, 176.0, 137.0, 117.0, 142.0, 147.0, 119.0, 115.0, 120.0], "value": 134.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 120.0, 121.0, 190.0, 143.0, 120.0, 137.0, 140.0, 167.0, 145.0], "value": 143.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [216.0, 217.0, 251.0, 235.0, 196.0, 229.0, 208.0, 192.0, 290.0, 242.0], "value": 227.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 70.0, 59.0, 81.0, 81.0, 85.0, 88.0, 86.0, 72.0, 62.0], "value": 76.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 61.0, 88.0, 102.0, 102.0, 103.0, 118.0, 60.0, 92.0, 112.0], "value": 93.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 109.0, 78.0, 133.0, 136.0, 89.0, 91.0, 124.0, 139.0, 88.0], "value": 109.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 98.0, 97.0, 97.0, 107.0, 142.0, 95.0, 96.0, 97.0, 105.0], "value": 102.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 95.0, 81.0, 147.0, 147.0, 93.0, 93.0, 97.0, 93.0, 98.0], "value": 103.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 62.0, 64.0, 64.0, 63.0, 64.0, 64.0, 66.0, 64.0, 64.0], "value": 63.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 106.0, 73.0, 85.0, 115.0, 103.0, 77.0, 73.0, 72.0, 72.0], "value": 84.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 91.0, 96.0, 90.0, 87.0, 80.0, 111.0, 110.0, 109.0, 108.0], "value": 97.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 169.0, 143.0, 152.0, 168.0, 162.0, 155.0, 159.0, 187.0, 162.0], "value": 160.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 183.0, 179.0, 180.0, 183.0, 234.0, 224.0, 179.0, 209.0, 196.0], "value": 194.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 71.0, 69.0, 71.0, 70.0, 68.0, 70.0, 70.0, 70.0, 67.0], "value": 69.5, "unit": "score"}]}]} 04:49:09 INFO - Return code: 0 04:49:09 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:49:09 ERROR - # TBPL SUCCESS # 04:49:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:49:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:49:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:49:09 INFO - 2017-02-09 04:49:09.148 screenresolution[1915:11366] starting screenresolution argv=screenresolution get 04:49:09 INFO - 2017-02-09 04:49:09.151 screenresolution[1915:11366] Display 0: 1600x1200x32@60 04:49:09 INFO - 2017-02-09 04:49:09.157 screenresolution[1916:11369] starting screenresolution argv=screenresolution list 04:49:09 INFO - Available Modes on Display 0 04:49:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:49:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:49:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:49:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:49:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:49:09 INFO - Intel Iris: 04:49:09 INFO - Chipset Model: Intel Iris 04:49:09 INFO - Type: GPU 04:49:09 INFO - Bus: Built-In 04:49:09 INFO - VRAM (Dynamic, Max): 1536 MB 04:49:09 INFO - Vendor: Intel (0x8086) 04:49:09 INFO - Device ID: 0x0a2e 04:49:09 INFO - Revision ID: 0x0009 04:49:09 INFO - Displays: 04:49:09 INFO - Display: 04:49:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:49:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:49:09 INFO - Display Serial Number: 1600x1200 60 04:49:09 INFO - Main Display: Yes 04:49:09 INFO - Mirror: Off 04:49:09 INFO - Online: Yes 04:49:09 INFO - Rotation: Supported 04:49:09 INFO - Return code: 0 04:49:09 INFO - Running post-action listener: _resource_record_post_action 04:49:09 INFO - [mozharness: 2017-02-09 12:49:09.403956Z] Finished run-tests step (success) 04:49:09 INFO - Running post-run listener: _resource_record_post_run 04:49:09 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 3630080; Write bytes: 558646784; Read time: 34; Write time: 1849 04:49:09 INFO - TinderboxPrint: CPU usage
54.5% 04:49:09 INFO - TinderboxPrint: I/O read bytes / time
3,630,080 / 34 04:49:09 INFO - TinderboxPrint: I/O write bytes / time
558,646,784 / 1,849 04:49:09 INFO - TinderboxPrint: CPU idle
2,189.9 (45.5%) 04:49:09 INFO - TinderboxPrint: CPU system
970.2 (20.2%) 04:49:09 INFO - TinderboxPrint: CPU user
1,649.7 (34.3%) 04:49:09 INFO - TinderboxPrint: Swap in / out
256,380,928 / 0 04:49:09 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 252652032; Write bytes: 246708224; Read time: 18126; Write time: 608 04:49:09 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 2655232; Write bytes: 301579776; Read time: 29; Write time: 1209 04:49:09 INFO - Running post-run listener: _upload_blobber_files 04:49:09 INFO - Blob upload gear active. 04:49:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:49:09 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:49:09 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:49:09 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:49:10 INFO - (blobuploader) - INFO - Open directory for files ... 04:49:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:49:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:49:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:49:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:49:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:49:11 INFO - (blobuploader) - INFO - Done attempting. 04:49:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:49:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:49:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:49:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:49:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:49:12 INFO - (blobuploader) - INFO - Done attempting. 04:49:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:49:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:49:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:49:13 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:49:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:49:13 INFO - (blobuploader) - INFO - Done attempting. 04:49:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:49:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:49:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:49:15 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:49:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:49:15 INFO - (blobuploader) - INFO - Done attempting. 04:49:15 INFO - (blobuploader) - INFO - Iteration through files over. 04:49:15 INFO - Return code: 0 04:49:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:49:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:49:15 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/120f9c0c1e0715fa8f34eea06abdd9199c1302e5db2032b7db6dafec082ebfd8081b7c3e904de0658d6dcb8fe79d4eab8074295bf32d12a3696a73ab07357934", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/796fd1ff4edbb321c61d232a5357819cf76207e0f3139a62ec0f9686391f54b9a98813e9563293c0ef4672e667f7135dff54ae263cdbeead31fb2b9ffe2e2e0a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eae2ed80de57ca1a3375cce1d944e47605e9fcadc00369d8251fae81bf9e7af0c694bbe4fc362f823382de7e26f41dac4d9a75539c1780b5d9af6d44223517d2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/630eac16d73b788eabfdab462df997b638d8efeb595edcfcb8ee1908d4d09fa782165ac6c603b50f2bed640489fee7901079765ce9900973d61875bef9f61ca1"} 04:49:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:49:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:49:15 INFO - Contents: 04:49:15 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/120f9c0c1e0715fa8f34eea06abdd9199c1302e5db2032b7db6dafec082ebfd8081b7c3e904de0658d6dcb8fe79d4eab8074295bf32d12a3696a73ab07357934", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/796fd1ff4edbb321c61d232a5357819cf76207e0f3139a62ec0f9686391f54b9a98813e9563293c0ef4672e667f7135dff54ae263cdbeead31fb2b9ffe2e2e0a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eae2ed80de57ca1a3375cce1d944e47605e9fcadc00369d8251fae81bf9e7af0c694bbe4fc362f823382de7e26f41dac4d9a75539c1780b5d9af6d44223517d2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/630eac16d73b788eabfdab462df997b638d8efeb595edcfcb8ee1908d4d09fa782165ac6c603b50f2bed640489fee7901079765ce9900973d61875bef9f61ca1"} 04:49:15 INFO - Running post-run listener: copy_logs_to_upload_dir 04:49:15 INFO - Copying logs to upload dir... 04:49:15 INFO - mkdir: /builds/slave/test/build/upload/logs 04:49:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1262.767418 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-02-09 04:49:15.715933) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-09 04:49:15.719366) ========= 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.Q4nofNE2zL/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.ABVtj1RE5R/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/120f9c0c1e0715fa8f34eea06abdd9199c1302e5db2032b7db6dafec082ebfd8081b7c3e904de0658d6dcb8fe79d4eab8074295bf32d12a3696a73ab07357934", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/796fd1ff4edbb321c61d232a5357819cf76207e0f3139a62ec0f9686391f54b9a98813e9563293c0ef4672e667f7135dff54ae263cdbeead31fb2b9ffe2e2e0a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eae2ed80de57ca1a3375cce1d944e47605e9fcadc00369d8251fae81bf9e7af0c694bbe4fc362f823382de7e26f41dac4d9a75539c1780b5d9af6d44223517d2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/630eac16d73b788eabfdab462df997b638d8efeb595edcfcb8ee1908d4d09fa782165ac6c603b50f2bed640489fee7901079765ce9900973d61875bef9f61ca1"} build_url:https://queue.taskcluster.net/v1/task/DaDNVud2SSWuqkyX4aZNPw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011328 build_url: 'https://queue.taskcluster.net/v1/task/DaDNVud2SSWuqkyX4aZNPw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/120f9c0c1e0715fa8f34eea06abdd9199c1302e5db2032b7db6dafec082ebfd8081b7c3e904de0658d6dcb8fe79d4eab8074295bf32d12a3696a73ab07357934", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/796fd1ff4edbb321c61d232a5357819cf76207e0f3139a62ec0f9686391f54b9a98813e9563293c0ef4672e667f7135dff54ae263cdbeead31fb2b9ffe2e2e0a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eae2ed80de57ca1a3375cce1d944e47605e9fcadc00369d8251fae81bf9e7af0c694bbe4fc362f823382de7e26f41dac4d9a75539c1780b5d9af6d44223517d2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/630eac16d73b788eabfdab462df997b638d8efeb595edcfcb8ee1908d4d09fa782165ac6c603b50f2bed640489fee7901079765ce9900973d61875bef9f61ca1"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-09 04:49:15.754102) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 04:49:15.754393) ========= 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.Q4nofNE2zL/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.ABVtj1RE5R/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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.005245 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 04:49:15.802916) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-09 04:49:15.804714) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-09 04:49:15.805077) ========= ========= Total master_lag: 0.18 =========