builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0217 starttime: 1489539637.25 results: success (0) buildid: 20170314164236 builduid: 1f0032370c8b4056a942c2ad39708b53 revision: cef93bf5a0a15ab7a2dc96d0cee90a599a4eae76 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-14 18:00:37.246549) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-14 18:00:37.247345) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-14 18:00:37.247847) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-14 18:00:37.278916) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-14 18:00:37.279537) ========= 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.SRkF2aiXvJ/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.NpQIcpsTww/Listeners TMPDIR=/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-03-14 18:00:37-- 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% 18.0M=0.001s 2017-03-14 18:00:37 (18.0 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.126900 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-14 18:00:37.433902) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-14 18:00:37.434665) ========= 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.SRkF2aiXvJ/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.NpQIcpsTww/Listeners TMPDIR=/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/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.026456 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-14 18:00:37.485447) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-14 18:00:37.485938) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev cef93bf5a0a15ab7a2dc96d0cee90a599a4eae76 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev cef93bf5a0a15ab7a2dc96d0cee90a599a4eae76 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.SRkF2aiXvJ/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.NpQIcpsTww/Listeners TMPDIR=/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-03-14 18:00:37,592 truncating revision to first 12 chars 2017-03-14 18:00:37,592 Setting DEBUG logging. 2017-03-14 18:00:37,592 attempt 1/10 2017-03-14 18:00:37,592 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/cef93bf5a0a1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-14 18:00:38,824 unpacking tar archive at: mozilla-central-cef93bf5a0a1/testing/mozharness/ program finished with exit code 0 elapsedTime=1.499699 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-14 18:00:39.010998) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-14 18:00:39.011526) ========= script_repo_revision: cef93bf5a0a15ab7a2dc96d0cee90a599a4eae76 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-14 18:00:39.012062) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-14 18:00:39.012388) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-14 18:00:39.033277) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 28 secs) (at 2017-03-14 18:00:39.033714) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.SRkF2aiXvJ/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.NpQIcpsTww/Listeners TMPDIR=/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/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 18:00:39 INFO - MultiFileLogger online at 20170314 18:00:39 in /builds/slave/test 18:00:39 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 18:00:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:00:39 INFO - {'append_to_log': False, 18:00:39 INFO - 'base_work_dir': '/builds/slave/test', 18:00:39 INFO - 'blob_upload_branch': 'Firefox', 18:00:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:00:39 INFO - 'branch': 'Firefox', 18:00:39 INFO - 'buildbot_json_path': 'buildprops.json', 18:00:39 INFO - 'config_files': ('talos/mac_config.py',), 18:00:39 INFO - 'default_actions': ('clobber', 18:00:39 INFO - 'read-buildbot-config', 18:00:39 INFO - 'download-and-extract', 18:00:39 INFO - 'populate-webroot', 18:00:39 INFO - 'create-virtualenv', 18:00:39 INFO - 'install', 18:00:39 INFO - 'run-tests'), 18:00:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:00:39 INFO - 'download_minidump_stackwalk': True, 18:00:39 INFO - 'download_symbols': 'ondemand', 18:00:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:00:39 INFO - 'tooltool.py': '/tools/tooltool.py', 18:00:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:00:39 INFO - '/tools/misc-python/virtualenv.py')}, 18:00:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:00:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:00:39 INFO - 'gecko_profile': False, 18:00:39 INFO - 'gecko_profile_interval': 0, 18:00:39 INFO - 'installer_path': 'installer.exe', 18:00:39 INFO - 'log_level': 'info', 18:00:39 INFO - 'log_name': 'talos', 18:00:39 INFO - 'log_to_console': True, 18:00:39 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:00:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:00:39 INFO - 'opt_config_files': (), 18:00:39 INFO - 'pip_index': False, 18:00:39 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:00:39 INFO - 'cmd': ('bash', 18:00:39 INFO - '-c', 18:00:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:00:39 INFO - 'enabled': True, 18:00:39 INFO - 'halt_on_failure': False, 18:00:39 INFO - 'name': 'check_screen_resolution'},), 18:00:39 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:00:39 INFO - 'cmd': ('bash', 18:00:39 INFO - '-c', 18:00:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:00:39 INFO - 'enabled': True, 18:00:39 INFO - 'halt_on_failure': False, 18:00:39 INFO - 'name': 'check_screen_resolution'},), 18:00:39 INFO - 'run_cmd_checks_enabled': True, 18:00:39 INFO - 'suite': 'dromaeojs', 18:00:39 INFO - 'system_bits': '32', 18:00:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:00:39 INFO - 'title': 't-yosemite-r7-0217', 18:00:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:00:39 INFO - 'use_talos_json': True, 18:00:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:00:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:00:39 INFO - 'work_dir': 'build'} 18:00:39 INFO - [mozharness: 2017-03-15 01:00:39.247133Z] Running clobber step. 18:00:39 INFO - Running pre-action listener: _resource_record_pre_action 18:00:39 INFO - Running main action method: clobber 18:00:39 INFO - rmtree: /builds/slave/test/build 18:00:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:00:41 INFO - Running post-action listener: _resource_record_post_action 18:00:41 INFO - [mozharness: 2017-03-15 01:00:41.689118Z] Finished clobber step (success) 18:00:41 INFO - [mozharness: 2017-03-15 01:00:41.689246Z] Running read-buildbot-config step. 18:00:41 INFO - Running pre-action listener: _resource_record_pre_action 18:00:41 INFO - Running main action method: read_buildbot_config 18:00:41 INFO - Using buildbot properties: 18:00:41 INFO - { 18:00:41 INFO - "project": "", 18:00:41 INFO - "product": "firefox", 18:00:41 INFO - "script_repo_revision": "production", 18:00:41 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 18:00:41 INFO - "repository": "", 18:00:41 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 18:00:41 INFO - "buildid": "20170314164236", 18:00:41 INFO - "pgo_build": "False", 18:00:41 INFO - "basedir": "/builds/slave/test", 18:00:41 INFO - "buildnumber": 41, 18:00:41 INFO - "slavename": "t-yosemite-r7-0217", 18:00:41 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 18:00:41 INFO - "platform": "yosemite_r7", 18:00:41 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 18:00:41 INFO - "branch": "mozilla-central", 18:00:41 INFO - "repo_path": "mozilla-central", 18:00:41 INFO - "revision": "cef93bf5a0a15ab7a2dc96d0cee90a599a4eae76", 18:00:41 INFO - "stage_platform": "macosx64", 18:00:41 INFO - "builduid": "1f0032370c8b4056a942c2ad39708b53", 18:00:41 INFO - "slavebuilddir": "test" 18:00:41 INFO - } 18:00:41 INFO - Found installer url https://queue.taskcluster.net/v1/task/eUbnioplRtahrrdxjtWT1Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 18:00:41 INFO - Running post-action listener: _resource_record_post_action 18:00:41 INFO - [mozharness: 2017-03-15 01:00:41.692618Z] Finished read-buildbot-config step (success) 18:00:41 INFO - [mozharness: 2017-03-15 01:00:41.692732Z] Running download-and-extract step. 18:00:41 INFO - Running pre-action listener: _resource_record_pre_action 18:00:41 INFO - Running main action method: download_and_extract 18:00:41 INFO - mkdir: /builds/slave/test/build/tests 18:00:41 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')]} 18:00:41 INFO - trying https://queue.taskcluster.net/v1/task/eUbnioplRtahrrdxjtWT1Q/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 18:00:41 INFO - Downloading https://queue.taskcluster.net/v1/task/eUbnioplRtahrrdxjtWT1Q/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 18:00:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eUbnioplRtahrrdxjtWT1Q/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 18:00:42 INFO - Downloaded 1199 bytes. 18:00:42 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 18:00:42 INFO - Using the following test package requirements: 18:00:42 INFO - {u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 18:00:42 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:00:42 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 18:00:42 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:00:42 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 18:00:42 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:00:42 INFO - u'jsshell-mac.zip'], 18:00:42 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:00:42 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 18:00:42 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 18:00:42 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:00:42 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 18:00:42 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:00:42 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 18:00:42 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:00:42 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 18:00:42 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:00:42 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 18:00:42 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:00:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eUbnioplRtahrrdxjtWT1Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 18:00:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eUbnioplRtahrrdxjtWT1Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:00:42 INFO - Fetch https://queue.taskcluster.net/v1/task/eUbnioplRtahrrdxjtWT1Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 18:00:45 INFO - Expected file size: 52470989 18:00:45 INFO - Obtained file size: 52470989 18:01:01 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 18:01:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eUbnioplRtahrrdxjtWT1Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 18:01:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eUbnioplRtahrrdxjtWT1Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:01:01 INFO - Fetch https://queue.taskcluster.net/v1/task/eUbnioplRtahrrdxjtWT1Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 18:01:03 INFO - Expected file size: 52470989 18:01:03 INFO - Obtained file size: 52470989 18:01:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eUbnioplRtahrrdxjtWT1Q/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 18:01:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eUbnioplRtahrrdxjtWT1Q/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 18:01:19 INFO - Fetch https://queue.taskcluster.net/v1/task/eUbnioplRtahrrdxjtWT1Q/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 18:01:20 INFO - Expected file size: 14404452 18:01:20 INFO - Obtained file size: 14404452 18:01:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:01:20 INFO - trying https://queue.taskcluster.net/v1/task/eUbnioplRtahrrdxjtWT1Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:01:20 INFO - Downloading https://queue.taskcluster.net/v1/task/eUbnioplRtahrrdxjtWT1Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 18:01:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eUbnioplRtahrrdxjtWT1Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 18:01:22 INFO - Downloaded 74991064 bytes. 18:01:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eUbnioplRtahrrdxjtWT1Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:01:22 INFO - mkdir: /builds/slave/test/properties 18:01:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:01:22 INFO - Writing to file /builds/slave/test/properties/build_url 18:01:22 INFO - Contents: 18:01:22 INFO - build_url:https://queue.taskcluster.net/v1/task/eUbnioplRtahrrdxjtWT1Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:01:23 INFO - Running post-action listener: _resource_record_post_action 18:01:23 INFO - Running post-action listener: set_extra_try_arguments 18:01:23 INFO - [mozharness: 2017-03-15 01:01:23.509102Z] Finished download-and-extract step (success) 18:01:23 INFO - [mozharness: 2017-03-15 01:01:23.509258Z] Running populate-webroot step. 18:01:23 INFO - Running pre-action listener: _resource_record_pre_action 18:01:23 INFO - Running main action method: populate_webroot 18:01:23 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:01:23 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:01:23 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:01:23 INFO - '--tppagecycles', 18:01:23 INFO - '7'], 18:01:23 INFO - 'tests': ['tsvgm']}}, 18:01:23 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:01:23 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:01:23 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:01:23 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:01:23 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:01:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:01:23 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:01:23 INFO - 'talos_options': ['--disable-e10s'], 18:01:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:01:23 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:01:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:01:23 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:01:23 INFO - 'talos_options': ['--disable-e10s'], 18:01:23 INFO - 'tests': ['damp', 'tps']}, 18:01:23 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:01:23 INFO - 'tests': ['damp', 'tps']}, 18:01:23 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:01:23 INFO - 'tests': ['dromaeo_dom']}, 18:01:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:01:23 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:01:23 INFO - 'tests': ['basic_compositor_video']}, 18:01:23 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:01:23 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:01:23 INFO - 'tests': ['a11yr', 18:01:23 INFO - 'ts_paint', 18:01:23 INFO - 'tpaint', 18:01:23 INFO - 'sessionrestore', 18:01:23 INFO - 'sessionrestore_no_auto_restore', 18:01:23 INFO - 'tabpaint']}, 18:01:23 INFO - 'other-e10s': {'tests': ['a11yr', 18:01:23 INFO - 'ts_paint', 18:01:23 INFO - 'tpaint', 18:01:23 INFO - 'sessionrestore', 18:01:23 INFO - 'sessionrestore_no_auto_restore', 18:01:23 INFO - 'tabpaint']}, 18:01:23 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:01:23 INFO - 'ts_paint', 18:01:23 INFO - 'tpaint', 18:01:23 INFO - 'sessionrestore', 18:01:23 INFO - 'sessionrestore_no_auto_restore', 18:01:23 INFO - 'tabpaint']}, 18:01:23 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:01:23 INFO - 'ts_paint', 18:01:23 INFO - 'tpaint', 18:01:23 INFO - 'sessionrestore', 18:01:23 INFO - 'sessionrestore_no_auto_restore', 18:01:23 INFO - 'tabpaint']}, 18:01:23 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:01:23 INFO - 'tests': ['a11yr', 18:01:23 INFO - 'ts_paint', 18:01:23 INFO - 'tpaint', 18:01:23 INFO - 'sessionrestore', 18:01:23 INFO - 'sessionrestore_no_auto_restore', 18:01:23 INFO - 'tabpaint']}, 18:01:23 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:01:23 INFO - 'tests': ['a11yr', 18:01:23 INFO - 'ts_paint', 18:01:23 INFO - 'tpaint', 18:01:23 INFO - 'sessionrestore', 18:01:23 INFO - 'sessionrestore_no_auto_restore', 18:01:23 INFO - 'tabpaint']}, 18:01:23 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:01:23 INFO - 'tests': ['tsvgx', 18:01:23 INFO - 'tsvgr_opacity', 18:01:23 INFO - 'tart', 18:01:23 INFO - 'tscrollx', 18:01:23 INFO - 'cart', 18:01:23 INFO - 'tsvg_static']}, 18:01:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:01:23 INFO - 'tsvgr_opacity', 18:01:23 INFO - 'tart', 18:01:23 INFO - 'tscrollx', 18:01:23 INFO - 'cart', 18:01:23 INFO - 'tsvg_static']}, 18:01:23 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:01:23 INFO - 'talos_options': ['--disable-e10s'], 18:01:23 INFO - 'tests': ['tp5o']}, 18:01:23 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:01:23 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:01:23 INFO - 'talos_options': ['--disable-e10s', 18:01:23 INFO - '--xperf_path', 18:01:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:01:23 INFO - 'tests': ['tp5n']}, 18:01:23 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:01:23 INFO - 'talos_options': ['--xperf_path', 18:01:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:01:23 INFO - 'tests': ['tp5n']}}, 18:01:23 INFO - 'talos.zip': {'path': '', 18:01:23 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:01:23 INFO - Running post-action listener: _resource_record_post_action 18:01:23 INFO - [mozharness: 2017-03-15 01:01:23.527757Z] Finished populate-webroot step (success) 18:01:23 INFO - [mozharness: 2017-03-15 01:01:23.527877Z] Running create-virtualenv step. 18:01:23 INFO - Running pre-action listener: _resource_record_pre_action 18:01:23 INFO - Running main action method: create_virtualenv 18:01:23 INFO - Creating virtualenv /builds/slave/test/build/venv 18:01:23 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 18:01:23 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:01:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:01:23 INFO - Using real prefix '/tools/python27' 18:01:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:01:24 INFO - Installing distribute.............................................................................................................................................................................................done. 18:01:27 INFO - Installing pip.................done. 18:01:27 INFO - Return code: 0 18:01:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:01:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:01:27 INFO - Reading from file tmpfile_stdout 18:01:27 INFO - Output received: 18:01:27 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) 18:01:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:01: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')]} 18:01:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:01:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01: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')]} 18:01:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:01:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:27 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 0x108aa6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108aa9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0eb495730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108884d30>, '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 0x1087abdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108879be8>, '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.SRkF2aiXvJ/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.NpQIcpsTww/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/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 18:01:27 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 18:01:27 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 18:01:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SRkF2aiXvJ/Render', 18:01:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:01:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:01:27 INFO - 'HOME': '/Users/cltbld', 18:01:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:01:27 INFO - 'LOGNAME': 'cltbld', 18:01:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:01:27 INFO - 'MOZ_NO_REMOTE': '1', 18:01:27 INFO - 'NO_EM_RESTART': '1', 18:01:27 INFO - 'PAGER': '/bin/cat', 18:01:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:01:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:01:27 INFO - 'PWD': '/builds/slave/test', 18:01:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:01:27 INFO - 'SHELL': '/bin/bash', 18:01:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NpQIcpsTww/Listeners', 18:01:27 INFO - 'TMPDIR': '/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/', 18:01:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:01:27 INFO - 'USER': 'cltbld', 18:01:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:01:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:01:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:01:27 INFO - 'XPC_FLAGS': '0x0', 18:01:27 INFO - 'XPC_SERVICE_NAME': '0', 18:01:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:01:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:01:27 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 18:01:27 INFO - Cleaning up... 18:01:27 INFO - Return code: 0 18:01:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:01: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')]} 18:01:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:01:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01: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')]} 18:01:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:01:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:27 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 0x108aa6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108aa9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0eb495730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108884d30>, '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 0x1087abdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108879be8>, '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.SRkF2aiXvJ/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.NpQIcpsTww/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/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 18:01:27 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 18:01:27 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 18:01:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SRkF2aiXvJ/Render', 18:01:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:01:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:01:27 INFO - 'HOME': '/Users/cltbld', 18:01:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:01:27 INFO - 'LOGNAME': 'cltbld', 18:01:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:01:27 INFO - 'MOZ_NO_REMOTE': '1', 18:01:27 INFO - 'NO_EM_RESTART': '1', 18:01:27 INFO - 'PAGER': '/bin/cat', 18:01:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:01:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:01:27 INFO - 'PWD': '/builds/slave/test', 18:01:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:01:27 INFO - 'SHELL': '/bin/bash', 18:01:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NpQIcpsTww/Listeners', 18:01:27 INFO - 'TMPDIR': '/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/', 18:01:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:01:27 INFO - 'USER': 'cltbld', 18:01:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:01:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:01:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:01:27 INFO - 'XPC_FLAGS': '0x0', 18:01:27 INFO - 'XPC_SERVICE_NAME': '0', 18:01:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:01:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:01:28 INFO - Downloading/unpacking psutil>=3.1.1 18:01:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:01:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:01:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:01:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:01:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:01:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:01:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:01:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:01:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:01:31 INFO - Installing collected packages: psutil 18:01:31 INFO - Running setup.py install for psutil 18:01:31 INFO - building 'psutil._psutil_osx' extension 18:01:31 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 18:01:32 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 18:01:32 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 18:01:32 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 18:01:32 INFO - building 'psutil._psutil_posix' extension 18:01:32 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 18:01:32 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:01:32 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:01:32 INFO - ^ 18:01:32 INFO - 1 warning generated. 18:01:32 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 18:01:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:01:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:01:32 INFO - Successfully installed psutil 18:01:32 INFO - Cleaning up... 18:01:32 INFO - Return code: 0 18:01:32 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:01: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')]} 18:01:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:01:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:01:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:01:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108aa6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108aa9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0eb495730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108884d30>, '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 0x1087abdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108879be8>, '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.SRkF2aiXvJ/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.NpQIcpsTww/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/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 18:01:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:01:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:01:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SRkF2aiXvJ/Render', 18:01:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:01:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:01:33 INFO - 'HOME': '/Users/cltbld', 18:01:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:01:33 INFO - 'LOGNAME': 'cltbld', 18:01:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:01:33 INFO - 'MOZ_NO_REMOTE': '1', 18:01:33 INFO - 'NO_EM_RESTART': '1', 18:01:33 INFO - 'PAGER': '/bin/cat', 18:01:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:01:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:01:33 INFO - 'PWD': '/builds/slave/test', 18:01:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:01:33 INFO - 'SHELL': '/bin/bash', 18:01:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NpQIcpsTww/Listeners', 18:01:33 INFO - 'TMPDIR': '/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/', 18:01:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:01:33 INFO - 'USER': 'cltbld', 18:01:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:01:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:01:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:01:33 INFO - 'XPC_FLAGS': '0x0', 18:01:33 INFO - 'XPC_SERVICE_NAME': '0', 18:01:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:01:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:01:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:01:33 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 18:01:33 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 18:01:33 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 18:01:33 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 18:01:33 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 18:01:33 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 18:01:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:01:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:01:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:01:36 INFO - Installing collected packages: mozsystemmonitor 18:01:36 INFO - Running setup.py install for mozsystemmonitor 18:01:37 INFO - Successfully installed mozsystemmonitor 18:01:37 INFO - Cleaning up... 18:01:37 INFO - Return code: 0 18:01:37 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:01: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')]} 18:01:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:01:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01: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')]} 18:01:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:01:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:37 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 0x108aa6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108aa9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0eb495730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108884d30>, '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 0x1087abdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108879be8>, '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.SRkF2aiXvJ/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.NpQIcpsTww/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/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 18:01:37 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 18:01:37 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 18:01:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SRkF2aiXvJ/Render', 18:01:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:01:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:01:37 INFO - 'HOME': '/Users/cltbld', 18:01:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:01:37 INFO - 'LOGNAME': 'cltbld', 18:01:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:01:37 INFO - 'MOZ_NO_REMOTE': '1', 18:01:37 INFO - 'NO_EM_RESTART': '1', 18:01:37 INFO - 'PAGER': '/bin/cat', 18:01:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:01:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:01:37 INFO - 'PWD': '/builds/slave/test', 18:01:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:01:37 INFO - 'SHELL': '/bin/bash', 18:01:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NpQIcpsTww/Listeners', 18:01:37 INFO - 'TMPDIR': '/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/', 18:01:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:01:37 INFO - 'USER': 'cltbld', 18:01:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:01:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:01:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:01:37 INFO - 'XPC_FLAGS': '0x0', 18:01:37 INFO - 'XPC_SERVICE_NAME': '0', 18:01:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:01:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:01:37 INFO - Downloading/unpacking jsonschema==2.5.1 18:01:37 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 18:01:37 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 18:01:37 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 18:01:37 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 18:01:37 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 18:01:37 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 18:01:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:01:39 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:01:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:01:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:01:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:01:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:01:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:01:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:01:40 INFO - Downloading functools32-3.2.3-2.tar.gz 18:01:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:01:40 INFO - warning: no files found matching '*.txt' 18:01:40 INFO - no previously-included directories found matching 'build' 18:01:40 INFO - no previously-included directories found matching 'dist' 18:01:40 INFO - no previously-included directories found matching '.git*' 18:01:40 INFO - Installing collected packages: jsonschema, functools32 18:01:40 INFO - Running setup.py install for functools32 18:01:40 INFO - warning: no files found matching '*.txt' 18:01:40 INFO - no previously-included directories found matching 'build' 18:01:40 INFO - no previously-included directories found matching 'dist' 18:01:40 INFO - no previously-included directories found matching '.git*' 18:01:40 INFO - Successfully installed jsonschema functools32 18:01:40 INFO - Cleaning up... 18:01:40 INFO - Return code: 0 18:01:40 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:01:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:01:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:01:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:01:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:01:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:40 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 0x108aa6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108aa9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0eb495730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108884d30>, '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 0x1087abdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108879be8>, '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.SRkF2aiXvJ/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.NpQIcpsTww/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/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 18:01:40 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 18:01:40 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 18:01:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SRkF2aiXvJ/Render', 18:01:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:01:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:01:40 INFO - 'HOME': '/Users/cltbld', 18:01:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:01:40 INFO - 'LOGNAME': 'cltbld', 18:01:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:01:40 INFO - 'MOZ_NO_REMOTE': '1', 18:01:40 INFO - 'NO_EM_RESTART': '1', 18:01:40 INFO - 'PAGER': '/bin/cat', 18:01:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:01:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:01:40 INFO - 'PWD': '/builds/slave/test', 18:01:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:01:40 INFO - 'SHELL': '/bin/bash', 18:01:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NpQIcpsTww/Listeners', 18:01:40 INFO - 'TMPDIR': '/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/', 18:01:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:01:40 INFO - 'USER': 'cltbld', 18:01:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:01:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:01:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:01:40 INFO - 'XPC_FLAGS': '0x0', 18:01:40 INFO - 'XPC_SERVICE_NAME': '0', 18:01:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:01:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:01:41 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:01:41 INFO - Cleaning up... 18:01:41 INFO - Return code: 0 18:01:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:01:41 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')]} 18:01:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:01:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:41 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')]} 18:01:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:01:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:41 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 0x108aa6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108aa9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0eb495730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108884d30>, '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 0x1087abdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108879be8>, '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.SRkF2aiXvJ/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.NpQIcpsTww/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/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 18:01:41 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 18:01:41 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 18:01:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SRkF2aiXvJ/Render', 18:01:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:01:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:01:41 INFO - 'HOME': '/Users/cltbld', 18:01:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:01:41 INFO - 'LOGNAME': 'cltbld', 18:01:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:01:41 INFO - 'MOZ_NO_REMOTE': '1', 18:01:41 INFO - 'NO_EM_RESTART': '1', 18:01:41 INFO - 'PAGER': '/bin/cat', 18:01:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:01:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:01:41 INFO - 'PWD': '/builds/slave/test', 18:01:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:01:41 INFO - 'SHELL': '/bin/bash', 18:01:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NpQIcpsTww/Listeners', 18:01:41 INFO - 'TMPDIR': '/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/', 18:01:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:01:41 INFO - 'USER': 'cltbld', 18:01:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:01:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:01:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:01:41 INFO - 'XPC_FLAGS': '0x0', 18:01:41 INFO - 'XPC_SERVICE_NAME': '0', 18:01:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:01:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:01:41 INFO - Downloading/unpacking blobuploader==1.2.4 18:01:41 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 18:01:41 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 18:01:41 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 18:01:41 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 18:01:41 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 18:01:41 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 18:01:43 INFO - Downloading blobuploader-1.2.4.tar.gz 18:01:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:01:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:01:43 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 18:01:43 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 18:01:43 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 18:01:43 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 18:01:43 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 18:01:43 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 18:01:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:01:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:01:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:01:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:01:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:01:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:01:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:01:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:01:45 INFO - Downloading docopt-0.6.1.tar.gz 18:01:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:01:45 INFO - Installing collected packages: blobuploader, requests, docopt 18:01:45 INFO - Running setup.py install for blobuploader 18:01:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:01:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:01:45 INFO - Running setup.py install for requests 18:01:45 INFO - Running setup.py install for docopt 18:01:46 INFO - Successfully installed blobuploader requests docopt 18:01:46 INFO - Cleaning up... 18:01:46 INFO - Return code: 0 18:01:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:01:46 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')]} 18:01:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:01:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:46 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')]} 18:01:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:01:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:46 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 0x108aa6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108aa9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0eb495730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108884d30>, '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 0x1087abdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108879be8>, '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.SRkF2aiXvJ/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.NpQIcpsTww/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/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 18:01:46 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 18:01:46 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 18:01:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SRkF2aiXvJ/Render', 18:01:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:01:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:01:46 INFO - 'HOME': '/Users/cltbld', 18:01:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:01:46 INFO - 'LOGNAME': 'cltbld', 18:01:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:01:46 INFO - 'MOZ_NO_REMOTE': '1', 18:01:46 INFO - 'NO_EM_RESTART': '1', 18:01:46 INFO - 'PAGER': '/bin/cat', 18:01:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:01:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:01:46 INFO - 'PWD': '/builds/slave/test', 18:01:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:01:46 INFO - 'SHELL': '/bin/bash', 18:01:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NpQIcpsTww/Listeners', 18:01:46 INFO - 'TMPDIR': '/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/', 18:01:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:01:46 INFO - 'USER': 'cltbld', 18:01:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:01:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:01:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:01:46 INFO - 'XPC_FLAGS': '0x0', 18:01:46 INFO - 'XPC_SERVICE_NAME': '0', 18:01:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:01:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:01:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:01:46 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-5OHOe6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:01:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:01:46 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-o3TEzY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:01:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:01:46 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-eSidkC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:01:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:01:46 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-D3UAik-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:01:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:01:46 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-M1SwAA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:01:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:01:46 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-W3dqj6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:01:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:01:46 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-gclrhM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:01:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:01:47 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-KXE7I6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:01:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:01:47 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-3ibNpp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:01:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:01:47 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-FvWHuE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:01:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:01:47 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-vA_Bej-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:01:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:01:47 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-blh85g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:01:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:01:47 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-Mah2ul-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:01:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:01:47 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-SXfeZD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:01:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:01:47 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-B7Cl8J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:01:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:01:47 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-hqgWdM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:01:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:01:48 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-RwGTsI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:01:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:01:48 INFO - Running setup.py install for manifestparser 18:01:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:01:48 INFO - Running setup.py install for mozcrash 18:01:48 INFO - Running setup.py install for mozdebug 18:01:48 INFO - Running setup.py install for mozdevice 18:01:48 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:01:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:01:48 INFO - Running setup.py install for mozfile 18:01:48 INFO - Running setup.py install for mozhttpd 18:01:48 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:01:49 INFO - Running setup.py install for mozinfo 18:01:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:01:49 INFO - Running setup.py install for mozInstall 18:01:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:01:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:01:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:01:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:01:49 INFO - Running setup.py install for mozleak 18:01:49 INFO - Running setup.py install for mozlog 18:01:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:01:49 INFO - Running setup.py install for moznetwork 18:01:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:01:49 INFO - Running setup.py install for mozprocess 18:01:49 INFO - Running setup.py install for mozprofile 18:01:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:01:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:01:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:01:50 INFO - Running setup.py install for mozrunner 18:01:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:01:50 INFO - Running setup.py install for mozscreenshot 18:01:50 INFO - Running setup.py install for moztest 18:01:50 INFO - Running setup.py install for mozversion 18:01:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:01:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:01:50 INFO - Cleaning up... 18:01:50 INFO - Return code: 0 18:01:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:01:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:01:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:01:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:01:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:01:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:50 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 0x108aa6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108aa9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0eb495730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108884d30>, '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 0x1087abdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108879be8>, '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.SRkF2aiXvJ/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.NpQIcpsTww/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/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 18:01:50 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 18:01:50 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 18:01:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SRkF2aiXvJ/Render', 18:01:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:01:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:01:50 INFO - 'HOME': '/Users/cltbld', 18:01:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:01:50 INFO - 'LOGNAME': 'cltbld', 18:01:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:01:50 INFO - 'MOZ_NO_REMOTE': '1', 18:01:50 INFO - 'NO_EM_RESTART': '1', 18:01:50 INFO - 'PAGER': '/bin/cat', 18:01:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:01:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:01:50 INFO - 'PWD': '/builds/slave/test', 18:01:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:01:50 INFO - 'SHELL': '/bin/bash', 18:01:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NpQIcpsTww/Listeners', 18:01:50 INFO - 'TMPDIR': '/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/', 18:01:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:01:50 INFO - 'USER': 'cltbld', 18:01:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:01:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:01:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:01:50 INFO - 'XPC_FLAGS': '0x0', 18:01:50 INFO - 'XPC_SERVICE_NAME': '0', 18:01:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:01:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:01:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:01:50 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-t4gDp3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:01:51 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)) 18:01:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:01:51 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-h0FsXs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:01:51 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)) 18:01:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:01:51 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-_4CcbW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:01:51 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)) 18:01:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:01:51 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-Qq9kef-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:01:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:01:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:01:51 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-mFmya9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:01:51 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)) 18:01:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:01:51 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-5II6eX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:01:51 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)) 18:01:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:01:51 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-_3cmx1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:01:51 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)) 18:01:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:01:51 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-BBcyby-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:01:51 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)) 18:01:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:01:51 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-deWQ81-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:01:51 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)) 18:01:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:01:51 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-otOVkj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:01:52 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)) 18:01:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:01:52 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-FqertP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:01:52 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)) 18:01:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:01:52 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-XyzDE1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:01:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 18:01:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:01:52 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-fUuYy7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:01:52 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)) 18:01:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:01:52 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-uNAsvC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:01:52 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)) 18:01:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:01:52 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-mbYd5E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:01:52 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)) 18:01:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:01:52 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-DbndTJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:01:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 18:01:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:01:52 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-Kv2tSd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:01:52 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)) 18:01:52 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)) 18:01:52 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)) 18:01:52 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)) 18:01:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:01:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:01:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:01:52 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 18:01:52 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 18:01:52 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 18:01:52 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 18:01:52 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 18:01:52 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 18:01:55 INFO - Downloading blessings-1.6.tar.gz 18:01:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:01:55 INFO - Installing collected packages: blessings 18:01:55 INFO - Running setup.py install for blessings 18:01:55 INFO - Successfully installed blessings 18:01:55 INFO - Cleaning up... 18:01:55 INFO - Return code: 0 18:01:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:01:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:01:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:01:56 INFO - Reading from file tmpfile_stdout 18:01:56 INFO - Current package versions: 18:01:56 INFO - blessings == 1.6 18:01:56 INFO - blobuploader == 1.2.4 18:01:56 INFO - docopt == 0.6.1 18:01:56 INFO - functools32 == 3.2.3-2 18:01:56 INFO - jsonschema == 2.5.1 18:01:56 INFO - manifestparser == 1.1 18:01:56 INFO - mozInstall == 1.12 18:01:56 INFO - mozcrash == 1.0 18:01:56 INFO - mozdebug == 0.1 18:01:56 INFO - mozdevice == 0.49 18:01:56 INFO - mozfile == 1.2 18:01:56 INFO - mozhttpd == 0.7 18:01:56 INFO - mozinfo == 0.9 18:01:56 INFO - mozleak == 0.1 18:01:56 INFO - mozlog == 3.4 18:01:56 INFO - moznetwork == 0.27 18:01:56 INFO - mozprocess == 0.25 18:01:56 INFO - mozprofile == 0.28 18:01:56 INFO - mozrunner == 6.13 18:01:56 INFO - mozscreenshot == 0.1 18:01:56 INFO - mozsystemmonitor == 0.3 18:01:56 INFO - moztest == 0.8 18:01:56 INFO - mozversion == 1.4 18:01:56 INFO - psutil == 3.1.1 18:01:56 INFO - requests == 1.2.3 18:01:56 INFO - wsgiref == 0.1.2 18:01:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:01:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:01:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:01:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:01:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:01:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:56 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 0x108aa6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108aa9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0eb495730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108884d30>, '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 0x1087abdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108879be8>, '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.SRkF2aiXvJ/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.NpQIcpsTww/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/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 18:01:56 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 18:01:56 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 18:01:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SRkF2aiXvJ/Render', 18:01:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:01:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:01:56 INFO - 'HOME': '/Users/cltbld', 18:01:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:01:56 INFO - 'LOGNAME': 'cltbld', 18:01:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:01:56 INFO - 'MOZ_NO_REMOTE': '1', 18:01:56 INFO - 'NO_EM_RESTART': '1', 18:01:56 INFO - 'PAGER': '/bin/cat', 18:01:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:01:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:01:56 INFO - 'PWD': '/builds/slave/test', 18:01:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:01:56 INFO - 'SHELL': '/bin/bash', 18:01:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NpQIcpsTww/Listeners', 18:01:56 INFO - 'TMPDIR': '/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/', 18:01:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:01:56 INFO - 'USER': 'cltbld', 18:01:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:01:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:01:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:01:56 INFO - 'XPC_FLAGS': '0x0', 18:01:56 INFO - 'XPC_SERVICE_NAME': '0', 18:01:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:01:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:01:56 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)) 18:01:56 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)) 18:01:56 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)) 18:01:56 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)) 18:01:56 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)) 18:01:56 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)) 18:01:56 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)) 18:01:56 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)) 18:01:56 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)) 18:01:56 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)) 18:01:56 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)) 18:01:56 INFO - Cleaning up... 18:01:56 INFO - Return code: 0 18:01:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:01:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:01:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:01:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:01:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:01:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:56 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 0x108aa6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108aa9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0eb495730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108884d30>, '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 0x1087abdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108879be8>, '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.SRkF2aiXvJ/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.NpQIcpsTww/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/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 18:01:56 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 18:01:56 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 18:01:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SRkF2aiXvJ/Render', 18:01:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:01:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:01:56 INFO - 'HOME': '/Users/cltbld', 18:01:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:01:56 INFO - 'LOGNAME': 'cltbld', 18:01:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:01:56 INFO - 'MOZ_NO_REMOTE': '1', 18:01:56 INFO - 'NO_EM_RESTART': '1', 18:01:56 INFO - 'PAGER': '/bin/cat', 18:01:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:01:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:01:56 INFO - 'PWD': '/builds/slave/test', 18:01:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:01:56 INFO - 'SHELL': '/bin/bash', 18:01:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NpQIcpsTww/Listeners', 18:01:56 INFO - 'TMPDIR': '/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/', 18:01:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:01:56 INFO - 'USER': 'cltbld', 18:01:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:01:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:01:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:01:56 INFO - 'XPC_FLAGS': '0x0', 18:01:56 INFO - 'XPC_SERVICE_NAME': '0', 18:01:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:01:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:01:56 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:01:56 INFO - Cleaning up... 18:01:56 INFO - Return code: 0 18:01:56 INFO - Running post-action listener: _resource_record_post_action 18:01:56 INFO - Running post-action listener: _start_resource_monitoring 18:01:56 INFO - Starting resource monitoring. 18:01:56 INFO - [mozharness: 2017-03-15 01:01:56.635624Z] Finished create-virtualenv step (success) 18:01:56 INFO - [mozharness: 2017-03-15 01:01:56.636661Z] Running install step. 18:01:56 INFO - Running pre-action listener: _resource_record_pre_action 18:01:56 INFO - Running main action method: install 18:01:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:01:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:01:56 INFO - Reading from file tmpfile_stdout 18:01:56 INFO - Detecting whether we're running mozinstall >=1.0... 18:01:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:01:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:01:56 INFO - Reading from file tmpfile_stdout 18:01:57 INFO - Output received: 18:01:57 INFO - Usage: mozinstall [options] installer 18:01:57 INFO - Options: 18:01:57 INFO - -h, --help show this help message and exit 18:01:57 INFO - -d DEST, --destination=DEST 18:01:57 INFO - Directory to install application into. [default: 18:01:57 INFO - "/builds/slave/test"] 18:01:57 INFO - --app=APP Application being installed. [default: firefox] 18:01:57 INFO - mkdir: /builds/slave/test/build/application 18:01:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 18:01:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 18:02:17 INFO - Reading from file tmpfile_stdout 18:02:17 INFO - Output received: 18:02:17 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:02:17 INFO - Running post-action listener: _resource_record_post_action 18:02:17 INFO - [mozharness: 2017-03-15 01:02:17.322717Z] Finished install step (success) 18:02:17 INFO - [mozharness: 2017-03-15 01:02:17.322842Z] Running run-tests step. 18:02:17 INFO - Running pre-action listener: _resource_record_pre_action 18:02:17 INFO - Running main action method: run_tests 18:02:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:02:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:02:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:02:17 INFO - 2017-03-14 18:02:17.337 screenresolution[1621:7344] starting screenresolution argv=screenresolution get 18:02:17 INFO - 2017-03-14 18:02:17.340 screenresolution[1621:7344] Display 0: 1600x1200x32@60 18:02:17 INFO - 2017-03-14 18:02:17.345 screenresolution[1622:7347] starting screenresolution argv=screenresolution list 18:02:17 INFO - Available Modes on Display 0 18:02:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:02:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:02:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:02:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:02:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:02:17 INFO - Intel Iris: 18:02:17 INFO - Chipset Model: Intel Iris 18:02:17 INFO - Type: GPU 18:02:17 INFO - Bus: Built-In 18:02:17 INFO - VRAM (Dynamic, Max): 1536 MB 18:02:17 INFO - Vendor: Intel (0x8086) 18:02:17 INFO - Device ID: 0x0a2e 18:02:17 INFO - Revision ID: 0x0009 18:02:17 INFO - Displays: 18:02:17 INFO - Display: 18:02:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:02:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:02:17 INFO - Display Serial Number: 1600x1200 60 18:02:17 INFO - Main Display: Yes 18:02:17 INFO - Mirror: Off 18:02:17 INFO - Online: Yes 18:02:17 INFO - Rotation: Supported 18:02:17 INFO - Return code: 0 18:02:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:02:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:02:17 INFO - Python 2.7.3 18:02:17 INFO - Return code: 0 18:02:17 INFO - grabbing minidump binary from tooltool 18:02:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:02:17 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 0x108884d30>, '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 0x1087abdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108879be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:02:17 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 18:02:17 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 18:02:17 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 18:02:17 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:02:17 INFO - Return code: 0 18:02:17 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:02:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:02:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:02:17 INFO - ENV: RUST_BACKTRACE is now 1 18:02:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:02:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:02:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eUbnioplRtahrrdxjtWT1Q/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0217', '--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 18:02:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/eUbnioplRtahrrdxjtWT1Q/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0217 --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 18:02:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SRkF2aiXvJ/Render', 18:02:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:02:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:02:17 INFO - 'HOME': '/Users/cltbld', 18:02:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:02:17 INFO - 'LOGNAME': 'cltbld', 18:02:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:02:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:02:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:17 INFO - 'MOZ_NO_REMOTE': '1', 18:02:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:02:17 INFO - 'NO_EM_RESTART': '1', 18:02:17 INFO - 'PAGER': '/bin/cat', 18:02:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:02:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:17 INFO - 'PWD': '/builds/slave/test', 18:02:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:02:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:02:17 INFO - 'RUST_BACKTRACE': '1', 18:02:17 INFO - 'SHELL': '/bin/bash', 18:02:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NpQIcpsTww/Listeners', 18:02:17 INFO - 'TMPDIR': '/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/', 18:02:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:02:17 INFO - 'USER': 'cltbld', 18:02:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:02:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:02:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:17 INFO - 'XPC_FLAGS': '0x0', 18:02:17 INFO - 'XPC_SERVICE_NAME': '0', 18:02:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:02:17 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eUbnioplRtahrrdxjtWT1Q/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0217', '--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 18:02:17 INFO - mozversion application_buildid: 20170314164236 18:02:17 INFO - mozversion application_changeset: cef93bf5a0a15ab7a2dc96d0cee90a599a4eae76 18:02:17 INFO - mozversion application_display_name: Nightly 18:02:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:02:17 INFO - mozversion application_name: Firefox 18:02:17 INFO - mozversion application_remotingname: firefox 18:02:17 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:02:17 INFO - mozversion application_vendor: Mozilla 18:02:17 INFO - mozversion application_version: 55.0a1 18:02:17 INFO - mozversion platform_buildid: 20170314164236 18:02:17 INFO - mozversion platform_changeset: cef93bf5a0a15ab7a2dc96d0cee90a599a4eae76 18:02:17 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:02:17 INFO - mozversion platform_version: 55.0a1 18:02:17 INFO - using testdate: 1489539737 18:02:17 INFO - actual date: 1489539737 18:02:17 INFO - starting webserver on 'localhost:49245' 18:02:17 INFO - SUITE-START | Running 2 tests 18:02:17 INFO - TEST-START | dromaeo_css 18:02:17 INFO - operating with platform_type : mac_ 18:02:17 INFO - Initialising browser for dromaeo_css test... 18:02:17 INFO - TEST-INFO | started process 1632 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/tmpZHY3mv/profile http://localhost:49245/getInfo.html) 18:02:20 INFO - PID 1632 | __metrics Screen width/height:1600/1200 18:02:20 INFO - PID 1632 | colorDepth:24 18:02:20 INFO - PID 1632 | Browser inner width/height: 1024/689 18:02:20 INFO - PID 1632 | __metrics 18:02:20 INFO - PID 1632 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:02:20 INFO - PID 1632 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:02:21 INFO - TEST-INFO | 1632: exit 0 18:02:21 INFO - Browser initialized. 18:02:21 INFO - Running cycle 1/1 for dromaeo_css test... 18:02:21 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/tmpZHY3mv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:02:33 INFO - PID 1633 | RSS: Main: 233496576 18:02:33 INFO - PID 1633 | 18:05:38 INFO - PID 1633 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49245/tests/dromaeo/cssquery-ext.html) 18:05:39 INFO - PID 1633 | RSS: Main: 393691136 18:05:39 INFO - PID 1633 | 18:07:22 INFO - PID 1633 | ************************* 18:07:22 INFO - PID 1633 | A coding exception was thrown and uncaught in a Task. 18:07:22 INFO - PID 1633 | 18:07:22 ERROR - PID 1633 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:07:23 INFO - PID 1633 | Full stack: 18:07:23 INFO - PID 1633 | ************************* 18:08:43 INFO - PID 1633 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-ext.html (next: http://localhost:49245/tests/dromaeo/cssquery-jquery.html) 18:08:43 INFO - PID 1633 | RSS: Main: 387362816 18:08:43 INFO - PID 1633 | 18:11:46 INFO - PID 1633 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49245/tests/dromaeo/cssquery-mootools.html) 18:11:46 INFO - PID 1633 | RSS: Main: 359825408 18:11:46 INFO - PID 1633 | 18:14:49 INFO - PID 1633 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49245/tests/dromaeo/cssquery-prototype.html) 18:14:50 INFO - PID 1633 | RSS: Main: 338153472 18:14:50 INFO - PID 1633 | 18:17:53 INFO - PID 1633 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49245/tests/dromaeo/cssquery-yui.html) 18:17:54 INFO - PID 1633 | RSS: Main: 339509248 18:17:54 INFO - PID 1633 | 18:20:57 INFO - PID 1633 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-yui.html (next: http://localhost:49245/tests/dromaeo/cssquery-dojo.html) 18:20:57 INFO - PID 1633 | RSS: Main: 549986304 18:20:57 INFO - PID 1633 | 18:20:57 INFO - PID 1633 | __start_tp_report 18:20:57 INFO - PID 1633 | _x_x_mozilla_page_load 18:20:57 INFO - PID 1633 | _x_x_mozilla_page_load_details 18:20:57 INFO - PID 1633 | |i|pagename|runs| 18:20:57 INFO - PID 1633 | |0;dojo.html;3335.5163501292327;3366.7979921204715;3367.0908854609256;3417.521546983422;3420.640832712565;5725.4847063764255;5775.913361299584;5883.764649414029;5924.733386997585;5938.287405511334;5749.56878234132;5810.709464526767;5814.331351894533;5816.9418305817035;5823.592348535604;5257.158854583267;5267.341582302212;5297.152455607104;5333.120035194188;5372.274742909696;5402.162664786944;5408.2698835657075;5410.675359478424;5411.540019098362;5430.239766432703;5417.106177480711;5419.214213938976;5434.8913021739545;5439.456054394549;5441.83674489766;4500.842470513533;5404.648697834628;5431.34823821144;5435.1847222916485;5440.156775699773;5236.947630523722;5326.041312563737;5345.946540534584;5356.54469370104;5368.1679339702405;6031.758729650809;6059.575829691923;6063.090536419529;6078.6960651967665;6083.847903802441;6225.533085018642;6315.621062736251;6324.209473815773;6329.272133704637;6340.175777148987;6300.30696623371;6318.526110541682;6327.651979141146;6328.936710632881;6385.106085148083;6080.665563394055;6098.176746139259;6099.359567245464;6106.297775755845;6143.262808462969;6452.322506136882;6499.837504062436;6502.512311576651;6505.024246363083;6512.6092434454085;6153.292371377349;6213.65824879636;6222.688865556704;6245.75016999324;6315.621062736251;6301.40136687014;6365.777197798147;6393.6163830170335;6402.423781859562;6403.327650596713;6283.151774510383;6323.715432805535;6325.588836725598;6329.525285603599;6358.395952384516;6164.167837341902;6271.811845644638;6320.810375688737;6333.334999825044;6338.429541341301;4305.978470107692;5081.415637201695;5107.540321371094;5159.690418574897;5165.845024649267;4559.54404559548;4560.452745670541;4575.405197324327;4576.702514336557;4600.102979918884;5613.607047506635;5773.470030441937;5967.16459695643;6011.849703757441;6050.394960503914;6234.2830574483605;6297.842553936188;6299.622022678654;6314.1160237565855;6366.872662546723;5997.730102145414;6012.909806352908;6014.338422773491;6020.307664618537;6044.425779550936;5630.8029218977135;5640.266765320564;5642.858928526737;5644.887102258014;5662.57530685192;6233.532485063548;6396.680165991775;6546.18172728409;6550;6551.672416379257;6282.780102696383;6351.523635727246;6388.39310265524;6393.913034784191;6402.167718196604;5571.718504743851;6489.1888513935755;6506.089147519257;6544.6727663615675;6548.901766473602;5699.774548472098;6017.548683848817;6255.343188965093;6312.179416675803;6348.111264422892;5116.590672746097;6250.718717657625;6296.779612713533;6306.180196574416;6365.427111559982;6089.177960975212;6093.728924480204;6248.937510625017;6280.340564240689;6281.59169653971;6102.246889800232;6155.815325540241;6160.921838678303;6179.660118693515;6252.687365631609;6156.938430615636;6175;6189.96905015472;6196.318404975539;6199.504039676905;6105.854009884935;6186.690665466619;6189.133521307111;6209.782657606962;6294.4964402846745;6032.54755893301;6090.3605121461605;6092.939070609237;6098.26820781509;6099.695015249308 18:20:57 INFO - PID 1633 | |1;ext.html;647.4690753582063;731.8024133483959;794.1740589786621;855.4396870050119;865.5282870835395;28580;37015.444768328496;37448.81275593625;37545;37591.624083759154;39914.800425997826;40319.59680403195;40385.798071009674;40405.79797101018;40539.39190912131;7143.178534468525;8946.68686595971;9157.542122893861;9173.128552787475;9173.633054677839;7307.707691692326;10601.84097238542;10835.620753273637;10852.620158294461;10859.565617375296;10917.836232456519;10987.670369888876;10997.505112269928;10999.780004399907;11151;7176.928230717678;10986.285891417072;11094.833577496343;11113.11095112389;11136.610218642349;7288.416926645856;10424.739381515446;10622.734431639194;10647.254692171551;10672.30630009047;30906.0728178155;37906.05234869123;38293.61706382928;38663;38712.80643596792;3732.6267373262726;3734.439834024891;3784.4323351497496;3805.5243094613174;3927.6820400438232;22818.97314620846;26820.4787102026;28245.85877070622;29547.409051819057;29648.407031859457;3114.2837147455984;3126.2965832687464;3146.465100932847;3159.2417819723332;3250.2849373137874;340349;342860.9999999975;343568;344660;345647;52021;52023.2196517045;52110.73944630253;52150.0887433444;52310.95378092417;54243.72878135584;54379.912401751746;54442.18336724952;54474.18288725672;54545.90908181814;14768.926155369154;14903.925480372529;14906;14906.478273260384;14916.477923272634;8807.779805504786;10428.634997775041;11942.880571194177;12150.392480375946;12168.513259469522;11301.378424186749;12528.373581320897;12744.80882786759;12768.233905965672;12769.808452873214;4427.867163985085;5050.823221187241;5301.734913254322;5360.222767698662;5363.249145119687;1111.3220935229504;1124.6344937895217;1140.3692719411322;1150.5627861412609;1164.1443538999;21764.347069587937;24837.290242386574;26075.087371941892;26348.20955371342;26670.799814008416;1078.5793540519203;1222.6882145052955;1236.1779416687982;1279.8464184297757;1280.3726174174587;13407.865921340857;13764.07302977584;13778.517751878637;13842;13854.16874987504;361219.3878061238;432182;433051;433771;434082.82958585006;55494.16758748622;55506;55606.44393556013;55670.44329556733;55888.720556396955;1382.5644921849585;1421.2396367943309;1423.750843602386;1434.5409468970038;1451.3831621560794;21191.8940405297;24884.751152488967;25929.48141037131;25982.740172598787;26011.60982585225;46413.3038004437;46415;46432;46445.6066318011;46851.53148468608;412720;414385.92807036976;414749;415400;415583.61040974216;32822.01533953985;33501;33854.492182616785;33894.83052584721;33968.830155849064;33487;40601;43413;43480.91297717582;45616.54383456123;33147.09508092401;33729.494057588665;34804.82597586996;37994.0501487465;39321.60678393179;36892.63107368892;37316.440253395675;37495.43756843595;38430.80784596059;38485.42271865868;37187.88436346914;38948.80525597354;39098.2180356397;39130;39245;29472;31318.79625847983;33010.41729150486;33227.50158747669;33596;21930.342089737333;23933.760662393848;24309.878450607634;24910.12814551482;25264.747352526974 18:20:57 INFO - PID 1633 | |2;jquery.html;397.71960767658805;398.9561148273691;409.78895868627654;414.9377593360996;425.63395479887294;20711.06800193991;21153.471163220926;21568.676469852944;21627.891860540676;22066.117355305807;21225.36323910281;21951.341459756233;22059.6691049634;22143;22213.444663883412;6716.294789047153;7006.255407596002;7074.85850282994;7097.148342198943;7103.218645948968;6581.210254769434;6868.450523958097;6884.483663725241;6904.654767261617;6912.066870972405;6901.896471552931;6913.516053876231;7438.442116841232;7782.377409807202;7865.606719664022;7428.702851885918;7586.772396828049;7788.14330423653;7858.646360913773;7860.606969651494;6281.403266689657;7432.108147022338;7440.2931721486375;7456.3289303962365;7465.962670186615;26664.333391665168;27186;27880.860595696893;28920.855395723098;29041;2732.401545096121;2763.6130941668184;2777.097443330905;2787.5539913613725;2797.7062408447023;15578.610534736608;16151.950123241952;16374.426895058736;16685.749713754303;17390.739138912926;1976.2786582897138;1985.1463870535663;1993.8504612154147;2002.9499262518407;2010.8620245386533;130503;131514;146138.26930865383;154034.22982885127;157218;17219.913900430667;21238.46903827417;21267.93660316978;21490.92545372725;21567.14336931851;36346.8182659085;36458.27083458316;36506;36519.26961460756;36622.45066324007;10315;10414.479276036167;10623.415712135753;10680.412577308323;10792.46037698112;7129.536580122275;8548.786280343042;8568.143185681507;8598.87101693475;8780.824383512294;10456.424896630606;10514.947425262824;10548.578056877639;10721.070207362594;10721.517531711092;3454.5854497460464;3545.9290814183573;3550.822458877046;3563.058133317348;3567.126054116706;1030.1398332392537;1040.2302296300834;1048.2127971808202;1052.7894421115654;1057.6836727566604;22367.66448503274;22611.773882261296;22694.773052269265;22696.205632803103;22712.886435567718;1035.994820025895;1054.6308791922766;1083.1551389915878;1085.115630760943;1085.7122862441458;11064.446777661085;11129.888701113046;11975.46110425033;12007.03943684503;12155.817662735066;537308;627378;656464;667522;688239;146761.5323846748;146782;154241;156541.43458565496;157774;1167.6905620010705;1194.6416075177412;1224.2960297828783;1273.5733529267613;1294.8705129487164;19518.512037199183;20289;20682.793172068086;20827.062782174537;20921;48579;60134;62932.68533657303;63956.72086558336;65896.68206635937;75377;80598.59700701458;92532.53733731288;94460.52769736107;94877.83294085863;11955.282683039046;12311.69220769488;12433.813492797435;12441.564545240873;12554;13129.277889716157;13340.066195366619;13573.59279221625;13662.590122296348;14146.080504767157;9733.756656083655;10781.083607893417;11398.886011140114;11641.185117041725;12072.154949153475;8855.51294678773;9758.902410976083;11641;11740.530378784753;12008;9918.40489570628;11683.941580292045;11801.881981180079;12093.637190884288;12101;12686.682832928882;12800.206936346998;12822.551210707581;13051.804222936473;13191.538296159588;9412.34113612068;10142.442165680959;11579.768404631692;11745.412729363668;12109.455074521844 18:20:57 INFO - PID 1633 | |3;mootools.html;1324.9536266230684;1542.4524293875675;1568.435363269223;1574.4961612284067;1635;9141.588628511716;9587.904120958789;10407.375557466557;10479.633212837552;10758.892411075907;10406.687799366013;10514.264001519896;10514.421706806143;10531.26281160319;10536;1749.5276275405633;2236.351458077164;2270.761570035147;2309.6535519672016;2318.9304320870315;1940.9417717468498;2004.859659823813;2017.4452025692979;2022.5247066939257;2272.3410211038777;2243.9663405048937;2292.449812045114;2294.105298933417;2297.9659153380962;2299.7700229977036;2093.791050441783;2133.114757375687;2259.5142044460435;2286.8627882327114;2291.049214575949;2208.7791220877943;2251;2251.6059689554263;2264.083046366224;2297.7357603875603;10494.737631559196;10617.946910265398;10618.362898226054;10838.783224335548;10841;5635.21107045014;5826.47561719443;5853.326867410217;5887.587868849182;5892.263467066617;2912.6796052434215;3552.307300076486;3577.141486043331;3600.153963818527;3617.7829330240006;1178.2282604893724;1179.1863614106326;1215.9148859579832;1232.3530146672979;1237.641084085614;15283.006604570668;15333.31000104998;15364.692706145926;15367.615809604738;15381.23093845292;4612.747389745982;4709.882252943636;4812.614990800727;4824.975875120672;4915.606751459874;12370.567030154081;12391;12393.814092788616;12398.194117382342;12425.005999520017;4905.583025442806;5784.045632470596;5799.88400231993;5808.361080281165;5816.302043754777;4988.625853061035;5030.547250747451;5134.2298655201275;5183.740812959337;5251.422343542207;3445.7415693823064;3519.859205631746;3519.894403167909;3531.0642679689904;3541.8406171722336;945.2390825385493;947.2232769129386;961.6682244625733;1020.4132623741378;1032.4012072997643;722.9408915938176;751.5941391648448;778.1362687416963;780.2509590792789;786.4376970466144;6276.089966954766;6327.620342779448;6549.672516374162;6571.737130514726;6588.670566471657;759.3393747439762;964.5370222293338;974.2692980264801;998.6105418886494;1025.9538320775434;4021.939670904939;4024.0744628735556;4064.288749468831;4168.249715051259;4171.395147703566;11198.496067676973;11376.260543064674;11625.53497860093;11646.70883227926;11670.70823229426;11412.714682133013;11664.358460284766;11667.766644667228;11683.707907302385;11688.824667629993;9442.811143777224;9783.119519243302;9878.308518403643;9933.453660048623;9938.45338506389;5286.682799032071;6185.474234690102;6439.130717353098;6449.258335291502;6458.418742313214;11717.88282117168;11916;12122.333271670142;12140.93929530382;12184.268943863397;11682.70793230176;12084.093692972887;12216.205946613798;12259.938700306442;12264.325462099314;8008.719694810654;8277.793055173668;8282.627281772455;8293.548628990024;8301.418900676894;9900.702978910644;9903.059209375098;9953.601855925683;9986.101250887456;10022.298439109192;8720.563971801512;8864.867026994729;8901.198892099743;9143;9153.038930912424;8502.14978502145;8627.137286271323;8668;8726.956365218133;8740.956295218482;7285.344319011102;7631.275028872248;7776.066871975173;7776.377889768692;7800.531968081933;4716.434027916672;5392.676439413648;5588.6646801192055;5612.158176273591;5914.67469289176;5746.370529798045;5839.5036421904615;5885.676287804212;5961.344252132175;6029.1559181715475 18:20:57 INFO - PID 1633 | |4;prototype.html;378.31336124933244;384.15294276121153;386.86653104678874;387.68791123145854;390.0910877655063;5701.232617888458;5730.770769169233;5762.798302059428;5780.739866705998;5800.883982320351;4951.480094590071;5410.053240682884;5490.862728431781;5560.916586251209;5581;4440.424553758812;4442.289233722605;4443.666724995622;4447.3773671685985;4450.421445212126;4453.977730111361;4459.955400445986;4462.1968045751755;4466.397036400093;4468.106378724252;3840.615938406165;4218.072024154696;4299.732203525327;4404.69167158299;4426.513083560806;3596.532450781395;4405.977970110129;4430.933535996963;4435.2238358287495;4464.196444639963;3379.6282408935;3868.916703323074;3881.514810648669;3906.687465002793;3911.5697273300198;5409.864753381157;5411.594130440233;5538.9169162462595;5601.187827764992;5642.746076426571;5068.366454193226;5142.228665700175;5166.3800343958965;5178.766955486974;5187.3775146982225;4988.476209997934;5893.057110862242;6075.787347442818;6079.39206079393;6104.96947515264;3208.0375887233968;4221.197972385237;4313.223619748444;4347.043650396908;4372.404072513542;5470.480304371118;6205.658688772116;6285.585743207742;6408.839779005515;6428.389303016206;4356.978215108904;4586.922073312769;5299.6290259681455;5309.522143007071;5323.1749078892835;4818.494058123917;5201.713905735221;5388.838334849961;5572.442755724476;5641.323041235078;5793.899159159746;5821.825345239649;5843.328017277982;5845.473907348359;5858.355580886099;5541.778328866881;5882.3529411764675;6244.65654389013;6258.280297765724;6297.2128483939505;1612.5887898585786;1805.6388722255601;1838.6322735452964;1855.2486243071578;1962.3328068456508;1505.8268299145739;1529.2048134969752;1545.289166983178;1552.270432896537;1562.8671562917052;4864.610831133501;5765.365809760923;5766.711664416763;5779.075347944393;5802.3037235531165;5506.366767821752;6003.009503431972;6401.487880969511;6422.389872962154;6429.453496452854;4639.652026098056;5052.8484145475695;5346.278252435949;5406.3775471971685;5791.652500849962;3336.866525338959;3360.77365728903;3380.594328680574;3382.255903701182;3389.5933886940547;5698.4495543405155;6077.4226448487325;6314.789823561717;6647.401733843977;6720.764773232913;6627.608202277575;6647.6676166192465;6656.70044847983;6681;6706.966465167643;6720.596764194164;6724.327567243236;6730.226024007204;6740.629265390353;6807.795766127024;5374.623776335776;6143.662098584621;6425.775097871552;6440;6509.772157974448;6399.776007839703;6405.263394709668;6419.8395040122505;6420.775272865428;6452.451541619015;6427.517936154901;6640.701168447335;6704.497162712913;6722.563033402813;6762.357576030269;4364.716293440916;4954.479779623088;5015.448300686994;5047.520485553867;5054;5331.200319952039;6000.669963152069;6286.874262514815;6291.465225455888;6553.934460655332;6216.844578885564;6237.1891654084675;6291.150694656163;6294.590851594632;6306.211723534559;6201.565890387629;6272.121902933684;6285.52290211809;6288.151099601495;6313.810585682437;5857.384974577608;6067.757289708362;6098.176746139215;6127.314988378232;6136.20229370179;5212.931349073355;5444.373897001896;6253.906191407042;6282.465990390868;6313.463355614824;5116.334876466036;5790.507806836297;6076.118962750465;6117.449429551361;6123.632582045091 18:20:58 INFO - PID 1633 | |5;yui.html;631.6557476175484;694.6769752065288;728.8396552758395;834.236673443799;876.3909083187185;14948.252587370629;15262.694746105071;15582.766258506132;15767.290471928762;15783.447579334712;14616.269186540658;14762.852371476281;14782.704345913075;14860.034097783664;14984.250787460638;911.2664305234284;946.7538440005579;958.6500927161605;962.7544976031103;963.4556475591269;909.154486327715;952.7951490429556;958.6932181701858;959.8320293948566;963.5663951221943;1004.9748756281078;1006.0593345222204;1007.3200589602026;1008.1279693065519;1008.5814387029411;996.2776986684668;999.3853779925294;1000.229823036258;1002.478711070243;1002.9097381235724;742.6546655805023;743.4647054121024;745.1616930952679;745.2361329637112;745.9515131516434;12079.939600301943;15088.54734357971;15554;15611.375544978187;15734.685306293924;12379.566715165016;14477.203753793538;14794;14829.06600609071;14841.332140053724;11599.362035088066;11687.824682629767;11702.765944681143;11793;12048.397580120958;3152.621685397744;3732.533433320835;3740.551133863954;3879.1853710720784;3912.315344814646;112330.43834780774;112405.31392029126;113957;115310;116275.83724162735;10673.199510036779;11015.449227538591;11123;11593.304401735923;11846.526138954345;23871.283861484182;24233.63649545258;24237;24370.390740231636;24413.26760197197;6295.716692748837;7881.802954926173;7965.084015338311;8042.879356809653;8180.67277308901;4232.0852963054795;4279.2083464559155;4293.141371725667;4301;4404.713693609905;3417.79493230407;3495.1611613212644;3517.8592856285463;3525.6121826599056;3526.0655926179584;576.4667682393768;616.4698359410903;618.0482057631287;632.1055706175802;633.6293268437923;642.8874946884276;669.5346734019906;684.1311534351346;699.4124935054477;717.3830505765038;7446.171652066261;9569.563402953057;9953;10366.533505992249;10522.368657880552;632.5824955529329;687.8108520156997;704.1936982155506;709.3544874164588;719.0041792117954;4849.781759820817;4909.361782968192;4910.779014944336;4915.8033678652455;4917.557419832232;105408;108514;109099.90900090897;111650;113923;95160.52419737996;95493.56759648611;95511;96082;96929;81189.18810812052;83072.16927830645;83334;83861;85898;8676.826463470568;9116.444586611095;10318.535665895202;10934.234603577992;10973.83539246896;43885;46274.768626156656;47730.32943846949;48951.75524122499;50179;63654.68172659107;70260.29739702742;71304;71592;73132.63433682798;12894.355282236038;13213.405396756976;13469.461221551028;13507.459701611826;13746.587602371945;14410.495632652806;14582.416703332172;14611.844767886218;14748.852511475177;14779;13663.453461861414;13746.518871839438;13778.793318100437;13886.444542218602;14247.572572822832;13477.865221347662;13558;13691.657708557366;13742;14140.292985350898;12948.935255323662;12984;13214.603561893158;13316.866831331563;13421.463141474233;10775.736004877795;10869.239153259196;11047.171462140532;11200.38397888124;11373.54505819758;8681.739547813575;10874.456277186267;11261.549538018715;11289.830652540055;11368.54525818958 18:20:58 INFO - PID 1633 | __end_tp_report 18:20:58 INFO - PID 1633 | __start_cc_report 18:20:58 INFO - PID 1633 | _x_x_mozilla_cycle_collect,1752 18:20:58 INFO - PID 1633 | __end_cc_report 18:20:58 INFO - PID 1633 | __startTimestamp1489540857986__endTimestamp 18:20:58 INFO - PID 1633 | 18:20:58 INFO - PID 1633 | ------- Summary: start ------- 18:20:58 INFO - PID 1633 | Number of tests: 6 18:20:58 INFO - PID 1633 | 18:20:58 INFO - PID 1633 | [#0] dojo.html Cycles:165 Average:5873.70 Median:6099.36 stddev:656.48 (10.8%) stddev-sans-first:627.57 18:20:58 INFO - PID 1633 | Values: 3335.5 3366.8 3367.1 3417.5 3420.6 5725.5 5775.9 5883.8 5924.7 5938.3 5749.6 5810.7 5814.3 5816.9 5823.6 5257.2 5267.3 5297.2 5333.1 5372.3 5402.2 5408.3 5410.7 5411.5 5430.2 5417.1 5419.2 5434.9 5439.5 5441.8 4500.8 5404.6 5431.3 5435.2 5440.2 5236.9 5326.0 5345.9 5356.5 5368.2 6031.8 6059.6 6063.1 6078.7 6083.8 6225.5 6315.6 6324.2 6329.3 6340.2 6300.3 6318.5 6327.7 6328.9 6385.1 6080.7 6098.2 6099.4 6106.3 6143.3 6452.3 6499.8 6502.5 6505.0 6512.6 6153.3 6213.7 6222.7 6245.8 6315.6 6301.4 6365.8 6393.6 6402.4 6403.3 6283.2 6323.7 6325.6 6329.5 6358.4 6164.2 6271.8 6320.8 6333.3 6338.4 4306.0 5081.4 5107.5 5159.7 5165.8 4559.5 4560.5 4575.4 4576.7 4600.1 5613.6 5773.5 5967.2 6011.8 6050.4 6234.3 6297.8 6299.6 6314.1 6366.9 5997.7 6012.9 6014.3 6020.3 6044.4 5630.8 5640.3 5642.9 5644.9 5662.6 6233.5 6396.7 6546.2 6550.0 6551.7 6282.8 6351.5 6388.4 6393.9 6402.2 5571.7 6489.2 6506.1 6544.7 6548.9 5699.8 6017.5 6255.3 6312.2 6348.1 5116.6 6250.7 6296.8 6306.2 6365.4 6089.2 6093.7 6248.9 6280.3 6281.6 6102.2 6155.8 6160.9 6179.7 6252.7 6156.9 6175.0 6190.0 6196.3 6199.5 6105.9 6186.7 6189.1 6209.8 6294.5 6032.5 6090.4 6092.9 6098.3 6099.7 18:20:58 INFO - PID 1633 | 18:20:58 INFO - PID 1633 | [#1] ext.html Cycles:180 Average:53830.61 Median:187158.92 stddev:104229.89 (55.7%) stddev-sans-first:104445.80 18:20:58 INFO - PID 1633 | Values: 647.5 731.8 794.2 855.4 865.5 28580.0 37015.4 37448.8 37545.0 37591.6 39914.8 40319.6 40385.8 40405.8 40539.4 7143.2 8946.7 9157.5 9173.1 9173.6 7307.7 10601.8 10835.6 10852.6 10859.6 10917.8 10987.7 10997.5 10999.8 11151.0 7176.9 10986.3 11094.8 11113.1 11136.6 7288.4 10424.7 10622.7 10647.3 10672.3 30906.1 37906.1 38293.6 38663.0 38712.8 3732.6 3734.4 3784.4 3805.5 3927.7 22819.0 26820.5 28245.9 29547.4 29648.4 3114.3 3126.3 3146.5 3159.2 3250.3 340349.0 342861.0 343568.0 344660.0 345647.0 52021.0 52023.2 52110.7 52150.1 52311.0 54243.7 54379.9 54442.2 54474.2 54545.9 14768.9 14903.9 14906.0 14906.5 14916.5 8807.8 10428.6 11942.9 12150.4 12168.5 11301.4 12528.4 12744.8 12768.2 12769.8 4427.9 5050.8 5301.7 5360.2 5363.2 1111.3 1124.6 1140.4 1150.6 1164.1 21764.3 24837.3 26075.1 26348.2 26670.8 1078.6 1222.7 1236.2 1279.8 1280.4 13407.9 13764.1 13778.5 13842.0 13854.2 361219.4 432182.0 433051.0 433771.0 434082.8 55494.2 55506.0 55606.4 55670.4 55888.7 1382.6 1421.2 1423.8 1434.5 1451.4 21191.9 24884.8 25929.5 25982.7 26011.6 46413.3 46415.0 46432.0 46445.6 46851.5 412720.0 414385.9 414749.0 415400.0 415583.6 32822.0 33501.0 33854.5 33894.8 33968.8 33487.0 40601.0 43413.0 43480.9 45616.5 33147.1 33729.5 34804.8 37994.1 39321.6 36892.6 37316.4 37495.4 38430.8 38485.4 37187.9 38948.8 39098.2 39130.0 39245.0 29472.0 31318.8 33010.4 33227.5 33596.0 21930.3 23933.8 24309.9 24910.1 25264.7 18:20:58 INFO - PID 1633 | 18:20:58 INFO - PID 1633 | [#2] jquery.html Cycles:180 Average:39999.55 Median:21379.43 stddev:107119.37 (501.0%) stddev-sans-first:107378.60 18:20:58 INFO - PID 1633 | Values: 397.7 399.0 409.8 414.9 425.6 20711.1 21153.5 21568.7 21627.9 22066.1 21225.4 21951.3 22059.7 22143.0 22213.4 6716.3 7006.3 7074.9 7097.1 7103.2 6581.2 6868.5 6884.5 6904.7 6912.1 6901.9 6913.5 7438.4 7782.4 7865.6 7428.7 7586.8 7788.1 7858.6 7860.6 6281.4 7432.1 7440.3 7456.3 7466.0 26664.3 27186.0 27880.9 28920.9 29041.0 2732.4 2763.6 2777.1 2787.6 2797.7 15578.6 16152.0 16374.4 16685.7 17390.7 1976.3 1985.1 1993.9 2002.9 2010.9 130503.0 131514.0 146138.3 154034.2 157218.0 17219.9 21238.5 21267.9 21490.9 21567.1 36346.8 36458.3 36506.0 36519.3 36622.5 10315.0 10414.5 10623.4 10680.4 10792.5 7129.5 8548.8 8568.1 8598.9 8780.8 10456.4 10514.9 10548.6 10721.1 10721.5 3454.6 3545.9 3550.8 3563.1 3567.1 1030.1 1040.2 1048.2 1052.8 1057.7 22367.7 22611.8 22694.8 22696.2 22712.9 1036.0 1054.6 1083.2 1085.1 1085.7 11064.4 11129.9 11975.5 12007.0 12155.8 537308.0 627378.0 656464.0 667522.0 688239.0 146761.5 146782.0 154241.0 156541.4 157774.0 1167.7 1194.6 1224.3 1273.6 1294.9 19518.5 20289.0 20682.8 20827.1 20921.0 48579.0 60134.0 62932.7 63956.7 65896.7 75377.0 80598.6 92532.5 94460.5 94877.8 11955.3 12311.7 12433.8 12441.6 12554.0 13129.3 13340.1 13573.6 13662.6 14146.1 9733.8 10781.1 11398.9 11641.2 12072.2 8855.5 9758.9 11641.0 11740.5 12008.0 9918.4 11683.9 11801.9 12093.6 12101.0 12686.7 12800.2 12822.6 13051.8 13191.5 9412.3 10142.4 11579.8 11745.4 12109.5 18:20:58 INFO - PID 1633 | 18:20:58 INFO - PID 1633 | [#3] mootools.html Cycles:180 Average:6451.40 Median:3608.97 stddev:4034.59 (111.8%) stddev-sans-first:4027.52 18:20:58 INFO - PID 1633 | Values: 1325.0 1542.5 1568.4 1574.5 1635.0 9141.6 9587.9 10407.4 10479.6 10758.9 10406.7 10514.3 10514.4 10531.3 10536.0 1749.5 2236.4 2270.8 2309.7 2318.9 1940.9 2004.9 2017.4 2022.5 2272.3 2244.0 2292.4 2294.1 2298.0 2299.8 2093.8 2133.1 2259.5 2286.9 2291.0 2208.8 2251.0 2251.6 2264.1 2297.7 10494.7 10617.9 10618.4 10838.8 10841.0 5635.2 5826.5 5853.3 5887.6 5892.3 2912.7 3552.3 3577.1 3600.2 3617.8 1178.2 1179.2 1215.9 1232.4 1237.6 15283.0 15333.3 15364.7 15367.6 15381.2 4612.7 4709.9 4812.6 4825.0 4915.6 12370.6 12391.0 12393.8 12398.2 12425.0 4905.6 5784.0 5799.9 5808.4 5816.3 4988.6 5030.5 5134.2 5183.7 5251.4 3445.7 3519.9 3519.9 3531.1 3541.8 945.2 947.2 961.7 1020.4 1032.4 722.9 751.6 778.1 780.3 786.4 6276.1 6327.6 6549.7 6571.7 6588.7 759.3 964.5 974.3 998.6 1026.0 4021.9 4024.1 4064.3 4168.2 4171.4 11198.5 11376.3 11625.5 11646.7 11670.7 11412.7 11664.4 11667.8 11683.7 11688.8 9442.8 9783.1 9878.3 9933.5 9938.5 5286.7 6185.5 6439.1 6449.3 6458.4 11717.9 11916.0 12122.3 12140.9 12184.3 11682.7 12084.1 12216.2 12259.9 12264.3 8008.7 8277.8 8282.6 8293.5 8301.4 9900.7 9903.1 9953.6 9986.1 10022.3 8720.6 8864.9 8901.2 9143.0 9153.0 8502.1 8627.1 8668.0 8727.0 8741.0 7285.3 7631.3 7776.1 7776.4 7800.5 4716.4 5392.7 5588.7 5612.2 5914.7 5746.4 5839.5 5885.7 5961.3 6029.2 18:20:58 INFO - PID 1633 | 18:20:58 INFO - PID 1633 | [#4] prototype.html Cycles:180 Average:5157.30 Median:5591.09 stddev:1486.28 (26.6%) stddev-sans-first:1446.52 18:20:58 INFO - PID 1633 | Values: 378.3 384.2 386.9 387.7 390.1 5701.2 5730.8 5762.8 5780.7 5800.9 4951.5 5410.1 5490.9 5560.9 5581.0 4440.4 4442.3 4443.7 4447.4 4450.4 4454.0 4460.0 4462.2 4466.4 4468.1 3840.6 4218.1 4299.7 4404.7 4426.5 3596.5 4406.0 4430.9 4435.2 4464.2 3379.6 3868.9 3881.5 3906.7 3911.6 5409.9 5411.6 5538.9 5601.2 5642.7 5068.4 5142.2 5166.4 5178.8 5187.4 4988.5 5893.1 6075.8 6079.4 6105.0 3208.0 4221.2 4313.2 4347.0 4372.4 5470.5 6205.7 6285.6 6408.8 6428.4 4357.0 4586.9 5299.6 5309.5 5323.2 4818.5 5201.7 5388.8 5572.4 5641.3 5793.9 5821.8 5843.3 5845.5 5858.4 5541.8 5882.4 6244.7 6258.3 6297.2 1612.6 1805.6 1838.6 1855.2 1962.3 1505.8 1529.2 1545.3 1552.3 1562.9 4864.6 5765.4 5766.7 5779.1 5802.3 5506.4 6003.0 6401.5 6422.4 6429.5 4639.7 5052.8 5346.3 5406.4 5791.7 3336.9 3360.8 3380.6 3382.3 3389.6 5698.4 6077.4 6314.8 6647.4 6720.8 6627.6 6647.7 6656.7 6681.0 6707.0 6720.6 6724.3 6730.2 6740.6 6807.8 5374.6 6143.7 6425.8 6440.0 6509.8 6399.8 6405.3 6419.8 6420.8 6452.5 6427.5 6640.7 6704.5 6722.6 6762.4 4364.7 4954.5 5015.4 5047.5 5054.0 5331.2 6000.7 6286.9 6291.5 6553.9 6216.8 6237.2 6291.2 6294.6 6306.2 6201.6 6272.1 6285.5 6288.2 6313.8 5857.4 6067.8 6098.2 6127.3 6136.2 5212.9 5444.4 6253.9 6282.5 6313.5 5116.3 5790.5 6076.1 6117.4 6123.6 18:20:58 INFO - PID 1633 | 18:20:58 INFO - PID 1633 | [#5] yui.html Cycles:180 Average:21349.74 Median:24391.83 stddev:31362.47 (128.6%) stddev-sans-first:31411.87 18:20:58 INFO - PID 1633 | Values: 631.7 694.7 728.8 834.2 876.4 14948.3 15262.7 15582.8 15767.3 15783.4 14616.3 14762.9 14782.7 14860.0 14984.3 911.3 946.8 958.7 962.8 963.5 909.2 952.8 958.7 959.8 963.6 1005.0 1006.1 1007.3 1008.1 1008.6 996.3 999.4 1000.2 1002.5 1002.9 742.7 743.5 745.2 745.2 746.0 12079.9 15088.5 15554.0 15611.4 15734.7 12379.6 14477.2 14794.0 14829.1 14841.3 11599.4 11687.8 11702.8 11793.0 12048.4 3152.6 3732.5 3740.6 3879.2 3912.3 112330.4 112405.3 113957.0 115310.0 116275.8 10673.2 11015.4 11123.0 11593.3 11846.5 23871.3 24233.6 24237.0 24370.4 24413.3 6295.7 7881.8 7965.1 8042.9 8180.7 4232.1 4279.2 4293.1 4301.0 4404.7 3417.8 3495.2 3517.9 3525.6 3526.1 576.5 616.5 618.0 632.1 633.6 642.9 669.5 684.1 699.4 717.4 7446.2 9569.6 9953.0 10366.5 10522.4 632.6 687.8 704.2 709.4 719.0 4849.8 4909.4 4910.8 4915.8 4917.6 105408.0 108514.0 109099.9 111650.0 113923.0 95160.5 95493.6 95511.0 96082.0 96929.0 81189.2 83072.2 83334.0 83861.0 85898.0 8676.8 9116.4 10318.5 10934.2 10973.8 43885.0 46274.8 47730.3 48951.8 50179.0 63654.7 70260.3 71304.0 71592.0 73132.6 12894.4 13213.4 13469.5 13507.5 13746.6 14410.5 14582.4 14611.8 14748.9 14779.0 13663.5 13746.5 13778.8 13886.4 14247.6 13477.9 13558.0 13691.7 13742.0 14140.3 12948.9 12984.0 13214.6 13316.9 13421.5 10775.7 10869.2 11047.2 11200.4 11373.5 8681.7 10874.5 11261.5 11289.8 11368.5 18:20:58 INFO - PID 1633 | -------- Summary: end -------- 18:20:58 INFO - PID 1633 | 18:20:58 INFO - TEST-INFO | 1633: exit 0 18:20:58 INFO - TEST-OK | dromaeo_css | took 1120798ms 18:20:58 INFO - TEST-START | kraken 18:20:58 INFO - operating with platform_type : mac_ 18:20:58 INFO - Initialising browser for kraken test... 18:20:58 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/tmpYikW4w/profile http://localhost:49245/getInfo.html) 18:21:01 INFO - PID 1642 | __metrics Screen width/height:1600/1200 18:21:01 INFO - PID 1642 | colorDepth:24 18:21:01 INFO - PID 1642 | Browser inner width/height: 1024/689 18:21:01 INFO - PID 1642 | __metrics 18:21:01 INFO - PID 1642 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:21:01 INFO - PID 1642 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:21:02 INFO - TEST-INFO | 1642: exit 0 18:21:02 INFO - Browser initialized. 18:21:02 INFO - Running cycle 1/1 for kraken test... 18:21:02 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/tmpYikW4w/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:21:14 INFO - PID 1643 | RSS: Main: 233480192 18:21:14 INFO - PID 1643 | 18:21:17 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-beat-detection) 18:21:18 INFO - PID 1643 | RSS: Main: 284479488 18:21:18 INFO - PID 1643 | 18:21:20 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-dft) 18:21:21 INFO - PID 1643 | RSS: Main: 286220288 18:21:21 INFO - PID 1643 | 18:21:24 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-fft) 18:21:25 INFO - PID 1643 | RSS: Main: 378261504 18:21:25 INFO - PID 1643 | 18:21:27 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-oscillator) 18:21:28 INFO - PID 1643 | RSS: Main: 282333184 18:21:28 INFO - PID 1643 | 18:21:29 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:21:30 INFO - PID 1643 | RSS: Main: 286212096 18:21:30 INFO - PID 1643 | 18:21:33 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-darkroom) 18:21:34 INFO - PID 1643 | RSS: Main: 327368704 18:21:34 INFO - PID 1643 | 18:21:37 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-desaturate) 18:21:37 INFO - PID 1643 | RSS: Main: 331694080 18:21:37 INFO - PID 1643 | 18:21:40 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49245/tests/kraken/driver.html?testName=json-parse-financial) 18:21:41 INFO - PID 1643 | RSS: Main: 332369920 18:21:41 INFO - PID 1643 | 18:21:43 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49245/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:21:43 INFO - PID 1643 | RSS: Main: 303603712 18:21:43 INFO - PID 1643 | 18:21:45 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:21:46 INFO - PID 1643 | RSS: Main: 308006912 18:21:46 INFO - PID 1643 | 18:21:48 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:21:49 INFO - PID 1643 | RSS: Main: 310636544 18:21:49 INFO - PID 1643 | 18:21:52 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:21:53 INFO - PID 1643 | RSS: Main: 320368640 18:21:53 INFO - PID 1643 | 18:21:56 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:21:56 INFO - PID 1643 | RSS: Main: 319279104 18:21:56 INFO - PID 1643 | 18:21:58 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49245/tests/kraken/driver.html?testName=ai-astar) 18:21:58 INFO - PID 1643 | RSS: Main: 380379136 18:21:58 INFO - PID 1643 | 18:21:58 INFO - PID 1643 | __start_tp_report 18:21:58 INFO - PID 1643 | _x_x_mozilla_page_load 18:21:58 INFO - PID 1643 | _x_x_mozilla_page_load_details 18:21:58 INFO - PID 1643 | |i|pagename|runs| 18:21:58 INFO - PID 1643 | |0;ai-astar;117;114;116;114;115;116;115;115;116;115 18:21:58 INFO - PID 1643 | |1;audio-beat-detection;152;113;113;152;114;113;111;112;139;124 18:21:58 INFO - PID 1643 | |2;audio-dft;187;181;186;184;184;182;183;183;187;184 18:21:58 INFO - PID 1643 | |3;audio-fft;83;76;88;120;60;58;59;59;74;58 18:21:58 INFO - PID 1643 | |4;audio-oscillator;88;67;83;70;70;70;85;70;68;84 18:21:58 INFO - PID 1643 | |5;imaging-gaussian-blur;88;87;88;88;87;86;88;86;89;87 18:21:58 INFO - PID 1643 | |6;imaging-darkroom;127;144;101;94;94;93;95;94;95;94 18:21:58 INFO - PID 1643 | |7;imaging-desaturate;82;115;130;86;83;84;84;83;84;84 18:21:58 INFO - PID 1643 | |8;json-parse-financial;63;63;64;62;65;63;63;63;61;61 18:21:58 INFO - PID 1643 | |9;json-stringify-tinderbox;54;63;68;63;61;57;58;60;59;58 18:21:58 INFO - PID 1643 | |10;stanford-crypto-aes;82;81;81;84;97;106;74;93;95;73 18:21:58 INFO - PID 1643 | |11;stanford-crypto-ccm;141;173;144;154;147;136;150;145;245;183 18:21:58 INFO - PID 1643 | |12;stanford-crypto-pbkdf2;186;183;188;189;185;183;187;185;185;189 18:21:58 INFO - PID 1643 | |13;stanford-crypto-sha256-iterative;69;69;68;66;65;66;67;55;73;90 18:21:58 INFO - PID 1643 | __end_tp_report 18:21:58 INFO - PID 1643 | __start_cc_report 18:21:58 INFO - PID 1643 | _x_x_mozilla_cycle_collect,2141 18:21:58 INFO - PID 1643 | __end_cc_report 18:21:58 INFO - PID 1643 | __startTimestamp1489540918815__endTimestamp 18:21:58 INFO - PID 1643 | 18:21:58 INFO - PID 1643 | ------- Summary: start ------- 18:21:58 INFO - PID 1643 | Number of tests: 14 18:21:58 INFO - PID 1643 | 18:21:58 INFO - PID 1643 | [#0] ai-astar Cycles:10 Average:115.30 Median:115.50 stddev:0.95 (0.8%) stddev-sans-first:0.78 18:21:58 INFO - PID 1643 | Values: 117.0 114.0 116.0 114.0 115.0 116.0 115.0 115.0 116.0 115.0 18:21:58 INFO - PID 1643 | 18:21:58 INFO - PID 1643 | [#1] audio-beat-detection Cycles:10 Average:124.30 Median:119.00 stddev:16.89 (14.2%) stddev-sans-first:14.64 18:21:58 INFO - PID 1643 | Values: 152.0 113.0 113.0 152.0 114.0 113.0 111.0 112.0 139.0 124.0 18:21:58 INFO - PID 1643 | 18:21:58 INFO - PID 1643 | [#2] audio-dft Cycles:10 Average:184.10 Median:184.00 stddev:2.02 (1.1%) stddev-sans-first:1.86 18:21:58 INFO - PID 1643 | Values: 187.0 181.0 186.0 184.0 184.0 182.0 183.0 183.0 187.0 184.0 18:21:58 INFO - PID 1643 | 18:21:58 INFO - PID 1643 | [#3] audio-fft Cycles:10 Average:73.50 Median:67.00 stddev:19.87 (29.7%) stddev-sans-first:20.77 18:21:58 INFO - PID 1643 | Values: 83.0 76.0 88.0 120.0 60.0 58.0 59.0 59.0 74.0 58.0 18:21:58 INFO - PID 1643 | 18:21:58 INFO - PID 1643 | [#4] audio-oscillator Cycles:10 Average:75.50 Median:76.50 stddev:8.33 (10.9%) stddev-sans-first:7.51 18:21:58 INFO - PID 1643 | Values: 88.0 67.0 83.0 70.0 70.0 70.0 85.0 70.0 68.0 84.0 18:21:58 INFO - PID 1643 | 18:21:58 INFO - PID 1643 | [#5] imaging-gaussian-blur Cycles:10 Average:87.40 Median:88.00 stddev:0.97 (1.1%) stddev-sans-first:1.00 18:21:58 INFO - PID 1643 | Values: 88.0 87.0 88.0 88.0 87.0 86.0 88.0 86.0 89.0 87.0 18:21:58 INFO - PID 1643 | 18:21:58 INFO - PID 1643 | [#6] imaging-darkroom Cycles:10 Average:103.10 Median:94.00 stddev:17.68 (18.8%) stddev-sans-first:16.50 18:21:58 INFO - PID 1643 | Values: 127.0 144.0 101.0 94.0 94.0 93.0 95.0 94.0 95.0 94.0 18:21:58 INFO - PID 1643 | 18:21:58 INFO - PID 1643 | [#7] imaging-desaturate Cycles:10 Average:91.50 Median:84.00 stddev:16.75 (19.9%) stddev-sans-first:17.41 18:21:58 INFO - PID 1643 | Values: 82.0 115.0 130.0 86.0 83.0 84.0 84.0 83.0 84.0 84.0 18:21:58 INFO - PID 1643 | 18:21:58 INFO - PID 1643 | [#8] json-parse-financial Cycles:10 Average:62.80 Median:63.00 stddev:1.23 (2.0%) stddev-sans-first:1.30 18:21:58 INFO - PID 1643 | Values: 63.0 63.0 64.0 62.0 65.0 63.0 63.0 63.0 61.0 61.0 18:21:58 INFO - PID 1643 | 18:21:58 INFO - PID 1643 | [#9] json-stringify-tinderbox Cycles:10 Average:60.10 Median:60.50 stddev:3.90 (6.4%) stddev-sans-first:3.46 18:21:58 INFO - PID 1643 | Values: 54.0 63.0 68.0 63.0 61.0 57.0 58.0 60.0 59.0 58.0 18:21:58 INFO - PID 1643 | 18:21:58 INFO - PID 1643 | [#10] stanford-crypto-aes Cycles:10 Average:86.60 Median:83.00 stddev:10.70 (12.9%) stddev-sans-first:11.22 18:21:58 INFO - PID 1643 | Values: 82.0 81.0 81.0 84.0 97.0 106.0 74.0 93.0 95.0 73.0 18:21:58 INFO - PID 1643 | 18:21:58 INFO - PID 1643 | [#11] stanford-crypto-ccm Cycles:10 Average:161.80 Median:152.00 stddev:32.68 (21.5%) stddev-sans-first:33.79 18:21:58 INFO - PID 1643 | Values: 141.0 173.0 144.0 154.0 147.0 136.0 150.0 145.0 245.0 183.0 18:21:58 INFO - PID 1643 | 18:21:58 INFO - PID 1643 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:186.00 Median:186.50 stddev:2.21 (1.2%) stddev-sans-first:2.35 18:21:58 INFO - PID 1643 | Values: 186.0 183.0 188.0 189.0 185.0 183.0 187.0 185.0 185.0 189.0 18:21:58 INFO - PID 1643 | 18:21:58 INFO - PID 1643 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.80 Median:68.50 stddev:8.77 (12.8%) stddev-sans-first:9.30 18:21:58 INFO - PID 1643 | Values: 69.0 69.0 68.0 66.0 65.0 66.0 67.0 55.0 73.0 90.0 18:21:58 INFO - PID 1643 | -------- Summary: end -------- 18:21:58 INFO - PID 1643 | 18:21:59 INFO - TEST-INFO | 1643: exit 0 18:21:59 INFO - TEST-OK | kraken | took 61030ms 18:21:59 INFO - SUITE-END | took 1181s 18:21:59 INFO - Completed test suite (00:19:42) 18:21:59 INFO - javascript benchmark 18:22:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7829.131177830022, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3335.5163501292327, 3366.7979921204715, 3367.0908854609256, 3417.521546983422, 3420.640832712565, 5725.4847063764255, 5775.913361299584, 5883.764649414029, 5924.733386997585, 5938.287405511334, 5749.56878234132, 5810.709464526767, 5814.331351894533, 5816.9418305817035, 5823.592348535604, 5257.158854583267, 5267.341582302212, 5297.152455607104, 5333.120035194188, 5372.274742909696, 5402.162664786944, 5408.2698835657075, 5410.675359478424, 5411.540019098362, 5430.239766432703, 5417.106177480711, 5419.214213938976, 5434.8913021739545, 5439.456054394549, 5441.83674489766, 4500.842470513533, 5404.648697834628, 5431.34823821144, 5435.1847222916485, 5440.156775699773, 5236.947630523722, 5326.041312563737, 5345.946540534584, 5356.54469370104, 5368.1679339702405, 6031.758729650809, 6059.575829691923, 6063.090536419529, 6078.6960651967665, 6083.847903802441, 6225.533085018642, 6315.621062736251, 6324.209473815773, 6329.272133704637, 6340.175777148987, 6300.30696623371, 6318.526110541682, 6327.651979141146, 6328.936710632881, 6385.106085148083, 6080.665563394055, 6098.176746139259, 6099.359567245464, 6106.297775755845, 6143.262808462969, 6452.322506136882, 6499.837504062436, 6502.512311576651, 6505.024246363083, 6512.6092434454085, 6153.292371377349, 6213.65824879636, 6222.688865556704, 6245.75016999324, 6315.621062736251, 6301.40136687014, 6365.777197798147, 6393.6163830170335, 6402.423781859562, 6403.327650596713, 6283.151774510383, 6323.715432805535, 6325.588836725598, 6329.525285603599, 6358.395952384516, 6164.167837341902, 6271.811845644638, 6320.810375688737, 6333.334999825044, 6338.429541341301, 4305.978470107692, 5081.415637201695, 5107.540321371094, 5159.690418574897, 5165.845024649267, 4559.54404559548, 4560.452745670541, 4575.405197324327, 4576.702514336557, 4600.102979918884, 5613.607047506635, 5773.470030441937, 5967.16459695643, 6011.849703757441, 6050.394960503914, 6234.2830574483605, 6297.842553936188, 6299.622022678654, 6314.1160237565855, 6366.872662546723, 5997.730102145414, 6012.909806352908, 6014.338422773491, 6020.307664618537, 6044.425779550936, 5630.8029218977135, 5640.266765320564, 5642.858928526737, 5644.887102258014, 5662.57530685192, 6233.532485063548, 6396.680165991775, 6546.18172728409, 6550.0, 6551.672416379257, 6282.780102696383, 6351.523635727246, 6388.39310265524, 6393.913034784191, 6402.167718196604, 5571.718504743851, 6489.1888513935755, 6506.089147519257, 6544.6727663615675, 6548.901766473602, 5699.774548472098, 6017.548683848817, 6255.343188965093, 6312.179416675803, 6348.111264422892, 5116.590672746097, 6250.718717657625, 6296.779612713533, 6306.180196574416, 6365.427111559982, 6089.177960975212, 6093.728924480204, 6248.937510625017, 6280.340564240689, 6281.59169653971, 6102.246889800232, 6155.815325540241, 6160.921838678303, 6179.660118693515, 6252.687365631609, 6156.938430615636, 6175.0, 6189.96905015472, 6196.318404975539, 6199.504039676905, 6105.854009884935, 6186.690665466619, 6189.133521307111, 6209.782657606962, 6294.4964402846745, 6032.54755893301, 6090.3605121461605, 6092.939070609237, 6098.26820781509, 6099.695015249308], "value": 5831.74859709938, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [647.4690753582063, 731.8024133483959, 794.1740589786621, 855.4396870050119, 865.5282870835395, 28580.0, 37015.444768328496, 37448.81275593625, 37545.0, 37591.624083759154, 39914.800425997826, 40319.59680403195, 40385.798071009674, 40405.79797101018, 40539.39190912131, 7143.178534468525, 8946.68686595971, 9157.542122893861, 9173.128552787475, 9173.633054677839, 7307.707691692326, 10601.84097238542, 10835.620753273637, 10852.620158294461, 10859.565617375296, 10917.836232456519, 10987.670369888876, 10997.505112269928, 10999.780004399907, 11151.0, 7176.928230717678, 10986.285891417072, 11094.833577496343, 11113.11095112389, 11136.610218642349, 7288.416926645856, 10424.739381515446, 10622.734431639194, 10647.254692171551, 10672.30630009047, 30906.0728178155, 37906.05234869123, 38293.61706382928, 38663.0, 38712.80643596792, 3732.6267373262726, 3734.439834024891, 3784.4323351497496, 3805.5243094613174, 3927.6820400438232, 22818.97314620846, 26820.4787102026, 28245.85877070622, 29547.409051819057, 29648.407031859457, 3114.2837147455984, 3126.2965832687464, 3146.465100932847, 3159.2417819723332, 3250.2849373137874, 340349.0, 342860.9999999975, 343568.0, 344660.0, 345647.0, 52021.0, 52023.2196517045, 52110.73944630253, 52150.0887433444, 52310.95378092417, 54243.72878135584, 54379.912401751746, 54442.18336724952, 54474.18288725672, 54545.90908181814, 14768.926155369154, 14903.925480372529, 14906.0, 14906.478273260384, 14916.477923272634, 8807.779805504786, 10428.634997775041, 11942.880571194177, 12150.392480375946, 12168.513259469522, 11301.378424186749, 12528.373581320897, 12744.80882786759, 12768.233905965672, 12769.808452873214, 4427.867163985085, 5050.823221187241, 5301.734913254322, 5360.222767698662, 5363.249145119687, 1111.3220935229504, 1124.6344937895217, 1140.3692719411322, 1150.5627861412609, 1164.1443538999, 21764.347069587937, 24837.290242386574, 26075.087371941892, 26348.20955371342, 26670.799814008416, 1078.5793540519203, 1222.6882145052955, 1236.1779416687982, 1279.8464184297757, 1280.3726174174587, 13407.865921340857, 13764.07302977584, 13778.517751878637, 13842.0, 13854.16874987504, 361219.3878061238, 432182.0, 433051.0, 433771.0, 434082.82958585006, 55494.16758748622, 55506.0, 55606.44393556013, 55670.44329556733, 55888.720556396955, 1382.5644921849585, 1421.2396367943309, 1423.750843602386, 1434.5409468970038, 1451.3831621560794, 21191.8940405297, 24884.751152488967, 25929.48141037131, 25982.740172598787, 26011.60982585225, 46413.3038004437, 46415.0, 46432.0, 46445.6066318011, 46851.53148468608, 412720.0, 414385.92807036976, 414749.0, 415400.0, 415583.61040974216, 32822.01533953985, 33501.0, 33854.492182616785, 33894.83052584721, 33968.830155849064, 33487.0, 40601.0, 43413.0, 43480.91297717582, 45616.54383456123, 33147.09508092401, 33729.494057588665, 34804.82597586996, 37994.0501487465, 39321.60678393179, 36892.63107368892, 37316.440253395675, 37495.43756843595, 38430.80784596059, 38485.42271865868, 37187.88436346914, 38948.80525597354, 39098.2180356397, 39130.0, 39245.0, 29472.0, 31318.79625847983, 33010.41729150486, 33227.50158747669, 33596.0, 21930.342089737333, 23933.760662393848, 24309.878450607634, 24910.12814551482, 25264.747352526974], "value": 18679.188171821646, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [397.71960767658805, 398.9561148273691, 409.78895868627654, 414.9377593360996, 425.63395479887294, 20711.06800193991, 21153.471163220926, 21568.676469852944, 21627.891860540676, 22066.117355305807, 21225.36323910281, 21951.341459756233, 22059.6691049634, 22143.0, 22213.444663883412, 6716.294789047153, 7006.255407596002, 7074.85850282994, 7097.148342198943, 7103.218645948968, 6581.210254769434, 6868.450523958097, 6884.483663725241, 6904.654767261617, 6912.066870972405, 6901.896471552931, 6913.516053876231, 7438.442116841232, 7782.377409807202, 7865.606719664022, 7428.702851885918, 7586.772396828049, 7788.14330423653, 7858.646360913773, 7860.606969651494, 6281.403266689657, 7432.108147022338, 7440.2931721486375, 7456.3289303962365, 7465.962670186615, 26664.333391665168, 27186.0, 27880.860595696893, 28920.855395723098, 29041.0, 2732.401545096121, 2763.6130941668184, 2777.097443330905, 2787.5539913613725, 2797.7062408447023, 15578.610534736608, 16151.950123241952, 16374.426895058736, 16685.749713754303, 17390.739138912926, 1976.2786582897138, 1985.1463870535663, 1993.8504612154147, 2002.9499262518407, 2010.8620245386533, 130503.0, 131514.0, 146138.26930865383, 154034.22982885127, 157218.0, 17219.913900430667, 21238.46903827417, 21267.93660316978, 21490.92545372725, 21567.14336931851, 36346.8182659085, 36458.27083458316, 36506.0, 36519.26961460756, 36622.45066324007, 10315.0, 10414.479276036167, 10623.415712135753, 10680.412577308323, 10792.46037698112, 7129.536580122275, 8548.786280343042, 8568.143185681507, 8598.87101693475, 8780.824383512294, 10456.424896630606, 10514.947425262824, 10548.578056877639, 10721.070207362594, 10721.517531711092, 3454.5854497460464, 3545.9290814183573, 3550.822458877046, 3563.058133317348, 3567.126054116706, 1030.1398332392537, 1040.2302296300834, 1048.2127971808202, 1052.7894421115654, 1057.6836727566604, 22367.66448503274, 22611.773882261296, 22694.773052269265, 22696.205632803103, 22712.886435567718, 1035.994820025895, 1054.6308791922766, 1083.1551389915878, 1085.115630760943, 1085.7122862441458, 11064.446777661085, 11129.888701113046, 11975.46110425033, 12007.03943684503, 12155.817662735066, 537308.0, 627378.0, 656464.0, 667522.0, 688239.0, 146761.5323846748, 146782.0, 154241.0, 156541.43458565496, 157774.0, 1167.6905620010705, 1194.6416075177412, 1224.2960297828783, 1273.5733529267613, 1294.8705129487164, 19518.512037199183, 20289.0, 20682.793172068086, 20827.062782174537, 20921.0, 48579.0, 60134.0, 62932.68533657303, 63956.72086558336, 65896.68206635937, 75377.0, 80598.59700701458, 92532.53733731288, 94460.52769736107, 94877.83294085863, 11955.282683039046, 12311.69220769488, 12433.813492797435, 12441.564545240873, 12554.0, 13129.277889716157, 13340.066195366619, 13573.59279221625, 13662.590122296348, 14146.080504767157, 9733.756656083655, 10781.083607893417, 11398.886011140114, 11641.185117041725, 12072.154949153475, 8855.51294678773, 9758.902410976083, 11641.0, 11740.530378784753, 12008.0, 9918.40489570628, 11683.941580292045, 11801.881981180079, 12093.637190884288, 12101.0, 12686.682832928882, 12800.206936346998, 12822.551210707581, 13051.804222936473, 13191.538296159588, 9412.34113612068, 10142.442165680959, 11579.768404631692, 11745.412729363668, 12109.455074521844], "value": 11832.655031570828, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1324.9536266230684, 1542.4524293875675, 1568.435363269223, 1574.4961612284067, 1635.0, 9141.588628511716, 9587.904120958789, 10407.375557466557, 10479.633212837552, 10758.892411075907, 10406.687799366013, 10514.264001519896, 10514.421706806143, 10531.26281160319, 10536.0, 1749.5276275405633, 2236.351458077164, 2270.761570035147, 2309.6535519672016, 2318.9304320870315, 1940.9417717468498, 2004.859659823813, 2017.4452025692979, 2022.5247066939257, 2272.3410211038777, 2243.9663405048937, 2292.449812045114, 2294.105298933417, 2297.9659153380962, 2299.7700229977036, 2093.791050441783, 2133.114757375687, 2259.5142044460435, 2286.8627882327114, 2291.049214575949, 2208.7791220877943, 2251.0, 2251.6059689554263, 2264.083046366224, 2297.7357603875603, 10494.737631559196, 10617.946910265398, 10618.362898226054, 10838.783224335548, 10841.0, 5635.21107045014, 5826.47561719443, 5853.326867410217, 5887.587868849182, 5892.263467066617, 2912.6796052434215, 3552.307300076486, 3577.141486043331, 3600.153963818527, 3617.7829330240006, 1178.2282604893724, 1179.1863614106326, 1215.9148859579832, 1232.3530146672979, 1237.641084085614, 15283.006604570668, 15333.31000104998, 15364.692706145926, 15367.615809604738, 15381.23093845292, 4612.747389745982, 4709.882252943636, 4812.614990800727, 4824.975875120672, 4915.606751459874, 12370.567030154081, 12391.0, 12393.814092788616, 12398.194117382342, 12425.005999520017, 4905.583025442806, 5784.045632470596, 5799.88400231993, 5808.361080281165, 5816.302043754777, 4988.625853061035, 5030.547250747451, 5134.2298655201275, 5183.740812959337, 5251.422343542207, 3445.7415693823064, 3519.859205631746, 3519.894403167909, 3531.0642679689904, 3541.8406171722336, 945.2390825385493, 947.2232769129386, 961.6682244625733, 1020.4132623741378, 1032.4012072997643, 722.9408915938176, 751.5941391648448, 778.1362687416963, 780.2509590792789, 786.4376970466144, 6276.089966954766, 6327.620342779448, 6549.672516374162, 6571.737130514726, 6588.670566471657, 759.3393747439762, 964.5370222293338, 974.2692980264801, 998.6105418886494, 1025.9538320775434, 4021.939670904939, 4024.0744628735556, 4064.288749468831, 4168.249715051259, 4171.395147703566, 11198.496067676973, 11376.260543064674, 11625.53497860093, 11646.70883227926, 11670.70823229426, 11412.714682133013, 11664.358460284766, 11667.766644667228, 11683.707907302385, 11688.824667629993, 9442.811143777224, 9783.119519243302, 9878.308518403643, 9933.453660048623, 9938.45338506389, 5286.682799032071, 6185.474234690102, 6439.130717353098, 6449.258335291502, 6458.418742313214, 11717.88282117168, 11916.0, 12122.333271670142, 12140.93929530382, 12184.268943863397, 11682.70793230176, 12084.093692972887, 12216.205946613798, 12259.938700306442, 12264.325462099314, 8008.719694810654, 8277.793055173668, 8282.627281772455, 8293.548628990024, 8301.418900676894, 9900.702978910644, 9903.059209375098, 9953.601855925683, 9986.101250887456, 10022.298439109192, 8720.563971801512, 8864.867026994729, 8901.198892099743, 9143.0, 9153.038930912424, 8502.14978502145, 8627.137286271323, 8668.0, 8726.956365218133, 8740.956295218482, 7285.344319011102, 7631.275028872248, 7776.066871975173, 7776.377889768692, 7800.531968081933, 4716.434027916672, 5392.676439413648, 5588.6646801192055, 5612.158176273591, 5914.67469289176, 5746.370529798045, 5839.5036421904615, 5885.676287804212, 5961.344252132175, 6029.1559181715475], "value": 4894.324950496189, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [378.31336124933244, 384.15294276121153, 386.86653104678874, 387.68791123145854, 390.0910877655063, 5701.232617888458, 5730.770769169233, 5762.798302059428, 5780.739866705998, 5800.883982320351, 4951.480094590071, 5410.053240682884, 5490.862728431781, 5560.916586251209, 5581.0, 4440.424553758812, 4442.289233722605, 4443.666724995622, 4447.3773671685985, 4450.421445212126, 4453.977730111361, 4459.955400445986, 4462.1968045751755, 4466.397036400093, 4468.106378724252, 3840.615938406165, 4218.072024154696, 4299.732203525327, 4404.69167158299, 4426.513083560806, 3596.532450781395, 4405.977970110129, 4430.933535996963, 4435.2238358287495, 4464.196444639963, 3379.6282408935, 3868.916703323074, 3881.514810648669, 3906.687465002793, 3911.5697273300198, 5409.864753381157, 5411.594130440233, 5538.9169162462595, 5601.187827764992, 5642.746076426571, 5068.366454193226, 5142.228665700175, 5166.3800343958965, 5178.766955486974, 5187.3775146982225, 4988.476209997934, 5893.057110862242, 6075.787347442818, 6079.39206079393, 6104.96947515264, 3208.0375887233968, 4221.197972385237, 4313.223619748444, 4347.043650396908, 4372.404072513542, 5470.480304371118, 6205.658688772116, 6285.585743207742, 6408.839779005515, 6428.389303016206, 4356.978215108904, 4586.922073312769, 5299.6290259681455, 5309.522143007071, 5323.1749078892835, 4818.494058123917, 5201.713905735221, 5388.838334849961, 5572.442755724476, 5641.323041235078, 5793.899159159746, 5821.825345239649, 5843.328017277982, 5845.473907348359, 5858.355580886099, 5541.778328866881, 5882.3529411764675, 6244.65654389013, 6258.280297765724, 6297.2128483939505, 1612.5887898585786, 1805.6388722255601, 1838.6322735452964, 1855.2486243071578, 1962.3328068456508, 1505.8268299145739, 1529.2048134969752, 1545.289166983178, 1552.270432896537, 1562.8671562917052, 4864.610831133501, 5765.365809760923, 5766.711664416763, 5779.075347944393, 5802.3037235531165, 5506.366767821752, 6003.009503431972, 6401.487880969511, 6422.389872962154, 6429.453496452854, 4639.652026098056, 5052.8484145475695, 5346.278252435949, 5406.3775471971685, 5791.652500849962, 3336.866525338959, 3360.77365728903, 3380.594328680574, 3382.255903701182, 3389.5933886940547, 5698.4495543405155, 6077.4226448487325, 6314.789823561717, 6647.401733843977, 6720.764773232913, 6627.608202277575, 6647.6676166192465, 6656.70044847983, 6681.0, 6706.966465167643, 6720.596764194164, 6724.327567243236, 6730.226024007204, 6740.629265390353, 6807.795766127024, 5374.623776335776, 6143.662098584621, 6425.775097871552, 6440.0, 6509.772157974448, 6399.776007839703, 6405.263394709668, 6419.8395040122505, 6420.775272865428, 6452.451541619015, 6427.517936154901, 6640.701168447335, 6704.497162712913, 6722.563033402813, 6762.357576030269, 4364.716293440916, 4954.479779623088, 5015.448300686994, 5047.520485553867, 5054.0, 5331.200319952039, 6000.669963152069, 6286.874262514815, 6291.465225455888, 6553.934460655332, 6216.844578885564, 6237.1891654084675, 6291.150694656163, 6294.590851594632, 6306.211723534559, 6201.565890387629, 6272.121902933684, 6285.52290211809, 6288.151099601495, 6313.810585682437, 5857.384974577608, 6067.757289708362, 6098.176746139215, 6127.314988378232, 6136.20229370179, 5212.931349073355, 5444.373897001896, 6253.906191407042, 6282.465990390868, 6313.463355614824, 5116.334876466036, 5790.507806836297, 6076.118962750465, 6117.449429551361, 6123.632582045091], "value": 4730.183327130885, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [631.6557476175484, 694.6769752065288, 728.8396552758395, 834.236673443799, 876.3909083187185, 14948.252587370629, 15262.694746105071, 15582.766258506132, 15767.290471928762, 15783.447579334712, 14616.269186540658, 14762.852371476281, 14782.704345913075, 14860.034097783664, 14984.250787460638, 911.2664305234284, 946.7538440005579, 958.6500927161605, 962.7544976031103, 963.4556475591269, 909.154486327715, 952.7951490429556, 958.6932181701858, 959.8320293948566, 963.5663951221943, 1004.9748756281078, 1006.0593345222204, 1007.3200589602026, 1008.1279693065519, 1008.5814387029411, 996.2776986684668, 999.3853779925294, 1000.229823036258, 1002.478711070243, 1002.9097381235724, 742.6546655805023, 743.4647054121024, 745.1616930952679, 745.2361329637112, 745.9515131516434, 12079.939600301943, 15088.54734357971, 15554.0, 15611.375544978187, 15734.685306293924, 12379.566715165016, 14477.203753793538, 14794.0, 14829.06600609071, 14841.332140053724, 11599.362035088066, 11687.824682629767, 11702.765944681143, 11793.0, 12048.397580120958, 3152.621685397744, 3732.533433320835, 3740.551133863954, 3879.1853710720784, 3912.315344814646, 112330.43834780774, 112405.31392029126, 113957.0, 115310.0, 116275.83724162735, 10673.199510036779, 11015.449227538591, 11123.0, 11593.304401735923, 11846.526138954345, 23871.283861484182, 24233.63649545258, 24237.0, 24370.390740231636, 24413.26760197197, 6295.716692748837, 7881.802954926173, 7965.084015338311, 8042.879356809653, 8180.67277308901, 4232.0852963054795, 4279.2083464559155, 4293.141371725667, 4301.0, 4404.713693609905, 3417.79493230407, 3495.1611613212644, 3517.8592856285463, 3525.6121826599056, 3526.0655926179584, 576.4667682393768, 616.4698359410903, 618.0482057631287, 632.1055706175802, 633.6293268437923, 642.8874946884276, 669.5346734019906, 684.1311534351346, 699.4124935054477, 717.3830505765038, 7446.171652066261, 9569.563402953057, 9953.0, 10366.533505992249, 10522.368657880552, 632.5824955529329, 687.8108520156997, 704.1936982155506, 709.3544874164588, 719.0041792117954, 4849.781759820817, 4909.361782968192, 4910.779014944336, 4915.8033678652455, 4917.557419832232, 105408.0, 108514.0, 109099.90900090897, 111650.0, 113923.0, 95160.52419737996, 95493.56759648611, 95511.0, 96082.0, 96929.0, 81189.18810812052, 83072.16927830645, 83334.0, 83861.0, 85898.0, 8676.826463470568, 9116.444586611095, 10318.535665895202, 10934.234603577992, 10973.83539246896, 43885.0, 46274.768626156656, 47730.32943846949, 48951.75524122499, 50179.0, 63654.68172659107, 70260.29739702742, 71304.0, 71592.0, 73132.63433682798, 12894.355282236038, 13213.405396756976, 13469.461221551028, 13507.459701611826, 13746.587602371945, 14410.495632652806, 14582.416703332172, 14611.844767886218, 14748.852511475177, 14779.0, 13663.453461861414, 13746.518871839438, 13778.793318100437, 13886.444542218602, 14247.572572822832, 13477.865221347662, 13558.0, 13691.657708557366, 13742.0, 14140.292985350898, 12948.935255323662, 12984.0, 13214.603561893158, 13316.866831331563, 13421.463141474233, 10775.736004877795, 10869.239153259196, 11047.171462140532, 11200.38397888124, 11373.54505819758, 8681.739547813575, 10874.456277186267, 11261.549538018715, 11289.830652540055, 11368.54525818958], "value": 7716.728835153486, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1480.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 114.0, 116.0, 114.0, 115.0, 116.0, 115.0, 115.0, 116.0, 115.0], "value": 115.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 113.0, 113.0, 152.0, 114.0, 113.0, 111.0, 112.0, 139.0, 124.0], "value": 124.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 181.0, 186.0, 184.0, 184.0, 182.0, 183.0, 183.0, 187.0, 184.0], "value": 184.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 76.0, 88.0, 120.0, 60.0, 58.0, 59.0, 59.0, 74.0, 58.0], "value": 73.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 67.0, 83.0, 70.0, 70.0, 70.0, 85.0, 70.0, 68.0, 84.0], "value": 75.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 88.0, 88.0, 87.0, 86.0, 88.0, 86.0, 89.0, 87.0], "value": 87.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 144.0, 101.0, 94.0, 94.0, 93.0, 95.0, 94.0, 95.0, 94.0], "value": 103.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 115.0, 130.0, 86.0, 83.0, 84.0, 84.0, 83.0, 84.0, 84.0], "value": 91.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 64.0, 62.0, 65.0, 63.0, 63.0, 63.0, 61.0, 61.0], "value": 62.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 63.0, 68.0, 63.0, 61.0, 57.0, 58.0, 60.0, 59.0, 58.0], "value": 60.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 81.0, 84.0, 97.0, 106.0, 74.0, 93.0, 95.0, 73.0], "value": 86.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 173.0, 144.0, 154.0, 147.0, 136.0, 150.0, 145.0, 245.0, 183.0], "value": 161.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 183.0, 188.0, 189.0, 185.0, 183.0, 187.0, 185.0, 185.0, 189.0], "value": 186.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 68.0, 66.0, 65.0, 66.0, 67.0, 55.0, 73.0, 90.0], "value": 68.8, "unit": "score"}]}]} 18:22:00 INFO - Return code: 0 18:22:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:22:00 ERROR - # TBPL SUCCESS # 18:22:00 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:22:00 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:22:00 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:22:00 INFO - 2017-03-14 18:22:00.132 screenresolution[1647:9719] starting screenresolution argv=screenresolution get 18:22:00 INFO - 2017-03-14 18:22:00.134 screenresolution[1647:9719] Display 0: 1600x1200x32@60 18:22:00 INFO - 2017-03-14 18:22:00.141 screenresolution[1648:9722] starting screenresolution argv=screenresolution list 18:22:00 INFO - Available Modes on Display 0 18:22:00 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:22:00 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:22:00 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:22:00 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:22:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:22:00 INFO - Intel Iris: 18:22:00 INFO - Chipset Model: Intel Iris 18:22:00 INFO - Type: GPU 18:22:00 INFO - Bus: Built-In 18:22:00 INFO - VRAM (Dynamic, Max): 1536 MB 18:22:00 INFO - Vendor: Intel (0x8086) 18:22:00 INFO - Device ID: 0x0a2e 18:22:00 INFO - Revision ID: 0x0009 18:22:00 INFO - Displays: 18:22:00 INFO - Display: 18:22:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:22:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:22:00 INFO - Display Serial Number: 1600x1200 60 18:22:00 INFO - Main Display: Yes 18:22:00 INFO - Mirror: Off 18:22:00 INFO - Online: Yes 18:22:00 INFO - Rotation: Supported 18:22:00 INFO - Return code: 0 18:22:00 INFO - Running post-action listener: _resource_record_post_action 18:22:00 INFO - [mozharness: 2017-03-15 01:22:00.378387Z] Finished run-tests step (success) 18:22:00 INFO - Running post-run listener: _resource_record_post_run 18:22:00 INFO - Total resource usage - Wall time: 1203s; CPU: 53.0%; Read bytes: 36176896; Write bytes: 509539840; Read time: 239; Write time: 1564 18:22:00 INFO - TinderboxPrint: CPU usage
53.4% 18:22:00 INFO - TinderboxPrint: I/O read bytes / time
36,176,896 / 239 18:22:00 INFO - TinderboxPrint: I/O write bytes / time
509,539,840 / 1,564 18:22:00 INFO - TinderboxPrint: CPU idle
2,242.7 (46.6%) 18:22:00 INFO - TinderboxPrint: CPU system
960.7 (20.0%) 18:22:00 INFO - TinderboxPrint: CPU user
1,610.3 (33.5%) 18:22:00 INFO - TinderboxPrint: Swap in / out
246,255,616 / 0 18:22:00 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 203700736; Write bytes: 202782208; Read time: 15503; Write time: 413 18:22:00 INFO - run-tests - Wall time: 1183s; CPU: 53.0%; Read bytes: 35210240; Write bytes: 296833024; Read time: 224; Write time: 1116 18:22:00 INFO - Running post-run listener: _upload_blobber_files 18:22:00 INFO - Blob upload gear active. 18:22:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:22:00 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 18:22:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:22:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:22:01 INFO - (blobuploader) - INFO - Open directory for files ... 18:22:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:22:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:22:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:22:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:22:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:22:02 INFO - (blobuploader) - INFO - Done attempting. 18:22:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:22:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:22:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:22:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:22:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:22:04 INFO - (blobuploader) - INFO - Done attempting. 18:22:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:22:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:22:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:22:05 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:22:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:22:05 INFO - (blobuploader) - INFO - Done attempting. 18:22:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:22:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:22:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:22:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:22:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:22:07 INFO - (blobuploader) - INFO - Done attempting. 18:22:07 INFO - (blobuploader) - INFO - Iteration through files over. 18:22:07 INFO - Return code: 0 18:22:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:22:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:22:07 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8809997951c51562bd9db2342c602cd2a57db541fa8482e4f811d509042b278b034c0352ea602b8be68179f216d5b20f628a2245bdf7eb16e9ffd929bfd584e5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9fbe797a697b39ba9d08191835bd9d759ee9e5533161114b984456c0c74ab015c09af854cda0c57bb6b48e6f0175e55a935a50ef0d58c1feff3b00a687c7fd0c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3d915de3cf4ecc53e55474c138df0d0fc553e3ca55a2e1f13f5459bbd10ac35a0e7cae46ed1df87339b3d41918f0b207dd85995f90c16d96fbe053f610287ae0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7dc5a5e6e1cc997ccd01c490fcd0b756a109b1112a5cafe5772839e2c6ac793bfce5b0608237f026a5704ee0c1d70b66da45246b7e40af15abedb919af25fece"} 18:22:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:22:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:22:07 INFO - Contents: 18:22:07 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8809997951c51562bd9db2342c602cd2a57db541fa8482e4f811d509042b278b034c0352ea602b8be68179f216d5b20f628a2245bdf7eb16e9ffd929bfd584e5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9fbe797a697b39ba9d08191835bd9d759ee9e5533161114b984456c0c74ab015c09af854cda0c57bb6b48e6f0175e55a935a50ef0d58c1feff3b00a687c7fd0c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3d915de3cf4ecc53e55474c138df0d0fc553e3ca55a2e1f13f5459bbd10ac35a0e7cae46ed1df87339b3d41918f0b207dd85995f90c16d96fbe053f610287ae0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7dc5a5e6e1cc997ccd01c490fcd0b756a109b1112a5cafe5772839e2c6ac793bfce5b0608237f026a5704ee0c1d70b66da45246b7e40af15abedb919af25fece"} 18:22:07 INFO - Running post-run listener: copy_logs_to_upload_dir 18:22:07 INFO - Copying logs to upload dir... 18:22:07 INFO - mkdir: /builds/slave/test/build/upload/logs 18:22:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1288.334546 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 28 secs) (at 2017-03-14 18:22:07.407656) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-14 18:22:07.410922) ========= 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.SRkF2aiXvJ/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.NpQIcpsTww/Listeners TMPDIR=/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8809997951c51562bd9db2342c602cd2a57db541fa8482e4f811d509042b278b034c0352ea602b8be68179f216d5b20f628a2245bdf7eb16e9ffd929bfd584e5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9fbe797a697b39ba9d08191835bd9d759ee9e5533161114b984456c0c74ab015c09af854cda0c57bb6b48e6f0175e55a935a50ef0d58c1feff3b00a687c7fd0c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3d915de3cf4ecc53e55474c138df0d0fc553e3ca55a2e1f13f5459bbd10ac35a0e7cae46ed1df87339b3d41918f0b207dd85995f90c16d96fbe053f610287ae0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7dc5a5e6e1cc997ccd01c490fcd0b756a109b1112a5cafe5772839e2c6ac793bfce5b0608237f026a5704ee0c1d70b66da45246b7e40af15abedb919af25fece"} build_url:https://queue.taskcluster.net/v1/task/eUbnioplRtahrrdxjtWT1Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011139 build_url: 'https://queue.taskcluster.net/v1/task/eUbnioplRtahrrdxjtWT1Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8809997951c51562bd9db2342c602cd2a57db541fa8482e4f811d509042b278b034c0352ea602b8be68179f216d5b20f628a2245bdf7eb16e9ffd929bfd584e5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9fbe797a697b39ba9d08191835bd9d759ee9e5533161114b984456c0c74ab015c09af854cda0c57bb6b48e6f0175e55a935a50ef0d58c1feff3b00a687c7fd0c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3d915de3cf4ecc53e55474c138df0d0fc553e3ca55a2e1f13f5459bbd10ac35a0e7cae46ed1df87339b3d41918f0b207dd85995f90c16d96fbe053f610287ae0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7dc5a5e6e1cc997ccd01c490fcd0b756a109b1112a5cafe5772839e2c6ac793bfce5b0608237f026a5704ee0c1d70b66da45246b7e40af15abedb919af25fece"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-14 18:22:07.446220) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-14 18:22:07.446555) ========= 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.SRkF2aiXvJ/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.NpQIcpsTww/Listeners TMPDIR=/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/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.005269 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-14 18:22:07.495299) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-14 18:22:07.495774) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-14 18:22:07.496185) ========= ========= Total master_lag: 0.18 =========