builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0346 starttime: 1479840940.6 results: success (0) buildid: 20161122081851 builduid: 3bcd1a8ba9a940bebe2a10509b08dbd9 revision: 16b857fbf1ab78641be7ea6c8f4e2cad163193cc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-22 10:55:40.600628) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-22 10:55:40.601114) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-22 10:55:40.601418) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-22 10:55:40.629277) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 10:55:40.629705) ========= 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.8Qzlss1Elk/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.iTgWaX1bt3/Listeners TMPDIR=/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 --2016-11-22 10:55:40-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 29.8M=0s 2016-11-22 10:55:40 (29.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.144283 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 10:55:40.798287) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 10:55:40.798634) ========= 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.8Qzlss1Elk/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.iTgWaX1bt3/Listeners TMPDIR=/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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.027375 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 10:55:40.847813) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-22 10:55:40.848185) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 16b857fbf1ab78641be7ea6c8f4e2cad163193cc --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 16b857fbf1ab78641be7ea6c8f4e2cad163193cc --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.8Qzlss1Elk/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.iTgWaX1bt3/Listeners TMPDIR=/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 2016-11-22 10:55:40,947 truncating revision to first 12 chars 2016-11-22 10:55:40,947 Setting DEBUG logging. 2016-11-22 10:55:40,947 attempt 1/10 2016-11-22 10:55:40,947 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/16b857fbf1ab?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-22 10:55:42,046 unpacking tar archive at: mozilla-aurora-16b857fbf1ab/testing/mozharness/ program finished with exit code 0 elapsedTime=1.419027 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-22 10:55:42.291418) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-22 10:55:42.291764) ========= script_repo_revision: 16b857fbf1ab78641be7ea6c8f4e2cad163193cc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-22 10:55:42.292257) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-22 10:55:42.292839) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-22 10:55:42.313330) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-11-22 10:55:42.313733) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.8Qzlss1Elk/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.iTgWaX1bt3/Listeners TMPDIR=/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 10:55:42 INFO - MultiFileLogger online at 20161122 10:55:42 in /builds/slave/test 10:55:42 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 10:55:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:55:42 INFO - {'append_to_log': False, 10:55:42 INFO - 'base_work_dir': '/builds/slave/test', 10:55:42 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 10:55:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:55:42 INFO - 'branch': 'Mozilla-Aurora', 10:55:42 INFO - 'buildbot_json_path': 'buildprops.json', 10:55:42 INFO - 'config_files': ('talos/mac_config.py',), 10:55:42 INFO - 'default_actions': ('clobber', 10:55:42 INFO - 'read-buildbot-config', 10:55:42 INFO - 'download-and-extract', 10:55:42 INFO - 'populate-webroot', 10:55:42 INFO - 'create-virtualenv', 10:55:42 INFO - 'install', 10:55:42 INFO - 'run-tests'), 10:55:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:55:42 INFO - 'download_minidump_stackwalk': True, 10:55:42 INFO - 'download_symbols': 'ondemand', 10:55:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:55:42 INFO - 'tooltool.py': '/tools/tooltool.py', 10:55:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:55:42 INFO - '/tools/misc-python/virtualenv.py')}, 10:55:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:55:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:55:42 INFO - 'installer_path': 'installer.exe', 10:55:42 INFO - 'log_level': 'info', 10:55:42 INFO - 'log_name': 'talos', 10:55:42 INFO - 'log_to_console': True, 10:55:42 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:55:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:55:42 INFO - 'opt_config_files': (), 10:55:42 INFO - 'pip_index': False, 10:55:42 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:55:42 INFO - 'cmd': ('bash', 10:55:42 INFO - '-c', 10:55:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:55:42 INFO - 'enabled': True, 10:55:42 INFO - 'halt_on_failure': False, 10:55:42 INFO - 'name': 'check_screen_resolution'},), 10:55:42 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:55:42 INFO - 'cmd': ('bash', 10:55:42 INFO - '-c', 10:55:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:55:42 INFO - 'enabled': True, 10:55:42 INFO - 'halt_on_failure': False, 10:55:42 INFO - 'name': 'check_screen_resolution'},), 10:55:42 INFO - 'run_cmd_checks_enabled': True, 10:55:42 INFO - 'sps_profile': False, 10:55:42 INFO - 'sps_profile_interval': 0, 10:55:42 INFO - 'suite': 'dromaeojs', 10:55:42 INFO - 'system_bits': '32', 10:55:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:55:42 INFO - 'title': 't-yosemite-r7-0346', 10:55:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:55:42 INFO - 'use_talos_json': True, 10:55:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:55:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:55:42 INFO - 'work_dir': 'build'} 10:55:42 INFO - [mozharness: 2016-11-22 18:55:42.520387Z] Running clobber step. 10:55:42 INFO - Running pre-action listener: _resource_record_pre_action 10:55:42 INFO - Running main action method: clobber 10:55:42 INFO - rmtree: /builds/slave/test/build 10:55:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:55:45 INFO - Running post-action listener: _resource_record_post_action 10:55:45 INFO - [mozharness: 2016-11-22 18:55:45.054723Z] Finished clobber step (success) 10:55:45 INFO - [mozharness: 2016-11-22 18:55:45.054846Z] Running read-buildbot-config step. 10:55:45 INFO - Running pre-action listener: _resource_record_pre_action 10:55:45 INFO - Running main action method: read_buildbot_config 10:55:45 INFO - Using buildbot properties: 10:55:45 INFO - { 10:55:45 INFO - "project": "", 10:55:45 INFO - "product": "firefox", 10:55:45 INFO - "script_repo_revision": "production", 10:55:45 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 10:55:45 INFO - "repository": "", 10:55:45 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 10:55:45 INFO - "buildid": "20161122081851", 10:55:45 INFO - "pgo_build": "False", 10:55:45 INFO - "basedir": "/builds/slave/test", 10:55:45 INFO - "buildnumber": 2, 10:55:45 INFO - "slavename": "t-yosemite-r7-0346", 10:55:45 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 10:55:45 INFO - "platform": "yosemite_r7", 10:55:45 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 10:55:45 INFO - "branch": "mozilla-aurora", 10:55:45 INFO - "repo_path": "releases/mozilla-aurora", 10:55:45 INFO - "revision": "16b857fbf1ab78641be7ea6c8f4e2cad163193cc", 10:55:45 INFO - "stage_platform": "macosx64", 10:55:45 INFO - "builduid": "3bcd1a8ba9a940bebe2a10509b08dbd9", 10:55:45 INFO - "slavebuilddir": "test" 10:55:45 INFO - } 10:55:45 INFO - Found installer url https://queue.taskcluster.net/v1/task/Rz4JgEs6RkKsln0o_SeT5Q/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 10:55:45 INFO - Running post-action listener: _resource_record_post_action 10:55:45 INFO - [mozharness: 2016-11-22 18:55:45.058099Z] Finished read-buildbot-config step (success) 10:55:45 INFO - [mozharness: 2016-11-22 18:55:45.058206Z] Running download-and-extract step. 10:55:45 INFO - Running pre-action listener: _resource_record_pre_action 10:55:45 INFO - Running main action method: download_and_extract 10:55:45 INFO - mkdir: /builds/slave/test/build/tests 10:55:45 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')]} 10:55:45 INFO - trying https://queue.taskcluster.net/v1/task/Rz4JgEs6RkKsln0o_SeT5Q/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 10:55:45 INFO - Downloading https://queue.taskcluster.net/v1/task/Rz4JgEs6RkKsln0o_SeT5Q/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 10:55:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Rz4JgEs6RkKsln0o_SeT5Q/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 10:55:46 INFO - Downloaded 1199 bytes. 10:55:46 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 10:55:46 INFO - Using the following test package requirements: 10:55:46 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 10:55:46 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:55:46 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 10:55:46 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:55:46 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 10:55:46 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:55:46 INFO - u'jsshell-mac.zip'], 10:55:46 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:55:46 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 10:55:46 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 10:55:46 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:55:46 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 10:55:46 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:55:46 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 10:55:46 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:55:46 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 10:55:46 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:55:46 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 10:55:46 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 10:55:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Rz4JgEs6RkKsln0o_SeT5Q/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 10:55:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Rz4JgEs6RkKsln0o_SeT5Q/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 10:55:46 INFO - Fetch https://queue.taskcluster.net/v1/task/Rz4JgEs6RkKsln0o_SeT5Q/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 10:55:46 INFO - Http code: 200 10:55:46 INFO - via: 1.1 032d42677fbf05c24a4bb71369581429.cloudfront.net (CloudFront) 10:55:46 INFO - x-amz-cf-id: R_f1PH1an-JCNiDHZzmRO-Xe5jQ3jYG98racQh6hdMUogHEkWUto7A== 10:55:46 INFO - x-amz-version-id: o9W47O.k8QayfLCiBMYeVdFzDulQQf8v 10:55:46 INFO - Expected file size: 16277516 10:55:46 INFO - Obtained file size: 16277516 10:55:53 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 10:55:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Rz4JgEs6RkKsln0o_SeT5Q/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 10:55:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Rz4JgEs6RkKsln0o_SeT5Q/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 10:55:53 INFO - Fetch https://queue.taskcluster.net/v1/task/Rz4JgEs6RkKsln0o_SeT5Q/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 10:55:54 INFO - Http code: 200 10:55:54 INFO - via: 1.1 e2af8a85927835558866752f53562ecd.cloudfront.net (CloudFront) 10:55:54 INFO - x-amz-cf-id: hSfN3LWy7v0BriFGB15ZtmO0LVoC0pUhYHA84ZrhN1aYW7XxYf0OLQ== 10:55:54 INFO - x-amz-version-id: o9W47O.k8QayfLCiBMYeVdFzDulQQf8v 10:55:54 INFO - Expected file size: 16277516 10:55:54 INFO - Obtained file size: 16277516 10:56:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Rz4JgEs6RkKsln0o_SeT5Q/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 10:56:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Rz4JgEs6RkKsln0o_SeT5Q/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 10:56:00 INFO - Fetch https://queue.taskcluster.net/v1/task/Rz4JgEs6RkKsln0o_SeT5Q/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 10:56:01 INFO - Http code: 200 10:56:01 INFO - via: 1.1 41b2ee7cbe95749816a7586d9ab4629d.cloudfront.net (CloudFront) 10:56:01 INFO - x-amz-cf-id: aNfI-tKkpDLiBgTwqTRuEXkCHGx6-7xHs-mxJaGl3dCWZSfn2S3Pdw== 10:56:01 INFO - x-amz-version-id: clbw4kmHygW5pX7mffNr_ToAFbik9Crf 10:56:01 INFO - Expected file size: 14409038 10:56:01 INFO - Obtained file size: 14409038 10:56:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:56:02 INFO - trying https://queue.taskcluster.net/v1/task/Rz4JgEs6RkKsln0o_SeT5Q/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 10:56:02 INFO - Downloading https://queue.taskcluster.net/v1/task/Rz4JgEs6RkKsln0o_SeT5Q/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 10:56:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Rz4JgEs6RkKsln0o_SeT5Q/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 10:56:05 INFO - Downloaded 109677208 bytes. 10:56:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Rz4JgEs6RkKsln0o_SeT5Q/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 10:56:05 INFO - mkdir: /builds/slave/test/properties 10:56:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:56:05 INFO - Writing to file /builds/slave/test/properties/build_url 10:56:05 INFO - Contents: 10:56:05 INFO - build_url:https://queue.taskcluster.net/v1/task/Rz4JgEs6RkKsln0o_SeT5Q/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 10:56:06 INFO - Running post-action listener: _resource_record_post_action 10:56:06 INFO - Running post-action listener: set_extra_try_arguments 10:56:06 INFO - [mozharness: 2016-11-22 18:56:06.149977Z] Finished download-and-extract step (success) 10:56:06 INFO - [mozharness: 2016-11-22 18:56:06.150090Z] Running populate-webroot step. 10:56:06 INFO - Running pre-action listener: _resource_record_pre_action 10:56:06 INFO - Running main action method: populate_webroot 10:56:06 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:56:06 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:56:06 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:56:06 INFO - '--tppagecycles', 10:56:06 INFO - '7'], 10:56:06 INFO - 'tests': ['tsvgm']}}, 10:56:06 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:56:06 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:56:06 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:56:06 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:56:06 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:56:06 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:56:06 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:56:06 INFO - 'talos_options': ['--disable-e10s'], 10:56:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:56:06 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:56:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:56:06 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:56:06 INFO - 'talos_options': ['--disable-e10s'], 10:56:06 INFO - 'tests': ['damp', 'tps']}, 10:56:06 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:56:06 INFO - 'tests': ['damp', 'tps']}, 10:56:06 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:56:06 INFO - 'tests': ['dromaeo_dom']}, 10:56:06 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:56:06 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:56:06 INFO - 'tests': ['basic_compositor_video']}, 10:56:06 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:56:06 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:56:06 INFO - 'tests': ['a11yr', 10:56:06 INFO - 'ts_paint', 10:56:06 INFO - 'tpaint', 10:56:06 INFO - 'sessionrestore', 10:56:06 INFO - 'sessionrestore_no_auto_restore', 10:56:06 INFO - 'tabpaint']}, 10:56:06 INFO - 'other-e10s': {'tests': ['a11yr', 10:56:06 INFO - 'ts_paint', 10:56:06 INFO - 'tpaint', 10:56:06 INFO - 'sessionrestore', 10:56:06 INFO - 'sessionrestore_no_auto_restore', 10:56:06 INFO - 'tabpaint']}, 10:56:06 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:56:06 INFO - 'ts_paint', 10:56:06 INFO - 'tpaint', 10:56:06 INFO - 'sessionrestore', 10:56:06 INFO - 'sessionrestore_no_auto_restore', 10:56:06 INFO - 'tabpaint']}, 10:56:06 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:56:06 INFO - 'ts_paint', 10:56:06 INFO - 'tpaint', 10:56:06 INFO - 'sessionrestore', 10:56:06 INFO - 'sessionrestore_no_auto_restore', 10:56:06 INFO - 'tabpaint']}, 10:56:06 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:56:06 INFO - 'tests': ['a11yr', 10:56:06 INFO - 'ts_paint', 10:56:06 INFO - 'tpaint', 10:56:06 INFO - 'sessionrestore', 10:56:06 INFO - 'sessionrestore_no_auto_restore', 10:56:06 INFO - 'tabpaint']}, 10:56:06 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:56:06 INFO - 'tests': ['a11yr', 10:56:06 INFO - 'ts_paint', 10:56:06 INFO - 'tpaint', 10:56:06 INFO - 'sessionrestore', 10:56:06 INFO - 'sessionrestore_no_auto_restore', 10:56:06 INFO - 'tabpaint']}, 10:56:06 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:56:06 INFO - 'tests': ['tsvgx', 10:56:06 INFO - 'tsvgr_opacity', 10:56:06 INFO - 'tart', 10:56:06 INFO - 'tscrollx', 10:56:06 INFO - 'cart']}, 10:56:06 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:56:06 INFO - 'tsvgr_opacity', 10:56:06 INFO - 'tart', 10:56:06 INFO - 'tscrollx', 10:56:06 INFO - 'cart']}, 10:56:06 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:56:06 INFO - 'talos_options': ['--disable-e10s'], 10:56:06 INFO - 'tests': ['tp5o']}, 10:56:06 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:56:06 INFO - 'tests': ['tp5o']}, 10:56:06 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:56:06 INFO - 'talos_options': ['--disable-e10s', 10:56:06 INFO - '--xperf_path', 10:56:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:56:06 INFO - 'tests': ['tp5n']}, 10:56:06 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:56:06 INFO - 'talos_options': ['--xperf_path', 10:56:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:56:06 INFO - 'tests': ['tp5n']}}, 10:56:06 INFO - 'talos.zip': {'path': '', 10:56:06 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:56:06 INFO - Running post-action listener: _resource_record_post_action 10:56:06 INFO - [mozharness: 2016-11-22 18:56:06.168529Z] Finished populate-webroot step (success) 10:56:06 INFO - [mozharness: 2016-11-22 18:56:06.168658Z] Running create-virtualenv step. 10:56:06 INFO - Running pre-action listener: _resource_record_pre_action 10:56:06 INFO - Running main action method: create_virtualenv 10:56:06 INFO - Creating virtualenv /builds/slave/test/build/venv 10:56:06 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 10:56:06 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:56:06 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:56:06 INFO - Using real prefix '/tools/python27' 10:56:06 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:56:07 INFO - Installing distribute.............................................................................................................................................................................................done. 10:56:09 INFO - Installing pip.................done. 10:56:09 INFO - Return code: 0 10:56:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:56:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:56:10 INFO - Reading from file tmpfile_stdout 10:56:10 INFO - Output received: 10:56:10 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) 10:56:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:56:10 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')]} 10:56:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:56:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:56:10 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')]} 10:56:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:56:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:56:10 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 0x10793a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078b0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8560cc6b90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10774ed30>, '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 0x1075b4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107746b20>, '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.8Qzlss1Elk/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.iTgWaX1bt3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 10:56:10 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 10:56:10 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 10:56:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8Qzlss1Elk/Render', 10:56:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:56:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:56:10 INFO - 'HOME': '/Users/cltbld', 10:56:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:56:10 INFO - 'LOGNAME': 'cltbld', 10:56:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:56:10 INFO - 'MOZ_NO_REMOTE': '1', 10:56:10 INFO - 'NO_EM_RESTART': '1', 10:56:10 INFO - 'PAGER': '/bin/cat', 10:56:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:56:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:56:10 INFO - 'PWD': '/builds/slave/test', 10:56:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:56:10 INFO - 'SHELL': '/bin/bash', 10:56:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iTgWaX1bt3/Listeners', 10:56:10 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 10:56:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:56:10 INFO - 'USER': 'cltbld', 10:56:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:56:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:56:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:56:10 INFO - 'XPC_FLAGS': '0x0', 10:56:10 INFO - 'XPC_SERVICE_NAME': '0', 10:56:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:56:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:56:10 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 10:56:10 INFO - Cleaning up... 10:56:10 INFO - Return code: 0 10:56:10 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:56:10 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')]} 10:56:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:56:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:56:10 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')]} 10:56:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:56:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:56:10 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 0x10793a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078b0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8560cc6b90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10774ed30>, '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 0x1075b4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107746b20>, '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.8Qzlss1Elk/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.iTgWaX1bt3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 10:56:10 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 10:56:10 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 10:56:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8Qzlss1Elk/Render', 10:56:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:56:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:56:10 INFO - 'HOME': '/Users/cltbld', 10:56:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:56:10 INFO - 'LOGNAME': 'cltbld', 10:56:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:56:10 INFO - 'MOZ_NO_REMOTE': '1', 10:56:10 INFO - 'NO_EM_RESTART': '1', 10:56:10 INFO - 'PAGER': '/bin/cat', 10:56:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:56:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:56:10 INFO - 'PWD': '/builds/slave/test', 10:56:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:56:10 INFO - 'SHELL': '/bin/bash', 10:56:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iTgWaX1bt3/Listeners', 10:56:10 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 10:56:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:56:10 INFO - 'USER': 'cltbld', 10:56:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:56:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:56:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:56:10 INFO - 'XPC_FLAGS': '0x0', 10:56:10 INFO - 'XPC_SERVICE_NAME': '0', 10:56:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:56:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:56:10 INFO - Downloading/unpacking psutil>=3.1.1 10:56:10 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 10:56:10 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 10:56:10 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 10:56:10 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 10:56:10 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 10:56:10 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 10:56:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:56:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:56:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:56:14 INFO - Installing collected packages: psutil 10:56:14 INFO - Running setup.py install for psutil 10:56:14 INFO - building 'psutil._psutil_osx' extension 10:56:14 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 10:56:14 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 10:56:14 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 10:56:14 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 10:56:14 INFO - building 'psutil._psutil_posix' extension 10:56:14 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 10:56:15 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:56:15 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:56:15 INFO - ^ 10:56:15 INFO - 1 warning generated. 10:56:15 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 10:56:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:56:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:56:15 INFO - Successfully installed psutil 10:56:15 INFO - Cleaning up... 10:56:15 INFO - Return code: 0 10:56:15 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:56:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:56:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:56:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:56:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:56:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:56:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:56:15 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 0x10793a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078b0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8560cc6b90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10774ed30>, '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 0x1075b4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107746b20>, '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.8Qzlss1Elk/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.iTgWaX1bt3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 10:56:15 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 10:56:15 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 10:56:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8Qzlss1Elk/Render', 10:56:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:56:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:56:15 INFO - 'HOME': '/Users/cltbld', 10:56:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:56:15 INFO - 'LOGNAME': 'cltbld', 10:56:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:56:15 INFO - 'MOZ_NO_REMOTE': '1', 10:56:15 INFO - 'NO_EM_RESTART': '1', 10:56:15 INFO - 'PAGER': '/bin/cat', 10:56:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:56:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:56:15 INFO - 'PWD': '/builds/slave/test', 10:56:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:56:15 INFO - 'SHELL': '/bin/bash', 10:56:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iTgWaX1bt3/Listeners', 10:56:15 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 10:56:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:56:15 INFO - 'USER': 'cltbld', 10:56:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:56:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:56:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:56:15 INFO - 'XPC_FLAGS': '0x0', 10:56:15 INFO - 'XPC_SERVICE_NAME': '0', 10:56:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:56:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:56:15 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:56:15 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 10:56:15 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 10:56:15 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 10:56:15 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 10:56:15 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 10:56:15 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 10:56:18 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:56:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:56:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:56:18 INFO - Installing collected packages: mozsystemmonitor 10:56:18 INFO - Running setup.py install for mozsystemmonitor 10:56:18 INFO - Successfully installed mozsystemmonitor 10:56:18 INFO - Cleaning up... 10:56:18 INFO - Return code: 0 10:56:18 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:56:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:56:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:56:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:56:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:56:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:56:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:56:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10793a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078b0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8560cc6b90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10774ed30>, '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 0x1075b4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107746b20>, '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.8Qzlss1Elk/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.iTgWaX1bt3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 10:56:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 10:56:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 10:56:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8Qzlss1Elk/Render', 10:56:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:56:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:56:18 INFO - 'HOME': '/Users/cltbld', 10:56:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:56:18 INFO - 'LOGNAME': 'cltbld', 10:56:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:56:18 INFO - 'MOZ_NO_REMOTE': '1', 10:56:18 INFO - 'NO_EM_RESTART': '1', 10:56:18 INFO - 'PAGER': '/bin/cat', 10:56:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:56:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:56:18 INFO - 'PWD': '/builds/slave/test', 10:56:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:56:18 INFO - 'SHELL': '/bin/bash', 10:56:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iTgWaX1bt3/Listeners', 10:56:18 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 10:56:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:56:18 INFO - 'USER': 'cltbld', 10:56:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:56:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:56:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:56:18 INFO - 'XPC_FLAGS': '0x0', 10:56:18 INFO - 'XPC_SERVICE_NAME': '0', 10:56:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:56:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:56:18 INFO - Downloading/unpacking jsonschema==2.5.1 10:56:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:56:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:56:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:56:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:56:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:56:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:56:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:56:21 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:56:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:56:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:56:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:56:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:56:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:56:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:56:21 INFO - Downloading functools32-3.2.3-2.tar.gz 10:56:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:56:21 INFO - warning: no files found matching '*.txt' 10:56:21 INFO - no previously-included directories found matching 'build' 10:56:21 INFO - no previously-included directories found matching 'dist' 10:56:21 INFO - no previously-included directories found matching '.git*' 10:56:21 INFO - Installing collected packages: jsonschema, functools32 10:56:21 INFO - Running setup.py install for functools32 10:56:21 INFO - warning: no files found matching '*.txt' 10:56:21 INFO - no previously-included directories found matching 'build' 10:56:21 INFO - no previously-included directories found matching 'dist' 10:56:21 INFO - no previously-included directories found matching '.git*' 10:56:21 INFO - Successfully installed jsonschema functools32 10:56:21 INFO - Cleaning up... 10:56:22 INFO - Return code: 0 10:56:22 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:56:22 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')]} 10:56:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:56:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:56:22 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')]} 10:56:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:56:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:56:22 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 0x10793a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078b0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8560cc6b90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10774ed30>, '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 0x1075b4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107746b20>, '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.8Qzlss1Elk/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.iTgWaX1bt3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 10:56:22 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 10:56:22 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 10:56:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8Qzlss1Elk/Render', 10:56:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:56:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:56:22 INFO - 'HOME': '/Users/cltbld', 10:56:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:56:22 INFO - 'LOGNAME': 'cltbld', 10:56:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:56:22 INFO - 'MOZ_NO_REMOTE': '1', 10:56:22 INFO - 'NO_EM_RESTART': '1', 10:56:22 INFO - 'PAGER': '/bin/cat', 10:56:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:56:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:56:22 INFO - 'PWD': '/builds/slave/test', 10:56:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:56:22 INFO - 'SHELL': '/bin/bash', 10:56:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iTgWaX1bt3/Listeners', 10:56:22 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 10:56:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:56:22 INFO - 'USER': 'cltbld', 10:56:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:56:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:56:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:56:22 INFO - 'XPC_FLAGS': '0x0', 10:56:22 INFO - 'XPC_SERVICE_NAME': '0', 10:56:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:56:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:56:22 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:56:22 INFO - Cleaning up... 10:56:22 INFO - Return code: 0 10:56:22 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:56:22 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')]} 10:56:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:56:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:56:22 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')]} 10:56:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:56:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:56:22 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 0x10793a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078b0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8560cc6b90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10774ed30>, '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 0x1075b4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107746b20>, '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.8Qzlss1Elk/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.iTgWaX1bt3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 10:56:22 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 10:56:22 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 10:56:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8Qzlss1Elk/Render', 10:56:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:56:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:56:22 INFO - 'HOME': '/Users/cltbld', 10:56:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:56:22 INFO - 'LOGNAME': 'cltbld', 10:56:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:56:22 INFO - 'MOZ_NO_REMOTE': '1', 10:56:22 INFO - 'NO_EM_RESTART': '1', 10:56:22 INFO - 'PAGER': '/bin/cat', 10:56:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:56:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:56:22 INFO - 'PWD': '/builds/slave/test', 10:56:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:56:22 INFO - 'SHELL': '/bin/bash', 10:56:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iTgWaX1bt3/Listeners', 10:56:22 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 10:56:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:56:22 INFO - 'USER': 'cltbld', 10:56:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:56:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:56:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:56:22 INFO - 'XPC_FLAGS': '0x0', 10:56:22 INFO - 'XPC_SERVICE_NAME': '0', 10:56:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:56:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:56:22 INFO - Downloading/unpacking blobuploader==1.2.4 10:56:22 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 10:56:22 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 10:56:22 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 10:56:22 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 10:56:22 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 10:56:22 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 10:56:25 INFO - Downloading blobuploader-1.2.4.tar.gz 10:56:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:56:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:56:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:56:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:56:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:56:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:56:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:56:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:56:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:56:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:56:26 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 10:56:26 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 10:56:26 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 10:56:26 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 10:56:26 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 10:56:26 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 10:56:26 INFO - Downloading docopt-0.6.1.tar.gz 10:56:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:56:26 INFO - Installing collected packages: blobuploader, requests, docopt 10:56:26 INFO - Running setup.py install for blobuploader 10:56:26 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:56:26 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:56:26 INFO - Running setup.py install for requests 10:56:27 INFO - Running setup.py install for docopt 10:56:27 INFO - Successfully installed blobuploader requests docopt 10:56:27 INFO - Cleaning up... 10:56:27 INFO - Return code: 0 10:56:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:56:27 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')]} 10:56:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:56:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:56:27 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')]} 10:56:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:56:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:56:27 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 0x10793a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078b0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8560cc6b90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10774ed30>, '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 0x1075b4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107746b20>, '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.8Qzlss1Elk/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.iTgWaX1bt3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 10:56:27 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 10:56:27 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 10:56:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8Qzlss1Elk/Render', 10:56:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:56:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:56:27 INFO - 'HOME': '/Users/cltbld', 10:56:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:56:27 INFO - 'LOGNAME': 'cltbld', 10:56:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:56:27 INFO - 'MOZ_NO_REMOTE': '1', 10:56:27 INFO - 'NO_EM_RESTART': '1', 10:56:27 INFO - 'PAGER': '/bin/cat', 10:56:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:56:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:56:27 INFO - 'PWD': '/builds/slave/test', 10:56:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:56:27 INFO - 'SHELL': '/bin/bash', 10:56:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iTgWaX1bt3/Listeners', 10:56:27 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 10:56:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:56:27 INFO - 'USER': 'cltbld', 10:56:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:56:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:56:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:56:27 INFO - 'XPC_FLAGS': '0x0', 10:56:27 INFO - 'XPC_SERVICE_NAME': '0', 10:56:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:56:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:56:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:56:27 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-cZxtIn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:56:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:56:27 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-F6UzdZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:56:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:56:27 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-fpZEdx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:56:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:56:28 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-twugnc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:56:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:56:28 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-NvXUhV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:56:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:56:28 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-fbfLjI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:56:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:56:28 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-D8WQO9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:56:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:56:28 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-MqR7D4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:56:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:56:28 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-ePoxqI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:56:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:56:28 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-D_NfbU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:56:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:56:28 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-kLtnW0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:56:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:56:28 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-cO753j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:56:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:56:28 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-NoRChl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:56:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:56:29 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-POwlgH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:56:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:56:29 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-dhK48P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:56:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:56:29 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-_Shrxd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:56:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:56:29 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-tVh_Vz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:56:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:56:29 INFO - Running setup.py install for manifestparser 10:56:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:56:29 INFO - Running setup.py install for mozcrash 10:56:29 INFO - Running setup.py install for mozdebug 10:56:29 INFO - Running setup.py install for mozdevice 10:56:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:56:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:56:30 INFO - Running setup.py install for mozfile 10:56:30 INFO - Running setup.py install for mozhttpd 10:56:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:56:30 INFO - Running setup.py install for mozinfo 10:56:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:56:30 INFO - Running setup.py install for mozInstall 10:56:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:56:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:56:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:56:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:56:30 INFO - Running setup.py install for mozleak 10:56:30 INFO - Running setup.py install for mozlog 10:56:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:56:31 INFO - Running setup.py install for moznetwork 10:56:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:56:31 INFO - Running setup.py install for mozprocess 10:56:31 INFO - Running setup.py install for mozprofile 10:56:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:56:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:56:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:56:31 INFO - Running setup.py install for mozrunner 10:56:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:56:31 INFO - Running setup.py install for mozscreenshot 10:56:31 INFO - Running setup.py install for moztest 10:56:31 INFO - Running setup.py install for mozversion 10:56:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:56:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:56:32 INFO - Cleaning up... 10:56:32 INFO - Return code: 0 10:56:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:56: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')]} 10:56:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:56:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:56: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')]} 10:56:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:56:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:56:32 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 0x10793a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078b0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8560cc6b90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10774ed30>, '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 0x1075b4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107746b20>, '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.8Qzlss1Elk/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.iTgWaX1bt3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 10:56:32 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 10:56:32 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 10:56:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8Qzlss1Elk/Render', 10:56:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:56:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:56:32 INFO - 'HOME': '/Users/cltbld', 10:56:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:56:32 INFO - 'LOGNAME': 'cltbld', 10:56:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:56:32 INFO - 'MOZ_NO_REMOTE': '1', 10:56:32 INFO - 'NO_EM_RESTART': '1', 10:56:32 INFO - 'PAGER': '/bin/cat', 10:56:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:56:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:56:32 INFO - 'PWD': '/builds/slave/test', 10:56:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:56:32 INFO - 'SHELL': '/bin/bash', 10:56:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iTgWaX1bt3/Listeners', 10:56:32 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 10:56:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:56:32 INFO - 'USER': 'cltbld', 10:56:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:56:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:56:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:56:32 INFO - 'XPC_FLAGS': '0x0', 10:56:32 INFO - 'XPC_SERVICE_NAME': '0', 10:56:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:56:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:56:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:56:32 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-Qj5TCi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:56:32 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)) 10:56:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:56:32 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-BL2a8w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:56:32 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)) 10:56:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:56:32 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-qHs4iy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:56:32 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)) 10:56:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:56:32 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-ooRxpS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:56:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:56:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:56:32 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-4AACRy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:56:32 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)) 10:56:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:56:32 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-10IRtS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:56:32 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)) 10:56:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:56:32 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-6RN27X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:56:33 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)) 10:56:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:56:33 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-ag0Ivu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:56:33 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)) 10:56:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:56:33 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-Qi0oTi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:56:33 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)) 10:56:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:56:33 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-BEgLkL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:56:33 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)) 10:56:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:56:33 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-TsafZK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:56:33 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)) 10:56:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:56:33 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-SYrhj9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:56:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 10:56:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:56:33 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-fvdO5K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:56:33 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)) 10:56:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:56:33 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-mQlRnn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:56:33 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)) 10:56:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:56:33 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-aMsljl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:56:33 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)) 10:56:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:56:33 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-i5v3JG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:56:34 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 10:56:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:56:34 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-ogaozh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:56:34 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)) 10:56:34 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)) 10:56:34 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)) 10:56:34 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)) 10:56:34 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:56:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:56:34 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:56:34 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 10:56:34 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 10:56:34 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 10:56:34 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 10:56:34 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 10:56:34 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 10:56:36 INFO - Downloading blessings-1.6.tar.gz 10:56:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:56:36 INFO - Installing collected packages: blessings 10:56:36 INFO - Running setup.py install for blessings 10:56:36 INFO - Successfully installed blessings 10:56:36 INFO - Cleaning up... 10:56:37 INFO - Return code: 0 10:56:37 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:56:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:56:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:56:37 INFO - Reading from file tmpfile_stdout 10:56:37 INFO - Current package versions: 10:56:37 INFO - blessings == 1.6 10:56:37 INFO - blobuploader == 1.2.4 10:56:37 INFO - docopt == 0.6.1 10:56:37 INFO - functools32 == 3.2.3-2 10:56:37 INFO - jsonschema == 2.5.1 10:56:37 INFO - manifestparser == 1.1 10:56:37 INFO - mozInstall == 1.12 10:56:37 INFO - mozcrash == 1.0 10:56:37 INFO - mozdebug == 0.1 10:56:37 INFO - mozdevice == 0.48 10:56:37 INFO - mozfile == 1.2 10:56:37 INFO - mozhttpd == 0.7 10:56:37 INFO - mozinfo == 0.9 10:56:37 INFO - mozleak == 0.1 10:56:37 INFO - mozlog == 3.4 10:56:37 INFO - moznetwork == 0.27 10:56:37 INFO - mozprocess == 0.23 10:56:37 INFO - mozprofile == 0.28 10:56:37 INFO - mozrunner == 6.13 10:56:37 INFO - mozscreenshot == 0.1 10:56:37 INFO - mozsystemmonitor == 0.3 10:56:37 INFO - moztest == 0.7 10:56:37 INFO - mozversion == 1.4 10:56:37 INFO - psutil == 3.1.1 10:56:37 INFO - requests == 1.2.3 10:56:37 INFO - wsgiref == 0.1.2 10:56:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:56:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:56:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:56:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:56:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:56:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:56:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:56:38 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 0x10793a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078b0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8560cc6b90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10774ed30>, '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 0x1075b4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107746b20>, '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.8Qzlss1Elk/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.iTgWaX1bt3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 10:56:38 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 10:56:38 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 10:56:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8Qzlss1Elk/Render', 10:56:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:56:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:56:38 INFO - 'HOME': '/Users/cltbld', 10:56:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:56:38 INFO - 'LOGNAME': 'cltbld', 10:56:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:56:38 INFO - 'MOZ_NO_REMOTE': '1', 10:56:38 INFO - 'NO_EM_RESTART': '1', 10:56:38 INFO - 'PAGER': '/bin/cat', 10:56:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:56:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:56:38 INFO - 'PWD': '/builds/slave/test', 10:56:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:56:38 INFO - 'SHELL': '/bin/bash', 10:56:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iTgWaX1bt3/Listeners', 10:56:38 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 10:56:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:56:38 INFO - 'USER': 'cltbld', 10:56:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:56:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:56:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:56:38 INFO - 'XPC_FLAGS': '0x0', 10:56:38 INFO - 'XPC_SERVICE_NAME': '0', 10:56:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:56:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:56:38 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)) 10:56:38 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)) 10:56:38 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)) 10:56:38 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)) 10:56:38 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)) 10:56:38 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)) 10:56:38 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)) 10:56:38 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)) 10:56:38 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)) 10:56:38 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)) 10:56:38 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)) 10:56:38 INFO - Cleaning up... 10:56:38 INFO - Return code: 0 10:56:38 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:56: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')]} 10:56:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:56:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:56: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')]} 10:56:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:56:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:56: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10793a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078b0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8560cc6b90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10774ed30>, '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 0x1075b4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107746b20>, '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.8Qzlss1Elk/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.iTgWaX1bt3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 10:56: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', 'jsonschema'] in /builds/slave/test/build 10:56: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 jsonschema 10:56:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8Qzlss1Elk/Render', 10:56:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:56:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:56:38 INFO - 'HOME': '/Users/cltbld', 10:56:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:56:38 INFO - 'LOGNAME': 'cltbld', 10:56:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:56:38 INFO - 'MOZ_NO_REMOTE': '1', 10:56:38 INFO - 'NO_EM_RESTART': '1', 10:56:38 INFO - 'PAGER': '/bin/cat', 10:56:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:56:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:56:38 INFO - 'PWD': '/builds/slave/test', 10:56:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:56:38 INFO - 'SHELL': '/bin/bash', 10:56:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iTgWaX1bt3/Listeners', 10:56:38 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 10:56:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:56:38 INFO - 'USER': 'cltbld', 10:56:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:56:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:56:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:56:38 INFO - 'XPC_FLAGS': '0x0', 10:56:38 INFO - 'XPC_SERVICE_NAME': '0', 10:56:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:56:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:56:39 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:56:39 INFO - Cleaning up... 10:56:39 INFO - Return code: 0 10:56:39 INFO - Running post-action listener: _resource_record_post_action 10:56:39 INFO - Running post-action listener: _start_resource_monitoring 10:56:39 INFO - Starting resource monitoring. 10:56:39 INFO - [mozharness: 2016-11-22 18:56:39.046832Z] Finished create-virtualenv step (success) 10:56:39 INFO - [mozharness: 2016-11-22 18:56:39.047876Z] Running install step. 10:56:39 INFO - Running pre-action listener: _resource_record_pre_action 10:56:39 INFO - Running main action method: install 10:56:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:56:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:56:39 INFO - Reading from file tmpfile_stdout 10:56:39 INFO - Detecting whether we're running mozinstall >=1.0... 10:56:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:56:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:56:39 INFO - Reading from file tmpfile_stdout 10:56:39 INFO - Output received: 10:56:39 INFO - Usage: mozinstall [options] installer 10:56:39 INFO - Options: 10:56:39 INFO - -h, --help show this help message and exit 10:56:39 INFO - -d DEST, --destination=DEST 10:56:39 INFO - Directory to install application into. [default: 10:56:39 INFO - "/builds/slave/test"] 10:56:39 INFO - --app=APP Application being installed. [default: firefox] 10:56:39 INFO - mkdir: /builds/slave/test/build/application 10:56:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 10:56:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 10:57:10 INFO - Reading from file tmpfile_stdout 10:57:10 INFO - Output received: 10:57:10 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 10:57:10 INFO - Running post-action listener: _resource_record_post_action 10:57:10 INFO - [mozharness: 2016-11-22 18:57:10.460676Z] Finished install step (success) 10:57:10 INFO - [mozharness: 2016-11-22 18:57:10.460841Z] Running run-tests step. 10:57:10 INFO - Running pre-action listener: _resource_record_pre_action 10:57:10 INFO - Running main action method: run_tests 10:57:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:57:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:57:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:57:10 INFO - 2016-11-22 10:57:10.475 screenresolution[1622:7378] starting screenresolution argv=screenresolution get 10:57:10 INFO - 2016-11-22 10:57:10.478 screenresolution[1622:7378] Display 0: 1600x1200x32@60 10:57:10 INFO - 2016-11-22 10:57:10.484 screenresolution[1623:7380] starting screenresolution argv=screenresolution list 10:57:10 INFO - Available Modes on Display 0 10:57:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:57:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:57:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:57:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:57:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:57:10 INFO - Intel Iris: 10:57:10 INFO - Chipset Model: Intel Iris 10:57:10 INFO - Type: GPU 10:57:10 INFO - Bus: Built-In 10:57:10 INFO - VRAM (Dynamic, Max): 1536 MB 10:57:10 INFO - Vendor: Intel (0x8086) 10:57:10 INFO - Device ID: 0x0a2e 10:57:10 INFO - Revision ID: 0x0009 10:57:10 INFO - Displays: 10:57:10 INFO - Display: 10:57:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:57:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:57:10 INFO - Display Serial Number: 1600x1200 60 10:57:10 INFO - Main Display: Yes 10:57:10 INFO - Mirror: Off 10:57:10 INFO - Online: Yes 10:57:10 INFO - Rotation: Supported 10:57:10 INFO - Return code: 0 10:57:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:57:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:57:10 INFO - Python 2.7.3 10:57:10 INFO - Return code: 0 10:57:10 INFO - grabbing minidump binary from tooltool 10:57:10 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')]} 10:57:10 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 0x10774ed30>, '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 0x1075b4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107746b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:57:10 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 10:57:10 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 10:57:10 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 10:57:10 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:57:10 INFO - Return code: 0 10:57:10 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:57:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:57:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:57:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:57:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:57:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Rz4JgEs6RkKsln0o_SeT5Q/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0346', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 10:57:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Rz4JgEs6RkKsln0o_SeT5Q/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0346 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 10:57:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8Qzlss1Elk/Render', 10:57:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:57:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:57:10 INFO - 'HOME': '/Users/cltbld', 10:57:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:57:10 INFO - 'LOGNAME': 'cltbld', 10:57:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:57:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:57:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:57:10 INFO - 'MOZ_NO_REMOTE': '1', 10:57:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:57:10 INFO - 'NO_EM_RESTART': '1', 10:57:10 INFO - 'PAGER': '/bin/cat', 10:57:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:57:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:57:10 INFO - 'PWD': '/builds/slave/test', 10:57:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:57:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:57:10 INFO - 'SHELL': '/bin/bash', 10:57:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iTgWaX1bt3/Listeners', 10:57:10 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 10:57:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:57:10 INFO - 'USER': 'cltbld', 10:57:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:57:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:57:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:57:10 INFO - 'XPC_FLAGS': '0x0', 10:57:10 INFO - 'XPC_SERVICE_NAME': '0', 10:57:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:57:10 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Rz4JgEs6RkKsln0o_SeT5Q/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0346', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 10:57:11 INFO - mozversion application_buildid: 20161122081851 10:57:11 INFO - mozversion application_changeset: 16b857fbf1ab78641be7ea6c8f4e2cad163193cc 10:57:11 INFO - mozversion application_display_name: FirefoxDeveloperEdition 10:57:11 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:57:11 INFO - mozversion application_name: Firefox 10:57:11 INFO - mozversion application_remotingname: firefox-dev 10:57:11 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:57:11 INFO - mozversion application_vendor: Mozilla 10:57:11 INFO - mozversion application_version: 52.0a2 10:57:11 INFO - mozversion platform_buildid: 20161122081851 10:57:11 INFO - mozversion platform_changeset: 16b857fbf1ab78641be7ea6c8f4e2cad163193cc 10:57:11 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:57:11 INFO - mozversion platform_version: 52.0a2 10:57:11 INFO - using testdate: 1479841031 10:57:11 INFO - actual date: 1479841031 10:57:11 INFO - starting webserver on 'localhost:49249' 10:57:11 INFO - SUITE-START | Running 2 tests 10:57:11 INFO - TEST-START | dromaeo_css 10:57:11 INFO - operating with platform_type : mac_ 10:57:11 INFO - Initialising browser for dromaeo_css test... 10:57:11 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/tmpoGUQwZ/profile http://localhost:49249/getInfo.html) 10:57:13 ERROR - PROCESS | 1633 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5373: TypeError: parent.firstChild is null 10:57:13 INFO - PROCESS | 1633 | __metrics Screen width/height:1600/1200 10:57:13 INFO - PROCESS | 1633 | colorDepth:24 10:57:13 INFO - PROCESS | 1633 | Browser inner width/height: 1024/689 10:57:13 INFO - PROCESS | 1633 | __metrics 10:57:13 INFO - PROCESS | 1633 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:57:13 INFO - PROCESS | 1633 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:57:14 INFO - TEST-INFO | 1633: exit 0 10:57:14 INFO - Browser initialized. 10:57:14 INFO - Running cycle 1/1 for dromaeo_css test... 10:57:14 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/tmpoGUQwZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:57:27 INFO - PROCESS | 1635 | RSS: Main: 222978048 10:57:27 INFO - PROCESS | 1635 | 10:58:18 INFO - PROCESS | 1635 | 1479841098044 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 11:00:32 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49249/tests/dromaeo/cssquery-ext.html) 11:00:35 INFO - PROCESS | 1635 | RSS: Main: 415387648 11:00:35 INFO - PROCESS | 1635 | 11:03:37 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-ext.html (next: http://localhost:49249/tests/dromaeo/cssquery-jquery.html) 11:03:38 INFO - PROCESS | 1635 | RSS: Main: 348946432 11:03:38 INFO - PROCESS | 1635 | 11:06:40 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49249/tests/dromaeo/cssquery-mootools.html) 11:06:41 INFO - PROCESS | 1635 | RSS: Main: 317075456 11:06:41 INFO - PROCESS | 1635 | 11:09:43 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49249/tests/dromaeo/cssquery-prototype.html) 11:09:44 INFO - PROCESS | 1635 | RSS: Main: 336343040 11:09:44 INFO - PROCESS | 1635 | 11:12:47 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49249/tests/dromaeo/cssquery-yui.html) 11:12:48 INFO - PROCESS | 1635 | RSS: Main: 307093504 11:12:48 INFO - PROCESS | 1635 | 11:15:51 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-yui.html (next: http://localhost:49249/tests/dromaeo/cssquery-dojo.html) 11:15:51 INFO - PROCESS | 1635 | RSS: Main: 499965952 11:15:51 INFO - PROCESS | 1635 | 11:15:51 INFO - PROCESS | 1635 | __start_tp_report 11:15:51 INFO - PROCESS | 1635 | _x_x_mozilla_page_load 11:15:51 INFO - PROCESS | 1635 | _x_x_mozilla_page_load_details 11:15:51 INFO - PROCESS | 1635 | |i|pagename|runs| 11:15:51 INFO - PROCESS | 1635 | |0;dojo.html;3092.0723782865134;3391.3725960697275;3408.6080100788386;3409.301093275879;3469.028671971849;5490.752916118774;5498.092814685582;5724.6851423171765;5872.471477567024;5877.588568800185;5757.68332741699;5846.415358464152;5851.79518716845;5859.7070146492715;5860.648361098337;5226.7909283628705;5264.8157314494;5293.073712100387;5295.8146464873735;5309.973450132763;5208.166693329068;5217.34783152106;5234.7120908350025;5355.384130824966;5369.677819330833;5257.7108259045735;5293.708846013467;5293.947060529403;5300;5300.549453296455;4454.53227411122;5300.973495132538;5301.867453313659;5304.787808487656;5311.9468805311835;5157.277981082614;5251.396089449686;5251.422343542207;5256.8685782855355;5274.709890955995;5843.18195452637;5845.912311315334;5857.794977175821;5864.677442740647;5869.1196320551835;6192.28788689302;6225.439710426083;6226.62640241587;6228.56400051992;6236.282827474854;5370.91943620846;6257.061440783873;6265.216847894013;6314.842128946767;6334.714937827763;5831.096180092092;5847.707614619252;5911.704414779244;5925.111233314994;5927.525797936155;6229.190205273286;6295.496360291166;6300.991841305369;6315.242170939517;6317.46301564363;6111.622125543816;6124.421942273956;6138.785142519991;6150.04674275488;6156.045812899008;6247.375262473716;6302.810915672537;6306.211723534559;6315.179026726496;6329.620222786648;6327.588706734047;6329.905051424233;6342.936570634327;6345.17512723343;6362.586431882006;6121.300552271563;6179.227596550431;6194.5044396449075;6219.191505104398;6357.809265722036;4948.158813001798;4977.228529577921;4993.975030124826;4994.800207991712;5033.119204139261;4433.490148632884;4499.797509112098;4509.6843220974215;4525.140223357552;4532.206863798822;5686.175504551817;5794.188813566104;5797.101449275369;5947.821565353046;5957.672328021914;6218.782342618078;6275.748970041239;6295.496360291258;6301.275353334425;6306.810795676137;5957.1362152487645;5958.255218097738;5959.131697805324;5962.195103661037;5994.28068631767;5144.6913185209005;5477.397486276506;5487.067198576252;5489.99999999992;5497.835064948058;5847.970760146172;6198.783042593487;6202.689865506797;6253.312135664983;6288;6261.311255761953;6266.436020758154;6270.310265870659;6283.151774510383;6296.433321001132;6220.160278362544;6366;6368.172137622079;6375.394337537927;6386.137871387304;5863.794767183128;6130.5402094842175;6158.384161583985;6294.1188233648245;6298.338674439301;6230.59501132425;6294.3076261612105;6298.149749783904;6302.590331628429;6321.8735625288155;6225.533085018551;6256.343083976117;6262.749490020348;6268.091126786499;6284.340144284967;5136.0755064087325;6038.879222415615;6188.566800323934;6225.8132256032395;6243.125962365181;5392.137258038695;6042.093685947143;6050.364711705208;6110.40687992437;6129.358790882775;6222.688865556614;6228.283747369111;6230.345813689497;6246.6876656166105;6271.498280137669;5862.973979553646;5912.054071348563;5914.792982245601;5930.703464826827;5942.554308426799 11:15:51 INFO - PROCESS | 1635 | |1;ext.html;472.5794043301462;481.88434775653855;502.61549914315543;508.453412581475;542.3952293193091;29866.954656587022;39107.02232444187;39898;40028.59971400285;42367.78816105915;40994.795026024905;41442.37836432448;41616;41654.58345416545;41666.16667666652;9183.08169183083;9354.204892584139;9358.578863951105;9363;9374.250059995218;9267.026962168977;9346.906530934672;9375.624975000992;9395.718128456123;9402.81194376112;9354.204892584139;9407.20038796703;9410.153086222239;9418.434893906353;9434.952825235865;8392.866962960014;9232.30757693169;9337.066293370677;9387.389819661701;9389.342746007782;9141.908580914238;9142.222911052568;9245.953770231106;9254.305927055431;9260.39807412516;32770;39289.41065884014;39398.80300598507;39409.80295098535;39421;3174.2699179188685;3214.1803840020643;3241.33552621712;3683.336999340118;3811.0662887592716;28671;28772;30397.24006899823;30903.536446953312;31123.37753244945;3190.1546090286092;3198.2324242181708;3203.439398105322;3280.425925463034;3299.5215693724517;490154;492049;492468;492614;492950;32944.67055329416;33964.1508962273;34018.48972265418;51711.96576068457;56830.43169568251;35265;35319.64680353215;35347.82326088353;35397.82301088478;35411.82294088513;14305.78541321881;14445.638859028399;14446.566603001927;14450.710985780226;14455;12156.939215303866;12304.384780761106;12304.753904921852;12323.260604363766;12359.443825027894;6705.631190284484;7705.922940770521;7795.571243581544;7796.688132474751;7811.687532498637;3017.0043885517725;3055.4041961817675;3108.689131086873;3194.4888817789506;3202.711755941976;724.3380576381776;845.3786466946789;897.8922529296395;1006.5520843224695;1046.6853315657763;28462.71537284642;28929.987450439134;29032;29099.41801163965;29724.55413168804;1044.2116202267391;1056.5087234436023;1058.5606973106076;1076.6554702495282;1080.4813689429118;13334.399952002183;14364.209968451627;15090.54728358151;15239.466618668293;15464.922675386777;674192;678387;678976.6051169713;681128;681642;57579.84840303254;58116.54708632318;58139.127913081335;58203.835923281295;58348.70825645845;1242.6396345059832;1333.5665908579751;1342.100792469048;1368.8836448901757;1376.0711519724146;25094.99620015179;28332;28991.855040724662;29200.853995729885;29329.826806927485;48100.5189948096;48818.26772598485;48855;48890.51109488859;48983;603588;607947;608510;608749;608958.9552052212;25722.485550289264;25832.095876644227;26190.476190475703;26278;26296.159268851763;45533.54466455293;45545.772271138434;45575.77212113918;45603.54396456126;46119.538804611526;37850;38073.80963095167;38501.4224786634;39123.804380979054;39560.60439395684;37752.86741397763;37760.2447951034;38231.253777717924;38825;39049.804750976065;29612.223266604073;31444.899428519893;33629.663703362654;37568;37774.62225377711;29455.85272073626;32201.35597287994;33267.83366083251;34462.655373445945;34649.48025779565;25039.374015649755;25391.746082539677;25588.104416346083;25700.742992569834;25746.485070298862 11:15:51 INFO - PROCESS | 1635 | |2;jquery.html;354.34976817539814;371.4799281006592;374.7189607794154;387.43241151712743;407.7920260667059;14268.71462570748;14412.567622971328;14893.329800158992;19704;20625.38123856283;19408.320708775194;19410.90294548529;19900.303489377875;20110.798892011077;20236.29172978946;5501.862453438666;5796.710164491779;5797.130430435426;5797.594168408213;5812.709364531777;4443.88890277744;6342.84142896429;6416.967915160418;6467.5472716909835;6474.5791523550815;6119.66341851198;6374.107624932513;6407.615543067431;6433.678316084201;6441.033844923276;6385.361463853601;6388.744450221986;6391.392817682313;6403.391677790602;6407.423331900105;5045.016221836743;5311.044012077825;5359.517643412111;5410.242566040757;5578.414266501999;12596.937015314956;12685.985121190282;12720.427580758887;12871.292078935654;12874.163179393403;2099.370188943326;2101.1280318667737;2109.8817626657897;2125.553633736917;2142.871427714342;9697.806043879084;10097.939716329753;10256.846147307797;12094;14116;1761.5596100974756;1782.6523827853573;1855.6752568300494;1860.3674750584867;1883.6421079994757;97538.99999999929;111410.44294778474;118368.07819210594;118753;123556;9153.77115572116;9700.417974921527;9792.265580081523;9824.705258842247;11310.604128855615;10223.619811325525;10650.467476626138;11737.706557335963;12247.32639704807;12397.876021239672;7621.961890190624;7791.259830316224;7823.843523129505;7850.2934735872905;8001.239882211181;8168.387370947203;8197.836043279101;8311.709090181814;8332.50004999702;8398.41211115216;3761.134938963999;3815.790131542736;3822.8470861165797;3876.2441323942126;3913.1391093959282;1636.967260654804;1639.4999525144706;1860.237302705884;1914.2056046740724;1915.932942347039;893.3032234856822;894.0969620683027;926.7914719188128;957.1768279279814;982.8771403574553;19333;20364.490887727927;20420.693689594667;20426.285080022128;20451.97740113018;909.1226965977863;916.408916249029;931.5295775633261;990.9207263418909;1000.0499525450706;10069.496525173712;10226.201461456089;10465.9476702616;10532.526036328383;10567.365958042543;561431;588216;594701;614480;615158;92242;119191.40404297924;125075.3746231263;129196;133140.67437302592;898.9730308090768;934.1779234273796;981.4160574458328;1004.3973615830589;1006.2101250518308;15620.453284135001;18298.993555354584;18664.34674786376;18741.531461713566;18889.622207556047;54984.450155497936;55274.72362638161;55814.72092639511;56752.71623641856;56873.715631421575;61304;78978;79676.60161699154;81028.78456823035;86024.5698771502;7852.006552411854;7867.9606601966625;7868.645910934136;8065.919340806517;8163.510189388656;10872.61945831892;10895.237333386904;12117.848804363572;12149;12375.938120309342;10498.265121441425;10757.193210509087;11092.944535277273;11174.441277936234;11182.832257516306;9433.858492122486;10598;10709.410982395731;11011.449427528752;11089.946455086754;10724.89275107239;10781.407022613832;11028.28316159447;11275.210735248455;11333;7761.223877612194;7860.882086768589;7922.762317130495;7992.400569957393;8093.109757926741;10238.641647542301;10347.379157250589;10531.894681053398;10952.835707464235;11094.889051109385 11:15:51 INFO - PROCESS | 1635 | |3;mootools.html;1467.4277031957536;1498.3931507739367;1499.6925630245794;1820.6995845685462;1840.1351364858513;8261.87607185892;8691.999999999993;8692.565371731413;8693.30453563715;8694.130586941319;7622.237776222375;7840.607969601511;7859.80350491238;8207.58962051898;8482.703105391312;2325.7557956414585;2386.188695843413;2390.2112302940077;2396.0775101585928;2396.281115665302;2325.337278875521;2357.481354102095;2362.3503536527505;2414.070582825616;2423.248792874215;2364.7871691547757;2371.8221133414977;2392.83250172488;2394.7126344838643;2403.07481619576;2352.188494969233;2370.8577485350847;2389.1518510928545;2389.593769059247;2394.3295877154337;2382.868942208178;2403.1468828565958;2409.8795060247094;2412.0834083048503;2415.420299128197;8467;8473.49159050459;8539.01801292853;8558.572071396467;8571.914280857236;5176.456472070454;5320.787168513255;5334.306540149756;5341.43914888935;5352.866178345534;3313.8177400242976;3522.5244591980013;3530.16498399409;3657.762245454037;3659.1218107654236;1001.6227686930471;1048.8636477257908;1080.5569716416232;1090.2640717515724;1113.6603335982522;10819.350838949687;10929.234953553254;11002.33985960837;11024;11080.722981925517;4543.727376357429;4619.006913513611;4633.0733853229485;4745.335653008581;4821.421429428491;8390.706325278708;9576.856347154799;9603.135717785433;9629.470379129076;9630.133288004114;5343.011542864582;5347.679139251657;5349.037173308841;5352.678839269578;5353.4646535346155;3941.4172945641044;4089.734167279117;4621.052684199731;4732.881677958009;4866.6350023747655;3684.889453316351;3691.298653255873;3749.1376983293994;3768.453574231721;3786.015635934677;1149.0007387146304;1257.7547378261152;1280.6798300424894;1290.909636325467;1293.5537239652306;826.6404114210337;830.754927296449;885.3183049052194;911.1708345405649;926.2451102351562;5764.423557644202;5774.797882074191;5839.445252700986;5954.583179177416;5965.821025369246;923.3213588012806;933.8972713001565;934.1779234273931;956.6269155029544;995.2336700740534;3838.846446142179;3940.1087577285143;3950.822213000422;4049.6152865477734;4052.574479679591;10117.291789574807;10420.739481513023;10489.423081730425;10494.160467162608;10553.63062292816;10444;10479.84280235797;10490.580376784996;10497.422641754623;10499.002594753487;8003.359731221488;8130.674773009132;8143.307818835466;8161.306288965386;8171.999999999881;5430.212619170277;5557.305336832896;5563.638363506359;5778.739956701875;5785.450382213683;8177.67289308421;9496.192823609794;9611.663591774255;9622.951885240529;9629.277804164856;9426.575804088696;9664.323497355117;9743.61025558998;9755.756106097404;9919.355241909252;6835.282295359115;7180.928190718235;7412.62936853166;7436.330730234305;7468.9626551866895;8107.635156417858;8113.472624279403;8197.918020819954;8209.589520524069;8216.424850260424;7214.170370407261;7215.494915355875;7269.781906542812;7305.561666300039;7394.223606521453;7081.114860642419;7117.1459424869345;7118.501704880816;7156.212816590275;7245.891311630225;6801.693923773541;6912.4815638828295;6927.93072069293;6931.826704332432;6937.06349642812;4799.328094066764;4837.733924634038;4912.533309335608;4943.901121977612;5028.170351891897;4288.785560721889;4509.391232183613;4846.248831431134;4852.927206091841;4990.251462280687 11:15:51 INFO - PROCESS | 1635 | |4;prototype.html;299.959505466762;304.6800859097948;308.9845507724613;317.541153033866;318.96172459304887;5058.140116180249;5203.401608814987;5243.265942768011;5316.49493298137;5331.333583302087;4900.064474532557;5020.824271150511;5054.671446355985;5191.844244672657;5213.165893457048;3566.7324950628677;3995.3407687731633;4013.8595149169787;4041.333180025292;4059.8579049733266;3427.5372824668725;3704.685101766353;3723.1622884850963;3781.111438811877;4061.878143655695;3601.595377802659;3994.2810294147043;3997.4203740457615;4008.959910400888;4011.438398624195;3506.2461570762152;3968.583298753633;3982.4026396040827;3984.00399900025;4018.718689691723;3461.3077384522944;3471.2710330830555;3481.8781342653137;3491.9476207856646;3506.7019303359243;4224.831006759726;5133.562602471314;5152.330197074394;5212.035773381936;5245.816396426145;4012.25773231953;4466.464024317071;4561.6806823522365;4573.885652858672;4599.919019030525;4681.063787242565;4903.469501958584;5433.483819037185;5552.500274975231;5558.833235002957;3318.551995480603;3682.5949145593963;3706.4625629283605;3847.7306588538813;3848.1534062506203;5125.82059627915;5598.684309187338;5772.451617096369;5798.9130163047585;5865.50429640439;3299.307145499424;3841.5966323536186;3862.613738626171;3871.9032024199055;3909.533924778208;3590.379023312996;3899.200663863901;3920.2747491714126;3926.6073392660505;3943.5464921533817;3438.381091403521;4006.779627120536;4038.676905847525;4550.635949124062;4554.538550701491;4733.810647574127;5505.889882202334;5536.889262214733;5566.518952243402;5568.747031917867;1066.3601838896755;1123.3652986062848;1197.934113623759;1256.9064913525292;1257.5158563952943;775.6005657086605;844.4131328726476;871.930245580352;876.4390789894472;880.212210071982;4406.405135306757;4627.92243158296;5030.320906677625;5033.597312215014;5054.1407960646775;4464.419625448736;4565.977170114128;4892.59964324798;4918.95081049185;4927.77824997876;4286.39990401344;4454.554544545519;4600.3099535070005;4655.464621568563;4905.3132561441425;2373.5614401069765;2511.2466260122255;2660.5344064788583;2701.9729802701722;2824.8022638415523;4433.82264709408;5071.822486213038;5119.769610367543;5361.812336568201;5507.697076660742;4994.375703037121;5861.296644402613;5886.35182148999;5888.673973780418;5927.940720592739;5101.627743099402;5451.427600102012;5632.662040277596;5929.851753706192;5970.253718285215;5331.813386531453;5728;5733.569982251432;5765.308163020464;5772.249607550992;4995.550400463976;5062.265971434195;5691.430856914276;5708.372079071216;5709.286339207599;4726.834560790356;4912.631552633496;5481.616286859881;5910.763569457174;5954.821355359346;3086.4135814195233;3219.6136463624516;3456.343294773985;3492.738044646611;3897.6942724187716;4973.701577905337;5077.8730531737;5185.325907631984;5554.333479982428;5753.827385178452;4724.881877953078;5599.300087489064;5603.1984007996;5617.241672374177;5632.549396048388;5059.114654935445;5260.395054568774;5442.809501667581;5530.396185106274;5553.361363443256;4159.521655009713;5224.7721785379645;5298.311219541435;5386.8383948481605;5419.864503387447;5307.920381194208;5478.192656104129;5602.299712535933;5640.125780504029;5675.943240567542;4541.523140070245;4896.559309662005;5153;5467.699276539828;5516.282883225155 11:15:51 INFO - PROCESS | 1635 | |5;yui.html;719.4963525532129;739.8742213823649;764.7094104240388;778.3345239819954;787.5589669784918;13915;14260.500882469116;14270.643233919158;14274.214918179496;14328.641783955407;14314.570562883104;14910.10539367636;14946.177960212184;15253.237338133076;15265.694686106272;661;814.0475643497093;837.6481877611387;838.2162677896156;838.3586556284447;675.6993138053568;803.6584451608072;824.3034635732796;832.5129799067522;839.9081194447225;867.9435836670597;870.7605408512678;871.1549796697194;871.7471933139414;873.0091346321907;780.6994307191716;819.5001049359915;861.7802460372627;862.2627653356416;863.1756672377865;509.59741803974816;519.9860272468701;623.6694551887462;638.2723694987718;650.4893658478072;14518.782218266735;14530.128192308495;14533.563993080224;14541.78187327191;14550.345234464474;13304.201747895157;13406.262655553941;13561.86438135616;13562.186268823902;13591.456341746318;11920.701982450422;12129.454174562166;12359.38203089842;12438.689032774162;12828.422720977578;4431.7784110794655;4459.643228541709;4467.709598876063;4489.9775501122285;4631.143238500855;93176.53411732898;93218;94253;95843.52078239634;96836;10188.847167292497;10361.378317300836;10431;10689.83965240522;11061.723456913642;20354.28759993393;21058.368248952556;21368.893155534435;21402;21451.249206277706;7613.847723045619;7764.3788496920115;7852.842943141105;7948.403869709795;8112.675492980333;4152.190322887008;4248.702590818675;4260.169266992969;4310.849120280838;4430.335449682509;3460.6366331534828;3517.085557755002;3548;3557.4663800430144;3600.0819790953137;657.6382989355835;661.4642139866723;662.7117204016268;669.1702289161404;672.559473544829;569.7578529125105;577.2409281794427;652.5790864892118;656.3043174235326;677.7864972533636;8798.8126616334;9135.497547634945;9160.541972901328;9227.677031304007;9284.210842078499;562.8986782379129;619.7985654662252;628.767356078254;669.7053296549502;672.3679741043403;4233.322668373107;4512.887177820515;4514.751688657156;4544.113897789899;4565.885852853705;78066.24171978503;79019.60490197627;93864;115858.42070789592;118599.8140018606;67706.64586708238;68289.63420731557;69893;99629.00370996197;107506;94100;96317;97508.02491974989;98607.50696246473;102383.48808255911;8695.608697608497;8821.161989610977;8899;8902.287816974756;9134.406263592846;49018;50128;51072.744636276584;60371;60773.69613151906;68156;78315;78948;79234.6038269805;81665.59167204364;12254.20347677398;12415.875841241834;12665.176763510342;12720.809187862375;12770.680732981378;13207.60377188686;13803;13859.30703464843;13861.168329899834;13885.375158117708;12121.090918181348;13133.146345487512;13433.731325373634;13502.932485337511;13917.58247252584;11536.134789890624;12184.75630487403;12306.815397768862;13471.865281347453;13935;12093.516259349879;12134.575289864812;13158.539451119164;13177.341132943122;13198.614433317138;10791.838122428013;10928.945355273172;11317.893985789287;11471.713207169887;11541.084808204381;10491.790164196826;11385.487653055467;11387.715307117389;11509.366984815915;11643.301401915913 11:15:51 INFO - PROCESS | 1635 | __end_tp_report 11:15:51 INFO - PROCESS | 1635 | __start_cc_report 11:15:51 INFO - PROCESS | 1635 | _x_x_mozilla_cycle_collect,2525 11:15:51 INFO - PROCESS | 1635 | __end_cc_report 11:15:51 INFO - PROCESS | 1635 | __startTimestamp1479842151912__endTimestamp 11:15:51 INFO - PROCESS | 1635 | 11:15:51 INFO - PROCESS | 1635 | ------- Summary: start ------- 11:15:51 INFO - PROCESS | 1635 | Number of tests: 6 11:15:51 INFO - PROCESS | 1635 | 11:15:51 INFO - PROCESS | 1635 | [#0] dojo.html Cycles:165 Average:5787.54 Median:5959.13 stddev:648.65 (10.9%) stddev-sans-first:615.21 11:15:51 INFO - PROCESS | 1635 | Values: 3092.1 3391.4 3408.6 3409.3 3469.0 5490.8 5498.1 5724.7 5872.5 5877.6 5757.7 5846.4 5851.8 5859.7 5860.6 5226.8 5264.8 5293.1 5295.8 5310.0 5208.2 5217.3 5234.7 5355.4 5369.7 5257.7 5293.7 5293.9 5300.0 5300.5 4454.5 5301.0 5301.9 5304.8 5311.9 5157.3 5251.4 5251.4 5256.9 5274.7 5843.2 5845.9 5857.8 5864.7 5869.1 6192.3 6225.4 6226.6 6228.6 6236.3 5370.9 6257.1 6265.2 6314.8 6334.7 5831.1 5847.7 5911.7 5925.1 5927.5 6229.2 6295.5 6301.0 6315.2 6317.5 6111.6 6124.4 6138.8 6150.0 6156.0 6247.4 6302.8 6306.2 6315.2 6329.6 6327.6 6329.9 6342.9 6345.2 6362.6 6121.3 6179.2 6194.5 6219.2 6357.8 4948.2 4977.2 4994.0 4994.8 5033.1 4433.5 4499.8 4509.7 4525.1 4532.2 5686.2 5794.2 5797.1 5947.8 5957.7 6218.8 6275.7 6295.5 6301.3 6306.8 5957.1 5958.3 5959.1 5962.2 5994.3 5144.7 5477.4 5487.1 5490.0 5497.8 5848.0 6198.8 6202.7 6253.3 6288.0 6261.3 6266.4 6270.3 6283.2 6296.4 6220.2 6366.0 6368.2 6375.4 6386.1 5863.8 6130.5 6158.4 6294.1 6298.3 6230.6 6294.3 6298.1 6302.6 6321.9 6225.5 6256.3 6262.7 6268.1 6284.3 5136.1 6038.9 6188.6 6225.8 6243.1 5392.1 6042.1 6050.4 6110.4 6129.4 6222.7 6228.3 6230.3 6246.7 6271.5 5863.0 5912.1 5914.8 5930.7 5942.6 11:15:51 INFO - PROCESS | 1635 | 11:15:51 INFO - PROCESS | 1635 | [#1] ext.html Cycles:180 Average:69930.50 Median:21021.16 stddev:160466.14 (763.4%) stddev-sans-first:160831.55 11:15:51 INFO - PROCESS | 1635 | Values: 472.6 481.9 502.6 508.5 542.4 29867.0 39107.0 39898.0 40028.6 42367.8 40994.8 41442.4 41616.0 41654.6 41666.2 9183.1 9354.2 9358.6 9363.0 9374.3 9267.0 9346.9 9375.6 9395.7 9402.8 9354.2 9407.2 9410.2 9418.4 9435.0 8392.9 9232.3 9337.1 9387.4 9389.3 9141.9 9142.2 9246.0 9254.3 9260.4 32770.0 39289.4 39398.8 39409.8 39421.0 3174.3 3214.2 3241.3 3683.3 3811.1 28671.0 28772.0 30397.2 30903.5 31123.4 3190.2 3198.2 3203.4 3280.4 3299.5 490154.0 492049.0 492468.0 492614.0 492950.0 32944.7 33964.2 34018.5 51712.0 56830.4 35265.0 35319.6 35347.8 35397.8 35411.8 14305.8 14445.6 14446.6 14450.7 14455.0 12156.9 12304.4 12304.8 12323.3 12359.4 6705.6 7705.9 7795.6 7796.7 7811.7 3017.0 3055.4 3108.7 3194.5 3202.7 724.3 845.4 897.9 1006.6 1046.7 28462.7 28930.0 29032.0 29099.4 29724.6 1044.2 1056.5 1058.6 1076.7 1080.5 13334.4 14364.2 15090.5 15239.5 15464.9 674192.0 678387.0 678976.6 681128.0 681642.0 57579.8 58116.5 58139.1 58203.8 58348.7 1242.6 1333.6 1342.1 1368.9 1376.1 25095.0 28332.0 28991.9 29200.9 29329.8 48100.5 48818.3 48855.0 48890.5 48983.0 603588.0 607947.0 608510.0 608749.0 608959.0 25722.5 25832.1 26190.5 26278.0 26296.2 45533.5 45545.8 45575.8 45603.5 46119.5 37850.0 38073.8 38501.4 39123.8 39560.6 37752.9 37760.2 38231.3 38825.0 39049.8 29612.2 31444.9 33629.7 37568.0 37774.6 29455.9 32201.4 33267.8 34462.7 34649.5 25039.4 25391.7 25588.1 25700.7 25746.5 11:15:51 INFO - PROCESS | 1635 | 11:15:51 INFO - PROCESS | 1635 | [#2] jquery.html Cycles:180 Average:33943.26 Median:362.91 stddev:99246.18 (27347.0%) stddev-sans-first:99492.55 11:15:51 INFO - PROCESS | 1635 | Values: 354.3 371.5 374.7 387.4 407.8 14268.7 14412.6 14893.3 19704.0 20625.4 19408.3 19410.9 19900.3 20110.8 20236.3 5501.9 5796.7 5797.1 5797.6 5812.7 4443.9 6342.8 6417.0 6467.5 6474.6 6119.7 6374.1 6407.6 6433.7 6441.0 6385.4 6388.7 6391.4 6403.4 6407.4 5045.0 5311.0 5359.5 5410.2 5578.4 12596.9 12686.0 12720.4 12871.3 12874.2 2099.4 2101.1 2109.9 2125.6 2142.9 9697.8 10097.9 10256.8 12094.0 14116.0 1761.6 1782.7 1855.7 1860.4 1883.6 97539.0 111410.4 118368.1 118753.0 123556.0 9153.8 9700.4 9792.3 9824.7 11310.6 10223.6 10650.5 11737.7 12247.3 12397.9 7622.0 7791.3 7823.8 7850.3 8001.2 8168.4 8197.8 8311.7 8332.5 8398.4 3761.1 3815.8 3822.8 3876.2 3913.1 1637.0 1639.5 1860.2 1914.2 1915.9 893.3 894.1 926.8 957.2 982.9 19333.0 20364.5 20420.7 20426.3 20452.0 909.1 916.4 931.5 990.9 1000.0 10069.5 10226.2 10465.9 10532.5 10567.4 561431.0 588216.0 594701.0 614480.0 615158.0 92242.0 119191.4 125075.4 129196.0 133140.7 899.0 934.2 981.4 1004.4 1006.2 15620.5 18299.0 18664.3 18741.5 18889.6 54984.5 55274.7 55814.7 56752.7 56873.7 61304.0 78978.0 79676.6 81028.8 86024.6 7852.0 7868.0 7868.6 8065.9 8163.5 10872.6 10895.2 12117.8 12149.0 12375.9 10498.3 10757.2 11092.9 11174.4 11182.8 9433.9 10598.0 10709.4 11011.4 11089.9 10724.9 10781.4 11028.3 11275.2 11333.0 7761.2 7860.9 7922.8 7992.4 8093.1 10238.6 10347.4 10531.9 10952.8 11094.9 11:15:51 INFO - PROCESS | 1635 | 11:15:51 INFO - PROCESS | 1635 | [#3] mootools.html Cycles:180 Average:5557.11 Median:5248.62 stddev:3048.21 (58.1%) stddev-sans-first:3041.27 11:15:51 INFO - PROCESS | 1635 | Values: 1467.4 1498.4 1499.7 1820.7 1840.1 8261.9 8692.0 8692.6 8693.3 8694.1 7622.2 7840.6 7859.8 8207.6 8482.7 2325.8 2386.2 2390.2 2396.1 2396.3 2325.3 2357.5 2362.4 2414.1 2423.2 2364.8 2371.8 2392.8 2394.7 2403.1 2352.2 2370.9 2389.2 2389.6 2394.3 2382.9 2403.1 2409.9 2412.1 2415.4 8467.0 8473.5 8539.0 8558.6 8571.9 5176.5 5320.8 5334.3 5341.4 5352.9 3313.8 3522.5 3530.2 3657.8 3659.1 1001.6 1048.9 1080.6 1090.3 1113.7 10819.4 10929.2 11002.3 11024.0 11080.7 4543.7 4619.0 4633.1 4745.3 4821.4 8390.7 9576.9 9603.1 9629.5 9630.1 5343.0 5347.7 5349.0 5352.7 5353.5 3941.4 4089.7 4621.1 4732.9 4866.6 3684.9 3691.3 3749.1 3768.5 3786.0 1149.0 1257.8 1280.7 1290.9 1293.6 826.6 830.8 885.3 911.2 926.2 5764.4 5774.8 5839.4 5954.6 5965.8 923.3 933.9 934.2 956.6 995.2 3838.8 3940.1 3950.8 4049.6 4052.6 10117.3 10420.7 10489.4 10494.2 10553.6 10444.0 10479.8 10490.6 10497.4 10499.0 8003.4 8130.7 8143.3 8161.3 8172.0 5430.2 5557.3 5563.6 5778.7 5785.5 8177.7 9496.2 9611.7 9623.0 9629.3 9426.6 9664.3 9743.6 9755.8 9919.4 6835.3 7180.9 7412.6 7436.3 7469.0 8107.6 8113.5 8197.9 8209.6 8216.4 7214.2 7215.5 7269.8 7305.6 7394.2 7081.1 7117.1 7118.5 7156.2 7245.9 6801.7 6912.5 6927.9 6931.8 6937.1 4799.3 4837.7 4912.5 4943.9 5028.2 4288.8 4509.4 4846.2 4852.9 4990.3 11:15:51 INFO - PROCESS | 1635 | 11:15:51 INFO - PROCESS | 1635 | [#4] prototype.html Cycles:180 Average:4380.83 Median:4908.97 stddev:1370.04 (27.9%) stddev-sans-first:1339.21 11:15:51 INFO - PROCESS | 1635 | Values: 300.0 304.7 309.0 317.5 319.0 5058.1 5203.4 5243.3 5316.5 5331.3 4900.1 5020.8 5054.7 5191.8 5213.2 3566.7 3995.3 4013.9 4041.3 4059.9 3427.5 3704.7 3723.2 3781.1 4061.9 3601.6 3994.3 3997.4 4009.0 4011.4 3506.2 3968.6 3982.4 3984.0 4018.7 3461.3 3471.3 3481.9 3491.9 3506.7 4224.8 5133.6 5152.3 5212.0 5245.8 4012.3 4466.5 4561.7 4573.9 4599.9 4681.1 4903.5 5433.5 5552.5 5558.8 3318.6 3682.6 3706.5 3847.7 3848.2 5125.8 5598.7 5772.5 5798.9 5865.5 3299.3 3841.6 3862.6 3871.9 3909.5 3590.4 3899.2 3920.3 3926.6 3943.5 3438.4 4006.8 4038.7 4550.6 4554.5 4733.8 5505.9 5536.9 5566.5 5568.7 1066.4 1123.4 1197.9 1256.9 1257.5 775.6 844.4 871.9 876.4 880.2 4406.4 4627.9 5030.3 5033.6 5054.1 4464.4 4566.0 4892.6 4919.0 4927.8 4286.4 4454.6 4600.3 4655.5 4905.3 2373.6 2511.2 2660.5 2702.0 2824.8 4433.8 5071.8 5119.8 5361.8 5507.7 4994.4 5861.3 5886.4 5888.7 5927.9 5101.6 5451.4 5632.7 5929.9 5970.3 5331.8 5728.0 5733.6 5765.3 5772.2 4995.6 5062.3 5691.4 5708.4 5709.3 4726.8 4912.6 5481.6 5910.8 5954.8 3086.4 3219.6 3456.3 3492.7 3897.7 4973.7 5077.9 5185.3 5554.3 5753.8 4724.9 5599.3 5603.2 5617.2 5632.5 5059.1 5260.4 5442.8 5530.4 5553.4 4159.5 5224.8 5298.3 5386.8 5419.9 5307.9 5478.2 5602.3 5640.1 5675.9 4541.5 4896.6 5153.0 5467.7 5516.3 11:15:51 INFO - PROCESS | 1635 | 11:15:51 INFO - PROCESS | 1635 | [#5] yui.html Cycles:180 Average:20597.77 Median:4529.43 stddev:29898.31 (660.1%) stddev-sans-first:29944.93 11:15:51 INFO - PROCESS | 1635 | Values: 719.5 739.9 764.7 778.3 787.6 13915.0 14260.5 14270.6 14274.2 14328.6 14314.6 14910.1 14946.2 15253.2 15265.7 661.0 814.0 837.6 838.2 838.4 675.7 803.7 824.3 832.5 839.9 867.9 870.8 871.2 871.7 873.0 780.7 819.5 861.8 862.3 863.2 509.6 520.0 623.7 638.3 650.5 14518.8 14530.1 14533.6 14541.8 14550.3 13304.2 13406.3 13561.9 13562.2 13591.5 11920.7 12129.5 12359.4 12438.7 12828.4 4431.8 4459.6 4467.7 4490.0 4631.1 93176.5 93218.0 94253.0 95843.5 96836.0 10188.8 10361.4 10431.0 10689.8 11061.7 20354.3 21058.4 21368.9 21402.0 21451.2 7613.8 7764.4 7852.8 7948.4 8112.7 4152.2 4248.7 4260.2 4310.8 4430.3 3460.6 3517.1 3548.0 3557.5 3600.1 657.6 661.5 662.7 669.2 672.6 569.8 577.2 652.6 656.3 677.8 8798.8 9135.5 9160.5 9227.7 9284.2 562.9 619.8 628.8 669.7 672.4 4233.3 4512.9 4514.8 4544.1 4565.9 78066.2 79019.6 93864.0 115858.4 118599.8 67706.6 68289.6 69893.0 99629.0 107506.0 94100.0 96317.0 97508.0 98607.5 102383.5 8695.6 8821.2 8899.0 8902.3 9134.4 49018.0 50128.0 51072.7 60371.0 60773.7 68156.0 78315.0 78948.0 79234.6 81665.6 12254.2 12415.9 12665.2 12720.8 12770.7 13207.6 13803.0 13859.3 13861.2 13885.4 12121.1 13133.1 13433.7 13502.9 13917.6 11536.1 12184.8 12306.8 13471.9 13935.0 12093.5 12134.6 13158.5 13177.3 13198.6 10791.8 10928.9 11317.9 11471.7 11541.1 10491.8 11385.5 11387.7 11509.4 11643.3 11:15:51 INFO - PROCESS | 1635 | -------- Summary: end -------- 11:15:51 INFO - PROCESS | 1635 | 11:15:52 INFO - TEST-INFO | 1635: exit 0 11:15:52 INFO - TEST-OK | dromaeo_css | took 1121531ms 11:15:52 INFO - TEST-START | kraken 11:15:52 INFO - operating with platform_type : mac_ 11:15:52 INFO - Initialising browser for kraken test... 11:15:52 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/tmp2ma6yq/profile http://localhost:49249/getInfo.html) 11:15:54 ERROR - PROCESS | 1646 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5373: TypeError: parent.firstChild is null 11:15:55 INFO - PROCESS | 1646 | __metrics Screen width/height:1600/1200 11:15:55 INFO - PROCESS | 1646 | colorDepth:24 11:15:55 INFO - PROCESS | 1646 | Browser inner width/height: 1024/689 11:15:55 INFO - PROCESS | 1646 | __metrics 11:15:55 INFO - PROCESS | 1646 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:15:55 INFO - PROCESS | 1646 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:15:56 INFO - TEST-INFO | 1646: exit 0 11:15:56 INFO - Browser initialized. 11:15:56 INFO - Running cycle 1/1 for kraken test... 11:15:56 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/tmp2ma6yq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:16:08 INFO - PROCESS | 1647 | RSS: Main: 222830592 11:16:08 INFO - PROCESS | 1647 | 11:16:11 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-beat-detection) 11:16:11 INFO - PROCESS | 1647 | RSS: Main: 272207872 11:16:11 INFO - PROCESS | 1647 | 11:16:14 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-dft) 11:16:15 INFO - PROCESS | 1647 | RSS: Main: 282628096 11:16:15 INFO - PROCESS | 1647 | 11:16:18 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-fft) 11:16:18 INFO - PROCESS | 1647 | RSS: Main: 380010496 11:16:18 INFO - PROCESS | 1647 | 11:16:21 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-oscillator) 11:16:21 INFO - PROCESS | 1647 | RSS: Main: 282591232 11:16:21 INFO - PROCESS | 1647 | 11:16:23 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:16:24 INFO - PROCESS | 1647 | RSS: Main: 284864512 11:16:24 INFO - PROCESS | 1647 | 11:16:27 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-darkroom) 11:16:27 INFO - PROCESS | 1647 | RSS: Main: 324743168 11:16:27 INFO - PROCESS | 1647 | 11:16:30 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-desaturate) 11:16:31 INFO - PROCESS | 1647 | RSS: Main: 324988928 11:16:31 INFO - PROCESS | 1647 | 11:16:34 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49249/tests/kraken/driver.html?testName=json-parse-financial) 11:16:34 INFO - PROCESS | 1647 | RSS: Main: 321613824 11:16:34 INFO - PROCESS | 1647 | 11:16:36 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49249/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:16:36 INFO - PROCESS | 1647 | RSS: Main: 301445120 11:16:36 INFO - PROCESS | 1647 | 11:16:38 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:16:39 INFO - PROCESS | 1647 | RSS: Main: 299544576 11:16:39 INFO - PROCESS | 1647 | 11:16:41 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:16:42 INFO - PROCESS | 1647 | RSS: Main: 303337472 11:16:42 INFO - PROCESS | 1647 | 11:16:45 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:16:46 INFO - PROCESS | 1647 | RSS: Main: 313581568 11:16:46 INFO - PROCESS | 1647 | 11:16:48 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:16:49 INFO - PROCESS | 1647 | RSS: Main: 313942016 11:16:49 INFO - PROCESS | 1647 | 11:16:51 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49249/tests/kraken/driver.html?testName=ai-astar) 11:16:51 INFO - PROCESS | 1647 | RSS: Main: 357769216 11:16:51 INFO - PROCESS | 1647 | 11:16:51 INFO - PROCESS | 1647 | __start_tp_report 11:16:51 INFO - PROCESS | 1647 | _x_x_mozilla_page_load 11:16:51 INFO - PROCESS | 1647 | _x_x_mozilla_page_load_details 11:16:51 INFO - PROCESS | 1647 | |i|pagename|runs| 11:16:51 INFO - PROCESS | 1647 | |0;ai-astar;116;113;115;113;115;113;113;115;113;116 11:16:51 INFO - PROCESS | 1647 | |1;audio-beat-detection;171;139;135;145;113;113;113;112;158;108 11:16:51 INFO - PROCESS | 1647 | |2;audio-dft;183;185;183;184;183;183;182;184;184;181 11:16:51 INFO - PROCESS | 1647 | |3;audio-fft;81;59;58;87;63;78;79;58;82;57 11:16:51 INFO - PROCESS | 1647 | |4;audio-oscillator;86;71;93;67;77;97;121;81;69;93 11:16:51 INFO - PROCESS | 1647 | |5;imaging-gaussian-blur;88;86;88;86;87;85;89;85;88;84 11:16:51 INFO - PROCESS | 1647 | |6;imaging-darkroom;97;98;97;119;158;158;98;97;97;110 11:16:51 INFO - PROCESS | 1647 | |7;imaging-desaturate;82;83;84;81;83;82;84;84;81;87 11:16:51 INFO - PROCESS | 1647 | |8;json-parse-financial;55;52;53;53;57;57;57;55;55;57 11:16:51 INFO - PROCESS | 1647 | |9;json-stringify-tinderbox;64;75;64;75;71;69;69;70;75;70 11:16:51 INFO - PROCESS | 1647 | |10;stanford-crypto-aes;86;86;84;84;85;85;84;84;85;84 11:16:51 INFO - PROCESS | 1647 | |11;stanford-crypto-ccm;149;147;166;155;152;159;151;150;190;157 11:16:51 INFO - PROCESS | 1647 | |12;stanford-crypto-pbkdf2;160;168;168;169;170;170;172;170;173;158 11:16:51 INFO - PROCESS | 1647 | |13;stanford-crypto-sha256-iterative;67;68;67;66;67;66;67;67;66;65 11:16:51 INFO - PROCESS | 1647 | __end_tp_report 11:16:51 INFO - PROCESS | 1647 | __start_cc_report 11:16:51 INFO - PROCESS | 1647 | _x_x_mozilla_cycle_collect,2007 11:16:51 INFO - PROCESS | 1647 | __end_cc_report 11:16:51 INFO - PROCESS | 1647 | __startTimestamp1479842211591__endTimestamp 11:16:51 INFO - PROCESS | 1647 | 11:16:51 INFO - PROCESS | 1647 | ------- Summary: start ------- 11:16:51 INFO - PROCESS | 1647 | Number of tests: 14 11:16:51 INFO - PROCESS | 1647 | 11:16:51 INFO - PROCESS | 1647 | [#0] ai-astar Cycles:10 Average:114.20 Median:115.00 stddev:1.32 (1.1%) stddev-sans-first:1.22 11:16:51 INFO - PROCESS | 1647 | Values: 116.0 113.0 115.0 113.0 115.0 113.0 113.0 115.0 113.0 116.0 11:16:51 INFO - PROCESS | 1647 | 11:16:51 INFO - PROCESS | 1647 | [#1] audio-beat-detection Cycles:10 Average:130.70 Median:137.00 stddev:22.28 (16.3%) stddev-sans-first:18.24 11:16:51 INFO - PROCESS | 1647 | Values: 171.0 139.0 135.0 145.0 113.0 113.0 113.0 112.0 158.0 108.0 11:16:51 INFO - PROCESS | 1647 | 11:16:51 INFO - PROCESS | 1647 | [#2] audio-dft Cycles:10 Average:183.20 Median:183.50 stddev:1.14 (0.6%) stddev-sans-first:1.20 11:16:51 INFO - PROCESS | 1647 | Values: 183.0 185.0 183.0 184.0 183.0 183.0 182.0 184.0 184.0 181.0 11:16:51 INFO - PROCESS | 1647 | 11:16:51 INFO - PROCESS | 1647 | [#3] audio-fft Cycles:10 Average:70.20 Median:78.50 stddev:12.14 (15.5%) stddev-sans-first:12.23 11:16:51 INFO - PROCESS | 1647 | Values: 81.0 59.0 58.0 87.0 63.0 78.0 79.0 58.0 82.0 57.0 11:16:51 INFO - PROCESS | 1647 | 11:16:51 INFO - PROCESS | 1647 | [#4] audio-oscillator Cycles:10 Average:85.50 Median:83.50 stddev:16.41 (19.6%) stddev-sans-first:17.40 11:16:51 INFO - PROCESS | 1647 | Values: 86.0 71.0 93.0 67.0 77.0 97.0 121.0 81.0 69.0 93.0 11:16:51 INFO - PROCESS | 1647 | 11:16:51 INFO - PROCESS | 1647 | [#5] imaging-gaussian-blur Cycles:10 Average:86.60 Median:87.50 stddev:1.65 (1.9%) stddev-sans-first:1.67 11:16:51 INFO - PROCESS | 1647 | Values: 88.0 86.0 88.0 86.0 87.0 85.0 89.0 85.0 88.0 84.0 11:16:51 INFO - PROCESS | 1647 | 11:16:51 INFO - PROCESS | 1647 | [#6] imaging-darkroom Cycles:10 Average:112.90 Median:97.00 stddev:24.88 (25.6%) stddev-sans-first:25.71 11:16:51 INFO - PROCESS | 1647 | Values: 97.0 98.0 97.0 119.0 158.0 158.0 98.0 97.0 97.0 110.0 11:16:51 INFO - PROCESS | 1647 | 11:16:51 INFO - PROCESS | 1647 | [#7] imaging-desaturate Cycles:10 Average:83.10 Median:83.50 stddev:1.79 (2.1%) stddev-sans-first:1.86 11:16:51 INFO - PROCESS | 1647 | Values: 82.0 83.0 84.0 81.0 83.0 82.0 84.0 84.0 81.0 87.0 11:16:51 INFO - PROCESS | 1647 | 11:16:51 INFO - PROCESS | 1647 | [#8] json-parse-financial Cycles:10 Average:55.10 Median:56.00 stddev:1.91 (3.4%) stddev-sans-first:2.03 11:16:51 INFO - PROCESS | 1647 | Values: 55.0 52.0 53.0 53.0 57.0 57.0 57.0 55.0 55.0 57.0 11:16:51 INFO - PROCESS | 1647 | 11:16:51 INFO - PROCESS | 1647 | [#9] json-stringify-tinderbox Cycles:10 Average:70.20 Median:70.50 stddev:4.08 (5.8%) stddev-sans-first:3.66 11:16:51 INFO - PROCESS | 1647 | Values: 64.0 75.0 64.0 75.0 71.0 69.0 69.0 70.0 75.0 70.0 11:16:51 INFO - PROCESS | 1647 | 11:16:51 INFO - PROCESS | 1647 | [#10] stanford-crypto-aes Cycles:10 Average:84.70 Median:85.00 stddev:0.82 (1.0%) stddev-sans-first:0.73 11:16:51 INFO - PROCESS | 1647 | Values: 86.0 86.0 84.0 84.0 85.0 85.0 84.0 84.0 85.0 84.0 11:16:51 INFO - PROCESS | 1647 | 11:16:51 INFO - PROCESS | 1647 | [#11] stanford-crypto-ccm Cycles:10 Average:157.60 Median:156.00 stddev:12.69 (8.1%) stddev-sans-first:13.07 11:16:51 INFO - PROCESS | 1647 | Values: 149.0 147.0 166.0 155.0 152.0 159.0 151.0 150.0 190.0 157.0 11:16:51 INFO - PROCESS | 1647 | 11:16:51 INFO - PROCESS | 1647 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.80 Median:170.00 stddev:4.92 (2.9%) stddev-sans-first:4.33 11:16:51 INFO - PROCESS | 1647 | Values: 160.0 168.0 168.0 169.0 170.0 170.0 172.0 170.0 173.0 158.0 11:16:51 INFO - PROCESS | 1647 | 11:16:51 INFO - PROCESS | 1647 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.60 Median:67.00 stddev:0.84 (1.3%) stddev-sans-first:0.88 11:16:51 INFO - PROCESS | 1647 | Values: 67.0 68.0 67.0 66.0 67.0 66.0 67.0 67.0 66.0 65.0 11:16:51 INFO - PROCESS | 1647 | -------- Summary: end -------- 11:16:51 INFO - PROCESS | 1647 | 11:16:52 INFO - TEST-INFO | 1647: exit 0 11:16:52 INFO - TEST-OK | kraken | took 60066ms 11:16:52 INFO - SUITE-END | took 1181s 11:16:52 INFO - Completed test suite (00:19:41) 11:16:52 INFO - javascript benchmark 11:16:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3092.0723782865134, 3391.3725960697275, 3408.6080100788386, 3409.301093275879, 3469.028671971849, 5490.752916118774, 5498.092814685582, 5724.6851423171765, 5872.471477567024, 5877.588568800185, 5757.68332741699, 5846.415358464152, 5851.79518716845, 5859.7070146492715, 5860.648361098337, 5226.7909283628705, 5264.8157314494, 5293.073712100387, 5295.8146464873735, 5309.973450132763, 5208.166693329068, 5217.34783152106, 5234.7120908350025, 5355.384130824966, 5369.677819330833, 5257.7108259045735, 5293.708846013467, 5293.947060529403, 5300.0, 5300.549453296455, 4454.53227411122, 5300.973495132538, 5301.867453313659, 5304.787808487656, 5311.9468805311835, 5157.277981082614, 5251.396089449686, 5251.422343542207, 5256.8685782855355, 5274.709890955995, 5843.18195452637, 5845.912311315334, 5857.794977175821, 5864.677442740647, 5869.1196320551835, 6192.28788689302, 6225.439710426083, 6226.62640241587, 6228.56400051992, 6236.282827474854, 5370.91943620846, 6257.061440783873, 6265.216847894013, 6314.842128946767, 6334.714937827763, 5831.096180092092, 5847.707614619252, 5911.704414779244, 5925.111233314994, 5927.525797936155, 6229.190205273286, 6295.496360291166, 6300.991841305369, 6315.242170939517, 6317.46301564363, 6111.622125543816, 6124.421942273956, 6138.785142519991, 6150.04674275488, 6156.045812899008, 6247.375262473716, 6302.810915672537, 6306.211723534559, 6315.179026726496, 6329.620222786648, 6327.588706734047, 6329.905051424233, 6342.936570634327, 6345.17512723343, 6362.586431882006, 6121.300552271563, 6179.227596550431, 6194.5044396449075, 6219.191505104398, 6357.809265722036, 4948.158813001798, 4977.228529577921, 4993.975030124826, 4994.800207991712, 5033.119204139261, 4433.490148632884, 4499.797509112098, 4509.6843220974215, 4525.140223357552, 4532.206863798822, 5686.175504551817, 5794.188813566104, 5797.101449275369, 5947.821565353046, 5957.672328021914, 6218.782342618078, 6275.748970041239, 6295.496360291258, 6301.275353334425, 6306.810795676137, 5957.1362152487645, 5958.255218097738, 5959.131697805324, 5962.195103661037, 5994.28068631767, 5144.6913185209005, 5477.397486276506, 5487.067198576252, 5489.99999999992, 5497.835064948058, 5847.970760146172, 6198.783042593487, 6202.689865506797, 6253.312135664983, 6288.0, 6261.311255761953, 6266.436020758154, 6270.310265870659, 6283.151774510383, 6296.433321001132, 6220.160278362544, 6366.0, 6368.172137622079, 6375.394337537927, 6386.137871387304, 5863.794767183128, 6130.5402094842175, 6158.384161583985, 6294.1188233648245, 6298.338674439301, 6230.59501132425, 6294.3076261612105, 6298.149749783904, 6302.590331628429, 6321.8735625288155, 6225.533085018551, 6256.343083976117, 6262.749490020348, 6268.091126786499, 6284.340144284967, 5136.0755064087325, 6038.879222415615, 6188.566800323934, 6225.8132256032395, 6243.125962365181, 5392.137258038695, 6042.093685947143, 6050.364711705208, 6110.40687992437, 6129.358790882775, 6222.688865556614, 6228.283747369111, 6230.345813689497, 6246.6876656166105, 6271.498280137669, 5862.973979553646, 5912.054071348563, 5914.792982245601, 5930.703464826827, 5942.554308426799], "value": 5746.075051562235, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [472.5794043301462, 481.88434775653855, 502.61549914315543, 508.453412581475, 542.3952293193091, 29866.954656587022, 39107.02232444187, 39898.0, 40028.59971400285, 42367.78816105915, 40994.795026024905, 41442.37836432448, 41616.0, 41654.58345416545, 41666.16667666652, 9183.08169183083, 9354.204892584139, 9358.578863951105, 9363.0, 9374.250059995218, 9267.026962168977, 9346.906530934672, 9375.624975000992, 9395.718128456123, 9402.81194376112, 9354.204892584139, 9407.20038796703, 9410.153086222239, 9418.434893906353, 9434.952825235865, 8392.866962960014, 9232.30757693169, 9337.066293370677, 9387.389819661701, 9389.342746007782, 9141.908580914238, 9142.222911052568, 9245.953770231106, 9254.305927055431, 9260.39807412516, 32770.0, 39289.41065884014, 39398.80300598507, 39409.80295098535, 39421.0, 3174.2699179188685, 3214.1803840020643, 3241.33552621712, 3683.336999340118, 3811.0662887592716, 28671.0, 28772.0, 30397.24006899823, 30903.536446953312, 31123.37753244945, 3190.1546090286092, 3198.2324242181708, 3203.439398105322, 3280.425925463034, 3299.5215693724517, 490154.0, 492049.0, 492468.0, 492614.0, 492950.0, 32944.67055329416, 33964.1508962273, 34018.48972265418, 51711.96576068457, 56830.43169568251, 35265.0, 35319.64680353215, 35347.82326088353, 35397.82301088478, 35411.82294088513, 14305.78541321881, 14445.638859028399, 14446.566603001927, 14450.710985780226, 14455.0, 12156.939215303866, 12304.384780761106, 12304.753904921852, 12323.260604363766, 12359.443825027894, 6705.631190284484, 7705.922940770521, 7795.571243581544, 7796.688132474751, 7811.687532498637, 3017.0043885517725, 3055.4041961817675, 3108.689131086873, 3194.4888817789506, 3202.711755941976, 724.3380576381776, 845.3786466946789, 897.8922529296395, 1006.5520843224695, 1046.6853315657763, 28462.71537284642, 28929.987450439134, 29032.0, 29099.41801163965, 29724.55413168804, 1044.2116202267391, 1056.5087234436023, 1058.5606973106076, 1076.6554702495282, 1080.4813689429118, 13334.399952002183, 14364.209968451627, 15090.54728358151, 15239.466618668293, 15464.922675386777, 674192.0, 678387.0, 678976.6051169713, 681128.0, 681642.0, 57579.84840303254, 58116.54708632318, 58139.127913081335, 58203.835923281295, 58348.70825645845, 1242.6396345059832, 1333.5665908579751, 1342.100792469048, 1368.8836448901757, 1376.0711519724146, 25094.99620015179, 28332.0, 28991.855040724662, 29200.853995729885, 29329.826806927485, 48100.5189948096, 48818.26772598485, 48855.0, 48890.51109488859, 48983.0, 603588.0, 607947.0, 608510.0, 608749.0, 608958.9552052212, 25722.485550289264, 25832.095876644227, 26190.476190475703, 26278.0, 26296.159268851763, 45533.54466455293, 45545.772271138434, 45575.77212113918, 45603.54396456126, 46119.538804611526, 37850.0, 38073.80963095167, 38501.4224786634, 39123.804380979054, 39560.60439395684, 37752.86741397763, 37760.2447951034, 38231.253777717924, 38825.0, 39049.804750976065, 29612.223266604073, 31444.899428519893, 33629.663703362654, 37568.0, 37774.62225377711, 29455.85272073626, 32201.35597287994, 33267.83366083251, 34462.655373445945, 34649.48025779565, 25039.374015649755, 25391.746082539677, 25588.104416346083, 25700.742992569834, 25746.485070298862], "value": 18107.5885765776, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.34976817539814, 371.4799281006592, 374.7189607794154, 387.43241151712743, 407.7920260667059, 14268.71462570748, 14412.567622971328, 14893.329800158992, 19704.0, 20625.38123856283, 19408.320708775194, 19410.90294548529, 19900.303489377875, 20110.798892011077, 20236.29172978946, 5501.862453438666, 5796.710164491779, 5797.130430435426, 5797.594168408213, 5812.709364531777, 4443.88890277744, 6342.84142896429, 6416.967915160418, 6467.5472716909835, 6474.5791523550815, 6119.66341851198, 6374.107624932513, 6407.615543067431, 6433.678316084201, 6441.033844923276, 6385.361463853601, 6388.744450221986, 6391.392817682313, 6403.391677790602, 6407.423331900105, 5045.016221836743, 5311.044012077825, 5359.517643412111, 5410.242566040757, 5578.414266501999, 12596.937015314956, 12685.985121190282, 12720.427580758887, 12871.292078935654, 12874.163179393403, 2099.370188943326, 2101.1280318667737, 2109.8817626657897, 2125.553633736917, 2142.871427714342, 9697.806043879084, 10097.939716329753, 10256.846147307797, 12094.0, 14116.0, 1761.5596100974756, 1782.6523827853573, 1855.6752568300494, 1860.3674750584867, 1883.6421079994757, 97538.99999999929, 111410.44294778474, 118368.07819210594, 118753.0, 123556.0, 9153.77115572116, 9700.417974921527, 9792.265580081523, 9824.705258842247, 11310.604128855615, 10223.619811325525, 10650.467476626138, 11737.706557335963, 12247.32639704807, 12397.876021239672, 7621.961890190624, 7791.259830316224, 7823.843523129505, 7850.2934735872905, 8001.239882211181, 8168.387370947203, 8197.836043279101, 8311.709090181814, 8332.50004999702, 8398.41211115216, 3761.134938963999, 3815.790131542736, 3822.8470861165797, 3876.2441323942126, 3913.1391093959282, 1636.967260654804, 1639.4999525144706, 1860.237302705884, 1914.2056046740724, 1915.932942347039, 893.3032234856822, 894.0969620683027, 926.7914719188128, 957.1768279279814, 982.8771403574553, 19333.0, 20364.490887727927, 20420.693689594667, 20426.285080022128, 20451.97740113018, 909.1226965977863, 916.408916249029, 931.5295775633261, 990.9207263418909, 1000.0499525450706, 10069.496525173712, 10226.201461456089, 10465.9476702616, 10532.526036328383, 10567.365958042543, 561431.0, 588216.0, 594701.0, 614480.0, 615158.0, 92242.0, 119191.40404297924, 125075.3746231263, 129196.0, 133140.67437302592, 898.9730308090768, 934.1779234273796, 981.4160574458328, 1004.3973615830589, 1006.2101250518308, 15620.453284135001, 18298.993555354584, 18664.34674786376, 18741.531461713566, 18889.622207556047, 54984.450155497936, 55274.72362638161, 55814.72092639511, 56752.71623641856, 56873.715631421575, 61304.0, 78978.0, 79676.60161699154, 81028.78456823035, 86024.5698771502, 7852.006552411854, 7867.9606601966625, 7868.645910934136, 8065.919340806517, 8163.510189388656, 10872.61945831892, 10895.237333386904, 12117.848804363572, 12149.0, 12375.938120309342, 10498.265121441425, 10757.193210509087, 11092.944535277273, 11174.441277936234, 11182.832257516306, 9433.858492122486, 10598.0, 10709.410982395731, 11011.449427528752, 11089.946455086754, 10724.89275107239, 10781.407022613832, 11028.28316159447, 11275.210735248455, 11333.0, 7761.223877612194, 7860.882086768589, 7922.762317130495, 7992.400569957393, 8093.109757926741, 10238.641647542301, 10347.379157250589, 10531.894681053398, 10952.835707464235, 11094.889051109385], "value": 9075.691852424665, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1467.4277031957536, 1498.3931507739367, 1499.6925630245794, 1820.6995845685462, 1840.1351364858513, 8261.87607185892, 8691.999999999993, 8692.565371731413, 8693.30453563715, 8694.130586941319, 7622.237776222375, 7840.607969601511, 7859.80350491238, 8207.58962051898, 8482.703105391312, 2325.7557956414585, 2386.188695843413, 2390.2112302940077, 2396.0775101585928, 2396.281115665302, 2325.337278875521, 2357.481354102095, 2362.3503536527505, 2414.070582825616, 2423.248792874215, 2364.7871691547757, 2371.8221133414977, 2392.83250172488, 2394.7126344838643, 2403.07481619576, 2352.188494969233, 2370.8577485350847, 2389.1518510928545, 2389.593769059247, 2394.3295877154337, 2382.868942208178, 2403.1468828565958, 2409.8795060247094, 2412.0834083048503, 2415.420299128197, 8467.0, 8473.49159050459, 8539.01801292853, 8558.572071396467, 8571.914280857236, 5176.456472070454, 5320.787168513255, 5334.306540149756, 5341.43914888935, 5352.866178345534, 3313.8177400242976, 3522.5244591980013, 3530.16498399409, 3657.762245454037, 3659.1218107654236, 1001.6227686930471, 1048.8636477257908, 1080.5569716416232, 1090.2640717515724, 1113.6603335982522, 10819.350838949687, 10929.234953553254, 11002.33985960837, 11024.0, 11080.722981925517, 4543.727376357429, 4619.006913513611, 4633.0733853229485, 4745.335653008581, 4821.421429428491, 8390.706325278708, 9576.856347154799, 9603.135717785433, 9629.470379129076, 9630.133288004114, 5343.011542864582, 5347.679139251657, 5349.037173308841, 5352.678839269578, 5353.4646535346155, 3941.4172945641044, 4089.734167279117, 4621.052684199731, 4732.881677958009, 4866.6350023747655, 3684.889453316351, 3691.298653255873, 3749.1376983293994, 3768.453574231721, 3786.015635934677, 1149.0007387146304, 1257.7547378261152, 1280.6798300424894, 1290.909636325467, 1293.5537239652306, 826.6404114210337, 830.754927296449, 885.3183049052194, 911.1708345405649, 926.2451102351562, 5764.423557644202, 5774.797882074191, 5839.445252700986, 5954.583179177416, 5965.821025369246, 923.3213588012806, 933.8972713001565, 934.1779234273931, 956.6269155029544, 995.2336700740534, 3838.846446142179, 3940.1087577285143, 3950.822213000422, 4049.6152865477734, 4052.574479679591, 10117.291789574807, 10420.739481513023, 10489.423081730425, 10494.160467162608, 10553.63062292816, 10444.0, 10479.84280235797, 10490.580376784996, 10497.422641754623, 10499.002594753487, 8003.359731221488, 8130.674773009132, 8143.307818835466, 8161.306288965386, 8171.999999999881, 5430.212619170277, 5557.305336832896, 5563.638363506359, 5778.739956701875, 5785.450382213683, 8177.67289308421, 9496.192823609794, 9611.663591774255, 9622.951885240529, 9629.277804164856, 9426.575804088696, 9664.323497355117, 9743.61025558998, 9755.756106097404, 9919.355241909252, 6835.282295359115, 7180.928190718235, 7412.62936853166, 7436.330730234305, 7468.9626551866895, 8107.635156417858, 8113.472624279403, 8197.918020819954, 8209.589520524069, 8216.424850260424, 7214.170370407261, 7215.494915355875, 7269.781906542812, 7305.561666300039, 7394.223606521453, 7081.114860642419, 7117.1459424869345, 7118.501704880816, 7156.212816590275, 7245.891311630225, 6801.693923773541, 6912.4815638828295, 6927.93072069293, 6931.826704332432, 6937.06349642812, 4799.328094066764, 4837.733924634038, 4912.533309335608, 4943.901121977612, 5028.170351891897, 4288.785560721889, 4509.391232183613, 4846.248831431134, 4852.927206091841, 4990.251462280687], "value": 4503.788196442367, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.959505466762, 304.6800859097948, 308.9845507724613, 317.541153033866, 318.96172459304887, 5058.140116180249, 5203.401608814987, 5243.265942768011, 5316.49493298137, 5331.333583302087, 4900.064474532557, 5020.824271150511, 5054.671446355985, 5191.844244672657, 5213.165893457048, 3566.7324950628677, 3995.3407687731633, 4013.8595149169787, 4041.333180025292, 4059.8579049733266, 3427.5372824668725, 3704.685101766353, 3723.1622884850963, 3781.111438811877, 4061.878143655695, 3601.595377802659, 3994.2810294147043, 3997.4203740457615, 4008.959910400888, 4011.438398624195, 3506.2461570762152, 3968.583298753633, 3982.4026396040827, 3984.00399900025, 4018.718689691723, 3461.3077384522944, 3471.2710330830555, 3481.8781342653137, 3491.9476207856646, 3506.7019303359243, 4224.831006759726, 5133.562602471314, 5152.330197074394, 5212.035773381936, 5245.816396426145, 4012.25773231953, 4466.464024317071, 4561.6806823522365, 4573.885652858672, 4599.919019030525, 4681.063787242565, 4903.469501958584, 5433.483819037185, 5552.500274975231, 5558.833235002957, 3318.551995480603, 3682.5949145593963, 3706.4625629283605, 3847.7306588538813, 3848.1534062506203, 5125.82059627915, 5598.684309187338, 5772.451617096369, 5798.9130163047585, 5865.50429640439, 3299.307145499424, 3841.5966323536186, 3862.613738626171, 3871.9032024199055, 3909.533924778208, 3590.379023312996, 3899.200663863901, 3920.2747491714126, 3926.6073392660505, 3943.5464921533817, 3438.381091403521, 4006.779627120536, 4038.676905847525, 4550.635949124062, 4554.538550701491, 4733.810647574127, 5505.889882202334, 5536.889262214733, 5566.518952243402, 5568.747031917867, 1066.3601838896755, 1123.3652986062848, 1197.934113623759, 1256.9064913525292, 1257.5158563952943, 775.6005657086605, 844.4131328726476, 871.930245580352, 876.4390789894472, 880.212210071982, 4406.405135306757, 4627.92243158296, 5030.320906677625, 5033.597312215014, 5054.1407960646775, 4464.419625448736, 4565.977170114128, 4892.59964324798, 4918.95081049185, 4927.77824997876, 4286.39990401344, 4454.554544545519, 4600.3099535070005, 4655.464621568563, 4905.3132561441425, 2373.5614401069765, 2511.2466260122255, 2660.5344064788583, 2701.9729802701722, 2824.8022638415523, 4433.82264709408, 5071.822486213038, 5119.769610367543, 5361.812336568201, 5507.697076660742, 4994.375703037121, 5861.296644402613, 5886.35182148999, 5888.673973780418, 5927.940720592739, 5101.627743099402, 5451.427600102012, 5632.662040277596, 5929.851753706192, 5970.253718285215, 5331.813386531453, 5728.0, 5733.569982251432, 5765.308163020464, 5772.249607550992, 4995.550400463976, 5062.265971434195, 5691.430856914276, 5708.372079071216, 5709.286339207599, 4726.834560790356, 4912.631552633496, 5481.616286859881, 5910.763569457174, 5954.821355359346, 3086.4135814195233, 3219.6136463624516, 3456.343294773985, 3492.738044646611, 3897.6942724187716, 4973.701577905337, 5077.8730531737, 5185.325907631984, 5554.333479982428, 5753.827385178452, 4724.881877953078, 5599.300087489064, 5603.1984007996, 5617.241672374177, 5632.549396048388, 5059.114654935445, 5260.395054568774, 5442.809501667581, 5530.396185106274, 5553.361363443256, 4159.521655009713, 5224.7721785379645, 5298.311219541435, 5386.8383948481605, 5419.864503387447, 5307.920381194208, 5478.192656104129, 5602.299712535933, 5640.125780504029, 5675.943240567542, 4541.523140070245, 4896.559309662005, 5153.0, 5467.699276539828, 5516.282883225155], "value": 3945.1261895277585, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [719.4963525532129, 739.8742213823649, 764.7094104240388, 778.3345239819954, 787.5589669784918, 13915.0, 14260.500882469116, 14270.643233919158, 14274.214918179496, 14328.641783955407, 14314.570562883104, 14910.10539367636, 14946.177960212184, 15253.237338133076, 15265.694686106272, 661.0, 814.0475643497093, 837.6481877611387, 838.2162677896156, 838.3586556284447, 675.6993138053568, 803.6584451608072, 824.3034635732796, 832.5129799067522, 839.9081194447225, 867.9435836670597, 870.7605408512678, 871.1549796697194, 871.7471933139414, 873.0091346321907, 780.6994307191716, 819.5001049359915, 861.7802460372627, 862.2627653356416, 863.1756672377865, 509.59741803974816, 519.9860272468701, 623.6694551887462, 638.2723694987718, 650.4893658478072, 14518.782218266735, 14530.128192308495, 14533.563993080224, 14541.78187327191, 14550.345234464474, 13304.201747895157, 13406.262655553941, 13561.86438135616, 13562.186268823902, 13591.456341746318, 11920.701982450422, 12129.454174562166, 12359.38203089842, 12438.689032774162, 12828.422720977578, 4431.7784110794655, 4459.643228541709, 4467.709598876063, 4489.9775501122285, 4631.143238500855, 93176.53411732898, 93218.0, 94253.0, 95843.52078239634, 96836.0, 10188.847167292497, 10361.378317300836, 10431.0, 10689.83965240522, 11061.723456913642, 20354.28759993393, 21058.368248952556, 21368.893155534435, 21402.0, 21451.249206277706, 7613.847723045619, 7764.3788496920115, 7852.842943141105, 7948.403869709795, 8112.675492980333, 4152.190322887008, 4248.702590818675, 4260.169266992969, 4310.849120280838, 4430.335449682509, 3460.6366331534828, 3517.085557755002, 3548.0, 3557.4663800430144, 3600.0819790953137, 657.6382989355835, 661.4642139866723, 662.7117204016268, 669.1702289161404, 672.559473544829, 569.7578529125105, 577.2409281794427, 652.5790864892118, 656.3043174235326, 677.7864972533636, 8798.8126616334, 9135.497547634945, 9160.541972901328, 9227.677031304007, 9284.210842078499, 562.8986782379129, 619.7985654662252, 628.767356078254, 669.7053296549502, 672.3679741043403, 4233.322668373107, 4512.887177820515, 4514.751688657156, 4544.113897789899, 4565.885852853705, 78066.24171978503, 79019.60490197627, 93864.0, 115858.42070789592, 118599.8140018606, 67706.64586708238, 68289.63420731557, 69893.0, 99629.00370996197, 107506.0, 94100.0, 96317.0, 97508.02491974989, 98607.50696246473, 102383.48808255911, 8695.608697608497, 8821.161989610977, 8899.0, 8902.287816974756, 9134.406263592846, 49018.0, 50128.0, 51072.744636276584, 60371.0, 60773.69613151906, 68156.0, 78315.0, 78948.0, 79234.6038269805, 81665.59167204364, 12254.20347677398, 12415.875841241834, 12665.176763510342, 12720.809187862375, 12770.680732981378, 13207.60377188686, 13803.0, 13859.30703464843, 13861.168329899834, 13885.375158117708, 12121.090918181348, 13133.146345487512, 13433.731325373634, 13502.932485337511, 13917.58247252584, 11536.134789890624, 12184.75630487403, 12306.815397768862, 13471.865281347453, 13935.0, 12093.516259349879, 12134.575289864812, 13158.539451119164, 13177.341132943122, 13198.614433317138, 10791.838122428013, 10928.945355273172, 11317.893985789287, 11471.713207169887, 11541.084808204381, 10491.790164196826, 11385.487653055467, 11387.715307117389, 11509.366984815915, 11643.301401915913], "value": 7378.537552652105, "unit": "score"}], "name": "dromaeo_css", "value": 7059.827351649982, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 113.0, 115.0, 113.0, 115.0, 113.0, 113.0, 115.0, 113.0, 116.0], "value": 114.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 139.0, 135.0, 145.0, 113.0, 113.0, 113.0, 112.0, 158.0, 108.0], "value": 130.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 185.0, 183.0, 184.0, 183.0, 183.0, 182.0, 184.0, 184.0, 181.0], "value": 183.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 58.0, 87.0, 63.0, 78.0, 79.0, 58.0, 82.0, 57.0], "value": 70.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 71.0, 93.0, 67.0, 77.0, 97.0, 121.0, 81.0, 69.0, 93.0], "value": 85.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 88.0, 86.0, 87.0, 85.0, 89.0, 85.0, 88.0, 84.0], "value": 86.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 98.0, 97.0, 119.0, 158.0, 158.0, 98.0, 97.0, 97.0, 110.0], "value": 112.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 84.0, 81.0, 83.0, 82.0, 84.0, 84.0, 81.0, 87.0], "value": 83.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 52.0, 53.0, 53.0, 57.0, 57.0, 57.0, 55.0, 55.0, 57.0], "value": 55.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 75.0, 64.0, 75.0, 71.0, 69.0, 69.0, 70.0, 75.0, 70.0], "value": 70.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 84.0, 84.0, 85.0, 85.0, 84.0, 84.0, 85.0, 84.0], "value": 84.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 147.0, 166.0, 155.0, 152.0, 159.0, 151.0, 150.0, 190.0, 157.0], "value": 157.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 168.0, 168.0, 169.0, 170.0, 170.0, 172.0, 170.0, 173.0, 158.0], "value": 167.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 67.0, 66.0, 67.0, 66.0, 67.0, 67.0, 66.0, 65.0], "value": 66.6, "unit": "score"}], "name": "kraken", "value": 1468.3999999999999, "alertThreshold": 2.0}]} 11:16:52 INFO - Return code: 0 11:16:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:16:52 ERROR - # TBPL SUCCESS # 11:16:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:16:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:16:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:16:52 INFO - 2016-11-22 11:16:52.903 screenresolution[1651:9852] starting screenresolution argv=screenresolution get 11:16:52 INFO - 2016-11-22 11:16:52.907 screenresolution[1651:9852] Display 0: 1600x1200x32@60 11:16:52 INFO - 2016-11-22 11:16:52.913 screenresolution[1652:9855] starting screenresolution argv=screenresolution list 11:16:52 INFO - Available Modes on Display 0 11:16:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:16:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:16:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:16:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:16:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:16:53 INFO - Intel Iris: 11:16:53 INFO - Chipset Model: Intel Iris 11:16:53 INFO - Type: GPU 11:16:53 INFO - Bus: Built-In 11:16:53 INFO - VRAM (Dynamic, Max): 1536 MB 11:16:53 INFO - Vendor: Intel (0x8086) 11:16:53 INFO - Device ID: 0x0a2e 11:16:53 INFO - Revision ID: 0x0009 11:16:53 INFO - Displays: 11:16:53 INFO - Display: 11:16:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:16:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:16:53 INFO - Display Serial Number: 1600x1200 60 11:16:53 INFO - Main Display: Yes 11:16:53 INFO - Mirror: Off 11:16:53 INFO - Online: Yes 11:16:53 INFO - Rotation: Supported 11:16:53 INFO - Return code: 0 11:16:53 INFO - Running post-action listener: _resource_record_post_action 11:16:53 INFO - [mozharness: 2016-11-22 19:16:53.156929Z] Finished run-tests step (success) 11:16:53 INFO - Running post-run listener: _resource_record_post_run 11:16:53 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 38061056; Write bytes: 654303232; Read time: 245; Write time: 1931 11:16:53 INFO - TinderboxPrint: CPU usage
53.5% 11:16:53 INFO - TinderboxPrint: I/O read bytes / time
38,061,056 / 245 11:16:53 INFO - TinderboxPrint: I/O write bytes / time
654,303,232 / 1,931 11:16:53 INFO - TinderboxPrint: CPU idle
2,257.4 (46.5%) 11:16:53 INFO - TinderboxPrint: CPU system
973.7 (20.1%) 11:16:53 INFO - TinderboxPrint: CPU user
1,622.5 (33.4%) 11:16:53 INFO - TinderboxPrint: Swap in / out
386,191,360 / 0 11:16:53 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 345555456; Write bytes: 344561152; Read time: 26708; Write time: 748 11:16:53 INFO - run-tests - Wall time: 1183s; CPU: 53.0%; Read bytes: 36029440; Write bytes: 302635520; Read time: 228; Write time: 1154 11:16:53 INFO - Running post-run listener: _upload_blobber_files 11:16:53 INFO - Blob upload gear active. 11:16:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:16:53 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 11:16:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:16:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:16:54 INFO - (blobuploader) - INFO - Open directory for files ... 11:16:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:16:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:16:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:16:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:16:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:16:55 INFO - (blobuploader) - INFO - Done attempting. 11:16:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:16:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:16:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:16:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:16:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:16:56 INFO - (blobuploader) - INFO - Done attempting. 11:16:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:16:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:16:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:16:58 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:16:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:16:58 INFO - (blobuploader) - INFO - Done attempting. 11:16:58 INFO - (blobuploader) - INFO - Iteration through files over. 11:16:58 INFO - Return code: 0 11:16:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:16:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:16:58 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3b564a45c4806b9ced151dc71d033fe1c1104d1293196b4fc8b5fee66bfcbcfe266f5f629508652995784290a114b855da9285eea0c1aeaa9f19f97497d7dbe8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ebacd354b6250814f77e2fa1f3b3179356b3ab2f7b43c993d6a041433ff0cbf493a0971e202746aaee64ce8f0bade9b4683e71fe8ce9a78f526da0159b43afc1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b97b4c2547c713fa1b618e7aad5554500854f9c62edcae7597bee9499b90780a745bbe253314a8a84d39e69dc64e2b4e042ec7b20ccd579a6914cec707dc28b0"} 11:16:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:16:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:16:58 INFO - Contents: 11:16:58 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3b564a45c4806b9ced151dc71d033fe1c1104d1293196b4fc8b5fee66bfcbcfe266f5f629508652995784290a114b855da9285eea0c1aeaa9f19f97497d7dbe8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ebacd354b6250814f77e2fa1f3b3179356b3ab2f7b43c993d6a041433ff0cbf493a0971e202746aaee64ce8f0bade9b4683e71fe8ce9a78f526da0159b43afc1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b97b4c2547c713fa1b618e7aad5554500854f9c62edcae7597bee9499b90780a745bbe253314a8a84d39e69dc64e2b4e042ec7b20ccd579a6914cec707dc28b0"} 11:16:58 INFO - Running post-run listener: copy_logs_to_upload_dir 11:16:58 INFO - Copying logs to upload dir... 11:16:58 INFO - mkdir: /builds/slave/test/build/upload/logs 11:16:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.629226 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-11-22 11:16:59.010931) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-22 11:16:59.015012) ========= 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.8Qzlss1Elk/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.iTgWaX1bt3/Listeners TMPDIR=/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3b564a45c4806b9ced151dc71d033fe1c1104d1293196b4fc8b5fee66bfcbcfe266f5f629508652995784290a114b855da9285eea0c1aeaa9f19f97497d7dbe8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ebacd354b6250814f77e2fa1f3b3179356b3ab2f7b43c993d6a041433ff0cbf493a0971e202746aaee64ce8f0bade9b4683e71fe8ce9a78f526da0159b43afc1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b97b4c2547c713fa1b618e7aad5554500854f9c62edcae7597bee9499b90780a745bbe253314a8a84d39e69dc64e2b4e042ec7b20ccd579a6914cec707dc28b0"} build_url:https://queue.taskcluster.net/v1/task/Rz4JgEs6RkKsln0o_SeT5Q/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010724 build_url: 'https://queue.taskcluster.net/v1/task/Rz4JgEs6RkKsln0o_SeT5Q/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3b564a45c4806b9ced151dc71d033fe1c1104d1293196b4fc8b5fee66bfcbcfe266f5f629508652995784290a114b855da9285eea0c1aeaa9f19f97497d7dbe8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ebacd354b6250814f77e2fa1f3b3179356b3ab2f7b43c993d6a041433ff0cbf493a0971e202746aaee64ce8f0bade9b4683e71fe8ce9a78f526da0159b43afc1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b97b4c2547c713fa1b618e7aad5554500854f9c62edcae7597bee9499b90780a745bbe253314a8a84d39e69dc64e2b4e042ec7b20ccd579a6914cec707dc28b0"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-22 11:16:59.056280) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 11:16:59.056612) ========= 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.8Qzlss1Elk/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.iTgWaX1bt3/Listeners TMPDIR=/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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.004917 ========= master_lag: 0.06 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 11:16:59.124482) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-22 11:16:59.124882) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-22 11:16:59.125472) ========= ========= Total master_lag: 0.23 =========