builder: pine_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0015 starttime: 1489716227.98 results: success (0) buildid: 20170316163021 builduid: 9e5211f87e174f66a2b222f5a21fd611 revision: f4b9ef69bf348264ad740774295339e360e5a791 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-16 19:03:47.977538) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-16 19:03:47.977992) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-16 19:03:47.978269) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-16 19:03:48.005905) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-16 19:03:48.006242) ========= 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.RN4ttVBSyy/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.Rv6TYCZItC/Listeners TMPDIR=/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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-16 19:03:48-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 166M=0s 2017-03-16 19:03:48 (166 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.100986 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-16 19:03:48.131154) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-16 19:03:48.131486) ========= 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.RN4ttVBSyy/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.Rv6TYCZItC/Listeners TMPDIR=/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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.026307 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-16 19:03:48.179700) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-16 19:03:48.180076) ========= bash -c 'python archiver_client.py mozharness --repo projects/pine --rev f4b9ef69bf348264ad740774295339e360e5a791 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/pine --rev f4b9ef69bf348264ad740774295339e360e5a791 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.RN4ttVBSyy/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.Rv6TYCZItC/Listeners TMPDIR=/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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-16 19:03:48,281 truncating revision to first 12 chars 2017-03-16 19:03:48,281 Setting DEBUG logging. 2017-03-16 19:03:48,281 attempt 1/10 2017-03-16 19:03:48,282 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/pine/f4b9ef69bf34?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-16 19:03:49,150 unpacking tar archive at: pine-f4b9ef69bf34/testing/mozharness/ program finished with exit code 0 elapsedTime=1.132352 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-16 19:03:49.337972) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-16 19:03:49.338762) ========= script_repo_revision: f4b9ef69bf348264ad740774295339e360e5a791 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-16 19:03:49.339731) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-16 19:03:49.340449) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-16 19:03:49.359650) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-03-16 19:03:49.360333) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Pine', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Pine'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.RN4ttVBSyy/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.Rv6TYCZItC/Listeners TMPDIR=/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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 19:03:49 INFO - MultiFileLogger online at 20170316 19:03:49 in /builds/slave/test 19:03:49 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine 19:03:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:03:49 INFO - {'append_to_log': False, 19:03:49 INFO - 'base_work_dir': '/builds/slave/test', 19:03:49 INFO - 'blob_upload_branch': 'Pine', 19:03:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:03:49 INFO - 'branch': 'Pine', 19:03:49 INFO - 'buildbot_json_path': 'buildprops.json', 19:03:49 INFO - 'config_files': ('talos/mac_config.py',), 19:03:49 INFO - 'default_actions': ('clobber', 19:03:49 INFO - 'read-buildbot-config', 19:03:49 INFO - 'download-and-extract', 19:03:49 INFO - 'populate-webroot', 19:03:49 INFO - 'create-virtualenv', 19:03:49 INFO - 'install', 19:03:49 INFO - 'run-tests'), 19:03:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:03:49 INFO - 'download_minidump_stackwalk': True, 19:03:49 INFO - 'download_symbols': 'ondemand', 19:03:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:03:49 INFO - 'tooltool.py': '/tools/tooltool.py', 19:03:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:03:49 INFO - '/tools/misc-python/virtualenv.py')}, 19:03:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:03:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:03:49 INFO - 'gecko_profile': False, 19:03:49 INFO - 'gecko_profile_interval': 0, 19:03:49 INFO - 'installer_path': 'installer.exe', 19:03:49 INFO - 'log_level': 'info', 19:03:49 INFO - 'log_name': 'talos', 19:03:49 INFO - 'log_to_console': True, 19:03:49 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:03:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:03:49 INFO - 'opt_config_files': (), 19:03:49 INFO - 'pip_index': False, 19:03:49 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:03:49 INFO - 'cmd': ('bash', 19:03:49 INFO - '-c', 19:03:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:03:49 INFO - 'enabled': True, 19:03:49 INFO - 'halt_on_failure': False, 19:03:49 INFO - 'name': 'check_screen_resolution'},), 19:03:49 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:03:49 INFO - 'cmd': ('bash', 19:03:49 INFO - '-c', 19:03:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:03:49 INFO - 'enabled': True, 19:03:49 INFO - 'halt_on_failure': False, 19:03:49 INFO - 'name': 'check_screen_resolution'},), 19:03:49 INFO - 'run_cmd_checks_enabled': True, 19:03:49 INFO - 'suite': 'dromaeojs-e10s', 19:03:49 INFO - 'system_bits': '32', 19:03:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:03:49 INFO - 'title': 't-yosemite-r7-0015', 19:03:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:03:49 INFO - 'use_talos_json': True, 19:03:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:03:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:03:49 INFO - 'work_dir': 'build'} 19:03:49 INFO - [mozharness: 2017-03-17 02:03:49.564552Z] Running clobber step. 19:03:49 INFO - Running pre-action listener: _resource_record_pre_action 19:03:49 INFO - Running main action method: clobber 19:03:49 INFO - rmtree: /builds/slave/test/build 19:03:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:03:51 INFO - Running post-action listener: _resource_record_post_action 19:03:51 INFO - [mozharness: 2017-03-17 02:03:51.950536Z] Finished clobber step (success) 19:03:51 INFO - [mozharness: 2017-03-17 02:03:51.950629Z] Running read-buildbot-config step. 19:03:51 INFO - Running pre-action listener: _resource_record_pre_action 19:03:51 INFO - Running main action method: read_buildbot_config 19:03:51 INFO - Using buildbot properties: 19:03:51 INFO - { 19:03:51 INFO - "project": "", 19:03:51 INFO - "product": "firefox", 19:03:51 INFO - "script_repo_revision": "default", 19:03:51 INFO - "builddir": "pine_yosemite_r7_test-dromaeojs-e10s", 19:03:51 INFO - "repository": "", 19:03:51 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 pine talos dromaeojs-e10s", 19:03:51 INFO - "buildid": "20170316163021", 19:03:51 INFO - "pgo_build": "False", 19:03:51 INFO - "basedir": "/builds/slave/test", 19:03:51 INFO - "buildnumber": 1, 19:03:51 INFO - "slavename": "t-yosemite-r7-0015", 19:03:51 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 19:03:51 INFO - "platform": "yosemite_r7", 19:03:51 INFO - "scheduler": "tests-pine-macosx64-talos", 19:03:51 INFO - "branch": "pine", 19:03:51 INFO - "repo_path": "projects/pine", 19:03:51 INFO - "revision": "f4b9ef69bf348264ad740774295339e360e5a791", 19:03:51 INFO - "stage_platform": "macosx64", 19:03:51 INFO - "builduid": "9e5211f87e174f66a2b222f5a21fd611", 19:03:51 INFO - "slavebuilddir": "test" 19:03:51 INFO - } 19:03:51 INFO - Found installer url https://queue.taskcluster.net/v1/task/P4cwgh7gTjeK4-T2WdY99g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 19:03:51 INFO - Running post-action listener: _resource_record_post_action 19:03:51 INFO - [mozharness: 2017-03-17 02:03:51.954040Z] Finished read-buildbot-config step (success) 19:03:51 INFO - [mozharness: 2017-03-17 02:03:51.954176Z] Running download-and-extract step. 19:03:51 INFO - Running pre-action listener: _resource_record_pre_action 19:03:51 INFO - Running main action method: download_and_extract 19:03:51 INFO - mkdir: /builds/slave/test/build/tests 19:03:51 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')]} 19:03:51 INFO - trying https://queue.taskcluster.net/v1/task/P4cwgh7gTjeK4-T2WdY99g/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 19:03:51 INFO - Downloading https://queue.taskcluster.net/v1/task/P4cwgh7gTjeK4-T2WdY99g/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 19:03:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/P4cwgh7gTjeK4-T2WdY99g/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 19:03:53 INFO - Downloaded 1325 bytes. 19:03:53 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 19:03:53 INFO - Using the following test package requirements: 19:03:53 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:03:53 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 19:03:53 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 19:03:53 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:03:53 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 19:03:53 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:03:53 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 19:03:53 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:03:53 INFO - u'jsshell-mac.zip'], 19:03:53 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:03:53 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 19:03:53 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 19:03:53 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:03:53 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 19:03:53 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:03:53 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 19:03:53 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:03:53 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 19:03:53 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:03:53 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 19:03:53 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 19:03:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/P4cwgh7gTjeK4-T2WdY99g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 19:03:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/P4cwgh7gTjeK4-T2WdY99g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:03:53 INFO - Fetch https://queue.taskcluster.net/v1/task/P4cwgh7gTjeK4-T2WdY99g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 19:03:55 INFO - Expected file size: 52475340 19:03:55 INFO - Obtained file size: 52475340 19:04:11 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 19:04:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/P4cwgh7gTjeK4-T2WdY99g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 19:04:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/P4cwgh7gTjeK4-T2WdY99g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:04:11 INFO - Fetch https://queue.taskcluster.net/v1/task/P4cwgh7gTjeK4-T2WdY99g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 19:04:13 INFO - Expected file size: 52475340 19:04:13 INFO - Obtained file size: 52475340 19:04:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/P4cwgh7gTjeK4-T2WdY99g/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 19:04:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/P4cwgh7gTjeK4-T2WdY99g/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 19:04:29 INFO - Fetch https://queue.taskcluster.net/v1/task/P4cwgh7gTjeK4-T2WdY99g/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 19:04:30 INFO - Expected file size: 14404452 19:04:30 INFO - Obtained file size: 14404452 19:04:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:04:30 INFO - trying https://queue.taskcluster.net/v1/task/P4cwgh7gTjeK4-T2WdY99g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 19:04:30 INFO - Downloading https://queue.taskcluster.net/v1/task/P4cwgh7gTjeK4-T2WdY99g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 19:04:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/P4cwgh7gTjeK4-T2WdY99g/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 19:04:32 INFO - Downloaded 75088583 bytes. 19:04:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/P4cwgh7gTjeK4-T2WdY99g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 19:04:32 INFO - mkdir: /builds/slave/test/properties 19:04:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:04:32 INFO - Writing to file /builds/slave/test/properties/build_url 19:04:32 INFO - Contents: 19:04:32 INFO - build_url:https://queue.taskcluster.net/v1/task/P4cwgh7gTjeK4-T2WdY99g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 19:04:33 INFO - Running post-action listener: _resource_record_post_action 19:04:33 INFO - Running post-action listener: set_extra_try_arguments 19:04:33 INFO - [mozharness: 2017-03-17 02:04:33.336750Z] Finished download-and-extract step (success) 19:04:33 INFO - [mozharness: 2017-03-17 02:04:33.336841Z] Running populate-webroot step. 19:04:33 INFO - Running pre-action listener: _resource_record_pre_action 19:04:33 INFO - Running main action method: populate_webroot 19:04:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:04:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:04:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:04:33 INFO - '--tppagecycles', 19:04:33 INFO - '7'], 19:04:33 INFO - 'tests': ['tsvgm']}}, 19:04:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:04:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:04:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:04:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:04:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:04:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:04:33 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 19:04:33 INFO - 'talos_options': ['--disable-e10s'], 19:04:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:04:33 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 19:04:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:04:33 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 19:04:33 INFO - 'talos_options': ['--disable-e10s'], 19:04:33 INFO - 'tests': ['damp', 'tps']}, 19:04:33 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 19:04:33 INFO - 'tests': ['damp', 'tps']}, 19:04:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:04:33 INFO - 'tests': ['dromaeo_dom']}, 19:04:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:04:33 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:04:33 INFO - 'tests': ['basic_compositor_video']}, 19:04:33 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:04:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:04:33 INFO - 'tests': ['a11yr', 19:04:33 INFO - 'ts_paint', 19:04:33 INFO - 'tpaint', 19:04:33 INFO - 'sessionrestore', 19:04:33 INFO - 'sessionrestore_no_auto_restore', 19:04:33 INFO - 'tabpaint']}, 19:04:33 INFO - 'other-e10s': {'tests': ['a11yr', 19:04:33 INFO - 'ts_paint', 19:04:33 INFO - 'tpaint', 19:04:33 INFO - 'sessionrestore', 19:04:33 INFO - 'sessionrestore_no_auto_restore', 19:04:33 INFO - 'tabpaint']}, 19:04:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:04:33 INFO - 'ts_paint', 19:04:33 INFO - 'tpaint', 19:04:33 INFO - 'sessionrestore', 19:04:33 INFO - 'sessionrestore_no_auto_restore', 19:04:33 INFO - 'tabpaint']}, 19:04:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:04:33 INFO - 'ts_paint', 19:04:33 INFO - 'tpaint', 19:04:33 INFO - 'sessionrestore', 19:04:33 INFO - 'sessionrestore_no_auto_restore', 19:04:33 INFO - 'tabpaint']}, 19:04:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:04:33 INFO - 'tests': ['a11yr', 19:04:33 INFO - 'ts_paint', 19:04:33 INFO - 'tpaint', 19:04:33 INFO - 'sessionrestore', 19:04:33 INFO - 'sessionrestore_no_auto_restore', 19:04:33 INFO - 'tabpaint']}, 19:04:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:04:33 INFO - 'tests': ['a11yr', 19:04:33 INFO - 'ts_paint', 19:04:33 INFO - 'tpaint', 19:04:33 INFO - 'sessionrestore', 19:04:33 INFO - 'sessionrestore_no_auto_restore', 19:04:33 INFO - 'tabpaint']}, 19:04:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:04:33 INFO - 'tests': ['tsvgx', 19:04:33 INFO - 'tsvgr_opacity', 19:04:33 INFO - 'tart', 19:04:33 INFO - 'tscrollx', 19:04:33 INFO - 'cart', 19:04:33 INFO - 'tsvg_static']}, 19:04:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:04:33 INFO - 'tsvgr_opacity', 19:04:33 INFO - 'tart', 19:04:33 INFO - 'tscrollx', 19:04:33 INFO - 'cart', 19:04:33 INFO - 'tsvg_static']}, 19:04:33 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 19:04:33 INFO - 'talos_options': ['--disable-e10s'], 19:04:33 INFO - 'tests': ['tp5o']}, 19:04:33 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 19:04:33 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 19:04:33 INFO - 'talos_options': ['--disable-e10s', 19:04:33 INFO - '--xperf_path', 19:04:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:04:33 INFO - 'tests': ['tp5n']}, 19:04:33 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 19:04:33 INFO - 'talos_options': ['--xperf_path', 19:04:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:04:33 INFO - 'tests': ['tp5n']}}, 19:04:33 INFO - 'talos.zip': {'path': '', 19:04:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:04:33 INFO - Running post-action listener: _resource_record_post_action 19:04:33 INFO - [mozharness: 2017-03-17 02:04:33.355097Z] Finished populate-webroot step (success) 19:04:33 INFO - [mozharness: 2017-03-17 02:04:33.355195Z] Running create-virtualenv step. 19:04:33 INFO - Running pre-action listener: _resource_record_pre_action 19:04:33 INFO - Running main action method: create_virtualenv 19:04:33 INFO - Creating virtualenv /builds/slave/test/build/venv 19:04:33 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 19:04:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:04:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:04:33 INFO - Using real prefix '/tools/python27' 19:04:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:04:34 INFO - Installing distribute.............................................................................................................................................................................................done. 19:04:37 INFO - Installing pip.................done. 19:04:37 INFO - Return code: 0 19:04:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:04:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:04:37 INFO - Reading from file tmpfile_stdout 19:04:37 INFO - Output received: 19:04:37 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) 19:04:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:04: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')]} 19:04:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:04:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:04: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')]} 19:04:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:04:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:04: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106d08b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d0b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f852b48ceb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ae6d30>, '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 0x106a0ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106adbbe8>, '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.RN4ttVBSyy/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.Rv6TYCZItC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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 19:04: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', 'pip>=1.5'] in /builds/slave/test/build 19:04: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 pip>=1.5 19:04:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RN4ttVBSyy/Render', 19:04:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:04:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:04:37 INFO - 'HOME': '/Users/cltbld', 19:04:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:04:37 INFO - 'LOGNAME': 'cltbld', 19:04:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:04:37 INFO - 'MOZ_NO_REMOTE': '1', 19:04:37 INFO - 'NO_EM_RESTART': '1', 19:04:37 INFO - 'PAGER': '/bin/cat', 19:04:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:04:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:04:37 INFO - 'PWD': '/builds/slave/test', 19:04:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:04:37 INFO - 'SHELL': '/bin/bash', 19:04:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Rv6TYCZItC/Listeners', 19:04:37 INFO - 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/', 19:04:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:04:37 INFO - 'USER': 'cltbld', 19:04:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:04:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:04:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:04:37 INFO - 'XPC_FLAGS': '0x0', 19:04:37 INFO - 'XPC_SERVICE_NAME': '0', 19:04:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:04:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:04:37 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 19:04:37 INFO - Cleaning up... 19:04:37 INFO - Return code: 0 19:04:37 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:04: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')]} 19:04:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:04:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:04: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')]} 19:04:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:04:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:04: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', '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 0x106d08b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d0b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f852b48ceb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ae6d30>, '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 0x106a0ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106adbbe8>, '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.RN4ttVBSyy/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.Rv6TYCZItC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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 19:04: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', 'psutil>=3.1.1'] in /builds/slave/test/build 19:04: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 psutil>=3.1.1 19:04:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RN4ttVBSyy/Render', 19:04:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:04:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:04:37 INFO - 'HOME': '/Users/cltbld', 19:04:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:04:37 INFO - 'LOGNAME': 'cltbld', 19:04:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:04:37 INFO - 'MOZ_NO_REMOTE': '1', 19:04:37 INFO - 'NO_EM_RESTART': '1', 19:04:37 INFO - 'PAGER': '/bin/cat', 19:04:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:04:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:04:37 INFO - 'PWD': '/builds/slave/test', 19:04:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:04:37 INFO - 'SHELL': '/bin/bash', 19:04:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Rv6TYCZItC/Listeners', 19:04:37 INFO - 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/', 19:04:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:04:37 INFO - 'USER': 'cltbld', 19:04:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:04:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:04:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:04:37 INFO - 'XPC_FLAGS': '0x0', 19:04:37 INFO - 'XPC_SERVICE_NAME': '0', 19:04:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:04:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:04:37 INFO - Downloading/unpacking psutil>=3.1.1 19:04: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 19:04: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 19:04: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 19:04: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 19:04: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 19:04: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 19:04:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:04:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:04:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:04:41 INFO - Installing collected packages: psutil 19:04:41 INFO - Running setup.py install for psutil 19:04:41 INFO - building 'psutil._psutil_osx' extension 19:04:41 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 19:04:42 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 19:04:42 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 19:04:42 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 19:04:42 INFO - building 'psutil._psutil_posix' extension 19:04:42 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 19:04:42 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:04:42 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:04:42 INFO - ^ 19:04:42 INFO - 1 warning generated. 19:04:42 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 19:04:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:04:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:04:42 INFO - Successfully installed psutil 19:04:42 INFO - Cleaning up... 19:04:42 INFO - Return code: 0 19:04:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:04:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:04:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:04:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:04:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:04:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:04:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:04:42 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 0x106d08b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d0b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f852b48ceb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ae6d30>, '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 0x106a0ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106adbbe8>, '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.RN4ttVBSyy/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.Rv6TYCZItC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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 19:04:42 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 19:04:42 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 19:04:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RN4ttVBSyy/Render', 19:04:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:04:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:04:42 INFO - 'HOME': '/Users/cltbld', 19:04:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:04:42 INFO - 'LOGNAME': 'cltbld', 19:04:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:04:42 INFO - 'MOZ_NO_REMOTE': '1', 19:04:42 INFO - 'NO_EM_RESTART': '1', 19:04:42 INFO - 'PAGER': '/bin/cat', 19:04:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:04:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:04:42 INFO - 'PWD': '/builds/slave/test', 19:04:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:04:42 INFO - 'SHELL': '/bin/bash', 19:04:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Rv6TYCZItC/Listeners', 19:04:42 INFO - 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/', 19:04:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:04:42 INFO - 'USER': 'cltbld', 19:04:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:04:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:04:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:04:42 INFO - 'XPC_FLAGS': '0x0', 19:04:42 INFO - 'XPC_SERVICE_NAME': '0', 19:04:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:04:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:04:42 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:04:42 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 19:04:42 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 19:04:42 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 19:04:42 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 19:04:42 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 19:04:42 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 19:04:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:04:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:04:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:04:45 INFO - Installing collected packages: mozsystemmonitor 19:04:45 INFO - Running setup.py install for mozsystemmonitor 19:04:45 INFO - Successfully installed mozsystemmonitor 19:04:45 INFO - Cleaning up... 19:04:45 INFO - Return code: 0 19:04:45 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:04:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:04:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:04:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:04: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')]} 19:04:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:04:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:04:46 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 0x106d08b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d0b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f852b48ceb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ae6d30>, '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 0x106a0ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106adbbe8>, '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.RN4ttVBSyy/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.Rv6TYCZItC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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 19:04:46 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 19:04:46 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 19:04:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RN4ttVBSyy/Render', 19:04:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:04:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:04:46 INFO - 'HOME': '/Users/cltbld', 19:04:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:04:46 INFO - 'LOGNAME': 'cltbld', 19:04:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:04:46 INFO - 'MOZ_NO_REMOTE': '1', 19:04:46 INFO - 'NO_EM_RESTART': '1', 19:04:46 INFO - 'PAGER': '/bin/cat', 19:04:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:04:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:04:46 INFO - 'PWD': '/builds/slave/test', 19:04:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:04:46 INFO - 'SHELL': '/bin/bash', 19:04:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Rv6TYCZItC/Listeners', 19:04:46 INFO - 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/', 19:04:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:04:46 INFO - 'USER': 'cltbld', 19:04:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:04:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:04:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:04:46 INFO - 'XPC_FLAGS': '0x0', 19:04:46 INFO - 'XPC_SERVICE_NAME': '0', 19:04:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:04:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:04:46 INFO - Downloading/unpacking jsonschema==2.5.1 19:04:46 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 19:04:46 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 19:04:46 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 19:04:46 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 19:04:46 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 19:04:46 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 19:04:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:04:49 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:04:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:04:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:04:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:04:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:04:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:04:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:04:50 INFO - Downloading functools32-3.2.3-2.tar.gz 19:04:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:04:50 INFO - warning: no files found matching '*.txt' 19:04:50 INFO - no previously-included directories found matching 'build' 19:04:50 INFO - no previously-included directories found matching 'dist' 19:04:50 INFO - no previously-included directories found matching '.git*' 19:04:50 INFO - Installing collected packages: jsonschema, functools32 19:04:50 INFO - Running setup.py install for functools32 19:04:50 INFO - warning: no files found matching '*.txt' 19:04:50 INFO - no previously-included directories found matching 'build' 19:04:50 INFO - no previously-included directories found matching 'dist' 19:04:50 INFO - no previously-included directories found matching '.git*' 19:04:50 INFO - Successfully installed jsonschema functools32 19:04:50 INFO - Cleaning up... 19:04:50 INFO - Return code: 0 19:04:50 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:04: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')]} 19:04:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:04:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:04: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')]} 19:04:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:04:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:04:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106d08b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d0b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f852b48ceb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ae6d30>, '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 0x106a0ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106adbbe8>, '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.RN4ttVBSyy/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.Rv6TYCZItC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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 19:04:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 19:04:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 19:04:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RN4ttVBSyy/Render', 19:04:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:04:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:04:50 INFO - 'HOME': '/Users/cltbld', 19:04:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:04:50 INFO - 'LOGNAME': 'cltbld', 19:04:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:04:50 INFO - 'MOZ_NO_REMOTE': '1', 19:04:50 INFO - 'NO_EM_RESTART': '1', 19:04:50 INFO - 'PAGER': '/bin/cat', 19:04:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:04:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:04:50 INFO - 'PWD': '/builds/slave/test', 19:04:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:04:50 INFO - 'SHELL': '/bin/bash', 19:04:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Rv6TYCZItC/Listeners', 19:04:50 INFO - 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/', 19:04:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:04:50 INFO - 'USER': 'cltbld', 19:04:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:04:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:04:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:04:50 INFO - 'XPC_FLAGS': '0x0', 19:04:50 INFO - 'XPC_SERVICE_NAME': '0', 19:04:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:04:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:04:50 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:04:50 INFO - Cleaning up... 19:04:50 INFO - Return code: 0 19:04:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:04: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')]} 19:04:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:04:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:04: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')]} 19:04:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:04:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:04:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106d08b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d0b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f852b48ceb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ae6d30>, '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 0x106a0ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106adbbe8>, '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.RN4ttVBSyy/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.Rv6TYCZItC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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 19:04:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:04:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 19:04:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RN4ttVBSyy/Render', 19:04:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:04:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:04:50 INFO - 'HOME': '/Users/cltbld', 19:04:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:04:50 INFO - 'LOGNAME': 'cltbld', 19:04:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:04:50 INFO - 'MOZ_NO_REMOTE': '1', 19:04:50 INFO - 'NO_EM_RESTART': '1', 19:04:50 INFO - 'PAGER': '/bin/cat', 19:04:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:04:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:04:50 INFO - 'PWD': '/builds/slave/test', 19:04:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:04:50 INFO - 'SHELL': '/bin/bash', 19:04:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Rv6TYCZItC/Listeners', 19:04:50 INFO - 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/', 19:04:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:04:50 INFO - 'USER': 'cltbld', 19:04:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:04:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:04:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:04:50 INFO - 'XPC_FLAGS': '0x0', 19:04:50 INFO - 'XPC_SERVICE_NAME': '0', 19:04:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:04:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:04:51 INFO - Downloading/unpacking blobuploader==1.2.4 19:04:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:04:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:04:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:04:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:04:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:04:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:04:53 INFO - Downloading blobuploader-1.2.4.tar.gz 19:04:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:04:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:04:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:04:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:04:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:04:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:04:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:04:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:04:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:04:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:04:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:04:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:04:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:04:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:04:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:04:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:04:54 INFO - Downloading docopt-0.6.1.tar.gz 19:04:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:04:55 INFO - Installing collected packages: blobuploader, requests, docopt 19:04:55 INFO - Running setup.py install for blobuploader 19:04:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:04:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:04:55 INFO - Running setup.py install for requests 19:04:55 INFO - Running setup.py install for docopt 19:04:55 INFO - Successfully installed blobuploader requests docopt 19:04:55 INFO - Cleaning up... 19:04:55 INFO - Return code: 0 19:04:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:04:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:04:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:04:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:04:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:04:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:04:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:04:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106d08b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d0b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f852b48ceb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ae6d30>, '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 0x106a0ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106adbbe8>, '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.RN4ttVBSyy/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.Rv6TYCZItC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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 19:04:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 19:04:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:04:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RN4ttVBSyy/Render', 19:04:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:04:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:04:55 INFO - 'HOME': '/Users/cltbld', 19:04:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:04:55 INFO - 'LOGNAME': 'cltbld', 19:04:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:04:55 INFO - 'MOZ_NO_REMOTE': '1', 19:04:55 INFO - 'NO_EM_RESTART': '1', 19:04:55 INFO - 'PAGER': '/bin/cat', 19:04:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:04:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:04:55 INFO - 'PWD': '/builds/slave/test', 19:04:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:04:55 INFO - 'SHELL': '/bin/bash', 19:04:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Rv6TYCZItC/Listeners', 19:04:55 INFO - 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/', 19:04:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:04:55 INFO - 'USER': 'cltbld', 19:04:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:04:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:04:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:04:55 INFO - 'XPC_FLAGS': '0x0', 19:04:55 INFO - 'XPC_SERVICE_NAME': '0', 19:04:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:04:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:04:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:04:56 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-w150Qj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:04:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:04:56 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-xIcGoC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:04:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:04:56 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-AsYmKx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:04:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:04:56 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-dNQJUi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:04:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:04:56 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-2w7iz_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:04:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:04:56 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-S7kKD4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:04:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:04:56 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-rWNgEq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:04:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:04:56 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-yxclKw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:04:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:04:57 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-KCAutt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:04:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:04:57 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-L5AVH1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:04:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:04:57 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-xGzzE0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:04:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:04:57 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-Whgmu8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:04:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:04:57 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-frqGoH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:04:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:04:57 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-NFHVpE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:04:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:04:57 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-kYMS60-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:04:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:04:57 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-8rELVK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:04:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:04:57 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-w5hhXI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:04:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:04:57 INFO - Running setup.py install for manifestparser 19:04:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:04:58 INFO - Running setup.py install for mozcrash 19:04:58 INFO - Running setup.py install for mozdebug 19:04:58 INFO - Running setup.py install for mozdevice 19:04:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:04:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:04:58 INFO - Running setup.py install for mozfile 19:04:58 INFO - Running setup.py install for mozhttpd 19:04:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:04:58 INFO - Running setup.py install for mozinfo 19:04:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:04:59 INFO - Running setup.py install for mozInstall 19:04:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:04:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:04:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:04:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:04:59 INFO - Running setup.py install for mozleak 19:04:59 INFO - Running setup.py install for mozlog 19:04:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:04:59 INFO - Running setup.py install for moznetwork 19:04:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:04:59 INFO - Running setup.py install for mozprocess 19:04:59 INFO - Running setup.py install for mozprofile 19:04:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:04:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:04:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:04:59 INFO - Running setup.py install for mozrunner 19:05:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:05:00 INFO - Running setup.py install for mozscreenshot 19:05:00 INFO - Running setup.py install for moztest 19:05:00 INFO - Running setup.py install for mozversion 19:05:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:05:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:05:00 INFO - Cleaning up... 19:05:00 INFO - Return code: 0 19:05:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:05:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:05:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:05:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:05:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:05:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:05:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:05:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106d08b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d0b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f852b48ceb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ae6d30>, '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 0x106a0ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106adbbe8>, '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.RN4ttVBSyy/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.Rv6TYCZItC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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 19:05:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 19:05:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:05:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RN4ttVBSyy/Render', 19:05:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:05:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:05:00 INFO - 'HOME': '/Users/cltbld', 19:05:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:05:00 INFO - 'LOGNAME': 'cltbld', 19:05:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:05:00 INFO - 'MOZ_NO_REMOTE': '1', 19:05:00 INFO - 'NO_EM_RESTART': '1', 19:05:00 INFO - 'PAGER': '/bin/cat', 19:05:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:05:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:05:00 INFO - 'PWD': '/builds/slave/test', 19:05:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:05:00 INFO - 'SHELL': '/bin/bash', 19:05:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Rv6TYCZItC/Listeners', 19:05:00 INFO - 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/', 19:05:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:05:00 INFO - 'USER': 'cltbld', 19:05:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:05:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:05:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:05:00 INFO - 'XPC_FLAGS': '0x0', 19:05:00 INFO - 'XPC_SERVICE_NAME': '0', 19:05:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:05:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:05:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:05:00 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-u_j7qD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:05:00 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 19:05:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:05:00 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-vLVXcR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:05:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:05:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:05:01 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-orOWFk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:05:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 19:05:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:05:01 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-HHCH0Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:05:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:05:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:05:01 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-G2ZDX8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:05:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 19:05:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:05:01 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-zozM_D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:05:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 19:05:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:05:01 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-ZDr6eU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:05:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 19:05:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:05:01 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-kJYqzh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:05:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 19:05:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:05:01 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-qVZmdK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:05:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 19:05:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:05:01 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-DarorP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:05:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:05:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:05:01 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-DOWBhX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:05:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 19:05:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:05:02 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-Wzm8Zh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:05:02 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)) 19:05:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:05:02 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-WgL8eu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:05:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 19:05:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:05:02 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-CEsVbB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:05:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 19:05:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:05:02 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-P024aK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:05:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 19:05:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:05:02 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-RzCNF9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:05:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 19:05:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:05:02 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-7x0dwX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:05:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 19:05:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:05:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:05:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 19:05:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:05:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:05:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:05:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:05:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:05:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:05:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:05:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:05:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:05:05 INFO - Downloading blessings-1.6.tar.gz 19:05:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:05:05 INFO - Installing collected packages: blessings 19:05:05 INFO - Running setup.py install for blessings 19:05:05 INFO - Successfully installed blessings 19:05:05 INFO - Cleaning up... 19:05:05 INFO - Return code: 0 19:05:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:05:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:05:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:05:05 INFO - Reading from file tmpfile_stdout 19:05:05 INFO - Current package versions: 19:05:05 INFO - blessings == 1.6 19:05:05 INFO - blobuploader == 1.2.4 19:05:05 INFO - docopt == 0.6.1 19:05:05 INFO - functools32 == 3.2.3-2 19:05:05 INFO - jsonschema == 2.5.1 19:05:05 INFO - manifestparser == 1.1 19:05:05 INFO - mozInstall == 1.12 19:05:05 INFO - mozcrash == 1.0 19:05:05 INFO - mozdebug == 0.1 19:05:05 INFO - mozdevice == 0.49 19:05:05 INFO - mozfile == 1.2 19:05:05 INFO - mozhttpd == 0.7 19:05:05 INFO - mozinfo == 0.9 19:05:05 INFO - mozleak == 0.1 19:05:05 INFO - mozlog == 3.4 19:05:05 INFO - moznetwork == 0.27 19:05:05 INFO - mozprocess == 0.25 19:05:05 INFO - mozprofile == 0.28 19:05:05 INFO - mozrunner == 6.13 19:05:05 INFO - mozscreenshot == 0.1 19:05:05 INFO - mozsystemmonitor == 0.3 19:05:05 INFO - moztest == 0.8 19:05:05 INFO - mozversion == 1.4 19:05:05 INFO - psutil == 3.1.1 19:05:05 INFO - requests == 1.2.3 19:05:05 INFO - wsgiref == 0.1.2 19:05:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:05:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:05:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:05:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:05:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:05:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:05:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:05:06 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 0x106d08b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d0b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f852b48ceb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ae6d30>, '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 0x106a0ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106adbbe8>, '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.RN4ttVBSyy/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.Rv6TYCZItC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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 19:05:06 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 19:05:06 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 19:05:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RN4ttVBSyy/Render', 19:05:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:05:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:05:06 INFO - 'HOME': '/Users/cltbld', 19:05:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:05:06 INFO - 'LOGNAME': 'cltbld', 19:05:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:05:06 INFO - 'MOZ_NO_REMOTE': '1', 19:05:06 INFO - 'NO_EM_RESTART': '1', 19:05:06 INFO - 'PAGER': '/bin/cat', 19:05:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:05:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:05:06 INFO - 'PWD': '/builds/slave/test', 19:05:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:05:06 INFO - 'SHELL': '/bin/bash', 19:05:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Rv6TYCZItC/Listeners', 19:05:06 INFO - 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/', 19:05:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:05:06 INFO - 'USER': 'cltbld', 19:05:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:05:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:05:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:05:06 INFO - 'XPC_FLAGS': '0x0', 19:05:06 INFO - 'XPC_SERVICE_NAME': '0', 19:05:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:05:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:05:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 19:05:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 19:05:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 19:05:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 19:05:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 19:05:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 19:05:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 19:05:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 19:05:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 19:05:06 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 19:05:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 19:05:06 INFO - Cleaning up... 19:05:06 INFO - Return code: 0 19:05:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:05:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:05:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:05:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:05:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:05:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:05:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:05:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106d08b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d0b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f852b48ceb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ae6d30>, '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 0x106a0ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106adbbe8>, '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.RN4ttVBSyy/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.Rv6TYCZItC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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 19:05:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 19:05:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 19:05:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RN4ttVBSyy/Render', 19:05:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:05:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:05:06 INFO - 'HOME': '/Users/cltbld', 19:05:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:05:06 INFO - 'LOGNAME': 'cltbld', 19:05:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:05:06 INFO - 'MOZ_NO_REMOTE': '1', 19:05:06 INFO - 'NO_EM_RESTART': '1', 19:05:06 INFO - 'PAGER': '/bin/cat', 19:05:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:05:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:05:06 INFO - 'PWD': '/builds/slave/test', 19:05:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:05:06 INFO - 'SHELL': '/bin/bash', 19:05:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Rv6TYCZItC/Listeners', 19:05:06 INFO - 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/', 19:05:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:05:06 INFO - 'USER': 'cltbld', 19:05:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:05:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:05:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:05:06 INFO - 'XPC_FLAGS': '0x0', 19:05:06 INFO - 'XPC_SERVICE_NAME': '0', 19:05:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:05:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:05:06 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:05:06 INFO - Cleaning up... 19:05:06 INFO - Return code: 0 19:05:06 INFO - Running post-action listener: _resource_record_post_action 19:05:06 INFO - Running post-action listener: _start_resource_monitoring 19:05:06 INFO - Starting resource monitoring. 19:05:06 INFO - [mozharness: 2017-03-17 02:05:06.562376Z] Finished create-virtualenv step (success) 19:05:06 INFO - [mozharness: 2017-03-17 02:05:06.563322Z] Running install step. 19:05:06 INFO - Running pre-action listener: _resource_record_pre_action 19:05:06 INFO - Running main action method: install 19:05:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:05:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:05:06 INFO - Reading from file tmpfile_stdout 19:05:06 INFO - Detecting whether we're running mozinstall >=1.0... 19:05:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:05:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:05:06 INFO - Reading from file tmpfile_stdout 19:05:06 INFO - Output received: 19:05:06 INFO - Usage: mozinstall [options] installer 19:05:06 INFO - Options: 19:05:06 INFO - -h, --help show this help message and exit 19:05:06 INFO - -d DEST, --destination=DEST 19:05:06 INFO - Directory to install application into. [default: 19:05:06 INFO - "/builds/slave/test"] 19:05:06 INFO - --app=APP Application being installed. [default: firefox] 19:05:06 INFO - mkdir: /builds/slave/test/build/application 19:05:06 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'] 19:05:06 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 19:05:26 INFO - Reading from file tmpfile_stdout 19:05:26 INFO - Output received: 19:05:26 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 19:05:26 INFO - Running post-action listener: _resource_record_post_action 19:05:26 INFO - [mozharness: 2017-03-17 02:05:26.836689Z] Finished install step (success) 19:05:26 INFO - [mozharness: 2017-03-17 02:05:26.836831Z] Running run-tests step. 19:05:26 INFO - Running pre-action listener: _resource_record_pre_action 19:05:26 INFO - Running main action method: run_tests 19:05:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:05:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:05:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:05:26 INFO - 2017-03-16 19:05:26.851 screenresolution[1623:7244] starting screenresolution argv=screenresolution get 19:05:26 INFO - 2017-03-16 19:05:26.853 screenresolution[1623:7244] Display 0: 1600x1200x32@60 19:05:26 INFO - 2017-03-16 19:05:26.859 screenresolution[1624:7247] starting screenresolution argv=screenresolution list 19:05:26 INFO - Available Modes on Display 0 19:05:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:05:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:05:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:05:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:05:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:05:27 INFO - Intel Iris: 19:05:27 INFO - Chipset Model: Intel Iris 19:05:27 INFO - Type: GPU 19:05:27 INFO - Bus: Built-In 19:05:27 INFO - VRAM (Dynamic, Max): 1536 MB 19:05:27 INFO - Vendor: Intel (0x8086) 19:05:27 INFO - Device ID: 0x0a2e 19:05:27 INFO - Revision ID: 0x0009 19:05:27 INFO - Displays: 19:05:27 INFO - Display: 19:05:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:05:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:05:27 INFO - Display Serial Number: 1600x1200 60 19:05:27 INFO - Main Display: Yes 19:05:27 INFO - Mirror: Off 19:05:27 INFO - Online: Yes 19:05:27 INFO - Rotation: Supported 19:05:27 INFO - Return code: 0 19:05:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:05:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:05:27 INFO - Python 2.7.3 19:05:27 INFO - Return code: 0 19:05:27 INFO - grabbing minidump binary from tooltool 19:05: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')]} 19:05:27 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 0x106ae6d30>, '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 0x106a0ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106adbbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:05:27 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 19:05:27 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 19:05:27 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 19:05:27 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:05:27 INFO - Return code: 0 19:05:27 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:05:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:05:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:05:27 INFO - ENV: RUST_BACKTRACE is now 1 19:05:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:05:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:05:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/P4cwgh7gTjeK4-T2WdY99g/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0015', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 19:05:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Pine --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/P4cwgh7gTjeK4-T2WdY99g/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0015 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 19:05:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RN4ttVBSyy/Render', 19:05:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:05:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:05:27 INFO - 'HOME': '/Users/cltbld', 19:05:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:05:27 INFO - 'LOGNAME': 'cltbld', 19:05:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:05:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:05:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:05:27 INFO - 'MOZ_NO_REMOTE': '1', 19:05:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:05:27 INFO - 'NO_EM_RESTART': '1', 19:05:27 INFO - 'PAGER': '/bin/cat', 19:05:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:05:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:05:27 INFO - 'PWD': '/builds/slave/test', 19:05:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:05:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:05:27 INFO - 'RUST_BACKTRACE': '1', 19:05:27 INFO - 'SHELL': '/bin/bash', 19:05:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Rv6TYCZItC/Listeners', 19:05:27 INFO - 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/', 19:05:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:05:27 INFO - 'USER': 'cltbld', 19:05:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:05:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:05:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:05:27 INFO - 'XPC_FLAGS': '0x0', 19:05:27 INFO - 'XPC_SERVICE_NAME': '0', 19:05:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:05:27 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/P4cwgh7gTjeK4-T2WdY99g/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0015', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 19:05:27 INFO - mozversion application_buildid: 20170316163021 19:05:27 INFO - mozversion application_changeset: f4b9ef69bf348264ad740774295339e360e5a791 19:05:27 INFO - mozversion application_display_name: Nightly 19:05:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:05:27 INFO - mozversion application_name: Firefox 19:05:27 INFO - mozversion application_remotingname: firefox 19:05:27 INFO - mozversion application_repository: https://hg.mozilla.org/projects/pine 19:05:27 INFO - mozversion application_vendor: Mozilla 19:05:27 INFO - mozversion application_version: 55.0a1 19:05:27 INFO - mozversion platform_buildid: 20170316163021 19:05:27 INFO - mozversion platform_changeset: f4b9ef69bf348264ad740774295339e360e5a791 19:05:27 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/pine 19:05:27 INFO - mozversion platform_version: 55.0a1 19:05:27 INFO - using testdate: 1489716327 19:05:27 INFO - actual date: 1489716327 19:05:27 INFO - starting webserver on 'localhost:49241' 19:05:27 INFO - SUITE-START | Running 2 tests 19:05:27 INFO - TEST-START | dromaeo_css 19:05:27 INFO - operating with platform_type : mac_ 19:05:27 INFO - Initialising browser for dromaeo_css test... 19:05:27 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/tmpGXT9rV/profile http://localhost:49241/getInfo.html) 19:05:30 INFO - PID 1634 | 2017-03-16 19:05:30.444 plugin-container[1636:7431] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x903f, name = 'com.apple.tsm.portname' 19:05:30 INFO - PID 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:05:30 INFO - PID 1634 | 2017-03-16 19:05:30.446 plugin-container[1636:7431] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4e13, name = 'com.apple.CFPasteboardClient' 19:05:30 INFO - PID 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:05:30 INFO - PID 1634 | 2017-03-16 19:05:30.446 plugin-container[1636:7431] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:05:30 INFO - PID 1634 | __metrics Screen width/height:1600/1200 19:05:30 INFO - PID 1634 | colorDepth:24 19:05:30 INFO - PID 1634 | Browser inner width/height: 1024/689 19:05:30 INFO - PID 1634 | __metrics 19:05:30 ERROR - PID 1634 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3350: TypeError: this.tabs is undefined 19:05:31 INFO - TEST-INFO | 1634: exit 0 19:05:31 INFO - Browser initialized. 19:05:31 INFO - Running cycle 1/1 for dromaeo_css test... 19:05:31 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/tmpGXT9rV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:05:34 INFO - PID 1637 | 2017-03-16 19:05:34.153 plugin-container[1638:7567] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a4b, name = 'com.apple.tsm.portname' 19:05:34 INFO - PID 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:05:34 INFO - PID 1637 | 2017-03-16 19:05:34.154 plugin-container[1638:7567] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9003, name = 'com.apple.CFPasteboardClient' 19:05:34 INFO - PID 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:05:34 INFO - PID 1637 | 2017-03-16 19:05:34.154 plugin-container[1638:7567] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:05:43 INFO - PID 1637 | RSS: Main: 227782656 19:05:43 INFO - PID 1637 | 19:08:46 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 19:08:48 INFO - PID 1637 | RSS: Main: 251162624 19:08:48 INFO - PID 1637 | 19:10:31 INFO - PID 1637 | ************************* 19:10:31 INFO - PID 1637 | A coding exception was thrown and uncaught in a Task. 19:10:31 INFO - PID 1637 | 19:10:31 ERROR - PID 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 19:10:31 INFO - PID 1637 | Full stack: 19:10:31 INFO - PID 1637 | ************************* 19:11:50 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 19:11:51 INFO - PID 1637 | RSS: Main: 256913408 19:11:51 INFO - PID 1637 | 19:14:52 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 19:14:53 INFO - PID 1637 | RSS: Main: 269676544 19:14:53 INFO - PID 1637 | 19:17:55 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 19:17:56 INFO - PID 1637 | RSS: Main: 264634368 19:17:56 INFO - PID 1637 | 19:20:59 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 19:20:59 INFO - PID 1637 | RSS: Main: 274448384 19:20:59 INFO - PID 1637 | 19:24:02 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 19:24:02 INFO - PID 1637 | RSS: Main: 268275712 19:24:02 INFO - PID 1637 | 19:24:02 INFO - PID 1637 | __start_tp_report 19:24:02 INFO - PID 1637 | _x_x_mozilla_page_load 19:24:02 INFO - PID 1637 | _x_x_mozilla_page_load_details 19:24:02 INFO - PID 1637 | |i|pagename|runs| 19:24:02 INFO - PID 1637 | |0;dojo.html;3388.525606415102;3407.4548072308435;3441.070910854071;3448.4654878493834;3460.515527826105;5892.998190307652;5903.2916050074;5919.408059194078;5921;5928.614640048405;4956.859922217892;5635.295588051494;5674.063779476382;5791.21818554495;5795.884082318351;5178.818741344045;5262.342207224097;5315.309009828717;5346.759395827197;5395.73021348931;5393.865153371158;5394.595405344595;5398.64908780928;5407.729613519328;5427.864303392407;888.4281341817577;5243.164892287707;5257.185136303879;5383.246345511612;5394.352677678684;4616.792244349012;5297.099493086146;5313.1764576490705;5319.5478384337375;5342.2787923630585;5275.419703832576;5296.708681022542;5302.363716354023;5315.973420132875;5316.680999140064;5601.60788744788;5693.089105743103;5711.971440142815;5784.68184249866;5911.615744976562;6152.261728592553;6157.4458298753325;6229.626222426669;6235.220597425322;6242.937570624281;6196.96901515494;6228.283747369065;6239.532035097341;6240.531960102965;6243.500519958392;5874.441928016831;5895.823125306248;5910.7931222407005;5922.200502932092;5959.702014899238;6200.3799620037635;6262.5929314594405;6265.592736472115;6312.242530896322;6315.936840631535;5054.368203974504;6215.595986260879;6223.1287619733275;6268.216472940882;6285.779997700059;6200.224971878515;6254.499640028786;6270.874582508324;6276.246850377984;6278.968605157036;6136.059583474799;6189.96905015481;6214.9689251554355;6216.720247588869;6234.719437625317;6220.066989951543;6235.750569977241;6238.875222495525;6250.937490625036;6251.12484252205;5035.16919708244;5055.165897626924;5060.696358218519;5062.873428164326;5067.847964561069;4410.823567057346;4460.130274596488;4495.213337665896;4504.414426124583;4539.659525535598;5808.5933984621515;5829.446202610745;5837.7664893404635;5850.1224816277045;5858.648481091148;6211.503079753609;6216.409441103178;6260.655663938527;6275.215598050244;6286.339934306923;5969.2240008799445;5972.034370276052;5980.491658209101;5991.610545314627;5996.67018313997;5552.056150454352;5559.638623489541;5569.888602227933;5578.609497335228;5586.832395028156;6210.378962103753;6274.905876411857;6362.713677884413;6382.744690212339;6408.58344207625;6255.56211065221;6310.400511951358;6314.336994615683;6315.621062736251;6326.905096423466;6422.357764223727;6433;6442.516811239082;6442.774502892376;6452.9997850333275;6143.450468055707;6191.25704915413;6196.690165491797;6205.379462053758;6256.968715156395;6223.3465486125115;6247.375262473716;6260.248770147611;6271.498280137487;6278.309385967448;6234.251889773075;6253.812385628438;6260.436560709558;6269.341719119427;6276.937230627818;6121.785737499166;6134.344744984565;6191.566590338633;6198.194234749454;6268.153799237227;6039.63762174271;6049.243844519435;6077.088436734349;6123.908141377972;6126.387361264016;5663.915041274467;6110.175126357884;6196.378454501079;6222.875542489215;6234.563580549499;5958.285005799332;5984.551158663205;6021.909671354845;6030;6034.741239730496 19:24:02 INFO - PID 1637 | |1;ext.html;700.7267165805337;741.7107328142025;814.2102160903921;850.1965642467862;945.5177859291755;37141.628583714155;37828.62171378286;37886.621133788656;37910.24179516408;37941.81029094854;36452.635473645256;36881.077973050684;36917.630823691754;36920.26159476809;37094.258114837685;7531.096268447794;7732;10699.679009629723;10977.451127443635;11004.614838480655;11005.394703291317;11076.335419874793;11087.944560277187;11106.666799996014;11131.72170695731;11071.391073490955;11132;11158.218924675277;11159.27464714791;11182;10671.733206669818;11066.778664426707;11098.389588572625;11111.777764444747;11124.276922000045;10889.074428673572;10962.232643714942;10968.670939871816;10977.83533247002;10989.285696429786;31319;38259.852204433635;38270.23459530822;38333;38366.848994530206;3715.2198038411966;3768.8869333920024;3785.7160712946365;3798.924021519582;3948.4008976364617;27740.02909898144;28140.577891331646;28177.154685359474;28305.15084547467;28520.42959140826;2590.468953864472;3068.7330530610257;3110.7044830741042;3135;3218.2598002459567;244160;249644;277127.99999999796;353677;353818;45850.624481265615;46029.76985115053;46095.8476038095;46145.76927115343;48275.75862120667;41300.58699413027;45818.770906145255;48900.887389076364;49175;49192;14149.151050936976;14341.92829035848;14467;14472.927635361755;14483.710325793636;11516.481758320895;11625.302481850948;11664.591739289084;11676.416179191006;11722.88277117235;13457.327133643279;13541.526046588322;13672;13675.974301927396;13705.931470342584;5291.8567979968175;5847;5977.282726072899;6021.60859544137;6022.126791615191;993.6075250276245;1104.6644706069665;1129.4239937631867;1143.0112952296204;1193.1886317304316;22340.776592234193;22474.775252247597;25576.488470230863;25656.871715641304;26085.347866303116;1226.1294480918648;1240.7146356338092;1250.3373212197369;1257.6808721506443;1265.1839563481503;13066.54267100647;13434;13446.058775885595;13488.39302231402;13505.797413038812;443565;460023;463597;465786;466341;54588.45411545913;54954.72522637441;55108.17337739937;55201;55299;1400.138914567554;1456.7013762178728;1460.5618314505605;1460.6421426750514;1478.9260536973109;23807.285781426584;25136.74863251344;25274.620880687173;25512.97948082056;25604.615930761425;44555.55444445514;44611.77694111509;44655.330170048124;44692.77653611711;44875.775621121684;442985;442988;443924;445039;446800;31518.842405787826;32038.718451261688;32111.839440803582;32159.07045577273;32167.356652867278;34004.1498962518;40928.59071409367;42216;42350;42542.36186457144;32980.83509582437;34559.308813824086;34692.30615387729;35636.821815890755;35654.64345356617;33272.00183994484;34508.48237276493;35358.646413535535;35515;35556;26426.735732643196;34557.48163777495;34737.47893781646;34862.47706284357;34943.77696780601;24839.379015524755;24980.37549061288;27325.316867078483;28368;30388.69611303947;23320.742322220452;23369.76630233676;23489.647655284843;23637.881810590836;23671.526569468857 19:24:02 INFO - PID 1637 | |2;jquery.html;402.2919661395946;416.3733580960654;424.49485112715865;433.98264069437226;436.38687643860385;16610.25253863576;19750.11124499399;20789.584208315824;21195.57608847822;21548.461288467795;18464.07679616017;21441.356759297247;21606.891965540188;22139.114435422565;22173.445663858412;5894.970525147379;6654.667266636673;6675.8331041724105;6692.130023097015;6705.932940670604;6405.775797847076;6470;6479.740810367603;6547.836304092412;6565.868682626345;6549.44329731971;6568.737250509974;6575.572587781779;6578.736850525973;6586.604803711769;6400.071989561511;6469.7412103516035;6511.055896894947;6524.151860258184;6569.37590928861;6324.936750632481;6354.3963323484195;6413.358664133596;6420.325865784119;6439.098526206335;25684.614730779052;25876.482470350675;25935.740642593522;25991.480170396677;26020.73979260202;2839.9432011359863;2850.6009158717798;2880.5168777948948;2893.32006978362;2895.319599894023;15712.764308535381;16311.021338719715;16495;16530.88107369206;16573.08848013359;1996.2015193922548;1997.1312479071505;2004.9097790599458;2057.094878253579;2057.9485512862298;159211.20394398068;165445.9999999988;165978.34021659748;166051.3394866048;168764.81116699555;19294.45860190887;19401.417957461297;20707.4823129423;21925;22588.418810683095;34339.82830085834;35868.820655896554;35968.820155899055;35969.28061438809;36160.81919590385;9934.453605051647;10260.384376937407;10424.478776061167;10537.262391632512;10562.894371056345;8285.088640249567;8285.130061343472;8294.875576866352;8424.241818236389;8486.321094312563;8408.621612027351;8834.249088827393;10199.082082612453;10207.693769186937;10218.233632477593;4361.797292367334;4430.964789119464;4430.983902324435;4440.444944381952;4464.486584042812;1019.9494520643749;1032.9845052324222;1070.384528895888;1090.6346373964811;1111.699841042914;22723.772762272165;23029.193978211017;23071.769282307298;23437.76562234356;23502.294931152093;1044.749260177567;1089.428050273597;1102.3000394749392;1143.085531574737;1156.4738044189874;11317.773644527064;12161.756764864831;12186;12316.322602256783;12319.938400307941;396062.0196898997;616430;625506;707729;742958;111737;128570;129577;130410;131481;1210.0924306769923;1262.4129779652505;1291.7351942851692;1293.3080801771007;1306.3729409883308;20820.107274790716;20905.89547052316;20941.48702155751;21315.680264796352;21380.82405467652;59883;62786;62861.48554057969;63291.683541581995;63543.6822815883;78327.60836195783;81378.77931831146;88179.55910220408;89150.55424722836;91326.17347652877;9332.533373331442;11294.322340659586;11355.261907975955;11467.598634047768;11828.17202795796;13183.868161318263;13190.208587484782;13458.52895148665;13495.122817016863;13674.794878076638;11396.658100256675;11486.82769758471;11548.711282218012;11631.069514438655;11792.233504822503;9535.570899309687;10566.573512576011;11144.442777860912;11427.771444571228;11483.942580287045;11534.884651153381;11824.40877956116;11858.229215100991;11859.762804744028;11878.465469054086;11521.481533331187;11543.595974140864;11802.52789888395;11847.466863991314;11910;9979.850302245617;10239.747255603756;10260.948695256477;10264.692059238234;10364.896351036694 19:24:02 INFO - PID 1637 | |3;mootools.html;1393.7212557488506;1572.4182052640517;1584.6592982508757;1596.2657177698254;1653.4378311374132;10307.433091179983;10666.679999600025;10807.56769729212;10840.891591084086;11039.779204415907;9688.837339519268;10636.361818290909;10754.247202695815;10911.454427278624;10951.83572246417;2022.5449273913398;2344.882755862209;2375.964360534593;2380.9642855357183;2384.4277373430336;2049.549099198174;2117.5129720164373;2185.3443966809973;2338.205010296499;2371.8695471749134;2333.603287441139;2343.2150229672993;2346.9413264668433;2352.811775058;2357.56385068762;2278.111536500766;2287.942801429961;2290.770922907704;2297.931062068132;2299.9540009199804;2306.527161931817;2363.775441333071;2366.2664573981947;2368.5144545368157;2371.181942229945;9439.480828554426;10999.01008909202;11006.95433933775;11012.669619911416;11029.944850275777;5648.152777083429;5866.97327967604;5870.119482077723;5870.823875283749;5892.764289428418;3298.208429976812;3431.279431319426;3552.0231936217488;3610.4584312353095;3647.5258216431694;1139.3961200563715;1171.3557543351124;1196.994015029928;1214.6538236602614;1242.9689257768537;13259.204447733167;13557.52548660802;14542.636434089021;14913;14982.850171498363;4489.753063581535;4533.00547758985;4688.413948256468;4695.788689508912;4697.262013055123;11257.099432045417;11271.323720576627;11286.943565282285;11297.717557060974;11332.376719280519;5634.04221282383;5705.885882282331;5711.543076553949;5734.913976290359;5741.110127930177;5121.052605268037;5166.896662066738;5200.97399513;5212.635115541952;5222.451642577551;3748.34403979308;3754.342989976743;3834.8082595870096;3859.864904728321;3874.9150237933422;1040.1626690514058;1040.8542804007445;1041.073444634276;1052.6315789473622;1052.826283663187;889.6396959231522;910.2081189365294;924.1174678182454;941.5715849288557;947.7109481608038;6290.716917738713;6329.430351268408;6369.299377068519;6434.903476447857;6497.383520884756;981.1954197558076;1003.6286573967533;1026.5124066068706;1031.3090229546222;1037.9792404151874;3689.409694448875;3771.0949372150485;3849.9230015399535;4022.0749227677798;4044.928094055078;12858;13119.737605247841;13267.73464530704;13271.270080145492;13279;12739.299338536284;12787.552435664707;13077.346132693327;13108.410121544553;13125.081244312807;8534.74395768128;9656.32405731606;9716.417014979124;9805.852912206323;9870.851937220947;6163.19878415803;6503.122078519339;6677;6703.798886033427;6749.493787965824;11308.886911131112;11338.716532086764;11607.6517704469;11637.185397022127;11673.766524669629;11811.232269902428;11839.82240266414;11874.109441791727;12136.271823690608;12166.452509637265;8116;8246.01047874259;8256.844041834269;8273.089960104504;8294.502329860228;9879.357841740264;9964.953679863509;10027.49862506857;10073.748156295858;10074.69775906724;8754.649814007624;8789.736307910773;8867.0689577596;8879.733607991771;9054.139856713602;8611.870821937551;8645.91354086451;8663.740087797376;8770.736877893672;8857.867131993155;7559;7707.036620422447;7766.184550622103;7771.8834217485655;7778.688852445839;5742.684152371659;5808.854778630568;5871.266091738533;5905.675187864708;5983.880322393615;5802.4197580241635;5815.156802263733;5949.434803693643;5989.940100598938;6113.633182009094 19:24:02 INFO - PID 1637 | |4;prototype.html;312.91864115330014;373.3932359915138;391.2956677979636;392.5093632958802;394.1899396739699;5897.970510147454;5905.498032667223;6112.602680825755;6143.8771224575485;6173.931732869494;5064.746762661862;5150.351887396035;5567.053600887849;5930.199423077883;5962.8807423851495;4643.489216186217;4865.8783530411665;4882.267659851033;4883.29192267121;4904.534069263432;4628.514006029369;4768.761561921908;4866.223828302679;4875.609951203913;4945.944325652966;4467.642588592921;4807.735574543416;4871.2449570316485;4872.731999740013;4897.363342765436;4635.188841952645;4817.78319975602;4918.532739389752;4922.55696987271;4925.211966085445;4357.716748411343;4446.7554284514335;4461.330800379969;4463.995600989751;4504.572065653758;5073.898522029575;5518.862028449281;5842.065269556893;5884.176215329857;5912.733926973297;5529.502344788988;5661.2357331760095;5699.857503562403;5710.229119068956;5746.827854930554;5267.446918073585;6381.840453988641;6442.162518872517;6442.25914019889;6467.191601049869;4980.854403487182;4998.175301075318;4999.475055119233;5021;5123.359580052494;6048.879022419571;6695.062691223233;6772.8306792330095;6787;6829.556078854858;5054.267131265946;5539.750711218005;5572.693501857437;5629.577781666309;5637.802676906289;5547.5007249346945;5783.71081445926;5788.494991302292;5793.710314484259;5828.912566311508;5287.9735601321745;5545.863098064887;5550.722463876871;5932.673702946379;5941.316748573883;4853.466118726938;5149.588032957355;5227.320448341691;5281.55106815925;6402.551821372553;1666.6500034992546;1845.9538511537319;1925.3357591630865;1991.8207361337259;2029.5433527456205;1462.8464011278663;1535.4702627593463;1573.7645947930819;1592.5302035899438;1620.4558842616977;5763.2507773989955;5976.641401515923;6004.729787159588;6014.669193194329;6025.6083354581815;5531.778728850882;6583.967080164569;6599.8020059398295;6606.141201643852;6610.933890661032;4695.131400690883;5274.525292723673;5968.552358573124;5987.2815262168815;6012.278526576752;2894.435585060935;2944.3080875994124;3290.68738469845;3448.396530607184;3480.7911525308564;6894.482913781487;6912.377885990285;6915.51591388598;6934;6976.4767642426;6050.909236361458;6643.468522518187;6753.594784312859;6765.661716914233;6855.897161542581;6169.84575385619;6453.9351007083305;6706.161729783777;6830.965845170742;6867.896981545281;5000.87497812543;6226.968865155645;6540.542162048801;6574.671266436755;6654.567453115532;4953.7523123844385;5352.812651557177;6255.030470277115;6385.647535044139;6650.90023649636;6360.809175724736;6924.4114250289285;6932.6533673315125;6950.756723514653;6982.965085174542;5002.949970500394;5058.459078645037;5096.413912399973;5114.693118412907;5147.948520514897;5564.8330550083565;6649.2685804562425;6650.933490665225;6653.548026210675;6684.598924064571;6220.906686399798;6406.860638287299;6412.551121421456;6420.518461115342;6424.839379015562;6392.9360706392345;6400.519961003037;6432.131662225727;6453.709583068679;6506.707198175999;6058.757649694139;6277.372262773686;6310.558260921692;6310.6844657766005;6315.1900251767565;5595.664260144405;6251.9987208185885;6348.238211414475;6420.1974753155855;6448.484121270193;5403.918941215964;6021.337652858095;6065.029595264737;6172.475339596184;6242.094896239929 19:24:02 INFO - PID 1637 | |5;yui.html;629.9023651334045;702.3187508117124;752.9887051694226;754.5585832288111;758.4040713277539;14985.325660345283;15813.130277834714;15819;15827.446039388624;15840.76238856418;14733.116013039225;14882.553523394316;14911.627209319773;14916.7016659667;14968.401263949429;912.9863052054225;941.4680705401427;953.3279038278032;970.9902900971006;981.0532835813439;981.1709105805583;988.6144403682533;989.2481713897417;989.5645915797062;989.6288891665625;1031.339942436841;1035.6219979707428;1037.3516552154904;1037.3931250218632;1041.297124441003;1014.9746256343614;1024.1960061351804;1025.4257615735212;1029.8352263637782;1034.7257976636197;761.0486891385768;768.3161985832613;768.8423873106;769.1616138409161;770.0336078221795;15685.451009214737;15974.840251597569;16183.35266589335;16192.19039048043;16253.349866005346;14243.216623085726;14514.491992780308;14670.853291467056;14678.559643210721;14692.853071469255;9885.75285617858;11765;11864.822027669505;12012.939935300356;12238.143329966906;3215.951760723591;3240.5949256342956;3254.4630136027527;3317.93364132716;3320.8339583020993;88136;104280;105914;113197;117200;10408.95910408953;10927.235093543375;11284;11329.546818127348;11343.149263805248;24507.142250021163;24745.505089898103;24944.002239910562;24992.625110623354;25075.373115672253;6697.162854643169;7835;7845.607719613997;7921.3550395020675;7983.2016798320865;3922.156736301709;4194.874153775391;4219;4267.8079486423185;4275.700700950903;3487.738419618539;3542.291541691672;3547.396942519778;3565.928681426357;3573.088862340139;580.5848818095083;645.1645119570168;649.389573800626;655.131950166032;662.0301258656092;635.3075148088606;661.4113439039259;664.6875968294894;681.6956313105004;695.5722230828004;9600.567974441166;9747.985922554159;10101.646442374482;10114.291999560108;10305.948470257601;646.5958775765147;650.3918835888364;676.9264363594888;681.4071757570945;699.4998576018171;4562.5665561771575;4726.787294571753;4844.539768721966;4857.951420485821;4889.608831293559;74062.62968685121;75900;92722.53638731898;93985.53007235056;94087.64780880396;63623.681881590295;70549.64725176341;82680.58659706783;83383.7492437614;85580.57209713911;64913.67543162254;67207.99188012183;68031.65984170047;68543.65728171327;69312.96030559545;8947.239484643878;9385.15533601979;9391.154796068387;9475.677206792205;9493.620255189715;47019.82450438767;48344.516554834;49687.503124969735;49849.25226121684;50245.74877125591;48226;62676.68661656845;63857;64893.02660460191;65073.34926650862;11551.826722599;11793.646190614296;11834.881651183377;11845.5854045108;11886.762264754829;12437.875621244033;12563.560275390317;12727;12744;13042.087053906136;11728.648140555797;12202.572909948109;12276.877231227574;12284.938575307066;12499.125061245626;11346.489407976826;11686;11702.648920532398;11819.349935753273;12056.457459414172;11572.42137893085;11618.004866180088;11620.244684095846;11693.239939403911;11735.647930562096;9830.459324736921;10101.5454304555;10147.949260253652;10218.437985910847;10386.584536618451;8188.754337369888;8804.779880503038;8937;9122.635094596142;9318.860217096884 19:24:02 INFO - PID 1637 | __end_tp_report 19:24:02 INFO - PID 1637 | __start_cc_report 19:24:02 INFO - PID 1637 | _x_x_mozilla_cycle_collect,422 19:24:02 INFO - PID 1637 | __end_cc_report 19:24:02 INFO - PID 1637 | __startTimestamp1489717442676__endTimestamp 19:24:02 INFO - PID 1637 | 19:24:02 INFO - PID 1637 | ------- Summary: start ------- 19:24:02 INFO - PID 1637 | Number of tests: 6 19:24:02 INFO - PID 1637 | 19:24:02 INFO - PID 1637 | [#0] dojo.html Cycles:165 Average:5785.16 Median:6049.24 stddev:738.05 (12.2%) stddev-sans-first:715.97 19:24:02 INFO - PID 1637 | Values: 3388.5 3407.5 3441.1 3448.5 3460.5 5893.0 5903.3 5919.4 5921.0 5928.6 4956.9 5635.3 5674.1 5791.2 5795.9 5178.8 5262.3 5315.3 5346.8 5395.7 5393.9 5394.6 5398.6 5407.7 5427.9 888.4 5243.2 5257.2 5383.2 5394.4 4616.8 5297.1 5313.2 5319.5 5342.3 5275.4 5296.7 5302.4 5316.0 5316.7 5601.6 5693.1 5712.0 5784.7 5911.6 6152.3 6157.4 6229.6 6235.2 6242.9 6197.0 6228.3 6239.5 6240.5 6243.5 5874.4 5895.8 5910.8 5922.2 5959.7 6200.4 6262.6 6265.6 6312.2 6315.9 5054.4 6215.6 6223.1 6268.2 6285.8 6200.2 6254.5 6270.9 6276.2 6279.0 6136.1 6190.0 6215.0 6216.7 6234.7 6220.1 6235.8 6238.9 6250.9 6251.1 5035.2 5055.2 5060.7 5062.9 5067.8 4410.8 4460.1 4495.2 4504.4 4539.7 5808.6 5829.4 5837.8 5850.1 5858.6 6211.5 6216.4 6260.7 6275.2 6286.3 5969.2 5972.0 5980.5 5991.6 5996.7 5552.1 5559.6 5569.9 5578.6 5586.8 6210.4 6274.9 6362.7 6382.7 6408.6 6255.6 6310.4 6314.3 6315.6 6326.9 6422.4 6433.0 6442.5 6442.8 6453.0 6143.5 6191.3 6196.7 6205.4 6257.0 6223.3 6247.4 6260.2 6271.5 6278.3 6234.3 6253.8 6260.4 6269.3 6276.9 6121.8 6134.3 6191.6 6198.2 6268.2 6039.6 6049.2 6077.1 6123.9 6126.4 5663.9 6110.2 6196.4 6222.9 6234.6 5958.3 5984.6 6021.9 6030.0 6034.7 19:24:02 INFO - PID 1637 | 19:24:02 INFO - PID 1637 | [#1] ext.html Cycles:180 Average:53665.63 Median:17326.92 stddev:108252.27 (624.8%) stddev-sans-first:108482.90 19:24:02 INFO - PID 1637 | Values: 700.7 741.7 814.2 850.2 945.5 37141.6 37828.6 37886.6 37910.2 37941.8 36452.6 36881.1 36917.6 36920.3 37094.3 7531.1 7732.0 10699.7 10977.5 11004.6 11005.4 11076.3 11087.9 11106.7 11131.7 11071.4 11132.0 11158.2 11159.3 11182.0 10671.7 11066.8 11098.4 11111.8 11124.3 10889.1 10962.2 10968.7 10977.8 10989.3 31319.0 38259.9 38270.2 38333.0 38366.8 3715.2 3768.9 3785.7 3798.9 3948.4 27740.0 28140.6 28177.2 28305.2 28520.4 2590.5 3068.7 3110.7 3135.0 3218.3 244160.0 249644.0 277128.0 353677.0 353818.0 45850.6 46029.8 46095.8 46145.8 48275.8 41300.6 45818.8 48900.9 49175.0 49192.0 14149.2 14341.9 14467.0 14472.9 14483.7 11516.5 11625.3 11664.6 11676.4 11722.9 13457.3 13541.5 13672.0 13676.0 13705.9 5291.9 5847.0 5977.3 6021.6 6022.1 993.6 1104.7 1129.4 1143.0 1193.2 22340.8 22474.8 25576.5 25656.9 26085.3 1226.1 1240.7 1250.3 1257.7 1265.2 13066.5 13434.0 13446.1 13488.4 13505.8 443565.0 460023.0 463597.0 465786.0 466341.0 54588.5 54954.7 55108.2 55201.0 55299.0 1400.1 1456.7 1460.6 1460.6 1478.9 23807.3 25136.7 25274.6 25513.0 25604.6 44555.6 44611.8 44655.3 44692.8 44875.8 442985.0 442988.0 443924.0 445039.0 446800.0 31518.8 32038.7 32111.8 32159.1 32167.4 34004.1 40928.6 42216.0 42350.0 42542.4 32980.8 34559.3 34692.3 35636.8 35654.6 33272.0 34508.5 35358.6 35515.0 35556.0 26426.7 34557.5 34737.5 34862.5 34943.8 24839.4 24980.4 27325.3 28368.0 30388.7 23320.7 23369.8 23489.6 23637.9 23671.5 19:24:02 INFO - PID 1637 | 19:24:02 INFO - PID 1637 | [#2] jquery.html Cycles:180 Average:39110.07 Median:21348.25 stddev:105935.12 (496.2%) stddev-sans-first:106192.42 19:24:02 INFO - PID 1637 | Values: 402.3 416.4 424.5 434.0 436.4 16610.3 19750.1 20789.6 21195.6 21548.5 18464.1 21441.4 21606.9 22139.1 22173.4 5895.0 6654.7 6675.8 6692.1 6705.9 6405.8 6470.0 6479.7 6547.8 6565.9 6549.4 6568.7 6575.6 6578.7 6586.6 6400.1 6469.7 6511.1 6524.2 6569.4 6324.9 6354.4 6413.4 6420.3 6439.1 25684.6 25876.5 25935.7 25991.5 26020.7 2839.9 2850.6 2880.5 2893.3 2895.3 15712.8 16311.0 16495.0 16530.9 16573.1 1996.2 1997.1 2004.9 2057.1 2057.9 159211.2 165446.0 165978.3 166051.3 168764.8 19294.5 19401.4 20707.5 21925.0 22588.4 34339.8 35868.8 35968.8 35969.3 36160.8 9934.5 10260.4 10424.5 10537.3 10562.9 8285.1 8285.1 8294.9 8424.2 8486.3 8408.6 8834.2 10199.1 10207.7 10218.2 4361.8 4431.0 4431.0 4440.4 4464.5 1019.9 1033.0 1070.4 1090.6 1111.7 22723.8 23029.2 23071.8 23437.8 23502.3 1044.7 1089.4 1102.3 1143.1 1156.5 11317.8 12161.8 12186.0 12316.3 12319.9 396062.0 616430.0 625506.0 707729.0 742958.0 111737.0 128570.0 129577.0 130410.0 131481.0 1210.1 1262.4 1291.7 1293.3 1306.4 20820.1 20905.9 20941.5 21315.7 21380.8 59883.0 62786.0 62861.5 63291.7 63543.7 78327.6 81378.8 88179.6 89150.6 91326.2 9332.5 11294.3 11355.3 11467.6 11828.2 13183.9 13190.2 13458.5 13495.1 13674.8 11396.7 11486.8 11548.7 11631.1 11792.2 9535.6 10566.6 11144.4 11427.8 11483.9 11534.9 11824.4 11858.2 11859.8 11878.5 11521.5 11543.6 11802.5 11847.5 11910.0 9979.9 10239.7 10260.9 10264.7 10364.9 19:24:02 INFO - PID 1637 | 19:24:02 INFO - PID 1637 | [#3] mootools.html Cycles:180 Average:6520.61 Median:3364.74 stddev:4012.29 (119.2%) stddev-sans-first:4005.05 19:24:02 INFO - PID 1637 | Values: 1393.7 1572.4 1584.7 1596.3 1653.4 10307.4 10666.7 10807.6 10840.9 11039.8 9688.8 10636.4 10754.2 10911.5 10951.8 2022.5 2344.9 2376.0 2381.0 2384.4 2049.5 2117.5 2185.3 2338.2 2371.9 2333.6 2343.2 2346.9 2352.8 2357.6 2278.1 2287.9 2290.8 2297.9 2300.0 2306.5 2363.8 2366.3 2368.5 2371.2 9439.5 10999.0 11007.0 11012.7 11029.9 5648.2 5867.0 5870.1 5870.8 5892.8 3298.2 3431.3 3552.0 3610.5 3647.5 1139.4 1171.4 1197.0 1214.7 1243.0 13259.2 13557.5 14542.6 14913.0 14982.9 4489.8 4533.0 4688.4 4695.8 4697.3 11257.1 11271.3 11286.9 11297.7 11332.4 5634.0 5705.9 5711.5 5734.9 5741.1 5121.1 5166.9 5201.0 5212.6 5222.5 3748.3 3754.3 3834.8 3859.9 3874.9 1040.2 1040.9 1041.1 1052.6 1052.8 889.6 910.2 924.1 941.6 947.7 6290.7 6329.4 6369.3 6434.9 6497.4 981.2 1003.6 1026.5 1031.3 1038.0 3689.4 3771.1 3849.9 4022.1 4044.9 12858.0 13119.7 13267.7 13271.3 13279.0 12739.3 12787.6 13077.3 13108.4 13125.1 8534.7 9656.3 9716.4 9805.9 9870.9 6163.2 6503.1 6677.0 6703.8 6749.5 11308.9 11338.7 11607.7 11637.2 11673.8 11811.2 11839.8 11874.1 12136.3 12166.5 8116.0 8246.0 8256.8 8273.1 8294.5 9879.4 9965.0 10027.5 10073.7 10074.7 8754.6 8789.7 8867.1 8879.7 9054.1 8611.9 8645.9 8663.7 8770.7 8857.9 7559.0 7707.0 7766.2 7771.9 7778.7 5742.7 5808.9 5871.3 5905.7 5983.9 5802.4 5815.2 5949.4 5989.9 6113.6 19:24:02 INFO - PID 1637 | 19:24:02 INFO - PID 1637 | [#4] prototype.html Cycles:180 Average:5377.60 Median:5791.10 stddev:1493.90 (25.8%) stddev-sans-first:1448.92 19:24:02 INFO - PID 1637 | Values: 312.9 373.4 391.3 392.5 394.2 5898.0 5905.5 6112.6 6143.9 6173.9 5064.7 5150.4 5567.1 5930.2 5962.9 4643.5 4865.9 4882.3 4883.3 4904.5 4628.5 4768.8 4866.2 4875.6 4945.9 4467.6 4807.7 4871.2 4872.7 4897.4 4635.2 4817.8 4918.5 4922.6 4925.2 4357.7 4446.8 4461.3 4464.0 4504.6 5073.9 5518.9 5842.1 5884.2 5912.7 5529.5 5661.2 5699.9 5710.2 5746.8 5267.4 6381.8 6442.2 6442.3 6467.2 4980.9 4998.2 4999.5 5021.0 5123.4 6048.9 6695.1 6772.8 6787.0 6829.6 5054.3 5539.8 5572.7 5629.6 5637.8 5547.5 5783.7 5788.5 5793.7 5828.9 5288.0 5545.9 5550.7 5932.7 5941.3 4853.5 5149.6 5227.3 5281.6 6402.6 1666.7 1846.0 1925.3 1991.8 2029.5 1462.8 1535.5 1573.8 1592.5 1620.5 5763.3 5976.6 6004.7 6014.7 6025.6 5531.8 6584.0 6599.8 6606.1 6610.9 4695.1 5274.5 5968.6 5987.3 6012.3 2894.4 2944.3 3290.7 3448.4 3480.8 6894.5 6912.4 6915.5 6934.0 6976.5 6050.9 6643.5 6753.6 6765.7 6855.9 6169.8 6453.9 6706.2 6831.0 6867.9 5000.9 6227.0 6540.5 6574.7 6654.6 4953.8 5352.8 6255.0 6385.6 6650.9 6360.8 6924.4 6932.7 6950.8 6983.0 5002.9 5058.5 5096.4 5114.7 5147.9 5564.8 6649.3 6650.9 6653.5 6684.6 6220.9 6406.9 6412.6 6420.5 6424.8 6392.9 6400.5 6432.1 6453.7 6506.7 6058.8 6277.4 6310.6 6310.7 6315.2 5595.7 6252.0 6348.2 6420.2 6448.5 5403.9 6021.3 6065.0 6172.5 6242.1 19:24:02 INFO - PID 1637 | 19:24:02 INFO - PID 1637 | [#5] yui.html Cycles:180 Average:19014.61 Median:4058.52 stddev:26721.75 (658.4%) stddev-sans-first:26761.06 19:24:02 INFO - PID 1637 | Values: 629.9 702.3 753.0 754.6 758.4 14985.3 15813.1 15819.0 15827.4 15840.8 14733.1 14882.6 14911.6 14916.7 14968.4 913.0 941.5 953.3 971.0 981.1 981.2 988.6 989.2 989.6 989.6 1031.3 1035.6 1037.4 1037.4 1041.3 1015.0 1024.2 1025.4 1029.8 1034.7 761.0 768.3 768.8 769.2 770.0 15685.5 15974.8 16183.4 16192.2 16253.3 14243.2 14514.5 14670.9 14678.6 14692.9 9885.8 11765.0 11864.8 12012.9 12238.1 3216.0 3240.6 3254.5 3317.9 3320.8 88136.0 104280.0 105914.0 113197.0 117200.0 10409.0 10927.2 11284.0 11329.5 11343.1 24507.1 24745.5 24944.0 24992.6 25075.4 6697.2 7835.0 7845.6 7921.4 7983.2 3922.2 4194.9 4219.0 4267.8 4275.7 3487.7 3542.3 3547.4 3565.9 3573.1 580.6 645.2 649.4 655.1 662.0 635.3 661.4 664.7 681.7 695.6 9600.6 9748.0 10101.6 10114.3 10305.9 646.6 650.4 676.9 681.4 699.5 4562.6 4726.8 4844.5 4858.0 4889.6 74062.6 75900.0 92722.5 93985.5 94087.6 63623.7 70549.6 82680.6 83383.7 85580.6 64913.7 67208.0 68031.7 68543.7 69313.0 8947.2 9385.2 9391.2 9475.7 9493.6 47019.8 48344.5 49687.5 49849.3 50245.7 48226.0 62676.7 63857.0 64893.0 65073.3 11551.8 11793.6 11834.9 11845.6 11886.8 12437.9 12563.6 12727.0 12744.0 13042.1 11728.6 12202.6 12276.9 12284.9 12499.1 11346.5 11686.0 11702.6 11819.3 12056.5 11572.4 11618.0 11620.2 11693.2 11735.6 9830.5 10101.5 10147.9 10218.4 10386.6 8188.8 8804.8 8937.0 9122.6 9318.9 19:24:02 INFO - PID 1637 | -------- Summary: end -------- 19:24:02 INFO - PID 1637 | 19:24:03 INFO - TEST-INFO | 1637: exit 0 19:24:03 INFO - TEST-OK | dromaeo_css | took 1115991ms 19:24:03 INFO - TEST-START | kraken 19:24:03 INFO - operating with platform_type : mac_ 19:24:03 INFO - Initialising browser for kraken test... 19:24:03 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/tmpI4kVEe/profile http://localhost:49241/getInfo.html) 19:24:06 INFO - PID 1648 | 2017-03-16 19:24:06.140 plugin-container[1649:9995] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 19:24:06 INFO - PID 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:24:06 INFO - PID 1648 | 2017-03-16 19:24:06.146 plugin-container[1649:9995] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x431b, name = 'com.apple.CFPasteboardClient' 19:24:06 INFO - PID 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:24:06 INFO - PID 1648 | 2017-03-16 19:24:06.146 plugin-container[1649:9995] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:24:06 INFO - PID 1648 | __metrics Screen width/height:1600/1200 19:24:06 INFO - PID 1648 | colorDepth:24 19:24:06 INFO - PID 1648 | Browser inner width/height: 1024/689 19:24:06 INFO - PID 1648 | __metrics 19:24:06 ERROR - PID 1648 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3350: TypeError: this.tabs is undefined 19:24:07 INFO - TEST-INFO | 1648: exit 0 19:24:07 INFO - Browser initialized. 19:24:07 INFO - Running cycle 1/1 for kraken test... 19:24:07 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/tmpI4kVEe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:24:09 INFO - PID 1650 | 2017-03-16 19:24:09.426 plugin-container[1651:10116] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a47, name = 'com.apple.tsm.portname' 19:24:09 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:24:09 INFO - PID 1650 | 2017-03-16 19:24:09.426 plugin-container[1651:10116] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9017, name = 'com.apple.CFPasteboardClient' 19:24:09 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:24:09 INFO - PID 1650 | 2017-03-16 19:24:09.426 plugin-container[1651:10116] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:24:19 INFO - PID 1650 | RSS: Main: 227659776 19:24:19 INFO - PID 1650 | 19:24:21 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 19:24:22 INFO - PID 1650 | RSS: Main: 241778688 19:24:22 INFO - PID 1650 | 19:24:25 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 19:24:26 INFO - PID 1650 | RSS: Main: 250089472 19:24:26 INFO - PID 1650 | 19:24:29 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 19:24:30 INFO - PID 1650 | RSS: Main: 252850176 19:24:30 INFO - PID 1650 | 19:24:32 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 19:24:32 INFO - PID 1650 | RSS: Main: 255102976 19:24:32 INFO - PID 1650 | 19:24:35 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:24:35 INFO - PID 1650 | RSS: Main: 255459328 19:24:35 INFO - PID 1650 | 19:24:38 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 19:24:39 INFO - PID 1650 | RSS: Main: 261206016 19:24:39 INFO - PID 1650 | 19:24:42 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 19:24:42 INFO - PID 1650 | RSS: Main: 262127616 19:24:42 INFO - PID 1650 | 19:24:45 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 19:24:46 INFO - PID 1650 | RSS: Main: 262074368 19:24:46 INFO - PID 1650 | 19:24:48 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:24:48 INFO - PID 1650 | RSS: Main: 261128192 19:24:48 INFO - PID 1650 | 19:24:50 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:24:51 INFO - PID 1650 | RSS: Main: 261160960 19:24:51 INFO - PID 1650 | 19:24:53 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:24:54 INFO - PID 1650 | RSS: Main: 261267456 19:24:54 INFO - PID 1650 | 19:24:57 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:24:57 INFO - PID 1650 | RSS: Main: 261894144 19:24:57 INFO - PID 1650 | 19:25:00 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:25:01 INFO - PID 1650 | RSS: Main: 261689344 19:25:01 INFO - PID 1650 | 19:25:03 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 19:25:03 INFO - PID 1650 | RSS: Main: 263258112 19:25:03 INFO - PID 1650 | 19:25:03 INFO - PID 1650 | __start_tp_report 19:25:03 INFO - PID 1650 | _x_x_mozilla_page_load 19:25:03 INFO - PID 1650 | _x_x_mozilla_page_load_details 19:25:03 INFO - PID 1650 | |i|pagename|runs| 19:25:03 INFO - PID 1650 | |0;ai-astar;107;123;121;117;122;119;121;120;120;120 19:25:03 INFO - PID 1650 | |1;audio-beat-detection;151;113;127;188;119;115;119;105;169;146 19:25:03 INFO - PID 1650 | |2;audio-dft;196;216;192;260;258;271;253;282;200;196 19:25:03 INFO - PID 1650 | |3;audio-fft;85;60;63;90;61;70;55;58;77;57 19:25:03 INFO - PID 1650 | |4;audio-oscillator;86;71;93;69;71;69;81;75;105;117 19:25:03 INFO - PID 1650 | |5;imaging-gaussian-blur;112;87;89;87;88;97;89;88;88;88 19:25:03 INFO - PID 1650 | |6;imaging-darkroom;96;97;96;106;96;98;95;97;95;96 19:25:03 INFO - PID 1650 | |7;imaging-desaturate;92;109;140;101;93;93;91;92;91;96 19:25:03 INFO - PID 1650 | |8;json-parse-financial;67;70;67;86;61;62;67;68;69;67 19:25:03 INFO - PID 1650 | |9;json-stringify-tinderbox;60;65;61;73;64;66;58;60;59;60 19:25:03 INFO - PID 1650 | |10;stanford-crypto-aes;99;72;98;104;103;82;82;86;83;87 19:25:03 INFO - PID 1650 | |11;stanford-crypto-ccm;154;175;151;141;163;141;159;148;200;148 19:25:03 INFO - PID 1650 | |12;stanford-crypto-pbkdf2;190;186;199;189;188;188;189;186;193;193 19:25:03 INFO - PID 1650 | |13;stanford-crypto-sha256-iterative;71;73;67;71;71;67;68;75;72;86 19:25:03 INFO - PID 1650 | __end_tp_report 19:25:03 INFO - PID 1650 | __start_cc_report 19:25:03 INFO - PID 1650 | _x_x_mozilla_cycle_collect,991 19:25:03 INFO - PID 1650 | __end_cc_report 19:25:03 INFO - PID 1650 | __startTimestamp1489717503605__endTimestamp 19:25:03 INFO - PID 1650 | 19:25:03 INFO - PID 1650 | ------- Summary: start ------- 19:25:03 INFO - PID 1650 | Number of tests: 14 19:25:03 INFO - PID 1650 | 19:25:03 INFO - PID 1650 | [#0] ai-astar Cycles:10 Average:119.00 Median:120.50 stddev:4.52 (3.8%) stddev-sans-first:1.73 19:25:03 INFO - PID 1650 | Values: 107.0 123.0 121.0 117.0 122.0 119.0 121.0 120.0 120.0 120.0 19:25:03 INFO - PID 1650 | 19:25:03 INFO - PID 1650 | [#1] audio-beat-detection Cycles:10 Average:135.20 Median:136.50 stddev:27.29 (20.0%) stddev-sans-first:28.34 19:25:03 INFO - PID 1650 | Values: 151.0 113.0 127.0 188.0 119.0 115.0 119.0 105.0 169.0 146.0 19:25:03 INFO - PID 1650 | 19:25:03 INFO - PID 1650 | [#2] audio-dft Cycles:10 Average:232.40 Median:255.50 stddev:35.58 (13.9%) stddev-sans-first:35.21 19:25:03 INFO - PID 1650 | Values: 196.0 216.0 192.0 260.0 258.0 271.0 253.0 282.0 200.0 196.0 19:25:03 INFO - PID 1650 | 19:25:03 INFO - PID 1650 | [#3] audio-fft Cycles:10 Average:67.60 Median:66.50 stddev:12.40 (18.7%) stddev-sans-first:11.45 19:25:03 INFO - PID 1650 | Values: 85.0 60.0 63.0 90.0 61.0 70.0 55.0 58.0 77.0 57.0 19:25:03 INFO - PID 1650 | 19:25:03 INFO - PID 1650 | [#4] audio-oscillator Cycles:10 Average:83.70 Median:73.00 stddev:16.64 (22.8%) stddev-sans-first:17.63 19:25:03 INFO - PID 1650 | Values: 86.0 71.0 93.0 69.0 71.0 69.0 81.0 75.0 105.0 117.0 19:25:03 INFO - PID 1650 | 19:25:03 INFO - PID 1650 | [#5] imaging-gaussian-blur Cycles:10 Average:91.30 Median:88.00 stddev:7.83 (8.9%) stddev-sans-first:3.08 19:25:03 INFO - PID 1650 | Values: 112.0 87.0 89.0 87.0 88.0 97.0 89.0 88.0 88.0 88.0 19:25:03 INFO - PID 1650 | 19:25:03 INFO - PID 1650 | [#6] imaging-darkroom Cycles:10 Average:97.20 Median:96.00 stddev:3.22 (3.4%) stddev-sans-first:3.39 19:25:03 INFO - PID 1650 | Values: 96.0 97.0 96.0 106.0 96.0 98.0 95.0 97.0 95.0 96.0 19:25:03 INFO - PID 1650 | 19:25:03 INFO - PID 1650 | [#7] imaging-desaturate Cycles:10 Average:99.80 Median:92.00 stddev:15.22 (16.5%) stddev-sans-first:15.88 19:25:03 INFO - PID 1650 | Values: 92.0 109.0 140.0 101.0 93.0 93.0 91.0 92.0 91.0 96.0 19:25:03 INFO - PID 1650 | 19:25:03 INFO - PID 1650 | [#8] json-parse-financial Cycles:10 Average:68.40 Median:67.50 stddev:6.80 (10.1%) stddev-sans-first:7.20 19:25:03 INFO - PID 1650 | Values: 67.0 70.0 67.0 86.0 61.0 62.0 67.0 68.0 69.0 67.0 19:25:03 INFO - PID 1650 | 19:25:03 INFO - PID 1650 | [#9] json-stringify-tinderbox Cycles:10 Average:62.60 Median:62.50 stddev:4.53 (7.2%) stddev-sans-first:4.70 19:25:03 INFO - PID 1650 | Values: 60.0 65.0 61.0 73.0 64.0 66.0 58.0 60.0 59.0 60.0 19:25:03 INFO - PID 1650 | 19:25:03 INFO - PID 1650 | [#10] stanford-crypto-aes Cycles:10 Average:89.60 Median:84.50 stddev:10.72 (12.7%) stddev-sans-first:10.82 19:25:03 INFO - PID 1650 | Values: 99.0 72.0 98.0 104.0 103.0 82.0 82.0 86.0 83.0 87.0 19:25:03 INFO - PID 1650 | 19:25:03 INFO - PID 1650 | [#11] stanford-crypto-ccm Cycles:10 Average:158.00 Median:156.50 stddev:18.02 (11.5%) stddev-sans-first:19.05 19:25:03 INFO - PID 1650 | Values: 154.0 175.0 151.0 141.0 163.0 141.0 159.0 148.0 200.0 148.0 19:25:03 INFO - PID 1650 | 19:25:03 INFO - PID 1650 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:190.10 Median:189.50 stddev:3.96 (2.1%) stddev-sans-first:4.20 19:25:03 INFO - PID 1650 | Values: 190.0 186.0 199.0 189.0 188.0 188.0 189.0 186.0 193.0 193.0 19:25:03 INFO - PID 1650 | 19:25:03 INFO - PID 1650 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.10 Median:71.50 stddev:5.53 (7.7%) stddev-sans-first:5.85 19:25:03 INFO - PID 1650 | Values: 71.0 73.0 67.0 71.0 71.0 67.0 68.0 75.0 72.0 86.0 19:25:03 INFO - PID 1650 | -------- Summary: end -------- 19:25:03 INFO - PID 1650 | 19:25:04 INFO - TEST-INFO | 1650: exit 0 19:25:04 INFO - TEST-OK | kraken | took 61097ms 19:25:04 INFO - SUITE-END | took 1177s 19:25:04 INFO - Completed test suite (00:19:37) 19:25:04 INFO - javascript benchmark 19:25:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7794.713360861561, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3388.525606415102, 3407.4548072308435, 3441.070910854071, 3448.4654878493834, 3460.515527826105, 5892.998190307652, 5903.2916050074, 5919.408059194078, 5921.0, 5928.614640048405, 4956.859922217892, 5635.295588051494, 5674.063779476382, 5791.21818554495, 5795.884082318351, 5178.818741344045, 5262.342207224097, 5315.309009828717, 5346.759395827197, 5395.73021348931, 5393.865153371158, 5394.595405344595, 5398.64908780928, 5407.729613519328, 5427.864303392407, 888.4281341817577, 5243.164892287707, 5257.185136303879, 5383.246345511612, 5394.352677678684, 4616.792244349012, 5297.099493086146, 5313.1764576490705, 5319.5478384337375, 5342.2787923630585, 5275.419703832576, 5296.708681022542, 5302.363716354023, 5315.973420132875, 5316.680999140064, 5601.60788744788, 5693.089105743103, 5711.971440142815, 5784.68184249866, 5911.615744976562, 6152.261728592553, 6157.4458298753325, 6229.626222426669, 6235.220597425322, 6242.937570624281, 6196.96901515494, 6228.283747369065, 6239.532035097341, 6240.531960102965, 6243.500519958392, 5874.441928016831, 5895.823125306248, 5910.7931222407005, 5922.200502932092, 5959.702014899238, 6200.3799620037635, 6262.5929314594405, 6265.592736472115, 6312.242530896322, 6315.936840631535, 5054.368203974504, 6215.595986260879, 6223.1287619733275, 6268.216472940882, 6285.779997700059, 6200.224971878515, 6254.499640028786, 6270.874582508324, 6276.246850377984, 6278.968605157036, 6136.059583474799, 6189.96905015481, 6214.9689251554355, 6216.720247588869, 6234.719437625317, 6220.066989951543, 6235.750569977241, 6238.875222495525, 6250.937490625036, 6251.12484252205, 5035.16919708244, 5055.165897626924, 5060.696358218519, 5062.873428164326, 5067.847964561069, 4410.823567057346, 4460.130274596488, 4495.213337665896, 4504.414426124583, 4539.659525535598, 5808.5933984621515, 5829.446202610745, 5837.7664893404635, 5850.1224816277045, 5858.648481091148, 6211.503079753609, 6216.409441103178, 6260.655663938527, 6275.215598050244, 6286.339934306923, 5969.2240008799445, 5972.034370276052, 5980.491658209101, 5991.610545314627, 5996.67018313997, 5552.056150454352, 5559.638623489541, 5569.888602227933, 5578.609497335228, 5586.832395028156, 6210.378962103753, 6274.905876411857, 6362.713677884413, 6382.744690212339, 6408.58344207625, 6255.56211065221, 6310.400511951358, 6314.336994615683, 6315.621062736251, 6326.905096423466, 6422.357764223727, 6433.0, 6442.516811239082, 6442.774502892376, 6452.9997850333275, 6143.450468055707, 6191.25704915413, 6196.690165491797, 6205.379462053758, 6256.968715156395, 6223.3465486125115, 6247.375262473716, 6260.248770147611, 6271.498280137487, 6278.309385967448, 6234.251889773075, 6253.812385628438, 6260.436560709558, 6269.341719119427, 6276.937230627818, 6121.785737499166, 6134.344744984565, 6191.566590338633, 6198.194234749454, 6268.153799237227, 6039.63762174271, 6049.243844519435, 6077.088436734349, 6123.908141377972, 6126.387361264016, 5663.915041274467, 6110.175126357884, 6196.378454501079, 6222.875542489215, 6234.563580549499, 5958.285005799332, 5984.551158663205, 6021.909671354845, 6030.0, 6034.741239730496], "value": 5740.782929111145, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [700.7267165805337, 741.7107328142025, 814.2102160903921, 850.1965642467862, 945.5177859291755, 37141.628583714155, 37828.62171378286, 37886.621133788656, 37910.24179516408, 37941.81029094854, 36452.635473645256, 36881.077973050684, 36917.630823691754, 36920.26159476809, 37094.258114837685, 7531.096268447794, 7732.0, 10699.679009629723, 10977.451127443635, 11004.614838480655, 11005.394703291317, 11076.335419874793, 11087.944560277187, 11106.666799996014, 11131.72170695731, 11071.391073490955, 11132.0, 11158.218924675277, 11159.27464714791, 11182.0, 10671.733206669818, 11066.778664426707, 11098.389588572625, 11111.777764444747, 11124.276922000045, 10889.074428673572, 10962.232643714942, 10968.670939871816, 10977.83533247002, 10989.285696429786, 31319.0, 38259.852204433635, 38270.23459530822, 38333.0, 38366.848994530206, 3715.2198038411966, 3768.8869333920024, 3785.7160712946365, 3798.924021519582, 3948.4008976364617, 27740.02909898144, 28140.577891331646, 28177.154685359474, 28305.15084547467, 28520.42959140826, 2590.468953864472, 3068.7330530610257, 3110.7044830741042, 3135.0, 3218.2598002459567, 244160.0, 249644.0, 277127.99999999796, 353677.0, 353818.0, 45850.624481265615, 46029.76985115053, 46095.8476038095, 46145.76927115343, 48275.75862120667, 41300.58699413027, 45818.770906145255, 48900.887389076364, 49175.0, 49192.0, 14149.151050936976, 14341.92829035848, 14467.0, 14472.927635361755, 14483.710325793636, 11516.481758320895, 11625.302481850948, 11664.591739289084, 11676.416179191006, 11722.88277117235, 13457.327133643279, 13541.526046588322, 13672.0, 13675.974301927396, 13705.931470342584, 5291.8567979968175, 5847.0, 5977.282726072899, 6021.60859544137, 6022.126791615191, 993.6075250276245, 1104.6644706069665, 1129.4239937631867, 1143.0112952296204, 1193.1886317304316, 22340.776592234193, 22474.775252247597, 25576.488470230863, 25656.871715641304, 26085.347866303116, 1226.1294480918648, 1240.7146356338092, 1250.3373212197369, 1257.6808721506443, 1265.1839563481503, 13066.54267100647, 13434.0, 13446.058775885595, 13488.39302231402, 13505.797413038812, 443565.0, 460023.0, 463597.0, 465786.0, 466341.0, 54588.45411545913, 54954.72522637441, 55108.17337739937, 55201.0, 55299.0, 1400.138914567554, 1456.7013762178728, 1460.5618314505605, 1460.6421426750514, 1478.9260536973109, 23807.285781426584, 25136.74863251344, 25274.620880687173, 25512.97948082056, 25604.615930761425, 44555.55444445514, 44611.77694111509, 44655.330170048124, 44692.77653611711, 44875.775621121684, 442985.0, 442988.0, 443924.0, 445039.0, 446800.0, 31518.842405787826, 32038.718451261688, 32111.839440803582, 32159.07045577273, 32167.356652867278, 34004.1498962518, 40928.59071409367, 42216.0, 42350.0, 42542.36186457144, 32980.83509582437, 34559.308813824086, 34692.30615387729, 35636.821815890755, 35654.64345356617, 33272.00183994484, 34508.48237276493, 35358.646413535535, 35515.0, 35556.0, 26426.735732643196, 34557.48163777495, 34737.47893781646, 34862.47706284357, 34943.77696780601, 24839.379015524755, 24980.37549061288, 27325.316867078483, 28368.0, 30388.69611303947, 23320.742322220452, 23369.76630233676, 23489.647655284843, 23637.881810590836, 23671.526569468857], "value": 18546.611476539383, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [402.2919661395946, 416.3733580960654, 424.49485112715865, 433.98264069437226, 436.38687643860385, 16610.25253863576, 19750.11124499399, 20789.584208315824, 21195.57608847822, 21548.461288467795, 18464.07679616017, 21441.356759297247, 21606.891965540188, 22139.114435422565, 22173.445663858412, 5894.970525147379, 6654.667266636673, 6675.8331041724105, 6692.130023097015, 6705.932940670604, 6405.775797847076, 6470.0, 6479.740810367603, 6547.836304092412, 6565.868682626345, 6549.44329731971, 6568.737250509974, 6575.572587781779, 6578.736850525973, 6586.604803711769, 6400.071989561511, 6469.7412103516035, 6511.055896894947, 6524.151860258184, 6569.37590928861, 6324.936750632481, 6354.3963323484195, 6413.358664133596, 6420.325865784119, 6439.098526206335, 25684.614730779052, 25876.482470350675, 25935.740642593522, 25991.480170396677, 26020.73979260202, 2839.9432011359863, 2850.6009158717798, 2880.5168777948948, 2893.32006978362, 2895.319599894023, 15712.764308535381, 16311.021338719715, 16495.0, 16530.88107369206, 16573.08848013359, 1996.2015193922548, 1997.1312479071505, 2004.9097790599458, 2057.094878253579, 2057.9485512862298, 159211.20394398068, 165445.9999999988, 165978.34021659748, 166051.3394866048, 168764.81116699555, 19294.45860190887, 19401.417957461297, 20707.4823129423, 21925.0, 22588.418810683095, 34339.82830085834, 35868.820655896554, 35968.820155899055, 35969.28061438809, 36160.81919590385, 9934.453605051647, 10260.384376937407, 10424.478776061167, 10537.262391632512, 10562.894371056345, 8285.088640249567, 8285.130061343472, 8294.875576866352, 8424.241818236389, 8486.321094312563, 8408.621612027351, 8834.249088827393, 10199.082082612453, 10207.693769186937, 10218.233632477593, 4361.797292367334, 4430.964789119464, 4430.983902324435, 4440.444944381952, 4464.486584042812, 1019.9494520643749, 1032.9845052324222, 1070.384528895888, 1090.6346373964811, 1111.699841042914, 22723.772762272165, 23029.193978211017, 23071.769282307298, 23437.76562234356, 23502.294931152093, 1044.749260177567, 1089.428050273597, 1102.3000394749392, 1143.085531574737, 1156.4738044189874, 11317.773644527064, 12161.756764864831, 12186.0, 12316.322602256783, 12319.938400307941, 396062.0196898997, 616430.0, 625506.0, 707729.0, 742958.0, 111737.0, 128570.0, 129577.0, 130410.0, 131481.0, 1210.0924306769923, 1262.4129779652505, 1291.7351942851692, 1293.3080801771007, 1306.3729409883308, 20820.107274790716, 20905.89547052316, 20941.48702155751, 21315.680264796352, 21380.82405467652, 59883.0, 62786.0, 62861.48554057969, 63291.683541581995, 63543.6822815883, 78327.60836195783, 81378.77931831146, 88179.55910220408, 89150.55424722836, 91326.17347652877, 9332.533373331442, 11294.322340659586, 11355.261907975955, 11467.598634047768, 11828.17202795796, 13183.868161318263, 13190.208587484782, 13458.52895148665, 13495.122817016863, 13674.794878076638, 11396.658100256675, 11486.82769758471, 11548.711282218012, 11631.069514438655, 11792.233504822503, 9535.570899309687, 10566.573512576011, 11144.442777860912, 11427.771444571228, 11483.942580287045, 11534.884651153381, 11824.40877956116, 11858.229215100991, 11859.762804744028, 11878.465469054086, 11521.481533331187, 11543.595974140864, 11802.52789888395, 11847.466863991314, 11910.0, 9979.850302245617, 10239.747255603756, 10260.948695256477, 10264.692059238234, 10364.896351036694], "value": 11618.12742184404, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1393.7212557488506, 1572.4182052640517, 1584.6592982508757, 1596.2657177698254, 1653.4378311374132, 10307.433091179983, 10666.679999600025, 10807.56769729212, 10840.891591084086, 11039.779204415907, 9688.837339519268, 10636.361818290909, 10754.247202695815, 10911.454427278624, 10951.83572246417, 2022.5449273913398, 2344.882755862209, 2375.964360534593, 2380.9642855357183, 2384.4277373430336, 2049.549099198174, 2117.5129720164373, 2185.3443966809973, 2338.205010296499, 2371.8695471749134, 2333.603287441139, 2343.2150229672993, 2346.9413264668433, 2352.811775058, 2357.56385068762, 2278.111536500766, 2287.942801429961, 2290.770922907704, 2297.931062068132, 2299.9540009199804, 2306.527161931817, 2363.775441333071, 2366.2664573981947, 2368.5144545368157, 2371.181942229945, 9439.480828554426, 10999.01008909202, 11006.95433933775, 11012.669619911416, 11029.944850275777, 5648.152777083429, 5866.97327967604, 5870.119482077723, 5870.823875283749, 5892.764289428418, 3298.208429976812, 3431.279431319426, 3552.0231936217488, 3610.4584312353095, 3647.5258216431694, 1139.3961200563715, 1171.3557543351124, 1196.994015029928, 1214.6538236602614, 1242.9689257768537, 13259.204447733167, 13557.52548660802, 14542.636434089021, 14913.0, 14982.850171498363, 4489.753063581535, 4533.00547758985, 4688.413948256468, 4695.788689508912, 4697.262013055123, 11257.099432045417, 11271.323720576627, 11286.943565282285, 11297.717557060974, 11332.376719280519, 5634.04221282383, 5705.885882282331, 5711.543076553949, 5734.913976290359, 5741.110127930177, 5121.052605268037, 5166.896662066738, 5200.97399513, 5212.635115541952, 5222.451642577551, 3748.34403979308, 3754.342989976743, 3834.8082595870096, 3859.864904728321, 3874.9150237933422, 1040.1626690514058, 1040.8542804007445, 1041.073444634276, 1052.6315789473622, 1052.826283663187, 889.6396959231522, 910.2081189365294, 924.1174678182454, 941.5715849288557, 947.7109481608038, 6290.716917738713, 6329.430351268408, 6369.299377068519, 6434.903476447857, 6497.383520884756, 981.1954197558076, 1003.6286573967533, 1026.5124066068706, 1031.3090229546222, 1037.9792404151874, 3689.409694448875, 3771.0949372150485, 3849.9230015399535, 4022.0749227677798, 4044.928094055078, 12858.0, 13119.737605247841, 13267.73464530704, 13271.270080145492, 13279.0, 12739.299338536284, 12787.552435664707, 13077.346132693327, 13108.410121544553, 13125.081244312807, 8534.74395768128, 9656.32405731606, 9716.417014979124, 9805.852912206323, 9870.851937220947, 6163.19878415803, 6503.122078519339, 6677.0, 6703.798886033427, 6749.493787965824, 11308.886911131112, 11338.716532086764, 11607.6517704469, 11637.185397022127, 11673.766524669629, 11811.232269902428, 11839.82240266414, 11874.109441791727, 12136.271823690608, 12166.452509637265, 8116.0, 8246.01047874259, 8256.844041834269, 8273.089960104504, 8294.502329860228, 9879.357841740264, 9964.953679863509, 10027.49862506857, 10073.748156295858, 10074.69775906724, 8754.649814007624, 8789.736307910773, 8867.0689577596, 8879.733607991771, 9054.139856713602, 8611.870821937551, 8645.91354086451, 8663.740087797376, 8770.736877893672, 8857.867131993155, 7559.0, 7707.036620422447, 7766.184550622103, 7771.8834217485655, 7778.688852445839, 5742.684152371659, 5808.854778630568, 5871.266091738533, 5905.675187864708, 5983.880322393615, 5802.4197580241635, 5815.156802263733, 5949.434803693643, 5989.940100598938, 6113.633182009094], "value": 5006.715951459744, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [312.91864115330014, 373.3932359915138, 391.2956677979636, 392.5093632958802, 394.1899396739699, 5897.970510147454, 5905.498032667223, 6112.602680825755, 6143.8771224575485, 6173.931732869494, 5064.746762661862, 5150.351887396035, 5567.053600887849, 5930.199423077883, 5962.8807423851495, 4643.489216186217, 4865.8783530411665, 4882.267659851033, 4883.29192267121, 4904.534069263432, 4628.514006029369, 4768.761561921908, 4866.223828302679, 4875.609951203913, 4945.944325652966, 4467.642588592921, 4807.735574543416, 4871.2449570316485, 4872.731999740013, 4897.363342765436, 4635.188841952645, 4817.78319975602, 4918.532739389752, 4922.55696987271, 4925.211966085445, 4357.716748411343, 4446.7554284514335, 4461.330800379969, 4463.995600989751, 4504.572065653758, 5073.898522029575, 5518.862028449281, 5842.065269556893, 5884.176215329857, 5912.733926973297, 5529.502344788988, 5661.2357331760095, 5699.857503562403, 5710.229119068956, 5746.827854930554, 5267.446918073585, 6381.840453988641, 6442.162518872517, 6442.25914019889, 6467.191601049869, 4980.854403487182, 4998.175301075318, 4999.475055119233, 5021.0, 5123.359580052494, 6048.879022419571, 6695.062691223233, 6772.8306792330095, 6787.0, 6829.556078854858, 5054.267131265946, 5539.750711218005, 5572.693501857437, 5629.577781666309, 5637.802676906289, 5547.5007249346945, 5783.71081445926, 5788.494991302292, 5793.710314484259, 5828.912566311508, 5287.9735601321745, 5545.863098064887, 5550.722463876871, 5932.673702946379, 5941.316748573883, 4853.466118726938, 5149.588032957355, 5227.320448341691, 5281.55106815925, 6402.551821372553, 1666.6500034992546, 1845.9538511537319, 1925.3357591630865, 1991.8207361337259, 2029.5433527456205, 1462.8464011278663, 1535.4702627593463, 1573.7645947930819, 1592.5302035899438, 1620.4558842616977, 5763.2507773989955, 5976.641401515923, 6004.729787159588, 6014.669193194329, 6025.6083354581815, 5531.778728850882, 6583.967080164569, 6599.8020059398295, 6606.141201643852, 6610.933890661032, 4695.131400690883, 5274.525292723673, 5968.552358573124, 5987.2815262168815, 6012.278526576752, 2894.435585060935, 2944.3080875994124, 3290.68738469845, 3448.396530607184, 3480.7911525308564, 6894.482913781487, 6912.377885990285, 6915.51591388598, 6934.0, 6976.4767642426, 6050.909236361458, 6643.468522518187, 6753.594784312859, 6765.661716914233, 6855.897161542581, 6169.84575385619, 6453.9351007083305, 6706.161729783777, 6830.965845170742, 6867.896981545281, 5000.87497812543, 6226.968865155645, 6540.542162048801, 6574.671266436755, 6654.567453115532, 4953.7523123844385, 5352.812651557177, 6255.030470277115, 6385.647535044139, 6650.90023649636, 6360.809175724736, 6924.4114250289285, 6932.6533673315125, 6950.756723514653, 6982.965085174542, 5002.949970500394, 5058.459078645037, 5096.413912399973, 5114.693118412907, 5147.948520514897, 5564.8330550083565, 6649.2685804562425, 6650.933490665225, 6653.548026210675, 6684.598924064571, 6220.906686399798, 6406.860638287299, 6412.551121421456, 6420.518461115342, 6424.839379015562, 6392.9360706392345, 6400.519961003037, 6432.131662225727, 6453.709583068679, 6506.707198175999, 6058.757649694139, 6277.372262773686, 6310.558260921692, 6310.6844657766005, 6315.1900251767565, 5595.664260144405, 6251.9987208185885, 6348.238211414475, 6420.1974753155855, 6448.484121270193, 5403.918941215964, 6021.337652858095, 6065.029595264737, 6172.475339596184, 6242.094896239929], "value": 4934.833953358638, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [629.9023651334045, 702.3187508117124, 752.9887051694226, 754.5585832288111, 758.4040713277539, 14985.325660345283, 15813.130277834714, 15819.0, 15827.446039388624, 15840.76238856418, 14733.116013039225, 14882.553523394316, 14911.627209319773, 14916.7016659667, 14968.401263949429, 912.9863052054225, 941.4680705401427, 953.3279038278032, 970.9902900971006, 981.0532835813439, 981.1709105805583, 988.6144403682533, 989.2481713897417, 989.5645915797062, 989.6288891665625, 1031.339942436841, 1035.6219979707428, 1037.3516552154904, 1037.3931250218632, 1041.297124441003, 1014.9746256343614, 1024.1960061351804, 1025.4257615735212, 1029.8352263637782, 1034.7257976636197, 761.0486891385768, 768.3161985832613, 768.8423873106, 769.1616138409161, 770.0336078221795, 15685.451009214737, 15974.840251597569, 16183.35266589335, 16192.19039048043, 16253.349866005346, 14243.216623085726, 14514.491992780308, 14670.853291467056, 14678.559643210721, 14692.853071469255, 9885.75285617858, 11765.0, 11864.822027669505, 12012.939935300356, 12238.143329966906, 3215.951760723591, 3240.5949256342956, 3254.4630136027527, 3317.93364132716, 3320.8339583020993, 88136.0, 104280.0, 105914.0, 113197.0, 117200.0, 10408.95910408953, 10927.235093543375, 11284.0, 11329.546818127348, 11343.149263805248, 24507.142250021163, 24745.505089898103, 24944.002239910562, 24992.625110623354, 25075.373115672253, 6697.162854643169, 7835.0, 7845.607719613997, 7921.3550395020675, 7983.2016798320865, 3922.156736301709, 4194.874153775391, 4219.0, 4267.8079486423185, 4275.700700950903, 3487.738419618539, 3542.291541691672, 3547.396942519778, 3565.928681426357, 3573.088862340139, 580.5848818095083, 645.1645119570168, 649.389573800626, 655.131950166032, 662.0301258656092, 635.3075148088606, 661.4113439039259, 664.6875968294894, 681.6956313105004, 695.5722230828004, 9600.567974441166, 9747.985922554159, 10101.646442374482, 10114.291999560108, 10305.948470257601, 646.5958775765147, 650.3918835888364, 676.9264363594888, 681.4071757570945, 699.4998576018171, 4562.5665561771575, 4726.787294571753, 4844.539768721966, 4857.951420485821, 4889.608831293559, 74062.62968685121, 75900.0, 92722.53638731898, 93985.53007235056, 94087.64780880396, 63623.681881590295, 70549.64725176341, 82680.58659706783, 83383.7492437614, 85580.57209713911, 64913.67543162254, 67207.99188012183, 68031.65984170047, 68543.65728171327, 69312.96030559545, 8947.239484643878, 9385.15533601979, 9391.154796068387, 9475.677206792205, 9493.620255189715, 47019.82450438767, 48344.516554834, 49687.503124969735, 49849.25226121684, 50245.74877125591, 48226.0, 62676.68661656845, 63857.0, 64893.02660460191, 65073.34926650862, 11551.826722599, 11793.646190614296, 11834.881651183377, 11845.5854045108, 11886.762264754829, 12437.875621244033, 12563.560275390317, 12727.0, 12744.0, 13042.087053906136, 11728.648140555797, 12202.572909948109, 12276.877231227574, 12284.938575307066, 12499.125061245626, 11346.489407976826, 11686.0, 11702.648920532398, 11819.349935753273, 12056.457459414172, 11572.42137893085, 11618.004866180088, 11620.244684095846, 11693.239939403911, 11735.647930562096, 9830.459324736921, 10101.5454304555, 10147.949260253652, 10218.437985910847, 10386.584536618451, 8188.754337369888, 8804.779880503038, 8937.0, 9122.635094596142, 9318.860217096884], "value": 7337.827359319059, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1566.9999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 123.0, 121.0, 117.0, 122.0, 119.0, 121.0, 120.0, 120.0, 120.0], "value": 119.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 113.0, 127.0, 188.0, 119.0, 115.0, 119.0, 105.0, 169.0, 146.0], "value": 135.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 216.0, 192.0, 260.0, 258.0, 271.0, 253.0, 282.0, 200.0, 196.0], "value": 232.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 60.0, 63.0, 90.0, 61.0, 70.0, 55.0, 58.0, 77.0, 57.0], "value": 67.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 71.0, 93.0, 69.0, 71.0, 69.0, 81.0, 75.0, 105.0, 117.0], "value": 83.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 87.0, 89.0, 87.0, 88.0, 97.0, 89.0, 88.0, 88.0, 88.0], "value": 91.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 96.0, 106.0, 96.0, 98.0, 95.0, 97.0, 95.0, 96.0], "value": 97.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 109.0, 140.0, 101.0, 93.0, 93.0, 91.0, 92.0, 91.0, 96.0], "value": 99.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 70.0, 67.0, 86.0, 61.0, 62.0, 67.0, 68.0, 69.0, 67.0], "value": 68.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 65.0, 61.0, 73.0, 64.0, 66.0, 58.0, 60.0, 59.0, 60.0], "value": 62.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 72.0, 98.0, 104.0, 103.0, 82.0, 82.0, 86.0, 83.0, 87.0], "value": 89.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 175.0, 151.0, 141.0, 163.0, 141.0, 159.0, 148.0, 200.0, 148.0], "value": 158.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [190.0, 186.0, 199.0, 189.0, 188.0, 188.0, 189.0, 186.0, 193.0, 193.0], "value": 190.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 73.0, 67.0, 71.0, 71.0, 67.0, 68.0, 75.0, 72.0, 86.0], "value": 72.1, "unit": "score"}]}]} 19:25:04 INFO - Return code: 0 19:25:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:25:04 ERROR - # TBPL SUCCESS # 19:25:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:25:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:25:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:25:04 INFO - 2017-03-16 19:25:04.907 screenresolution[1655:10511] starting screenresolution argv=screenresolution get 19:25:04 INFO - 2017-03-16 19:25:04.910 screenresolution[1655:10511] Display 0: 1600x1200x32@60 19:25:04 INFO - 2017-03-16 19:25:04.916 screenresolution[1656:10514] starting screenresolution argv=screenresolution list 19:25:04 INFO - Available Modes on Display 0 19:25:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:25:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:25:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:25:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:25:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:25:05 INFO - Intel Iris: 19:25:05 INFO - Chipset Model: Intel Iris 19:25:05 INFO - Type: GPU 19:25:05 INFO - Bus: Built-In 19:25:05 INFO - VRAM (Dynamic, Max): 1536 MB 19:25:05 INFO - Vendor: Intel (0x8086) 19:25:05 INFO - Device ID: 0x0a2e 19:25:05 INFO - Revision ID: 0x0009 19:25:05 INFO - Displays: 19:25:05 INFO - Display: 19:25:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:25:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:25:05 INFO - Display Serial Number: 1600x1200 60 19:25:05 INFO - Main Display: Yes 19:25:05 INFO - Mirror: Off 19:25:05 INFO - Online: Yes 19:25:05 INFO - Rotation: Supported 19:25:05 INFO - Return code: 0 19:25:05 INFO - Running post-action listener: _resource_record_post_action 19:25:05 INFO - [mozharness: 2017-03-17 02:25:05.154758Z] Finished run-tests step (success) 19:25:05 INFO - Running post-run listener: _resource_record_post_run 19:25:05 INFO - Total resource usage - Wall time: 1198s; CPU: 54.0%; Read bytes: 40229376; Write bytes: 516515840; Read time: 387; Write time: 1689 19:25:05 INFO - TinderboxPrint: CPU usage
54.4% 19:25:05 INFO - TinderboxPrint: I/O read bytes / time
40,229,376 / 387 19:25:05 INFO - TinderboxPrint: I/O write bytes / time
516,515,840 / 1,689 19:25:05 INFO - TinderboxPrint: CPU idle
2,187.9 (45.6%) 19:25:05 INFO - TinderboxPrint: CPU system
967.1 (20.2%) 19:25:05 INFO - TinderboxPrint: CPU user
1,638.6 (34.2%) 19:25:05 INFO - TinderboxPrint: Swap in / out
250,064,896 / 0 19:25:05 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 207823360; Write bytes: 203876352; Read time: 15625; Write time: 456 19:25:05 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 37071360; Write bytes: 301379584; Read time: 358; Write time: 1195 19:25:05 INFO - Running post-run listener: _upload_blobber_files 19:25:05 INFO - Blob upload gear active. 19:25:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:25:05 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 19:25:05 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', 'Pine', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:25:05 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 Pine -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:25:06 INFO - (blobuploader) - INFO - Open directory for files ... 19:25:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:25:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:25:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:25:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:25:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:25:07 INFO - (blobuploader) - INFO - Done attempting. 19:25:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:25:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:25:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:25:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:25:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:25:08 INFO - (blobuploader) - INFO - Done attempting. 19:25:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 19:25:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:25:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:25:08 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 19:25:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:25:08 INFO - (blobuploader) - INFO - Done attempting. 19:25:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:25:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:25:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:25:11 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:25:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:25:11 INFO - (blobuploader) - INFO - Done attempting. 19:25:11 INFO - (blobuploader) - INFO - Iteration through files over. 19:25:11 INFO - Return code: 0 19:25:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:25:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:25:11 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/1e2d752e0ccfa39035d3b3ea6e3d7da3c230559671ea5fc31f82e72fc74ac696676c453fb72d73cb21906d40d52b31476fc73e2994df49d1296f8f2be9c99914", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/6523124ca1df01235240ea6a1d45fa7360b6cfdfeac7f6930a4aba2a9b799ba9b196323526bfd98064a30cd1925cf490fcc24605fda620a2481df1f3475e25c8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/dbceb9828cb46148e1afcd83c64a02e255ca92cbd1e17c1c7537514e30224927a05f3f5b56f41dfb39cf026138ecce198be7a12faae182763faf68d017ec0bc1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/e7df0cb9e6e20d1546915be6f0709e4c52ae377dc73e40f8230992fa421493445ef8e42e07be6e7e3cbe4d1172765114d43cdcfb17f9c5f1461464163a7fe010"} 19:25:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:25:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:25:11 INFO - Contents: 19:25:11 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/1e2d752e0ccfa39035d3b3ea6e3d7da3c230559671ea5fc31f82e72fc74ac696676c453fb72d73cb21906d40d52b31476fc73e2994df49d1296f8f2be9c99914", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/6523124ca1df01235240ea6a1d45fa7360b6cfdfeac7f6930a4aba2a9b799ba9b196323526bfd98064a30cd1925cf490fcc24605fda620a2481df1f3475e25c8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/dbceb9828cb46148e1afcd83c64a02e255ca92cbd1e17c1c7537514e30224927a05f3f5b56f41dfb39cf026138ecce198be7a12faae182763faf68d017ec0bc1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/e7df0cb9e6e20d1546915be6f0709e4c52ae377dc73e40f8230992fa421493445ef8e42e07be6e7e3cbe4d1172765114d43cdcfb17f9c5f1461464163a7fe010"} 19:25:11 INFO - Running post-run listener: copy_logs_to_upload_dir 19:25:11 INFO - Copying logs to upload dir... 19:25:11 INFO - mkdir: /builds/slave/test/build/upload/logs 19:25:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1281.801210 ========= master_lag: 3.01 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-03-16 19:25:14.169718) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-16 19:25:14.173421) ========= 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.RN4ttVBSyy/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.Rv6TYCZItC/Listeners TMPDIR=/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/1e2d752e0ccfa39035d3b3ea6e3d7da3c230559671ea5fc31f82e72fc74ac696676c453fb72d73cb21906d40d52b31476fc73e2994df49d1296f8f2be9c99914", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/6523124ca1df01235240ea6a1d45fa7360b6cfdfeac7f6930a4aba2a9b799ba9b196323526bfd98064a30cd1925cf490fcc24605fda620a2481df1f3475e25c8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/dbceb9828cb46148e1afcd83c64a02e255ca92cbd1e17c1c7537514e30224927a05f3f5b56f41dfb39cf026138ecce198be7a12faae182763faf68d017ec0bc1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/e7df0cb9e6e20d1546915be6f0709e4c52ae377dc73e40f8230992fa421493445ef8e42e07be6e7e3cbe4d1172765114d43cdcfb17f9c5f1461464163a7fe010"} build_url:https://queue.taskcluster.net/v1/task/P4cwgh7gTjeK4-T2WdY99g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011876 build_url: 'https://queue.taskcluster.net/v1/task/P4cwgh7gTjeK4-T2WdY99g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/1e2d752e0ccfa39035d3b3ea6e3d7da3c230559671ea5fc31f82e72fc74ac696676c453fb72d73cb21906d40d52b31476fc73e2994df49d1296f8f2be9c99914", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/6523124ca1df01235240ea6a1d45fa7360b6cfdfeac7f6930a4aba2a9b799ba9b196323526bfd98064a30cd1925cf490fcc24605fda620a2481df1f3475e25c8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/dbceb9828cb46148e1afcd83c64a02e255ca92cbd1e17c1c7537514e30224927a05f3f5b56f41dfb39cf026138ecce198be7a12faae182763faf68d017ec0bc1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/e7df0cb9e6e20d1546915be6f0709e4c52ae377dc73e40f8230992fa421493445ef8e42e07be6e7e3cbe4d1172765114d43cdcfb17f9c5f1461464163a7fe010"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-16 19:25:14.217545) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-16 19:25:14.217846) ========= 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.RN4ttVBSyy/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.Rv6TYCZItC/Listeners TMPDIR=/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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.005004 ========= master_lag: 0.30 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-16 19:25:14.521064) ========= ========= Started reboot skipped (results: 3, elapsed: 3 secs) (at 2017-03-16 19:25:14.521332) ========= ========= Finished reboot skipped (results: 3, elapsed: 3 secs) (at 2017-03-16 19:25:18.247485) ========= ========= Total master_lag: 3.41 =========