builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0277 starttime: 1487697696.14 results: success (0) buildid: 20170221083128 builduid: b3983ed51f4a497fb07d2ce8d495f901 revision: 63baf28e129ecef33ff1b21facd6c20456fc5939 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-21 09:21:36.142210) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-21 09:21:36.142646) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-21 09:21:36.142930) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-21 09:21:36.168467) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-21 09:21:36.168838) ========= 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.zNbsW3fenL/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.5iwErZpzNE/Listeners TMPDIR=/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-02-21 09:21:36-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 9.85M=0.001s 2017-02-21 09:21:36 (9.85 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.319331 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-21 09:21:36.512487) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-21 09:21:36.512806) ========= 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.zNbsW3fenL/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.5iwErZpzNE/Listeners TMPDIR=/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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.020425 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-21 09:21:36.561419) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-02-21 09:21:36.561775) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 63baf28e129ecef33ff1b21facd6c20456fc5939 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 63baf28e129ecef33ff1b21facd6c20456fc5939 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.zNbsW3fenL/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.5iwErZpzNE/Listeners TMPDIR=/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-02-21 09:21:36,657 truncating revision to first 12 chars 2017-02-21 09:21:36,657 Setting DEBUG logging. 2017-02-21 09:21:36,657 attempt 1/10 2017-02-21 09:21:36,657 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/63baf28e129e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-21 09:21:38,721 unpacking tar archive at: mozilla-aurora-63baf28e129e/testing/mozharness/ program finished with exit code 0 elapsedTime=2.335462 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-02-21 09:21:38.921404) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-21 09:21:38.921734) ========= script_repo_revision: 63baf28e129ecef33ff1b21facd6c20456fc5939 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-21 09:21:38.922104) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-21 09:21:38.922365) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-21 09:21:38.942437) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 59 secs) (at 2017-02-21 09:21:38.942722) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.zNbsW3fenL/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.5iwErZpzNE/Listeners TMPDIR=/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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 09:21:39 INFO - MultiFileLogger online at 20170221 09:21:39 in /builds/slave/test 09:21:39 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 09:21:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:21:39 INFO - {'append_to_log': False, 09:21:39 INFO - 'base_work_dir': '/builds/slave/test', 09:21:39 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:21:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:21:39 INFO - 'branch': 'Mozilla-Aurora', 09:21:39 INFO - 'buildbot_json_path': 'buildprops.json', 09:21:39 INFO - 'config_files': ('talos/mac_config.py',), 09:21:39 INFO - 'default_actions': ('clobber', 09:21:39 INFO - 'read-buildbot-config', 09:21:39 INFO - 'download-and-extract', 09:21:39 INFO - 'populate-webroot', 09:21:39 INFO - 'create-virtualenv', 09:21:39 INFO - 'install', 09:21:39 INFO - 'run-tests'), 09:21:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:21:39 INFO - 'download_minidump_stackwalk': True, 09:21:39 INFO - 'download_symbols': 'ondemand', 09:21:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:21:39 INFO - 'tooltool.py': '/tools/tooltool.py', 09:21:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:21:39 INFO - '/tools/misc-python/virtualenv.py')}, 09:21:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:21:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:21:39 INFO - 'installer_path': 'installer.exe', 09:21:39 INFO - 'log_level': 'info', 09:21:39 INFO - 'log_name': 'talos', 09:21:39 INFO - 'log_to_console': True, 09:21:39 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:21:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:21:39 INFO - 'opt_config_files': (), 09:21:39 INFO - 'pip_index': False, 09:21:39 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:21:39 INFO - 'cmd': ('bash', 09:21:39 INFO - '-c', 09:21:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:21:39 INFO - 'enabled': True, 09:21:39 INFO - 'halt_on_failure': False, 09:21:39 INFO - 'name': 'check_screen_resolution'},), 09:21:39 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:21:39 INFO - 'cmd': ('bash', 09:21:39 INFO - '-c', 09:21:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:21:39 INFO - 'enabled': True, 09:21:39 INFO - 'halt_on_failure': False, 09:21:39 INFO - 'name': 'check_screen_resolution'},), 09:21:39 INFO - 'run_cmd_checks_enabled': True, 09:21:39 INFO - 'sps_profile': False, 09:21:39 INFO - 'sps_profile_interval': 0, 09:21:39 INFO - 'suite': 'dromaeojs-e10s', 09:21:39 INFO - 'system_bits': '32', 09:21:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:21:39 INFO - 'title': 't-yosemite-r7-0277', 09:21:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:21:39 INFO - 'use_talos_json': True, 09:21:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:21:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:21:39 INFO - 'work_dir': 'build'} 09:21:39 INFO - [mozharness: 2017-02-21 17:21:39.155602Z] Running clobber step. 09:21:39 INFO - Running pre-action listener: _resource_record_pre_action 09:21:39 INFO - Running main action method: clobber 09:21:39 INFO - rmtree: /builds/slave/test/build 09:21:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:21:39 INFO - Running post-action listener: _resource_record_post_action 09:21:39 INFO - [mozharness: 2017-02-21 17:21:39.830086Z] Finished clobber step (success) 09:21:39 INFO - [mozharness: 2017-02-21 17:21:39.830234Z] Running read-buildbot-config step. 09:21:39 INFO - Running pre-action listener: _resource_record_pre_action 09:21:39 INFO - Running main action method: read_buildbot_config 09:21:39 INFO - Using buildbot properties: 09:21:39 INFO - { 09:21:39 INFO - "project": "", 09:21:39 INFO - "product": "firefox", 09:21:39 INFO - "script_repo_revision": "production", 09:21:39 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 09:21:39 INFO - "repository": "", 09:21:39 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 09:21:39 INFO - "buildid": "20170221083128", 09:21:39 INFO - "pgo_build": "False", 09:21:39 INFO - "basedir": "/builds/slave/test", 09:21:39 INFO - "buildnumber": 47, 09:21:39 INFO - "slavename": "t-yosemite-r7-0277", 09:21:39 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 09:21:39 INFO - "platform": "yosemite_r7", 09:21:39 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 09:21:39 INFO - "branch": "mozilla-aurora", 09:21:39 INFO - "repo_path": "releases/mozilla-aurora", 09:21:39 INFO - "revision": "63baf28e129ecef33ff1b21facd6c20456fc5939", 09:21:39 INFO - "stage_platform": "macosx64", 09:21:39 INFO - "builduid": "b3983ed51f4a497fb07d2ce8d495f901", 09:21:39 INFO - "slavebuilddir": "test" 09:21:39 INFO - } 09:21:39 INFO - Found installer url https://queue.taskcluster.net/v1/task/Z-B1Cr5mTo-VI8rcud0sQg/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg. 09:21:39 INFO - Running post-action listener: _resource_record_post_action 09:21:39 INFO - [mozharness: 2017-02-21 17:21:39.833748Z] Finished read-buildbot-config step (success) 09:21:39 INFO - [mozharness: 2017-02-21 17:21:39.833878Z] Running download-and-extract step. 09:21:39 INFO - Running pre-action listener: _resource_record_pre_action 09:21:39 INFO - Running main action method: download_and_extract 09:21:39 INFO - mkdir: /builds/slave/test/build/tests 09:21:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:21:39 INFO - trying https://queue.taskcluster.net/v1/task/Z-B1Cr5mTo-VI8rcud0sQg/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json 09:21:39 INFO - Downloading https://queue.taskcluster.net/v1/task/Z-B1Cr5mTo-VI8rcud0sQg/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 09:21:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Z-B1Cr5mTo-VI8rcud0sQg/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json'}, attempt #1 09:21:41 INFO - Downloaded 1199 bytes. 09:21:41 INFO - Reading from file /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 09:21:41 INFO - Using the following test package requirements: 09:21:41 INFO - {u'common': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 09:21:41 INFO - u'cppunittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:21:41 INFO - u'firefox-53.0a2.en-US.mac.cppunittest.tests.zip'], 09:21:41 INFO - u'gtest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:21:41 INFO - u'firefox-53.0a2.en-US.mac.gtest.tests.zip'], 09:21:41 INFO - u'jittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:21:41 INFO - u'jsshell-mac.zip'], 09:21:41 INFO - u'mochitest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:21:41 INFO - u'firefox-53.0a2.en-US.mac.mochitest.tests.zip'], 09:21:41 INFO - u'mozbase': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 09:21:41 INFO - u'reftest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:21:41 INFO - u'firefox-53.0a2.en-US.mac.reftest.tests.zip'], 09:21:41 INFO - u'talos': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:21:41 INFO - u'firefox-53.0a2.en-US.mac.talos.tests.zip'], 09:21:41 INFO - u'web-platform': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:21:41 INFO - u'firefox-53.0a2.en-US.mac.web-platform.tests.zip'], 09:21:41 INFO - u'xpcshell': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:21:41 INFO - u'firefox-53.0a2.en-US.mac.xpcshell.tests.zip']} 09:21:41 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip'] for test suite category: common 09:21:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Z-B1Cr5mTo-VI8rcud0sQg/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 09:21:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Z-B1Cr5mTo-VI8rcud0sQg/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:21:41 INFO - Fetch https://queue.taskcluster.net/v1/task/Z-B1Cr5mTo-VI8rcud0sQg/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 09:21:42 INFO - Expected file size: 16357304 09:21:42 INFO - Obtained file size: 16357304 09:21:49 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip', u'firefox-53.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 09:21:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Z-B1Cr5mTo-VI8rcud0sQg/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 09:21:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Z-B1Cr5mTo-VI8rcud0sQg/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:21:49 INFO - Fetch https://queue.taskcluster.net/v1/task/Z-B1Cr5mTo-VI8rcud0sQg/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 09:21:50 INFO - Expected file size: 16357304 09:21:50 INFO - Obtained file size: 16357304 09:21:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Z-B1Cr5mTo-VI8rcud0sQg/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip 09:21:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Z-B1Cr5mTo-VI8rcud0sQg/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip'}, attempt #1 09:21:56 INFO - Fetch https://queue.taskcluster.net/v1/task/Z-B1Cr5mTo-VI8rcud0sQg/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip into memory 09:21:57 INFO - Expected file size: 14403485 09:21:57 INFO - Obtained file size: 14403485 09:21:57 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')]} 09:21:57 INFO - trying https://queue.taskcluster.net/v1/task/Z-B1Cr5mTo-VI8rcud0sQg/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 09:21:57 INFO - Downloading https://queue.taskcluster.net/v1/task/Z-B1Cr5mTo-VI8rcud0sQg/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg 09:21:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Z-B1Cr5mTo-VI8rcud0sQg/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg'}, attempt #1 09:21:59 INFO - Downloaded 73911029 bytes. 09:21:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Z-B1Cr5mTo-VI8rcud0sQg/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 09:21:59 INFO - mkdir: /builds/slave/test/properties 09:21:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:21:59 INFO - Writing to file /builds/slave/test/properties/build_url 09:21:59 INFO - Contents: 09:21:59 INFO - build_url:https://queue.taskcluster.net/v1/task/Z-B1Cr5mTo-VI8rcud0sQg/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 09:22:00 INFO - Running post-action listener: _resource_record_post_action 09:22:00 INFO - Running post-action listener: set_extra_try_arguments 09:22:00 INFO - [mozharness: 2017-02-21 17:22:00.363936Z] Finished download-and-extract step (success) 09:22:00 INFO - [mozharness: 2017-02-21 17:22:00.364061Z] Running populate-webroot step. 09:22:00 INFO - Running pre-action listener: _resource_record_pre_action 09:22:00 INFO - Running main action method: populate_webroot 09:22:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:22:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:22:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:22:00 INFO - '--tppagecycles', 09:22:00 INFO - '7'], 09:22:00 INFO - 'tests': ['tsvgm']}}, 09:22:00 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:22:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:22:00 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:22:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:22:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:22:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:22:00 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:22:00 INFO - 'talos_options': ['--disable-e10s'], 09:22:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:22:00 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:22:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:22:00 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:22:00 INFO - 'talos_options': ['--disable-e10s'], 09:22:00 INFO - 'tests': ['damp', 'tps']}, 09:22:00 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:22:00 INFO - 'tests': ['damp', 'tps']}, 09:22:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:22:00 INFO - 'tests': ['dromaeo_dom']}, 09:22:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:22:00 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:22:00 INFO - 'tests': ['basic_compositor_video']}, 09:22:00 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:22:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:22:00 INFO - 'tests': ['a11yr', 09:22:00 INFO - 'ts_paint', 09:22:00 INFO - 'tpaint', 09:22:00 INFO - 'sessionrestore', 09:22:00 INFO - 'sessionrestore_no_auto_restore', 09:22:00 INFO - 'tabpaint']}, 09:22:00 INFO - 'other-e10s': {'tests': ['a11yr', 09:22:00 INFO - 'ts_paint', 09:22:00 INFO - 'tpaint', 09:22:00 INFO - 'sessionrestore', 09:22:00 INFO - 'sessionrestore_no_auto_restore', 09:22:00 INFO - 'tabpaint']}, 09:22:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:22:00 INFO - 'ts_paint', 09:22:00 INFO - 'tpaint', 09:22:00 INFO - 'sessionrestore', 09:22:00 INFO - 'sessionrestore_no_auto_restore', 09:22:00 INFO - 'tabpaint']}, 09:22:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:22:00 INFO - 'ts_paint', 09:22:00 INFO - 'tpaint', 09:22:00 INFO - 'sessionrestore', 09:22:00 INFO - 'sessionrestore_no_auto_restore', 09:22:00 INFO - 'tabpaint']}, 09:22:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:22:00 INFO - 'tests': ['a11yr', 09:22:00 INFO - 'ts_paint', 09:22:00 INFO - 'tpaint', 09:22:00 INFO - 'sessionrestore', 09:22:00 INFO - 'sessionrestore_no_auto_restore', 09:22:00 INFO - 'tabpaint']}, 09:22:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:22:00 INFO - 'tests': ['a11yr', 09:22:00 INFO - 'ts_paint', 09:22:00 INFO - 'tpaint', 09:22:00 INFO - 'sessionrestore', 09:22:00 INFO - 'sessionrestore_no_auto_restore', 09:22:00 INFO - 'tabpaint']}, 09:22:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:22:00 INFO - 'tests': ['tsvgx', 09:22:00 INFO - 'tsvgr_opacity', 09:22:00 INFO - 'tart', 09:22:00 INFO - 'tscrollx', 09:22:00 INFO - 'cart', 09:22:00 INFO - 'tsvg_static']}, 09:22:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:22:00 INFO - 'tsvgr_opacity', 09:22:00 INFO - 'tart', 09:22:00 INFO - 'tscrollx', 09:22:00 INFO - 'cart', 09:22:00 INFO - 'tsvg_static']}, 09:22:00 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:22:00 INFO - 'talos_options': ['--disable-e10s'], 09:22:00 INFO - 'tests': ['tp5o']}, 09:22:00 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:22:00 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:22:00 INFO - 'talos_options': ['--disable-e10s', 09:22:00 INFO - '--xperf_path', 09:22:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:22:00 INFO - 'tests': ['tp5n']}, 09:22:00 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:22:00 INFO - 'talos_options': ['--xperf_path', 09:22:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:22:00 INFO - 'tests': ['tp5n']}}, 09:22:00 INFO - 'talos.zip': {'path': '', 09:22:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:22:00 INFO - Running post-action listener: _resource_record_post_action 09:22:00 INFO - [mozharness: 2017-02-21 17:22:00.382750Z] Finished populate-webroot step (success) 09:22:00 INFO - [mozharness: 2017-02-21 17:22:00.382897Z] Running create-virtualenv step. 09:22:00 INFO - Running pre-action listener: _resource_record_pre_action 09:22:00 INFO - Running main action method: create_virtualenv 09:22:00 INFO - Creating virtualenv /builds/slave/test/build/venv 09:22:00 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 09:22:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:22:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:22:00 INFO - Using real prefix '/tools/python27' 09:22:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:22:01 INFO - Installing distribute.............................................................................................................................................................................................done. 09:22:04 INFO - Installing pip.................done. 09:22:04 INFO - Return code: 0 09:22:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:22:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:22:04 INFO - Reading from file tmpfile_stdout 09:22:04 INFO - Output received: 09:22:04 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) 09:22:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:22:04 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')]} 09:22:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:22:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:04 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')]} 09:22:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:22:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:04 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 0x10b520b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b523768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c3adbaf60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2fed30>, '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 0x10b225db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2f3be8>, '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.zNbsW3fenL/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.5iwErZpzNE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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 09:22:04 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 09:22:04 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 09:22:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zNbsW3fenL/Render', 09:22:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:22:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:22:04 INFO - 'HOME': '/Users/cltbld', 09:22:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:22:04 INFO - 'LOGNAME': 'cltbld', 09:22:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:22:04 INFO - 'MOZ_NO_REMOTE': '1', 09:22:04 INFO - 'NO_EM_RESTART': '1', 09:22:04 INFO - 'PAGER': '/bin/cat', 09:22:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:22:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:22:04 INFO - 'PWD': '/builds/slave/test', 09:22:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:22:04 INFO - 'SHELL': '/bin/bash', 09:22:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5iwErZpzNE/Listeners', 09:22:04 INFO - 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/', 09:22:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:22:04 INFO - 'USER': 'cltbld', 09:22:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:22:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:22:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:22:04 INFO - 'XPC_FLAGS': '0x0', 09:22:04 INFO - 'XPC_SERVICE_NAME': '0', 09:22:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:22:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:22:04 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 09:22:04 INFO - Cleaning up... 09:22:04 INFO - Return code: 0 09:22:04 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:22:04 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')]} 09:22:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:22:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:04 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')]} 09:22:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:22:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:04 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 0x10b520b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b523768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c3adbaf60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2fed30>, '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 0x10b225db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2f3be8>, '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.zNbsW3fenL/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.5iwErZpzNE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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 09:22:04 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 09:22:04 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 09:22:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zNbsW3fenL/Render', 09:22:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:22:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:22:04 INFO - 'HOME': '/Users/cltbld', 09:22:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:22:04 INFO - 'LOGNAME': 'cltbld', 09:22:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:22:04 INFO - 'MOZ_NO_REMOTE': '1', 09:22:04 INFO - 'NO_EM_RESTART': '1', 09:22:04 INFO - 'PAGER': '/bin/cat', 09:22:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:22:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:22:04 INFO - 'PWD': '/builds/slave/test', 09:22:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:22:04 INFO - 'SHELL': '/bin/bash', 09:22:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5iwErZpzNE/Listeners', 09:22:04 INFO - 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/', 09:22:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:22:04 INFO - 'USER': 'cltbld', 09:22:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:22:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:22:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:22:04 INFO - 'XPC_FLAGS': '0x0', 09:22:04 INFO - 'XPC_SERVICE_NAME': '0', 09:22:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:22:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:22:04 INFO - Downloading/unpacking psutil>=3.1.1 09:22:04 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 09:22:04 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 09:22:04 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 09:22:04 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 09:22:04 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 09:22:04 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 09:22:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:22:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:22:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:22:08 INFO - Installing collected packages: psutil 09:22:08 INFO - Running setup.py install for psutil 09:22:08 INFO - building 'psutil._psutil_osx' extension 09:22:08 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 09:22:08 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 09:22:08 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 09:22:09 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 09:22:09 INFO - building 'psutil._psutil_posix' extension 09:22:09 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 09:22:09 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:22:09 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:22:09 INFO - ^ 09:22:09 INFO - 1 warning generated. 09:22:09 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 09:22:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:22:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:22:09 INFO - Successfully installed psutil 09:22:09 INFO - Cleaning up... 09:22:09 INFO - Return code: 0 09:22:09 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:22:09 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')]} 09:22:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:22:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:09 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')]} 09:22:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:22:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:09 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 0x10b520b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b523768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c3adbaf60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2fed30>, '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 0x10b225db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2f3be8>, '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.zNbsW3fenL/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.5iwErZpzNE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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 09:22:09 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 09:22:09 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 09:22:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zNbsW3fenL/Render', 09:22:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:22:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:22:09 INFO - 'HOME': '/Users/cltbld', 09:22:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:22:09 INFO - 'LOGNAME': 'cltbld', 09:22:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:22:09 INFO - 'MOZ_NO_REMOTE': '1', 09:22:09 INFO - 'NO_EM_RESTART': '1', 09:22:09 INFO - 'PAGER': '/bin/cat', 09:22:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:22:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:22:09 INFO - 'PWD': '/builds/slave/test', 09:22:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:22:09 INFO - 'SHELL': '/bin/bash', 09:22:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5iwErZpzNE/Listeners', 09:22:09 INFO - 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/', 09:22:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:22:09 INFO - 'USER': 'cltbld', 09:22:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:22:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:22:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:22:09 INFO - 'XPC_FLAGS': '0x0', 09:22:09 INFO - 'XPC_SERVICE_NAME': '0', 09:22:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:22:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:22:09 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:22:09 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 09:22:09 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 09:22:09 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 09:22:09 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 09:22:09 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 09:22:09 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 09:22:12 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:22:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:22:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:22:12 INFO - Installing collected packages: mozsystemmonitor 09:22:12 INFO - Running setup.py install for mozsystemmonitor 09:22:12 INFO - Successfully installed mozsystemmonitor 09:22:12 INFO - Cleaning up... 09:22:12 INFO - Return code: 0 09:22:12 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:22:12 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')]} 09:22:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:22:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:12 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')]} 09:22:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:22:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:12 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 0x10b520b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b523768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c3adbaf60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2fed30>, '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 0x10b225db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2f3be8>, '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.zNbsW3fenL/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.5iwErZpzNE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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 09:22:12 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 09:22:12 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 09:22:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zNbsW3fenL/Render', 09:22:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:22:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:22:12 INFO - 'HOME': '/Users/cltbld', 09:22:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:22:12 INFO - 'LOGNAME': 'cltbld', 09:22:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:22:12 INFO - 'MOZ_NO_REMOTE': '1', 09:22:12 INFO - 'NO_EM_RESTART': '1', 09:22:12 INFO - 'PAGER': '/bin/cat', 09:22:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:22:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:22:12 INFO - 'PWD': '/builds/slave/test', 09:22:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:22:12 INFO - 'SHELL': '/bin/bash', 09:22:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5iwErZpzNE/Listeners', 09:22:12 INFO - 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/', 09:22:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:22:12 INFO - 'USER': 'cltbld', 09:22:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:22:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:22:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:22:12 INFO - 'XPC_FLAGS': '0x0', 09:22:12 INFO - 'XPC_SERVICE_NAME': '0', 09:22:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:22:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:22:12 INFO - Downloading/unpacking jsonschema==2.5.1 09:22:12 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 09:22:12 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 09:22:12 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 09:22:12 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 09:22:12 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 09:22:12 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 09:22:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:22:15 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:22:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:22:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:22:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:22:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:22:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:22:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:22:15 INFO - Downloading functools32-3.2.3-2.tar.gz 09:22:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:22:15 INFO - warning: no files found matching '*.txt' 09:22:15 INFO - no previously-included directories found matching 'build' 09:22:15 INFO - no previously-included directories found matching 'dist' 09:22:15 INFO - no previously-included directories found matching '.git*' 09:22:15 INFO - Installing collected packages: jsonschema, functools32 09:22:15 INFO - Running setup.py install for functools32 09:22:16 INFO - warning: no files found matching '*.txt' 09:22:16 INFO - no previously-included directories found matching 'build' 09:22:16 INFO - no previously-included directories found matching 'dist' 09:22:16 INFO - no previously-included directories found matching '.git*' 09:22:16 INFO - Successfully installed jsonschema functools32 09:22:16 INFO - Cleaning up... 09:22:16 INFO - Return code: 0 09:22:16 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:22:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:22:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:22:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:22:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:22:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b520b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b523768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c3adbaf60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2fed30>, '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 0x10b225db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2f3be8>, '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.zNbsW3fenL/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.5iwErZpzNE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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 09:22:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:22:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:22:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zNbsW3fenL/Render', 09:22:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:22:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:22:16 INFO - 'HOME': '/Users/cltbld', 09:22:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:22:16 INFO - 'LOGNAME': 'cltbld', 09:22:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:22:16 INFO - 'MOZ_NO_REMOTE': '1', 09:22:16 INFO - 'NO_EM_RESTART': '1', 09:22:16 INFO - 'PAGER': '/bin/cat', 09:22:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:22:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:22:16 INFO - 'PWD': '/builds/slave/test', 09:22:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:22:16 INFO - 'SHELL': '/bin/bash', 09:22:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5iwErZpzNE/Listeners', 09:22:16 INFO - 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/', 09:22:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:22:16 INFO - 'USER': 'cltbld', 09:22:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:22:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:22:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:22:16 INFO - 'XPC_FLAGS': '0x0', 09:22:16 INFO - 'XPC_SERVICE_NAME': '0', 09:22:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:22:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:22:16 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:22:16 INFO - Cleaning up... 09:22:16 INFO - Return code: 0 09:22:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:22:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:22:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:22:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:22:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:22:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b520b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b523768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c3adbaf60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2fed30>, '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 0x10b225db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2f3be8>, '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.zNbsW3fenL/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.5iwErZpzNE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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 09:22:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:22:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:22:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zNbsW3fenL/Render', 09:22:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:22:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:22:16 INFO - 'HOME': '/Users/cltbld', 09:22:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:22:16 INFO - 'LOGNAME': 'cltbld', 09:22:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:22:16 INFO - 'MOZ_NO_REMOTE': '1', 09:22:16 INFO - 'NO_EM_RESTART': '1', 09:22:16 INFO - 'PAGER': '/bin/cat', 09:22:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:22:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:22:16 INFO - 'PWD': '/builds/slave/test', 09:22:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:22:16 INFO - 'SHELL': '/bin/bash', 09:22:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5iwErZpzNE/Listeners', 09:22:16 INFO - 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/', 09:22:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:22:16 INFO - 'USER': 'cltbld', 09:22:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:22:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:22:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:22:16 INFO - 'XPC_FLAGS': '0x0', 09:22:16 INFO - 'XPC_SERVICE_NAME': '0', 09:22:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:22:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:22:16 INFO - Downloading/unpacking blobuploader==1.2.4 09:22:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:22:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:22:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:22:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:22:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:22:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:22:19 INFO - Downloading blobuploader-1.2.4.tar.gz 09:22:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:22:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:22:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:22:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:22:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:22:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:22:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:22:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:22:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:22:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:22:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:22:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:22:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:22:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:22:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:22:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:22:20 INFO - Downloading docopt-0.6.1.tar.gz 09:22:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:22:20 INFO - Installing collected packages: blobuploader, requests, docopt 09:22:20 INFO - Running setup.py install for blobuploader 09:22:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:22:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:22:20 INFO - Running setup.py install for requests 09:22:21 INFO - Running setup.py install for docopt 09:22:21 INFO - Successfully installed blobuploader requests docopt 09:22:21 INFO - Cleaning up... 09:22:21 INFO - Return code: 0 09:22:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:22:21 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')]} 09:22:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:22:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:21 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')]} 09:22:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:22:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:21 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 0x10b520b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b523768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c3adbaf60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2fed30>, '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 0x10b225db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2f3be8>, '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.zNbsW3fenL/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.5iwErZpzNE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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 09:22:21 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 09:22:21 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 09:22:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zNbsW3fenL/Render', 09:22:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:22:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:22:21 INFO - 'HOME': '/Users/cltbld', 09:22:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:22:21 INFO - 'LOGNAME': 'cltbld', 09:22:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:22:21 INFO - 'MOZ_NO_REMOTE': '1', 09:22:21 INFO - 'NO_EM_RESTART': '1', 09:22:21 INFO - 'PAGER': '/bin/cat', 09:22:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:22:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:22:21 INFO - 'PWD': '/builds/slave/test', 09:22:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:22:21 INFO - 'SHELL': '/bin/bash', 09:22:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5iwErZpzNE/Listeners', 09:22:21 INFO - 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/', 09:22:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:22:21 INFO - 'USER': 'cltbld', 09:22:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:22:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:22:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:22:21 INFO - 'XPC_FLAGS': '0x0', 09:22:21 INFO - 'XPC_SERVICE_NAME': '0', 09:22:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:22:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:22:21 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-keB8NE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:22:21 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-CDkBYZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:22:22 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-VzOkTt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:22:22 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-dWsnF9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:22:22 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-xCpsP0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:22:22 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-Ku6B3d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:22:22 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-nXeR1F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:22:22 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-eN7PS3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:22:22 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-hza2cr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:22:22 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-PV97Af-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:22:22 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-QqmJn4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:22:22 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-f3Jha_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:22:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:22:23 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-y0JVTn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:22:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:22:23 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-ma1RmB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:22:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:22:23 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-dWiizI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:22:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:22:23 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-xW1lgc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:22:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:22:23 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-S9b5fC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:22:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:22:23 INFO - Running setup.py install for manifestparser 09:22:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:22:23 INFO - Running setup.py install for mozcrash 09:22:23 INFO - Running setup.py install for mozdebug 09:22:24 INFO - Running setup.py install for mozdevice 09:22:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:22:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:22:24 INFO - Running setup.py install for mozfile 09:22:24 INFO - Running setup.py install for mozhttpd 09:22:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:22:24 INFO - Running setup.py install for mozinfo 09:22:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:22:24 INFO - Running setup.py install for mozInstall 09:22:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:22:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:22:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:22:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:22:24 INFO - Running setup.py install for mozleak 09:22:24 INFO - Running setup.py install for mozlog 09:22:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:22:25 INFO - Running setup.py install for moznetwork 09:22:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:22:25 INFO - Running setup.py install for mozprocess 09:22:25 INFO - Running setup.py install for mozprofile 09:22:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:22:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:22:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:22:25 INFO - Running setup.py install for mozrunner 09:22:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:22:25 INFO - Running setup.py install for mozscreenshot 09:22:25 INFO - Running setup.py install for moztest 09:22:26 INFO - Running setup.py install for mozversion 09:22:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:22:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:22:26 INFO - Cleaning up... 09:22:26 INFO - Return code: 0 09:22:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:22:26 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')]} 09:22:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:22:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:26 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')]} 09:22:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:22:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:26 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 0x10b520b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b523768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c3adbaf60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2fed30>, '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 0x10b225db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2f3be8>, '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.zNbsW3fenL/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.5iwErZpzNE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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 09:22:26 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 09:22:26 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 09:22:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zNbsW3fenL/Render', 09:22:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:22:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:22:26 INFO - 'HOME': '/Users/cltbld', 09:22:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:22:26 INFO - 'LOGNAME': 'cltbld', 09:22:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:22:26 INFO - 'MOZ_NO_REMOTE': '1', 09:22:26 INFO - 'NO_EM_RESTART': '1', 09:22:26 INFO - 'PAGER': '/bin/cat', 09:22:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:22:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:22:26 INFO - 'PWD': '/builds/slave/test', 09:22:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:22:26 INFO - 'SHELL': '/bin/bash', 09:22:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5iwErZpzNE/Listeners', 09:22:26 INFO - 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/', 09:22:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:22:26 INFO - 'USER': 'cltbld', 09:22:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:22:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:22:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:22:26 INFO - 'XPC_FLAGS': '0x0', 09:22:26 INFO - 'XPC_SERVICE_NAME': '0', 09:22:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:22:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:22:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:22:26 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-kOuXEX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:22:26 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)) 09:22:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:22:26 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-0YSwa9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:22:26 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)) 09:22:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:22:26 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-YSlqAA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:22:26 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)) 09:22:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:22:26 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-ProiHR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:22:26 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)) 09:22:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:22:26 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-5X9jy2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:22:27 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)) 09:22:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:22:27 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-S89Hpd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:22:27 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)) 09:22:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:22:27 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-4dLNKJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:22:27 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)) 09:22:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:22:27 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-w6rBMS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:22:27 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)) 09:22:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:22:27 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-nvXn5q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:22:27 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)) 09:22:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:22:27 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-xLLW9x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:22:27 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)) 09:22:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:22:27 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-f3MEKq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:22:27 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)) 09:22:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:22:27 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-z7_kWz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:22:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:22:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:22:27 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-pCOTY3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:22:27 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)) 09:22:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:22:27 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-NCXI0o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:22:27 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)) 09:22:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:22:27 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-EkaPhK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:22:28 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)) 09:22:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:22:28 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-Ch5Z44-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:22:28 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)) 09:22:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:22:28 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-qkRJjx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:22:28 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)) 09:22:28 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)) 09:22:28 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)) 09:22:28 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)) 09:22:28 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)) 09:22:28 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)) 09:22:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:22:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:22:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:22:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:22:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:22:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:22:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:22:30 INFO - Downloading blessings-1.6.tar.gz 09:22:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:22:31 INFO - Installing collected packages: blessings 09:22:31 INFO - Running setup.py install for blessings 09:22:31 INFO - Successfully installed blessings 09:22:31 INFO - Cleaning up... 09:22:31 INFO - Return code: 0 09:22:31 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:22:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:22:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:22:31 INFO - Reading from file tmpfile_stdout 09:22:31 INFO - Current package versions: 09:22:31 INFO - blessings == 1.6 09:22:31 INFO - blobuploader == 1.2.4 09:22:31 INFO - docopt == 0.6.1 09:22:31 INFO - functools32 == 3.2.3-2 09:22:31 INFO - jsonschema == 2.5.1 09:22:31 INFO - manifestparser == 1.1 09:22:31 INFO - mozInstall == 1.12 09:22:31 INFO - mozcrash == 1.0 09:22:31 INFO - mozdebug == 0.1 09:22:31 INFO - mozdevice == 0.49 09:22:31 INFO - mozfile == 1.2 09:22:31 INFO - mozhttpd == 0.7 09:22:31 INFO - mozinfo == 0.9 09:22:31 INFO - mozleak == 0.1 09:22:31 INFO - mozlog == 3.4 09:22:31 INFO - moznetwork == 0.27 09:22:31 INFO - mozprocess == 0.24 09:22:31 INFO - mozprofile == 0.28 09:22:31 INFO - mozrunner == 6.13 09:22:31 INFO - mozscreenshot == 0.1 09:22:31 INFO - mozsystemmonitor == 0.3 09:22:31 INFO - moztest == 0.8 09:22:31 INFO - mozversion == 1.4 09:22:31 INFO - psutil == 3.1.1 09:22:31 INFO - requests == 1.2.3 09:22:31 INFO - wsgiref == 0.1.2 09:22:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:22:31 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')]} 09:22:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:22:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:31 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')]} 09:22:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:22:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:32 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 0x10b520b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b523768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c3adbaf60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2fed30>, '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 0x10b225db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2f3be8>, '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.zNbsW3fenL/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.5iwErZpzNE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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 09:22:32 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 09:22:32 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 09:22:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zNbsW3fenL/Render', 09:22:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:22:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:22:32 INFO - 'HOME': '/Users/cltbld', 09:22:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:22:32 INFO - 'LOGNAME': 'cltbld', 09:22:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:22:32 INFO - 'MOZ_NO_REMOTE': '1', 09:22:32 INFO - 'NO_EM_RESTART': '1', 09:22:32 INFO - 'PAGER': '/bin/cat', 09:22:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:22:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:22:32 INFO - 'PWD': '/builds/slave/test', 09:22:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:22:32 INFO - 'SHELL': '/bin/bash', 09:22:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5iwErZpzNE/Listeners', 09:22:32 INFO - 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/', 09:22:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:22:32 INFO - 'USER': 'cltbld', 09:22:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:22:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:22:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:22:32 INFO - 'XPC_FLAGS': '0x0', 09:22:32 INFO - 'XPC_SERVICE_NAME': '0', 09:22:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:22:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:22:32 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)) 09:22:32 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)) 09:22:32 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)) 09:22:32 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)) 09:22:32 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)) 09:22:32 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)) 09:22:32 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)) 09:22:32 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)) 09:22:32 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)) 09:22:32 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)) 09:22:32 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)) 09:22:32 INFO - Cleaning up... 09:22:32 INFO - Return code: 0 09:22:32 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:22:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:22:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:22:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:22:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:22:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b520b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b523768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c3adbaf60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2fed30>, '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 0x10b225db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2f3be8>, '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.zNbsW3fenL/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.5iwErZpzNE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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 09:22:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:22:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:22:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zNbsW3fenL/Render', 09:22:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:22:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:22:33 INFO - 'HOME': '/Users/cltbld', 09:22:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:22:33 INFO - 'LOGNAME': 'cltbld', 09:22:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:22:33 INFO - 'MOZ_NO_REMOTE': '1', 09:22:33 INFO - 'NO_EM_RESTART': '1', 09:22:33 INFO - 'PAGER': '/bin/cat', 09:22:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:22:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:22:33 INFO - 'PWD': '/builds/slave/test', 09:22:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:22:33 INFO - 'SHELL': '/bin/bash', 09:22:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5iwErZpzNE/Listeners', 09:22:33 INFO - 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/', 09:22:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:22:33 INFO - 'USER': 'cltbld', 09:22:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:22:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:22:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:22:33 INFO - 'XPC_FLAGS': '0x0', 09:22:33 INFO - 'XPC_SERVICE_NAME': '0', 09:22:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:22:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:22:33 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:22:33 INFO - Cleaning up... 09:22:33 INFO - Return code: 0 09:22:33 INFO - Running post-action listener: _resource_record_post_action 09:22:33 INFO - Running post-action listener: _start_resource_monitoring 09:22:33 INFO - Starting resource monitoring. 09:22:33 INFO - [mozharness: 2017-02-21 17:22:33.331388Z] Finished create-virtualenv step (success) 09:22:33 INFO - [mozharness: 2017-02-21 17:22:33.332366Z] Running install step. 09:22:33 INFO - Running pre-action listener: _resource_record_pre_action 09:22:33 INFO - Running main action method: install 09:22:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:22:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:22:33 INFO - Reading from file tmpfile_stdout 09:22:33 INFO - Detecting whether we're running mozinstall >=1.0... 09:22:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:22:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:22:33 INFO - Reading from file tmpfile_stdout 09:22:33 INFO - Output received: 09:22:33 INFO - Usage: mozinstall [options] installer 09:22:33 INFO - Options: 09:22:33 INFO - -h, --help show this help message and exit 09:22:33 INFO - -d DEST, --destination=DEST 09:22:33 INFO - Directory to install application into. [default: 09:22:33 INFO - "/builds/slave/test"] 09:22:33 INFO - --app=APP Application being installed. [default: firefox] 09:22:33 INFO - mkdir: /builds/slave/test/build/application 09:22:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:22:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 09:22:53 INFO - Reading from file tmpfile_stdout 09:22:53 INFO - Output received: 09:22:53 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 09:22:53 INFO - Running post-action listener: _resource_record_post_action 09:22:53 INFO - [mozharness: 2017-02-21 17:22:53.687070Z] Finished install step (success) 09:22:53 INFO - [mozharness: 2017-02-21 17:22:53.687220Z] Running run-tests step. 09:22:53 INFO - Running pre-action listener: _resource_record_pre_action 09:22:53 INFO - Running main action method: run_tests 09:22:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:22:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:22:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:22:53 INFO - 2017-02-21 09:22:53.701 screenresolution[6557:118641] starting screenresolution argv=screenresolution get 09:22:53 INFO - 2017-02-21 09:22:53.704 screenresolution[6557:118641] Display 0: 1600x1200x32@60 09:22:53 INFO - 2017-02-21 09:22:53.710 screenresolution[6558:118643] starting screenresolution argv=screenresolution list 09:22:53 INFO - Available Modes on Display 0 09:22:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:22:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:22:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:22:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:22:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:22:53 INFO - Intel Iris: 09:22:53 INFO - Chipset Model: Intel Iris 09:22:53 INFO - Type: GPU 09:22:53 INFO - Bus: Built-In 09:22:53 INFO - VRAM (Dynamic, Max): 1536 MB 09:22:53 INFO - Vendor: Intel (0x8086) 09:22:53 INFO - Device ID: 0x0a2e 09:22:53 INFO - Revision ID: 0x0009 09:22:53 INFO - Displays: 09:22:53 INFO - Display: 09:22:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:22:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:22:53 INFO - Display Serial Number: 1600x1200 60 09:22:53 INFO - Main Display: Yes 09:22:53 INFO - Mirror: Off 09:22:53 INFO - Online: Yes 09:22:53 INFO - Rotation: Supported 09:22:53 INFO - Return code: 0 09:22:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:22:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:22:53 INFO - Python 2.7.3 09:22:53 INFO - Return code: 0 09:22:53 INFO - grabbing minidump binary from tooltool 09:22:53 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')]} 09:22:53 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 0x10b2fed30>, '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 0x10b225db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2f3be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:22:53 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 09:22:53 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 09:22:53 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 09:22:54 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:22:54 INFO - Return code: 0 09:22:54 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:22:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:22:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:22:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:22:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:22:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Z-B1Cr5mTo-VI8rcud0sQg/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0277', '--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 09:22:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Z-B1Cr5mTo-VI8rcud0sQg/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0277 --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 09:22:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zNbsW3fenL/Render', 09:22:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:22:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:22:54 INFO - 'HOME': '/Users/cltbld', 09:22:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:22:54 INFO - 'LOGNAME': 'cltbld', 09:22:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:22:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:22:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:22:54 INFO - 'MOZ_NO_REMOTE': '1', 09:22:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:22:54 INFO - 'NO_EM_RESTART': '1', 09:22:54 INFO - 'PAGER': '/bin/cat', 09:22:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:22:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:22:54 INFO - 'PWD': '/builds/slave/test', 09:22:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:22:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:22:54 INFO - 'SHELL': '/bin/bash', 09:22:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5iwErZpzNE/Listeners', 09:22:54 INFO - 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/', 09:22:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:22:54 INFO - 'USER': 'cltbld', 09:22:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:22:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:22:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:22:54 INFO - 'XPC_FLAGS': '0x0', 09:22:54 INFO - 'XPC_SERVICE_NAME': '0', 09:22:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:22:54 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Z-B1Cr5mTo-VI8rcud0sQg/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0277', '--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 09:22:54 INFO - mozversion application_buildid: 20170221083128 09:22:54 INFO - mozversion application_changeset: 63baf28e129ecef33ff1b21facd6c20456fc5939 09:22:54 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:22:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:22:54 INFO - mozversion application_name: Firefox 09:22:54 INFO - mozversion application_remotingname: firefox-dev 09:22:54 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:22:54 INFO - mozversion application_vendor: Mozilla 09:22:54 INFO - mozversion application_version: 53.0a2 09:22:54 INFO - mozversion platform_buildid: 20170221083128 09:22:54 INFO - mozversion platform_changeset: 63baf28e129ecef33ff1b21facd6c20456fc5939 09:22:54 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:22:54 INFO - mozversion platform_version: 53.0a2 09:22:54 INFO - using testdate: 1487697774 09:22:54 INFO - actual date: 1487697774 09:22:54 INFO - starting webserver on 'localhost:57225' 09:22:54 INFO - SUITE-START | Running 2 tests 09:22:54 INFO - TEST-START | dromaeo_css 09:22:54 INFO - operating with platform_type : mac_ 09:22:54 INFO - Initialising browser for dromaeo_css test... 09:22:54 INFO - TEST-INFO | started process 6568 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/tmpWXIGeT/profile http://localhost:57225/getInfo.html) 09:22:57 INFO - PROCESS | 6568 | 2017-02-21 09:22:57.047 plugin-container[6570:118817] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d3f, name = 'com.apple.tsm.portname' 09:22:57 INFO - PROCESS | 6568 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:22:57 INFO - PROCESS | 6568 | 2017-02-21 09:22:57.050 plugin-container[6570:118817] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x920f, name = 'com.apple.CFPasteboardClient' 09:22:57 INFO - PROCESS | 6568 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:22:57 INFO - PROCESS | 6568 | 2017-02-21 09:22:57.050 plugin-container[6570:118817] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:22:57 INFO - PROCESS | 6568 | __metrics Screen width/height:1600/1200 09:22:57 INFO - PROCESS | 6568 | colorDepth:24 09:22:57 INFO - PROCESS | 6568 | Browser inner width/height: 1024/689 09:22:57 INFO - PROCESS | 6568 | __metrics 09:22:57 ERROR - PROCESS | 6568 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 09:22:57 INFO - TEST-INFO | 6568: exit 0 09:22:57 INFO - Browser initialized. 09:22:57 INFO - Running cycle 1/1 for dromaeo_css test... 09:22:57 INFO - TEST-INFO | started process 6572 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/tmpWXIGeT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:23:00 INFO - PROCESS | 6572 | 2017-02-21 09:23:00.562 plugin-container[6573:118952] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 09:23:00 INFO - PROCESS | 6572 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:23:00 INFO - PROCESS | 6572 | 2017-02-21 09:23:00.563 plugin-container[6573:118952] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4327, name = 'com.apple.CFPasteboardClient' 09:23:00 INFO - PROCESS | 6572 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:23:00 INFO - PROCESS | 6572 | 2017-02-21 09:23:00.563 plugin-container[6573:118952] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:23:10 INFO - PROCESS | 6572 | RSS: Main: 219152384 09:23:10 INFO - PROCESS | 6572 | 09:26:13 INFO - PROCESS | 6572 | Cycle 1(1): loaded http://localhost:57225/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57225/tests/dromaeo/cssquery-ext.html) 09:26:15 INFO - PROCESS | 6572 | RSS: Main: 239464448 09:26:15 INFO - PROCESS | 6572 | 09:27:58 INFO - PROCESS | 6572 | ************************* 09:27:58 INFO - PROCESS | 6572 | A coding exception was thrown and uncaught in a Task. 09:27:58 INFO - PROCESS | 6572 | 09:27:58 ERROR - PROCESS | 6572 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:27:58 INFO - PROCESS | 6572 | Full stack: 09:27:58 INFO - PROCESS | 6572 | ************************* 09:29:17 INFO - PROCESS | 6572 | Cycle 1(1): loaded http://localhost:57225/tests/dromaeo/cssquery-ext.html (next: http://localhost:57225/tests/dromaeo/cssquery-jquery.html) 09:29:18 INFO - PROCESS | 6572 | RSS: Main: 250478592 09:29:18 INFO - PROCESS | 6572 | 09:32:20 INFO - PROCESS | 6572 | Cycle 1(1): loaded http://localhost:57225/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57225/tests/dromaeo/cssquery-mootools.html) 09:32:20 INFO - PROCESS | 6572 | RSS: Main: 256421888 09:32:20 INFO - PROCESS | 6572 | 09:35:22 INFO - PROCESS | 6572 | Cycle 1(1): loaded http://localhost:57225/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57225/tests/dromaeo/cssquery-prototype.html) 09:35:23 INFO - PROCESS | 6572 | RSS: Main: 252891136 09:35:23 INFO - PROCESS | 6572 | 09:38:26 INFO - PROCESS | 6572 | Cycle 1(1): loaded http://localhost:57225/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57225/tests/dromaeo/cssquery-yui.html) 09:38:27 INFO - PROCESS | 6572 | RSS: Main: 257409024 09:38:27 INFO - PROCESS | 6572 | 09:41:29 INFO - PROCESS | 6572 | Cycle 1(1): loaded http://localhost:57225/tests/dromaeo/cssquery-yui.html (next: http://localhost:57225/tests/dromaeo/cssquery-dojo.html) 09:41:29 INFO - PROCESS | 6572 | RSS: Main: 260001792 09:41:29 INFO - PROCESS | 6572 | 09:41:29 INFO - PROCESS | 6572 | __start_tp_report 09:41:29 INFO - PROCESS | 6572 | _x_x_mozilla_page_load 09:41:29 INFO - PROCESS | 6572 | _x_x_mozilla_page_load_details 09:41:29 INFO - PROCESS | 6572 | |i|pagename|runs| 09:41:29 INFO - PROCESS | 6572 | |0;dojo.html;3303.273279878427;3349.263162104336;3397.184675677838;3402.064432281123;3402.642722514136;5174.146265866137;5751.309842818862;5841.591088623798;5891;5902.026165682668;5824.126381042845;5853.619514731539;5937.22816033915;5944.702764861761;5958.940410595893;4937.259411088329;5352.91970620439;5353.866153346178;5367.194920761878;5399.352077750676;5410.783568657249;5422.566194704415;5447.727613619323;5453.182022696587;5484.643498172606;5262.315898933133;5286;5411.9717253721665;5418.4039755626845;5425.430329815372;4862.5380588844;5357.276767636358;5373.274607927958;5374.811881584145;5379.246905433242;4475.507694153641;5161.483851614846;5300.0459917214885;5321.388040375367;5370.087085195526;5247.921281180786;5918.881622367571;5925.822225333204;5947.86990471809;5950.732217050243;6080.665563394012;6150.8769824603705;6252.280987686383;6257.155284036642;6277.246730392337;6195.597286176384;6222.875542489125;6250.937490625081;6251.812445626593;6307.653079080649;5970.044792833126;5987.64074155552;5992.640441573476;6011.098335249748;6027.9095813562835;6321.525885558602;6386.87226255477;6402.263739669951;6403.423691867662;6411.807645770635;4665.440147182292;5549.556035517149;6107.370879189147;6112.898653789767;6197.597156184834;6267.655278959701;6272;6278.30938596754;6283.214598175228;6308.337624549448;6261.968690156611;6262.373762623702;6264.5301602380005;6269.811905642838;6272.090546870678;6126.517673797367;6148.077788331786;6224.751009959641;6269.122322874801;6290.622562646256;5044.873878153076;5056.797728090909;5059.089363914457;5077.39071311445;5085.389753229562;4475.4181956345465;4508.864734057984;4509.774511274423;4525.705829121097;4547.181507328646;5918.112283157475;5979.342272350038;6009.038553831366;6072.817815465543;6076.969615151985;5074.847754567295;6253.437190652863;6263.091851681481;6280.152179455807;6293.653849038255;5956.672383018976;6054.303755068136;6057.649225867442;6061.575529750505;6086.360932102154;5461.962227176824;5482.205080263339;5575.916361254584;5581;5588.804391846266;6334.239891213084;6358.459530939829;6360.713767880549;6420.6147631142285;6485.708143133476;6145.446909777963;6250.843728906813;6263.217097862767;6271.122042913905;6399.680015999088;6254.21822272216;6299.685015749103;6304.779332723516;6349.68251587428;6378.74485020594;6136.519078024374;6158.353372895782;6281.183446151971;6284.088807123033;6304.464120549805;6154.01535754295;6247.937520624736;6258.749650013949;6259.874802503833;6271.686415679289;6220.844478888064;6222.222222222223;6245.5628106032145;6266.749330026748;6271.122042914087;6097.207363042776;6117.431594453822;6180.227471566054;6264.780732674334;6294.779682711083;5986.2217911671205;6091.847703807441;6144.6620435874665;6160.568760186744;6163.876722465615;5146.459621739664;5187.870303242449;6008.909866352095;6255.78104766331;6260;4789.401324834395;6043.576949613484;6131.294901086432;6144.8771024580155;6175.135481032748 09:41:29 INFO - PROCESS | 6572 | |1;ext.html;452.8664044106988;487.9436021014504;493.8962817808265;499.88502644391787;513.948605139486;40514.79742601287;42559.14881702364;42788.78605606975;44772.776136119275;44863.327050094274;36699.449508257334;38367.61632383675;43374.91562710934;44237.77881110598;44918.55081449184;6250.718717657716;8543.060263371024;9167.358284920076;9373.625054997792;9390.671326503574;11072.833907491395;11340.092792576574;11342.71643208918;11505.367204803733;11544.422778861066;11312.660620181408;11375.317480951127;11394.658160255205;11398.487068081915;11402.771944561104;11231.26996745213;11274.379909104997;11309.49107290174;11312;11312.943435282812;11208.831867521994;11296.039836613898;11297.265677731004;11302.15233857464;11323;41440.79279603613;41841.581584184074;41852.372214416806;41874.95312617209;41963.95090122741;3588.3002814451193;3972.483577134983;3981.9203615927518;4030.979845100785;4061.5304938474474;26263.474730505284;27970.744070485573;28894;29462.11613651594;29535.113946581638;3103.8913638022786;3128.311771410286;3226.951595726066;3321.618013928405;3341.599008119017;497156;497518.51240743924;501954;505945;506254;57381.71309143511;57687.42312576905;57723.42276577181;57754.42245577575;57852.4214757847;61898.381016190164;62182.68908655428;62314;62344.68827655923;62377.37622623716;15722.29249683767;16088.75866861998;16124.596885077732;16143.031418114715;16166.43417480406;9132.589033493508;11235.887641123647;11523;12348.876511234772;12717;18531.258749650133;18709.970951597792;18711.064446777607;18727.96996165197;18809;7629.656665450068;7772.300492955661;7780.805479863048;7854.332381747616;7915.881261781078;863.1282404771101;870.9433886797337;878.7346221441104;889.2752406788576;910.39003867409;30304.696953030187;30357.848210758806;30363.544546831814;30490.237744056576;30781.076567703003;1013.0882206014646;1049.4123290957089;1073.592035026685;1086.766345235778;1198.3109711915683;13221.867781322257;14091.577252682231;14745.631359216106;15706.293216805272;15921.196207293593;548367;562179;653231;654756;656991;61994;62457.750844982846;62503.74992500216;62869;62929;1376.7590671632424;1409.2319685771279;1420.8508106648653;1422.7865820127063;1428.8642578954982;24771.018318534112;28601.42797144087;29876.551851722677;30377.69622303749;30424.239394015327;46695;53289.467105330004;53608;53658;53737;516511;519948;521127.3943630258;521293;522061;24968.875155624104;25500.597467139487;26664.866675667272;27047.1885843425;27201.455970880867;47563;48048.27927581019;48226.5177348236;48232.7588362056;49231.50768492412;38127.42808857925;38702.419463707505;39404.80297598494;39835.40246896357;41319.7934010328;38235.80882095572;38857.6114238854;39172.6082739169;40382.596174037884;40528;35012.47481287732;36967.44548831716;37516;38404.80797595994;38626.0343491415;27027.32431689145;32533.83733081414;33030.50454243236;33697.15757106092;34437.139071523416;24023.75976240215;24120.51758964776;24387.02451901975;24546.386340341633;24804.255872323858 09:41:29 INFO - PROCESS | 6572 | |2;jquery.html;390.2760379496035;391.6025234387099;398.3805182940527;406.36606893246545;424.48213179920486;18351.632967340644;18983;19352.225910963563;19426.222951081956;19558.804411955876;20341;20531.384058478234;20658.793412065876;20732.89633551834;20746.89626551865;4866.537678920506;5714.657120572758;5901.704914754267;5975.880482390371;5982.252218472691;5797.768089276424;5849.473547380735;5933.554983376264;5946.22699049123;5960.403959604048;5584.21820945068;5737.713114344287;5762.423757624246;5771.913421298684;5818.44724751148;5670.716464176775;5755.079187330007;5763.279590051244;5807.361190269067;5817.476427121558;5144.3312369392115;5160.870978225536;5166.43169251382;5167.224916262553;5171.095058364809;22636.094556217733;23952;24208;24212.426192297444;24315.905784239752;2829.5189817731034;2896.174590241771;2991;2998.9100326990224;3025.1529571719952;10018.849717254247;10053.849192262122;10082.243831712578;10343.689689309333;10478.371297722086;1738.9134286641186;1802.8467580255694;1837.5589858434014;1844.7786265648074;1847.7136043913215;135499.32250338784;138213;141712;143013;143233;11958.461869215906;12061.396930153458;12070.879291206975;12087.939560302142;12132.5146994121;15849.049057056614;15850.76223856643;15885.761713574306;15902.840971590367;15921.840781592267;8035.638396272182;8145.674173033012;8688.087750786202;8971.596278167499;9010.234130098885;8553.44402613828;8596.484210947237;8722.171393717586;8731.781705457415;8738.86891696625;4688.765561721901;4824.49342819006;4831.444383895897;4839.903201935942;4919.188333924933;1989.1049027937486;2043.3359158273324;2044.427560283123;2044.9079791409124;2045.1717054592914;862.8274345130999;983.9262055345878;1017.3133135133755;1028.228828378716;1056.2606175677056;20769.792302076785;20901.268455603982;20974.47563810917;21106;21336.78663213379;873.6287077991828;907.2424525521263;1048.1016172693776;1071.517816982361;1075.8063548561333;9219.953900230455;9906.7027989159;11100.167487438475;11115;11177.888221117684;710567;717626;748133;750830;769436;141725;147221.263893682;151644;157919;159458;799.1688643810488;908.4844203751678;918.4535201555065;940.0552444793076;943.6932996776076;17625.294988200327;18375.540611484284;18515.72226416578;18582.349617763313;18664.533386665444;33078.669213307556;57375.426245737006;59362;59762.40237597568;60443.09335359885;85772.28455430732;85824.28351432811;87393;88685;89538;7595.696172153052;7878.803029924297;8012.959935200287;8215.34277257806;8274.864015170322;11517.712057198638;12267;12305.138640295452;12432.875671243533;12673.619791406272;9791.755206119904;9920.900790992286;10048.39709617396;10672.946635267084;10698.090662293482;10313.896861031593;10404.063634272952;10513.106385956973;10712.625058122929;10811;5900.262467191601;6757.695903684247;10926;10948.850370710961;11177.776444470903;7433.368163705929;7480.663370148465;7482.663280152297;7689.538627682357;7690.692372305045;5437.211604317431;6412.871742565216;8191.303739182236;10065.245106616887;10633.149348051982 09:41:29 INFO - PROCESS | 6572 | |3;mootools.html;1268.739908318794;1369.2469141971912;1425.7861320801871;1431.6184881589265;1444.9524095031104;7606.125295590995;7854.489458185229;8117.6752929882805;8559.486430814155;8585.570721463926;8033.678652853879;8289.461185022985;8307.833843323146;8411.285040771541;8425.452345597532;2337.1118974789556;2360.1621424394352;2361.291612516247;2373.537160253751;2377.2274010946426;2091.3203208957075;2185.2133232036454;2338.1582630253097;2374.976250237493;2388.235764555343;1841.3187120765335;2349.18952961229;2349.95300093998;2351.882855643564;2353.105819788478;2066.9483262918397;2169.719865279477;2292.3695983604475;2320.9883950580224;2325.7325407578005;2327.453048533593;2332.6734257204007;2373.15752907717;2386.4511162432736;2402.447437089479;8444.113368096385;8459.619317130744;8476;8531.146885311482;8561.101084386111;5145.691258524501;5295.993761185362;5318.30861987943;5331.840044798662;5338.172583249602;2835.9149225523265;3350.782199157047;3412.67579579942;3479.408500554912;3540.5751309842726;1203.5065623094576;1206.8853458921387;1225.3873063468266;1228.9139760216788;1267.2459886367596;10883.292585981964;10975.670729878117;10985;10994.395308258043;11068.114550835913;4431.601155895984;4741.170295198326;4780.163471392492;4802.471728109905;4814.037192561432;9620;9631.518424078768;9653.662121825702;9722.2708296878;9728.708138755848;5231.790728370823;5319.707416092075;5327.227552004938;5330.253764472977;5340.412554618989;4603.95396046042;4676.368690226844;4732.266498692708;4856.514348565115;4866.148424025781;3923.2349691809823;3989.860354887623;4001.419794129893;4048.8785336439955;4106.301928672133;1204.2353105777895;1239.8283621977146;1263.7219811641423;1293.0560690695845;1297.5588299977865;852.5950173667459;870.3689824876914;875.2778957360204;896.0367604824839;904.3850181876387;4046.8785936421955;5399.136138217867;5457.2359869618285;5604.215409842625;5608.691521966331;774.2528460036092;822.5558198572824;907.6369452219166;930.330162283155;956.0391806234828;3402.897913062612;3410.3861304965344;3482.703970162514;3512.2799826035603;3568.37553428149;9850.60597576089;10172.186225101974;10198.031187037219;10201.877793442716;10261.076503114606;10058.748531286776;10105.494725263707;10149.746256343502;10161.288709790391;10167.034131757471;7422.480426370103;7821.491603045783;7854.8821767673535;7900.881486777703;7907.446478746548;5256.763445645031;5289.18017707256;5370.704611246419;5417.106177480671;5545.722713864371;8917;9117.088291171096;9135.31485138604;9292.674756383494;9455.574499147417;8953.731388058368;9250.907490925274;9362.20421264173;9425.293103017164;9527.237820974444;7178.856422871618;7215.639218039182;7223.2415596361625;7307.963460182665;7334.889976650461;7835.686572537034;7870.173631768811;7873.212678732081;7894.921050789649;7897.723579674684;7033.402160816388;7136.7145314186855;7167.426605871622;7222.349988501061;7291.453141014551;5818.389069147678;7051.788446346618;7092.078029856086;7146.714131434893;7147.356737893615;6239.875202495834;6395.904061439175;6446.000869865178;6498.187726534184;6521.478281737544;4076.510818701775;4199.811008504686;4730.172219861579;4814.229723244264;4819.614430845594;4788;4837.540433658797;4847.903041939212;4951.579115775199;5019.598432125495 09:41:29 INFO - PROCESS | 6572 | |4;prototype.html;351.72213950978727;355.7598620930872;357.70668052197175;361.5498704113379;369.0699437417709;5213.426523082468;5314.468553144693;5554.361248456429;5611.120274707972;5618.578606604506;4652.18564759744;5265.3155089838265;5330.920036199451;5397.541208997231;5505.642133261335;3722.162513434476;4306.375575541545;4342.413774140482;4343.804528796211;4351.456067991501;3853.479780229661;4022.4167495713104;4128.864562245377;4192.840687046174;4360.694751367406;3851.5378154621485;4253.085586598881;4262.573742625744;4281.7002809803325;4296.355546668009;3828.272628200647;3867.2652196082654;4060.8731276916906;4188.39268306097;4307.698461107724;3495.8426870790877;3809.3904975204114;3812.4281357796276;3819.2170605025904;3819.6371344722206;4399.802008909607;5112.437631860493;5131;5336.466353364672;5380.219868119101;4584.633229341678;4921.212605983025;4965.686715214686;4966.87582810429;5003.17447621142;5565.909056843217;5804.82585522435;5814.854628634275;5850.3564607893095;5855.180274761537;3951.762894226327;3951.8616848410165;3978.7864701266103;4177.436046133764;4199.4120823084795;5367.946320536823;6058.878822423571;6111.327753947062;6133.478654314388;6273.56085074045;3316.6517515660507;4163.437935878678;4309.457420122143;4311.504177019683;4331.895366681476;3327.851891097616;3940.625640564142;3989.940150897739;4015.31739604268;4046.676265898721;4085.733422638991;4099.651529620016;4117.773522456234;4589.311603259471;4687.5000000000355;4972.776225069881;5501.477359650827;5526.093497743273;5528.944710552923;5827.417258274223;1252.2736812648825;1292.634478903604;1296.9157004794847;1315.0597322914161;1323.4706117553055;831.7213733399258;860.4168330272204;885.5262434597588;886.906874778139;894.9910500895038;4519.728816271035;4920.237363208708;4960.578350840219;5197;5262.526372626482;4708.223143181405;5417.916416716673;5540.7783688652;5665.3768085510565;5722.62656962326;4676.030720492512;4757.904841903142;5245.816396426183;5273.419923808378;5320.654157479828;2482.7020757509213;2888.581155732407;2947.938742052859;2970.9500444693094;2985.2984548631052;5315;5647.860446993216;5711.744305745685;5955.528455690926;6047.213862197887;5041.420236672756;5338.412774594792;5342.973285133549;5665.291838520185;5857;5356.839294821161;5508.9724551377785;5515.669059856422;5630.805050237517;5955.76176952917;4808.8797780055775;4999.225429886448;5542.981338529534;5714.6284292018345;5753.3958934311295;5074.035933172833;5457.435005547386;5588.748506317307;5643.661380317194;5705.885882282414;5273.499017593323;5333.280007198979;5467.637402997208;6005.759769609342;6074.483668888194;3198.2484116232204;3395.1851555625976;3685.668289853926;3800.4988029728656;3834;5755.395683453177;5940.970295148497;5957.851053723692;5959.970200148971;5973.789697811838;4928.556429921325;5484.040292948638;5804.21643078179;5818.76724930998;5820.330661973927;5161.535461808456;5306.8407947761625;5480.369757477941;5505.064139096443;5867.5305975521005;4860.295257187618;5079.568236699774;5494.890102198014;5692.032354499662;5726.682862941463;5216.087184742731;5276.894462110659;5807.970960145341;5839.3868643793485;5919.556033297606;5594.440555944373;5622.522085622768;5668.348139963792;5670.234518339971;5680.40355762639 09:41:29 INFO - PROCESS | 6572 | |5;yui.html;691.851251980824;700.075899812248;720.6144712578771;785.3677789379548;835.6740871060288;14229.43082276709;15334.923325383357;15353.078815271092;15357.539273821803;15433.45982890599;13931.094478858868;14018.929905350484;14168;14168.504102356419;14199.645008874782;667.8063361625123;695.7564852301705;743.9590822504773;856.6316483911916;861.4271509446211;825.9504429734235;853.5262929074361;868.6134670071789;872.5942436766902;877.5568337989306;902.6163880350857;935.2985261054209;937.2876613773514;938.1837801113041;939.2532936315612;916.8899732032199;921.7972046149837;929.7675581104724;929.7954450020984;929.8047410043899;675.625028109404;689.2280645676874;689.2659317826499;694.9965250173767;695.1310861423221;14670.926645366811;14812.555623331318;14828.70342593153;14982.700345993018;15205.619859503491;13862.93068534651;14074.22591757453;14124;14171.008029437944;14349;11317.773644527146;11383.487743051583;11444.828327575093;11488.253263537843;11606.767864642745;3590.4793804898404;3664;3709.3137769512455;3711.536057992751;3841.078141246081;102131.97868021374;103073.48463257635;103213;103964;104046;10432.18927356686;10691.09125724322;10776.67669969902;10922.126229901747;11096.223264371578;22528.32415027552;22568.887155564116;22777.202797902326;22790.31629051131;22862.542749144923;7648.847023059619;7705.9229407706325;7717.382609391235;7815.726449574237;7844.254795794504;4174.144300418407;4218.49378074633;4253.468316460442;4269.637080848101;4366.694331396835;3472.3228970351042;3562.946555801665;3566.4293713006314;3571.8571257149424;3573.8927832165077;617.117521943625;640.8910485217431;660.3330636057616;661.2792056658169;668.5453891353828;566.4873289672853;590.9527237820964;668.96978652875;671.4997326991422;684.948628852828;8117.472364296302;8118.715844945398;8445;10223.795524089477;10433.478326083665;549.9835004949858;573.6816067082725;609.960352577081;633.979293337726;696.2828286864537;4274.700770946004;4385.342198670225;4917.65576409655;4924.704517728876;4958.504149585085;80934;95266.52366738122;96117.51941240249;99113.5132973006;100863;73962;79915;81915;81930.59034704907;86432.70350944741;52804.73597631987;58740.7062964691;68846.65576672084;75736.03654940006;78568;10137.290389672653;10408.47957602132;10576.365418074633;10778.89221107779;10857.131429485464;44429;48047;48919;51213;52292;60017.79964400775;62654.37345626485;63103.6844815773;63640.68179659072;63997.36002639914;12870.16343937641;12960.157589756636;13099.476020959055;13475.32623368808;13912.652183695489;14288.481116380537;14476.288751854036;14601;14638.707225855636;15330.310136044129;13069.803952940523;13128.146670466771;13414.731705366034;13490.32548372597;13528.323583820573;12890.96872250199;13372.892035503559;13522.163943211734;13588.45646174142;13629.795553066513;12804.359782010675;13158;13251.867481325064;13440.865591343962;13597.932010339884;10453.895461045293;10535.367877927349;10823.837642435212;11087.722806929569;11102.333859968428;9248.84900662922;10451.216158788273;10599.788004239717;10624.415657138932;10648.680539583826 09:41:29 INFO - PROCESS | 6572 | __end_tp_report 09:41:29 INFO - PROCESS | 6572 | __start_cc_report 09:41:29 INFO - PROCESS | 6572 | _x_x_mozilla_cycle_collect,389 09:41:29 INFO - PROCESS | 6572 | __end_cc_report 09:41:29 INFO - PROCESS | 6572 | __startTimestamp1487698889832__endTimestamp 09:41:29 INFO - PROCESS | 6572 | 09:41:29 INFO - PROCESS | 6572 | ------- Summary: start ------- 09:41:29 INFO - PROCESS | 6572 | Number of tests: 6 09:41:29 INFO - PROCESS | 6572 | 09:41:29 INFO - PROCESS | 6572 | [#0] dojo.html Cycles:165 Average:5800.32 Median:6076.97 stddev:657.92 (10.8%) stddev-sans-first:630.10 09:41:29 INFO - PROCESS | 6572 | Values: 3303.3 3349.3 3397.2 3402.1 3402.6 5174.1 5751.3 5841.6 5891.0 5902.0 5824.1 5853.6 5937.2 5944.7 5958.9 4937.3 5352.9 5353.9 5367.2 5399.4 5410.8 5422.6 5447.7 5453.2 5484.6 5262.3 5286.0 5412.0 5418.4 5425.4 4862.5 5357.3 5373.3 5374.8 5379.2 4475.5 5161.5 5300.0 5321.4 5370.1 5247.9 5918.9 5925.8 5947.9 5950.7 6080.7 6150.9 6252.3 6257.2 6277.2 6195.6 6222.9 6250.9 6251.8 6307.7 5970.0 5987.6 5992.6 6011.1 6027.9 6321.5 6386.9 6402.3 6403.4 6411.8 4665.4 5549.6 6107.4 6112.9 6197.6 6267.7 6272.0 6278.3 6283.2 6308.3 6262.0 6262.4 6264.5 6269.8 6272.1 6126.5 6148.1 6224.8 6269.1 6290.6 5044.9 5056.8 5059.1 5077.4 5085.4 4475.4 4508.9 4509.8 4525.7 4547.2 5918.1 5979.3 6009.0 6072.8 6077.0 5074.8 6253.4 6263.1 6280.2 6293.7 5956.7 6054.3 6057.6 6061.6 6086.4 5462.0 5482.2 5575.9 5581.0 5588.8 6334.2 6358.5 6360.7 6420.6 6485.7 6145.4 6250.8 6263.2 6271.1 6399.7 6254.2 6299.7 6304.8 6349.7 6378.7 6136.5 6158.4 6281.2 6284.1 6304.5 6154.0 6247.9 6258.7 6259.9 6271.7 6220.8 6222.2 6245.6 6266.7 6271.1 6097.2 6117.4 6180.2 6264.8 6294.8 5986.2 6091.8 6144.7 6160.6 6163.9 5146.5 5187.9 6008.9 6255.8 6260.0 4789.4 6043.6 6131.3 6144.9 6175.1 09:41:29 INFO - PROCESS | 6572 | 09:41:29 INFO - PROCESS | 6572 | [#1] ext.html Cycles:180 Average:68517.73 Median:38301.71 stddev:146342.07 (382.1%) stddev-sans-first:146663.37 09:41:29 INFO - PROCESS | 6572 | Values: 452.9 487.9 493.9 499.9 513.9 40514.8 42559.1 42788.8 44772.8 44863.3 36699.4 38367.6 43374.9 44237.8 44918.6 6250.7 8543.1 9167.4 9373.6 9390.7 11072.8 11340.1 11342.7 11505.4 11544.4 11312.7 11375.3 11394.7 11398.5 11402.8 11231.3 11274.4 11309.5 11312.0 11312.9 11208.8 11296.0 11297.3 11302.2 11323.0 41440.8 41841.6 41852.4 41875.0 41964.0 3588.3 3972.5 3981.9 4031.0 4061.5 26263.5 27970.7 28894.0 29462.1 29535.1 3103.9 3128.3 3227.0 3321.6 3341.6 497156.0 497518.5 501954.0 505945.0 506254.0 57381.7 57687.4 57723.4 57754.4 57852.4 61898.4 62182.7 62314.0 62344.7 62377.4 15722.3 16088.8 16124.6 16143.0 16166.4 9132.6 11235.9 11523.0 12348.9 12717.0 18531.3 18710.0 18711.1 18728.0 18809.0 7629.7 7772.3 7780.8 7854.3 7915.9 863.1 870.9 878.7 889.3 910.4 30304.7 30357.8 30363.5 30490.2 30781.1 1013.1 1049.4 1073.6 1086.8 1198.3 13221.9 14091.6 14745.6 15706.3 15921.2 548367.0 562179.0 653231.0 654756.0 656991.0 61994.0 62457.8 62503.7 62869.0 62929.0 1376.8 1409.2 1420.9 1422.8 1428.9 24771.0 28601.4 29876.6 30377.7 30424.2 46695.0 53289.5 53608.0 53658.0 53737.0 516511.0 519948.0 521127.4 521293.0 522061.0 24968.9 25500.6 26664.9 27047.2 27201.5 47563.0 48048.3 48226.5 48232.8 49231.5 38127.4 38702.4 39404.8 39835.4 41319.8 38235.8 38857.6 39172.6 40382.6 40528.0 35012.5 36967.4 37516.0 38404.8 38626.0 27027.3 32533.8 33030.5 33697.2 34437.1 24023.8 24120.5 24387.0 24546.4 24804.3 09:41:29 INFO - PROCESS | 6572 | 09:41:29 INFO - PROCESS | 6572 | [#2] jquery.html Cycles:180 Average:40293.22 Median:3012.03 stddev:123471.58 (4099.3%) stddev-sans-first:123781.59 09:41:29 INFO - PROCESS | 6572 | Values: 390.3 391.6 398.4 406.4 424.5 18351.6 18983.0 19352.2 19426.2 19558.8 20341.0 20531.4 20658.8 20732.9 20746.9 4866.5 5714.7 5901.7 5975.9 5982.3 5797.8 5849.5 5933.6 5946.2 5960.4 5584.2 5737.7 5762.4 5771.9 5818.4 5670.7 5755.1 5763.3 5807.4 5817.5 5144.3 5160.9 5166.4 5167.2 5171.1 22636.1 23952.0 24208.0 24212.4 24315.9 2829.5 2896.2 2991.0 2998.9 3025.2 10018.8 10053.8 10082.2 10343.7 10478.4 1738.9 1802.8 1837.6 1844.8 1847.7 135499.3 138213.0 141712.0 143013.0 143233.0 11958.5 12061.4 12070.9 12087.9 12132.5 15849.0 15850.8 15885.8 15902.8 15921.8 8035.6 8145.7 8688.1 8971.6 9010.2 8553.4 8596.5 8722.2 8731.8 8738.9 4688.8 4824.5 4831.4 4839.9 4919.2 1989.1 2043.3 2044.4 2044.9 2045.2 862.8 983.9 1017.3 1028.2 1056.3 20769.8 20901.3 20974.5 21106.0 21336.8 873.6 907.2 1048.1 1071.5 1075.8 9220.0 9906.7 11100.2 11115.0 11177.9 710567.0 717626.0 748133.0 750830.0 769436.0 141725.0 147221.3 151644.0 157919.0 159458.0 799.2 908.5 918.5 940.1 943.7 17625.3 18375.5 18515.7 18582.3 18664.5 33078.7 57375.4 59362.0 59762.4 60443.1 85772.3 85824.3 87393.0 88685.0 89538.0 7595.7 7878.8 8013.0 8215.3 8274.9 11517.7 12267.0 12305.1 12432.9 12673.6 9791.8 9920.9 10048.4 10672.9 10698.1 10313.9 10404.1 10513.1 10712.6 10811.0 5900.3 6757.7 10926.0 10948.9 11177.8 7433.4 7480.7 7482.7 7689.5 7690.7 5437.2 6412.9 8191.3 10065.2 10633.1 09:41:29 INFO - PROCESS | 6572 | 09:41:29 INFO - PROCESS | 6572 | [#3] mootools.html Cycles:180 Average:5435.88 Median:5188.74 stddev:3006.20 (57.9%) stddev-sans-first:2998.32 09:41:29 INFO - PROCESS | 6572 | Values: 1268.7 1369.2 1425.8 1431.6 1445.0 7606.1 7854.5 8117.7 8559.5 8585.6 8033.7 8289.5 8307.8 8411.3 8425.5 2337.1 2360.2 2361.3 2373.5 2377.2 2091.3 2185.2 2338.2 2375.0 2388.2 1841.3 2349.2 2350.0 2351.9 2353.1 2066.9 2169.7 2292.4 2321.0 2325.7 2327.5 2332.7 2373.2 2386.5 2402.4 8444.1 8459.6 8476.0 8531.1 8561.1 5145.7 5296.0 5318.3 5331.8 5338.2 2835.9 3350.8 3412.7 3479.4 3540.6 1203.5 1206.9 1225.4 1228.9 1267.2 10883.3 10975.7 10985.0 10994.4 11068.1 4431.6 4741.2 4780.2 4802.5 4814.0 9620.0 9631.5 9653.7 9722.3 9728.7 5231.8 5319.7 5327.2 5330.3 5340.4 4604.0 4676.4 4732.3 4856.5 4866.1 3923.2 3989.9 4001.4 4048.9 4106.3 1204.2 1239.8 1263.7 1293.1 1297.6 852.6 870.4 875.3 896.0 904.4 4046.9 5399.1 5457.2 5604.2 5608.7 774.3 822.6 907.6 930.3 956.0 3402.9 3410.4 3482.7 3512.3 3568.4 9850.6 10172.2 10198.0 10201.9 10261.1 10058.7 10105.5 10149.7 10161.3 10167.0 7422.5 7821.5 7854.9 7900.9 7907.4 5256.8 5289.2 5370.7 5417.1 5545.7 8917.0 9117.1 9135.3 9292.7 9455.6 8953.7 9250.9 9362.2 9425.3 9527.2 7178.9 7215.6 7223.2 7308.0 7334.9 7835.7 7870.2 7873.2 7894.9 7897.7 7033.4 7136.7 7167.4 7222.3 7291.5 5818.4 7051.8 7092.1 7146.7 7147.4 6239.9 6395.9 6446.0 6498.2 6521.5 4076.5 4199.8 4730.2 4814.2 4819.6 4788.0 4837.5 4847.9 4951.6 5019.6 09:41:29 INFO - PROCESS | 6572 | 09:41:29 INFO - PROCESS | 6572 | [#4] prototype.html Cycles:180 Average:4604.95 Median:5214.76 stddev:1397.91 (26.8%) stddev-sans-first:1364.90 09:41:29 INFO - PROCESS | 6572 | Values: 351.7 355.8 357.7 361.5 369.1 5213.4 5314.5 5554.4 5611.1 5618.6 4652.2 5265.3 5330.9 5397.5 5505.6 3722.2 4306.4 4342.4 4343.8 4351.5 3853.5 4022.4 4128.9 4192.8 4360.7 3851.5 4253.1 4262.6 4281.7 4296.4 3828.3 3867.3 4060.9 4188.4 4307.7 3495.8 3809.4 3812.4 3819.2 3819.6 4399.8 5112.4 5131.0 5336.5 5380.2 4584.6 4921.2 4965.7 4966.9 5003.2 5565.9 5804.8 5814.9 5850.4 5855.2 3951.8 3951.9 3978.8 4177.4 4199.4 5367.9 6058.9 6111.3 6133.5 6273.6 3316.7 4163.4 4309.5 4311.5 4331.9 3327.9 3940.6 3989.9 4015.3 4046.7 4085.7 4099.7 4117.8 4589.3 4687.5 4972.8 5501.5 5526.1 5528.9 5827.4 1252.3 1292.6 1296.9 1315.1 1323.5 831.7 860.4 885.5 886.9 895.0 4519.7 4920.2 4960.6 5197.0 5262.5 4708.2 5417.9 5540.8 5665.4 5722.6 4676.0 4757.9 5245.8 5273.4 5320.7 2482.7 2888.6 2947.9 2971.0 2985.3 5315.0 5647.9 5711.7 5955.5 6047.2 5041.4 5338.4 5343.0 5665.3 5857.0 5356.8 5509.0 5515.7 5630.8 5955.8 4808.9 4999.2 5543.0 5714.6 5753.4 5074.0 5457.4 5588.7 5643.7 5705.9 5273.5 5333.3 5467.6 6005.8 6074.5 3198.2 3395.2 3685.7 3800.5 3834.0 5755.4 5941.0 5957.9 5960.0 5973.8 4928.6 5484.0 5804.2 5818.8 5820.3 5161.5 5306.8 5480.4 5505.1 5867.5 4860.3 5079.6 5494.9 5692.0 5726.7 5216.1 5276.9 5808.0 5839.4 5919.6 5594.4 5622.5 5668.3 5670.2 5680.4 09:41:29 INFO - PROCESS | 6572 | 09:41:29 INFO - PROCESS | 6572 | [#5] yui.html Cycles:180 Average:19438.23 Median:3776.31 stddev:27253.32 (721.7%) stddev-sans-first:27293.43 09:41:29 INFO - PROCESS | 6572 | Values: 691.9 700.1 720.6 785.4 835.7 14229.4 15334.9 15353.1 15357.5 15433.5 13931.1 14018.9 14168.0 14168.5 14199.6 667.8 695.8 744.0 856.6 861.4 826.0 853.5 868.6 872.6 877.6 902.6 935.3 937.3 938.2 939.3 916.9 921.8 929.8 929.8 929.8 675.6 689.2 689.3 695.0 695.1 14670.9 14812.6 14828.7 14982.7 15205.6 13862.9 14074.2 14124.0 14171.0 14349.0 11317.8 11383.5 11444.8 11488.3 11606.8 3590.5 3664.0 3709.3 3711.5 3841.1 102132.0 103073.5 103213.0 103964.0 104046.0 10432.2 10691.1 10776.7 10922.1 11096.2 22528.3 22568.9 22777.2 22790.3 22862.5 7648.8 7705.9 7717.4 7815.7 7844.3 4174.1 4218.5 4253.5 4269.6 4366.7 3472.3 3562.9 3566.4 3571.9 3573.9 617.1 640.9 660.3 661.3 668.5 566.5 591.0 669.0 671.5 684.9 8117.5 8118.7 8445.0 10223.8 10433.5 550.0 573.7 610.0 634.0 696.3 4274.7 4385.3 4917.7 4924.7 4958.5 80934.0 95266.5 96117.5 99113.5 100863.0 73962.0 79915.0 81915.0 81930.6 86432.7 52804.7 58740.7 68846.7 75736.0 78568.0 10137.3 10408.5 10576.4 10778.9 10857.1 44429.0 48047.0 48919.0 51213.0 52292.0 60017.8 62654.4 63103.7 63640.7 63997.4 12870.2 12960.2 13099.5 13475.3 13912.7 14288.5 14476.3 14601.0 14638.7 15330.3 13069.8 13128.1 13414.7 13490.3 13528.3 12891.0 13372.9 13522.2 13588.5 13629.8 12804.4 13158.0 13251.9 13440.9 13597.9 10453.9 10535.4 10823.8 11087.7 11102.3 9248.8 10451.2 10599.8 10624.4 10648.7 09:41:29 INFO - PROCESS | 6572 | -------- Summary: end -------- 09:41:29 INFO - PROCESS | 6572 | 09:41:30 INFO - TEST-INFO | 6572: exit 0 09:41:30 INFO - TEST-OK | dromaeo_css | took 1116214ms 09:41:30 INFO - TEST-START | kraken 09:41:30 INFO - operating with platform_type : mac_ 09:41:30 INFO - Initialising browser for kraken test... 09:41:30 INFO - TEST-INFO | started process 6577 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/tmpbomK8R/profile http://localhost:57225/getInfo.html) 09:41:33 INFO - PROCESS | 6577 | 2017-02-21 09:41:33.420 plugin-container[6578:120941] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 09:41:33 INFO - PROCESS | 6577 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:41:33 INFO - PROCESS | 6577 | 2017-02-21 09:41:33.423 plugin-container[6578:120941] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x480b, name = 'com.apple.CFPasteboardClient' 09:41:33 INFO - PROCESS | 6577 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:41:33 INFO - PROCESS | 6577 | 2017-02-21 09:41:33.423 plugin-container[6578:120941] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:41:33 INFO - PROCESS | 6577 | __metrics Screen width/height:1600/1200 09:41:33 INFO - PROCESS | 6577 | colorDepth:24 09:41:33 INFO - PROCESS | 6577 | Browser inner width/height: 1024/689 09:41:33 INFO - PROCESS | 6577 | __metrics 09:41:33 ERROR - PROCESS | 6577 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 09:41:34 INFO - TEST-INFO | 6577: exit 0 09:41:34 INFO - Browser initialized. 09:41:34 INFO - Running cycle 1/1 for kraken test... 09:41:34 INFO - TEST-INFO | started process 6579 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/tmpbomK8R/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:41:36 INFO - PROCESS | 6579 | 2017-02-21 09:41:36.683 plugin-container[6580:121066] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4343, name = 'com.apple.tsm.portname' 09:41:36 INFO - PROCESS | 6579 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:41:36 INFO - PROCESS | 6579 | 2017-02-21 09:41:36.684 plugin-container[6580:121066] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 09:41:36 INFO - PROCESS | 6579 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:41:36 INFO - PROCESS | 6579 | 2017-02-21 09:41:36.684 plugin-container[6580:121066] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:41:46 INFO - PROCESS | 6579 | RSS: Main: 219615232 09:41:46 INFO - PROCESS | 6579 | 09:41:49 INFO - PROCESS | 6579 | Cycle 1(1): loaded http://localhost:57225/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57225/tests/kraken/driver.html?testName=audio-beat-detection) 09:41:50 INFO - PROCESS | 6579 | RSS: Main: 233910272 09:41:50 INFO - PROCESS | 6579 | 09:41:52 INFO - PROCESS | 6579 | Cycle 1(1): loaded http://localhost:57225/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57225/tests/kraken/driver.html?testName=audio-dft) 09:41:53 INFO - PROCESS | 6579 | RSS: Main: 241467392 09:41:53 INFO - PROCESS | 6579 | 09:41:56 INFO - PROCESS | 6579 | Cycle 1(1): loaded http://localhost:57225/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57225/tests/kraken/driver.html?testName=audio-fft) 09:41:57 INFO - PROCESS | 6579 | RSS: Main: 245141504 09:41:57 INFO - PROCESS | 6579 | 09:41:59 INFO - PROCESS | 6579 | Cycle 1(1): loaded http://localhost:57225/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57225/tests/kraken/driver.html?testName=audio-oscillator) 09:42:00 INFO - PROCESS | 6579 | RSS: Main: 245436416 09:42:00 INFO - PROCESS | 6579 | 09:42:02 INFO - PROCESS | 6579 | Cycle 1(1): loaded http://localhost:57225/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57225/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:42:02 INFO - PROCESS | 6579 | RSS: Main: 246861824 09:42:02 INFO - PROCESS | 6579 | 09:42:05 INFO - PROCESS | 6579 | Cycle 1(1): loaded http://localhost:57225/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57225/tests/kraken/driver.html?testName=imaging-darkroom) 09:42:06 INFO - PROCESS | 6579 | RSS: Main: 250839040 09:42:06 INFO - PROCESS | 6579 | 09:42:09 INFO - PROCESS | 6579 | Cycle 1(1): loaded http://localhost:57225/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57225/tests/kraken/driver.html?testName=imaging-desaturate) 09:42:10 INFO - PROCESS | 6579 | RSS: Main: 249716736 09:42:10 INFO - PROCESS | 6579 | 09:42:13 INFO - PROCESS | 6579 | Cycle 1(1): loaded http://localhost:57225/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57225/tests/kraken/driver.html?testName=json-parse-financial) 09:42:13 INFO - PROCESS | 6579 | RSS: Main: 250228736 09:42:13 INFO - PROCESS | 6579 | 09:42:15 INFO - PROCESS | 6579 | Cycle 1(1): loaded http://localhost:57225/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57225/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:42:16 INFO - PROCESS | 6579 | RSS: Main: 249856000 09:42:16 INFO - PROCESS | 6579 | 09:42:18 INFO - PROCESS | 6579 | Cycle 1(1): loaded http://localhost:57225/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57225/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:42:18 INFO - PROCESS | 6579 | RSS: Main: 249847808 09:42:18 INFO - PROCESS | 6579 | 09:42:21 INFO - PROCESS | 6579 | Cycle 1(1): loaded http://localhost:57225/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57225/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:42:21 INFO - PROCESS | 6579 | RSS: Main: 249868288 09:42:21 INFO - PROCESS | 6579 | 09:42:24 INFO - PROCESS | 6579 | Cycle 1(1): loaded http://localhost:57225/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57225/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:42:25 INFO - PROCESS | 6579 | RSS: Main: 250707968 09:42:25 INFO - PROCESS | 6579 | 09:42:28 INFO - PROCESS | 6579 | Cycle 1(1): loaded http://localhost:57225/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57225/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:42:29 INFO - PROCESS | 6579 | RSS: Main: 250019840 09:42:29 INFO - PROCESS | 6579 | 09:42:30 INFO - PROCESS | 6579 | Cycle 1(1): loaded http://localhost:57225/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57225/tests/kraken/driver.html?testName=ai-astar) 09:42:31 INFO - PROCESS | 6579 | RSS: Main: 251838464 09:42:31 INFO - PROCESS | 6579 | 09:42:31 INFO - PROCESS | 6579 | __start_tp_report 09:42:31 INFO - PROCESS | 6579 | _x_x_mozilla_page_load 09:42:31 INFO - PROCESS | 6579 | _x_x_mozilla_page_load_details 09:42:31 INFO - PROCESS | 6579 | |i|pagename|runs| 09:42:31 INFO - PROCESS | 6579 | |0;ai-astar;108;119;119;120;119;119;118;118;120;120 09:42:31 INFO - PROCESS | 6579 | |1;audio-beat-detection;152;111;112;146;114;115;111;117;133;111 09:42:31 INFO - PROCESS | 6579 | |2;audio-dft;205;200;200;203;228;281;204;199;199;199 09:42:31 INFO - PROCESS | 6579 | |3;audio-fft;74;61;62;87;61;60;62;76;94;83 09:42:31 INFO - PROCESS | 6579 | |4;audio-oscillator;91;72;87;76;99;101;86;70;72;83 09:42:31 INFO - PROCESS | 6579 | |5;imaging-gaussian-blur;90;87;89;87;91;106;118;80;136;133 09:42:31 INFO - PROCESS | 6579 | |6;imaging-darkroom;123;153;101;119;96;96;116;154;105;97 09:42:31 INFO - PROCESS | 6579 | |7;imaging-desaturate;94;90;92;92;95;93;89;96;92;143 09:42:31 INFO - PROCESS | 6579 | |8;json-parse-financial;61;59;60;61;67;64;61;64;64;64 09:42:31 INFO - PROCESS | 6579 | |9;json-stringify-tinderbox;69;74;69;84;77;112;92;61;104;102 09:42:31 INFO - PROCESS | 6579 | |10;stanford-crypto-aes;86;87;87;88;87;80;96;105;104;105 09:42:31 INFO - PROCESS | 6579 | |11;stanford-crypto-ccm;141;141;163;150;160;162;161;144;211;183 09:42:31 INFO - PROCESS | 6579 | |12;stanford-crypto-pbkdf2;171;172;171;172;174;170;182;232;196;174 09:42:31 INFO - PROCESS | 6579 | |13;stanford-crypto-sha256-iterative;68;68;67;67;67;64;69;67;68;66 09:42:31 INFO - PROCESS | 6579 | __end_tp_report 09:42:31 INFO - PROCESS | 6579 | __start_cc_report 09:42:31 INFO - PROCESS | 6579 | _x_x_mozilla_cycle_collect,987 09:42:31 INFO - PROCESS | 6579 | __end_cc_report 09:42:31 INFO - PROCESS | 6579 | __startTimestamp1487698951015__endTimestamp 09:42:31 INFO - PROCESS | 6579 | 09:42:31 INFO - PROCESS | 6579 | ------- Summary: start ------- 09:42:31 INFO - PROCESS | 6579 | Number of tests: 14 09:42:31 INFO - PROCESS | 6579 | 09:42:31 INFO - PROCESS | 6579 | [#0] ai-astar Cycles:10 Average:118.00 Median:119.00 stddev:3.59 (3.0%) stddev-sans-first:0.78 09:42:31 INFO - PROCESS | 6579 | Values: 108.0 119.0 119.0 120.0 119.0 119.0 118.0 118.0 120.0 120.0 09:42:31 INFO - PROCESS | 6579 | 09:42:31 INFO - PROCESS | 6579 | [#1] audio-beat-detection Cycles:10 Average:122.20 Median:116.00 stddev:15.63 (13.5%) stddev-sans-first:12.30 09:42:31 INFO - PROCESS | 6579 | Values: 152.0 111.0 112.0 146.0 114.0 115.0 111.0 117.0 133.0 111.0 09:42:31 INFO - PROCESS | 6579 | 09:42:31 INFO - PROCESS | 6579 | [#2] audio-dft Cycles:10 Average:211.80 Median:203.50 stddev:25.83 (12.7%) stddev-sans-first:27.28 09:42:31 INFO - PROCESS | 6579 | Values: 205.0 200.0 200.0 203.0 228.0 281.0 204.0 199.0 199.0 199.0 09:42:31 INFO - PROCESS | 6579 | 09:42:31 INFO - PROCESS | 6579 | [#3] audio-fft Cycles:10 Average:72.00 Median:75.00 stddev:12.63 (16.8%) stddev-sans-first:13.38 09:42:31 INFO - PROCESS | 6579 | Values: 74.0 61.0 62.0 87.0 61.0 60.0 62.0 76.0 94.0 83.0 09:42:31 INFO - PROCESS | 6579 | 09:42:31 INFO - PROCESS | 6579 | [#4] audio-oscillator Cycles:10 Average:83.70 Median:84.50 stddev:11.18 (13.2%) stddev-sans-first:11.54 09:42:31 INFO - PROCESS | 6579 | Values: 91.0 72.0 87.0 76.0 99.0 101.0 86.0 70.0 72.0 83.0 09:42:31 INFO - PROCESS | 6579 | 09:42:31 INFO - PROCESS | 6579 | [#5] imaging-gaussian-blur Cycles:10 Average:101.70 Median:87.00 stddev:20.43 (23.5%) stddev-sans-first:21.22 09:42:31 INFO - PROCESS | 6579 | Values: 90.0 87.0 89.0 87.0 91.0 106.0 118.0 80.0 136.0 133.0 09:42:31 INFO - PROCESS | 6579 | 09:42:31 INFO - PROCESS | 6579 | [#6] imaging-darkroom Cycles:10 Average:116.00 Median:153.50 stddev:22.06 (14.4%) stddev-sans-first:23.25 09:42:31 INFO - PROCESS | 6579 | Values: 123.0 153.0 101.0 119.0 96.0 96.0 116.0 154.0 105.0 97.0 09:42:31 INFO - PROCESS | 6579 | 09:42:31 INFO - PROCESS | 6579 | [#7] imaging-desaturate Cycles:10 Average:97.60 Median:92.50 stddev:16.09 (17.4%) stddev-sans-first:17.01 09:42:31 INFO - PROCESS | 6579 | Values: 94.0 90.0 92.0 92.0 95.0 93.0 89.0 96.0 92.0 143.0 09:42:31 INFO - PROCESS | 6579 | 09:42:31 INFO - PROCESS | 6579 | [#8] json-parse-financial Cycles:10 Average:62.50 Median:64.00 stddev:2.46 (3.8%) stddev-sans-first:2.55 09:42:31 INFO - PROCESS | 6579 | Values: 61.0 59.0 60.0 61.0 67.0 64.0 61.0 64.0 64.0 64.0 09:42:31 INFO - PROCESS | 6579 | 09:42:31 INFO - PROCESS | 6579 | [#9] json-stringify-tinderbox Cycles:10 Average:84.40 Median:71.50 stddev:17.32 (24.2%) stddev-sans-first:17.45 09:42:31 INFO - PROCESS | 6579 | Values: 69.0 74.0 69.0 84.0 77.0 112.0 92.0 61.0 104.0 102.0 09:42:31 INFO - PROCESS | 6579 | 09:42:31 INFO - PROCESS | 6579 | [#10] stanford-crypto-aes Cycles:10 Average:92.50 Median:87.00 stddev:9.23 (10.6%) stddev-sans-first:9.48 09:42:31 INFO - PROCESS | 6579 | Values: 86.0 87.0 87.0 88.0 87.0 80.0 96.0 105.0 104.0 105.0 09:42:31 INFO - PROCESS | 6579 | 09:42:31 INFO - PROCESS | 6579 | [#11] stanford-crypto-ccm Cycles:10 Average:161.60 Median:161.50 stddev:21.59 (13.4%) stddev-sans-first:21.58 09:42:31 INFO - PROCESS | 6579 | Values: 141.0 141.0 163.0 150.0 160.0 162.0 161.0 144.0 211.0 183.0 09:42:31 INFO - PROCESS | 6579 | 09:42:31 INFO - PROCESS | 6579 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.40 Median:174.00 stddev:19.45 (11.2%) stddev-sans-first:20.27 09:42:31 INFO - PROCESS | 6579 | Values: 171.0 172.0 171.0 172.0 174.0 170.0 182.0 232.0 196.0 174.0 09:42:31 INFO - PROCESS | 6579 | 09:42:31 INFO - PROCESS | 6579 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.10 Median:67.50 stddev:1.37 (2.0%) stddev-sans-first:1.41 09:42:31 INFO - PROCESS | 6579 | Values: 68.0 68.0 67.0 67.0 67.0 64.0 69.0 67.0 68.0 66.0 09:42:31 INFO - PROCESS | 6579 | -------- Summary: end -------- 09:42:31 INFO - PROCESS | 6579 | 09:42:31 INFO - TEST-INFO | 6579: exit 0 09:42:31 INFO - TEST-OK | kraken | took 61424ms 09:42:31 INFO - SUITE-END | took 1177s 09:42:32 INFO - Completed test suite (00:19:37) 09:42:32 INFO - javascript benchmark 09:42:32 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7295.846913564226, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3303.273279878427, 3349.263162104336, 3397.184675677838, 3402.064432281123, 3402.642722514136, 5174.146265866137, 5751.309842818862, 5841.591088623798, 5891.0, 5902.026165682668, 5824.126381042845, 5853.619514731539, 5937.22816033915, 5944.702764861761, 5958.940410595893, 4937.259411088329, 5352.91970620439, 5353.866153346178, 5367.194920761878, 5399.352077750676, 5410.783568657249, 5422.566194704415, 5447.727613619323, 5453.182022696587, 5484.643498172606, 5262.315898933133, 5286.0, 5411.9717253721665, 5418.4039755626845, 5425.430329815372, 4862.5380588844, 5357.276767636358, 5373.274607927958, 5374.811881584145, 5379.246905433242, 4475.507694153641, 5161.483851614846, 5300.0459917214885, 5321.388040375367, 5370.087085195526, 5247.921281180786, 5918.881622367571, 5925.822225333204, 5947.86990471809, 5950.732217050243, 6080.665563394012, 6150.8769824603705, 6252.280987686383, 6257.155284036642, 6277.246730392337, 6195.597286176384, 6222.875542489125, 6250.937490625081, 6251.812445626593, 6307.653079080649, 5970.044792833126, 5987.64074155552, 5992.640441573476, 6011.098335249748, 6027.9095813562835, 6321.525885558602, 6386.87226255477, 6402.263739669951, 6403.423691867662, 6411.807645770635, 4665.440147182292, 5549.556035517149, 6107.370879189147, 6112.898653789767, 6197.597156184834, 6267.655278959701, 6272.0, 6278.30938596754, 6283.214598175228, 6308.337624549448, 6261.968690156611, 6262.373762623702, 6264.5301602380005, 6269.811905642838, 6272.090546870678, 6126.517673797367, 6148.077788331786, 6224.751009959641, 6269.122322874801, 6290.622562646256, 5044.873878153076, 5056.797728090909, 5059.089363914457, 5077.39071311445, 5085.389753229562, 4475.4181956345465, 4508.864734057984, 4509.774511274423, 4525.705829121097, 4547.181507328646, 5918.112283157475, 5979.342272350038, 6009.038553831366, 6072.817815465543, 6076.969615151985, 5074.847754567295, 6253.437190652863, 6263.091851681481, 6280.152179455807, 6293.653849038255, 5956.672383018976, 6054.303755068136, 6057.649225867442, 6061.575529750505, 6086.360932102154, 5461.962227176824, 5482.205080263339, 5575.916361254584, 5581.0, 5588.804391846266, 6334.239891213084, 6358.459530939829, 6360.713767880549, 6420.6147631142285, 6485.708143133476, 6145.446909777963, 6250.843728906813, 6263.217097862767, 6271.122042913905, 6399.680015999088, 6254.21822272216, 6299.685015749103, 6304.779332723516, 6349.68251587428, 6378.74485020594, 6136.519078024374, 6158.353372895782, 6281.183446151971, 6284.088807123033, 6304.464120549805, 6154.01535754295, 6247.937520624736, 6258.749650013949, 6259.874802503833, 6271.686415679289, 6220.844478888064, 6222.222222222223, 6245.5628106032145, 6266.749330026748, 6271.122042914087, 6097.207363042776, 6117.431594453822, 6180.227471566054, 6264.780732674334, 6294.779682711083, 5986.2217911671205, 6091.847703807441, 6144.6620435874665, 6160.568760186744, 6163.876722465615, 5146.459621739664, 5187.870303242449, 6008.909866352095, 6255.78104766331, 6260.0, 4789.401324834395, 6043.576949613484, 6131.294901086432, 6144.8771024580155, 6175.135481032748], "value": 5760.20346224362, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [452.8664044106988, 487.9436021014504, 493.8962817808265, 499.88502644391787, 513.948605139486, 40514.79742601287, 42559.14881702364, 42788.78605606975, 44772.776136119275, 44863.327050094274, 36699.449508257334, 38367.61632383675, 43374.91562710934, 44237.77881110598, 44918.55081449184, 6250.718717657716, 8543.060263371024, 9167.358284920076, 9373.625054997792, 9390.671326503574, 11072.833907491395, 11340.092792576574, 11342.71643208918, 11505.367204803733, 11544.422778861066, 11312.660620181408, 11375.317480951127, 11394.658160255205, 11398.487068081915, 11402.771944561104, 11231.26996745213, 11274.379909104997, 11309.49107290174, 11312.0, 11312.943435282812, 11208.831867521994, 11296.039836613898, 11297.265677731004, 11302.15233857464, 11323.0, 41440.79279603613, 41841.581584184074, 41852.372214416806, 41874.95312617209, 41963.95090122741, 3588.3002814451193, 3972.483577134983, 3981.9203615927518, 4030.979845100785, 4061.5304938474474, 26263.474730505284, 27970.744070485573, 28894.0, 29462.11613651594, 29535.113946581638, 3103.8913638022786, 3128.311771410286, 3226.951595726066, 3321.618013928405, 3341.599008119017, 497156.0, 497518.51240743924, 501954.0, 505945.0, 506254.0, 57381.71309143511, 57687.42312576905, 57723.42276577181, 57754.42245577575, 57852.4214757847, 61898.381016190164, 62182.68908655428, 62314.0, 62344.68827655923, 62377.37622623716, 15722.29249683767, 16088.75866861998, 16124.596885077732, 16143.031418114715, 16166.43417480406, 9132.589033493508, 11235.887641123647, 11523.0, 12348.876511234772, 12717.0, 18531.258749650133, 18709.970951597792, 18711.064446777607, 18727.96996165197, 18809.0, 7629.656665450068, 7772.300492955661, 7780.805479863048, 7854.332381747616, 7915.881261781078, 863.1282404771101, 870.9433886797337, 878.7346221441104, 889.2752406788576, 910.39003867409, 30304.696953030187, 30357.848210758806, 30363.544546831814, 30490.237744056576, 30781.076567703003, 1013.0882206014646, 1049.4123290957089, 1073.592035026685, 1086.766345235778, 1198.3109711915683, 13221.867781322257, 14091.577252682231, 14745.631359216106, 15706.293216805272, 15921.196207293593, 548367.0, 562179.0, 653231.0, 654756.0, 656991.0, 61994.0, 62457.750844982846, 62503.74992500216, 62869.0, 62929.0, 1376.7590671632424, 1409.2319685771279, 1420.8508106648653, 1422.7865820127063, 1428.8642578954982, 24771.018318534112, 28601.42797144087, 29876.551851722677, 30377.69622303749, 30424.239394015327, 46695.0, 53289.467105330004, 53608.0, 53658.0, 53737.0, 516511.0, 519948.0, 521127.3943630258, 521293.0, 522061.0, 24968.875155624104, 25500.597467139487, 26664.866675667272, 27047.1885843425, 27201.455970880867, 47563.0, 48048.27927581019, 48226.5177348236, 48232.7588362056, 49231.50768492412, 38127.42808857925, 38702.419463707505, 39404.80297598494, 39835.40246896357, 41319.7934010328, 38235.80882095572, 38857.6114238854, 39172.6082739169, 40382.596174037884, 40528.0, 35012.47481287732, 36967.44548831716, 37516.0, 38404.80797595994, 38626.0343491415, 27027.32431689145, 32533.83733081414, 33030.50454243236, 33697.15757106092, 34437.139071523416, 24023.75976240215, 24120.51758964776, 24387.02451901975, 24546.386340341633, 24804.255872323858], "value": 20175.68743779501, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [390.2760379496035, 391.6025234387099, 398.3805182940527, 406.36606893246545, 424.48213179920486, 18351.632967340644, 18983.0, 19352.225910963563, 19426.222951081956, 19558.804411955876, 20341.0, 20531.384058478234, 20658.793412065876, 20732.89633551834, 20746.89626551865, 4866.537678920506, 5714.657120572758, 5901.704914754267, 5975.880482390371, 5982.252218472691, 5797.768089276424, 5849.473547380735, 5933.554983376264, 5946.22699049123, 5960.403959604048, 5584.21820945068, 5737.713114344287, 5762.423757624246, 5771.913421298684, 5818.44724751148, 5670.716464176775, 5755.079187330007, 5763.279590051244, 5807.361190269067, 5817.476427121558, 5144.3312369392115, 5160.870978225536, 5166.43169251382, 5167.224916262553, 5171.095058364809, 22636.094556217733, 23952.0, 24208.0, 24212.426192297444, 24315.905784239752, 2829.5189817731034, 2896.174590241771, 2991.0, 2998.9100326990224, 3025.1529571719952, 10018.849717254247, 10053.849192262122, 10082.243831712578, 10343.689689309333, 10478.371297722086, 1738.9134286641186, 1802.8467580255694, 1837.5589858434014, 1844.7786265648074, 1847.7136043913215, 135499.32250338784, 138213.0, 141712.0, 143013.0, 143233.0, 11958.461869215906, 12061.396930153458, 12070.879291206975, 12087.939560302142, 12132.5146994121, 15849.049057056614, 15850.76223856643, 15885.761713574306, 15902.840971590367, 15921.840781592267, 8035.638396272182, 8145.674173033012, 8688.087750786202, 8971.596278167499, 9010.234130098885, 8553.44402613828, 8596.484210947237, 8722.171393717586, 8731.781705457415, 8738.86891696625, 4688.765561721901, 4824.49342819006, 4831.444383895897, 4839.903201935942, 4919.188333924933, 1989.1049027937486, 2043.3359158273324, 2044.427560283123, 2044.9079791409124, 2045.1717054592914, 862.8274345130999, 983.9262055345878, 1017.3133135133755, 1028.228828378716, 1056.2606175677056, 20769.792302076785, 20901.268455603982, 20974.47563810917, 21106.0, 21336.78663213379, 873.6287077991828, 907.2424525521263, 1048.1016172693776, 1071.517816982361, 1075.8063548561333, 9219.953900230455, 9906.7027989159, 11100.167487438475, 11115.0, 11177.888221117684, 710567.0, 717626.0, 748133.0, 750830.0, 769436.0, 141725.0, 147221.263893682, 151644.0, 157919.0, 159458.0, 799.1688643810488, 908.4844203751678, 918.4535201555065, 940.0552444793076, 943.6932996776076, 17625.294988200327, 18375.540611484284, 18515.72226416578, 18582.349617763313, 18664.533386665444, 33078.669213307556, 57375.426245737006, 59362.0, 59762.40237597568, 60443.09335359885, 85772.28455430732, 85824.28351432811, 87393.0, 88685.0, 89538.0, 7595.696172153052, 7878.803029924297, 8012.959935200287, 8215.34277257806, 8274.864015170322, 11517.712057198638, 12267.0, 12305.138640295452, 12432.875671243533, 12673.619791406272, 9791.755206119904, 9920.900790992286, 10048.39709617396, 10672.946635267084, 10698.090662293482, 10313.896861031593, 10404.063634272952, 10513.106385956973, 10712.625058122929, 10811.0, 5900.262467191601, 6757.695903684247, 10926.0, 10948.850370710961, 11177.776444470903, 7433.368163705929, 7480.663370148465, 7482.663280152297, 7689.538627682357, 7690.692372305045, 5437.211604317431, 6412.871742565216, 8191.303739182236, 10065.245106616887, 10633.149348051982], "value": 9636.14773135899, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1268.739908318794, 1369.2469141971912, 1425.7861320801871, 1431.6184881589265, 1444.9524095031104, 7606.125295590995, 7854.489458185229, 8117.6752929882805, 8559.486430814155, 8585.570721463926, 8033.678652853879, 8289.461185022985, 8307.833843323146, 8411.285040771541, 8425.452345597532, 2337.1118974789556, 2360.1621424394352, 2361.291612516247, 2373.537160253751, 2377.2274010946426, 2091.3203208957075, 2185.2133232036454, 2338.1582630253097, 2374.976250237493, 2388.235764555343, 1841.3187120765335, 2349.18952961229, 2349.95300093998, 2351.882855643564, 2353.105819788478, 2066.9483262918397, 2169.719865279477, 2292.3695983604475, 2320.9883950580224, 2325.7325407578005, 2327.453048533593, 2332.6734257204007, 2373.15752907717, 2386.4511162432736, 2402.447437089479, 8444.113368096385, 8459.619317130744, 8476.0, 8531.146885311482, 8561.101084386111, 5145.691258524501, 5295.993761185362, 5318.30861987943, 5331.840044798662, 5338.172583249602, 2835.9149225523265, 3350.782199157047, 3412.67579579942, 3479.408500554912, 3540.5751309842726, 1203.5065623094576, 1206.8853458921387, 1225.3873063468266, 1228.9139760216788, 1267.2459886367596, 10883.292585981964, 10975.670729878117, 10985.0, 10994.395308258043, 11068.114550835913, 4431.601155895984, 4741.170295198326, 4780.163471392492, 4802.471728109905, 4814.037192561432, 9620.0, 9631.518424078768, 9653.662121825702, 9722.2708296878, 9728.708138755848, 5231.790728370823, 5319.707416092075, 5327.227552004938, 5330.253764472977, 5340.412554618989, 4603.95396046042, 4676.368690226844, 4732.266498692708, 4856.514348565115, 4866.148424025781, 3923.2349691809823, 3989.860354887623, 4001.419794129893, 4048.8785336439955, 4106.301928672133, 1204.2353105777895, 1239.8283621977146, 1263.7219811641423, 1293.0560690695845, 1297.5588299977865, 852.5950173667459, 870.3689824876914, 875.2778957360204, 896.0367604824839, 904.3850181876387, 4046.8785936421955, 5399.136138217867, 5457.2359869618285, 5604.215409842625, 5608.691521966331, 774.2528460036092, 822.5558198572824, 907.6369452219166, 930.330162283155, 956.0391806234828, 3402.897913062612, 3410.3861304965344, 3482.703970162514, 3512.2799826035603, 3568.37553428149, 9850.60597576089, 10172.186225101974, 10198.031187037219, 10201.877793442716, 10261.076503114606, 10058.748531286776, 10105.494725263707, 10149.746256343502, 10161.288709790391, 10167.034131757471, 7422.480426370103, 7821.491603045783, 7854.8821767673535, 7900.881486777703, 7907.446478746548, 5256.763445645031, 5289.18017707256, 5370.704611246419, 5417.106177480671, 5545.722713864371, 8917.0, 9117.088291171096, 9135.31485138604, 9292.674756383494, 9455.574499147417, 8953.731388058368, 9250.907490925274, 9362.20421264173, 9425.293103017164, 9527.237820974444, 7178.856422871618, 7215.639218039182, 7223.2415596361625, 7307.963460182665, 7334.889976650461, 7835.686572537034, 7870.173631768811, 7873.212678732081, 7894.921050789649, 7897.723579674684, 7033.402160816388, 7136.7145314186855, 7167.426605871622, 7222.349988501061, 7291.453141014551, 5818.389069147678, 7051.788446346618, 7092.078029856086, 7146.714131434893, 7147.356737893615, 6239.875202495834, 6395.904061439175, 6446.000869865178, 6498.187726534184, 6521.478281737544, 4076.510818701775, 4199.811008504686, 4730.172219861579, 4814.229723244264, 4819.614430845594, 4788.0, 4837.540433658797, 4847.903041939212, 4951.579115775199, 5019.598432125495], "value": 4398.281011095852, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.72213950978727, 355.7598620930872, 357.70668052197175, 361.5498704113379, 369.0699437417709, 5213.426523082468, 5314.468553144693, 5554.361248456429, 5611.120274707972, 5618.578606604506, 4652.18564759744, 5265.3155089838265, 5330.920036199451, 5397.541208997231, 5505.642133261335, 3722.162513434476, 4306.375575541545, 4342.413774140482, 4343.804528796211, 4351.456067991501, 3853.479780229661, 4022.4167495713104, 4128.864562245377, 4192.840687046174, 4360.694751367406, 3851.5378154621485, 4253.085586598881, 4262.573742625744, 4281.7002809803325, 4296.355546668009, 3828.272628200647, 3867.2652196082654, 4060.8731276916906, 4188.39268306097, 4307.698461107724, 3495.8426870790877, 3809.3904975204114, 3812.4281357796276, 3819.2170605025904, 3819.6371344722206, 4399.802008909607, 5112.437631860493, 5131.0, 5336.466353364672, 5380.219868119101, 4584.633229341678, 4921.212605983025, 4965.686715214686, 4966.87582810429, 5003.17447621142, 5565.909056843217, 5804.82585522435, 5814.854628634275, 5850.3564607893095, 5855.180274761537, 3951.762894226327, 3951.8616848410165, 3978.7864701266103, 4177.436046133764, 4199.4120823084795, 5367.946320536823, 6058.878822423571, 6111.327753947062, 6133.478654314388, 6273.56085074045, 3316.6517515660507, 4163.437935878678, 4309.457420122143, 4311.504177019683, 4331.895366681476, 3327.851891097616, 3940.625640564142, 3989.940150897739, 4015.31739604268, 4046.676265898721, 4085.733422638991, 4099.651529620016, 4117.773522456234, 4589.311603259471, 4687.5000000000355, 4972.776225069881, 5501.477359650827, 5526.093497743273, 5528.944710552923, 5827.417258274223, 1252.2736812648825, 1292.634478903604, 1296.9157004794847, 1315.0597322914161, 1323.4706117553055, 831.7213733399258, 860.4168330272204, 885.5262434597588, 886.906874778139, 894.9910500895038, 4519.728816271035, 4920.237363208708, 4960.578350840219, 5197.0, 5262.526372626482, 4708.223143181405, 5417.916416716673, 5540.7783688652, 5665.3768085510565, 5722.62656962326, 4676.030720492512, 4757.904841903142, 5245.816396426183, 5273.419923808378, 5320.654157479828, 2482.7020757509213, 2888.581155732407, 2947.938742052859, 2970.9500444693094, 2985.2984548631052, 5315.0, 5647.860446993216, 5711.744305745685, 5955.528455690926, 6047.213862197887, 5041.420236672756, 5338.412774594792, 5342.973285133549, 5665.291838520185, 5857.0, 5356.839294821161, 5508.9724551377785, 5515.669059856422, 5630.805050237517, 5955.76176952917, 4808.8797780055775, 4999.225429886448, 5542.981338529534, 5714.6284292018345, 5753.3958934311295, 5074.035933172833, 5457.435005547386, 5588.748506317307, 5643.661380317194, 5705.885882282414, 5273.499017593323, 5333.280007198979, 5467.637402997208, 6005.759769609342, 6074.483668888194, 3198.2484116232204, 3395.1851555625976, 3685.668289853926, 3800.4988029728656, 3834.0, 5755.395683453177, 5940.970295148497, 5957.851053723692, 5959.970200148971, 5973.789697811838, 4928.556429921325, 5484.040292948638, 5804.21643078179, 5818.76724930998, 5820.330661973927, 5161.535461808456, 5306.8407947761625, 5480.369757477941, 5505.064139096443, 5867.5305975521005, 4860.295257187618, 5079.568236699774, 5494.890102198014, 5692.032354499662, 5726.682862941463, 5216.087184742731, 5276.894462110659, 5807.970960145341, 5839.3868643793485, 5919.556033297606, 5594.440555944373, 5622.522085622768, 5668.348139963792, 5670.234518339971, 5680.40355762639], "value": 4166.8837885655885, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [691.851251980824, 700.075899812248, 720.6144712578771, 785.3677789379548, 835.6740871060288, 14229.43082276709, 15334.923325383357, 15353.078815271092, 15357.539273821803, 15433.45982890599, 13931.094478858868, 14018.929905350484, 14168.0, 14168.504102356419, 14199.645008874782, 667.8063361625123, 695.7564852301705, 743.9590822504773, 856.6316483911916, 861.4271509446211, 825.9504429734235, 853.5262929074361, 868.6134670071789, 872.5942436766902, 877.5568337989306, 902.6163880350857, 935.2985261054209, 937.2876613773514, 938.1837801113041, 939.2532936315612, 916.8899732032199, 921.7972046149837, 929.7675581104724, 929.7954450020984, 929.8047410043899, 675.625028109404, 689.2280645676874, 689.2659317826499, 694.9965250173767, 695.1310861423221, 14670.926645366811, 14812.555623331318, 14828.70342593153, 14982.700345993018, 15205.619859503491, 13862.93068534651, 14074.22591757453, 14124.0, 14171.008029437944, 14349.0, 11317.773644527146, 11383.487743051583, 11444.828327575093, 11488.253263537843, 11606.767864642745, 3590.4793804898404, 3664.0, 3709.3137769512455, 3711.536057992751, 3841.078141246081, 102131.97868021374, 103073.48463257635, 103213.0, 103964.0, 104046.0, 10432.18927356686, 10691.09125724322, 10776.67669969902, 10922.126229901747, 11096.223264371578, 22528.32415027552, 22568.887155564116, 22777.202797902326, 22790.31629051131, 22862.542749144923, 7648.847023059619, 7705.9229407706325, 7717.382609391235, 7815.726449574237, 7844.254795794504, 4174.144300418407, 4218.49378074633, 4253.468316460442, 4269.637080848101, 4366.694331396835, 3472.3228970351042, 3562.946555801665, 3566.4293713006314, 3571.8571257149424, 3573.8927832165077, 617.117521943625, 640.8910485217431, 660.3330636057616, 661.2792056658169, 668.5453891353828, 566.4873289672853, 590.9527237820964, 668.96978652875, 671.4997326991422, 684.948628852828, 8117.472364296302, 8118.715844945398, 8445.0, 10223.795524089477, 10433.478326083665, 549.9835004949858, 573.6816067082725, 609.960352577081, 633.979293337726, 696.2828286864537, 4274.700770946004, 4385.342198670225, 4917.65576409655, 4924.704517728876, 4958.504149585085, 80934.0, 95266.52366738122, 96117.51941240249, 99113.5132973006, 100863.0, 73962.0, 79915.0, 81915.0, 81930.59034704907, 86432.70350944741, 52804.73597631987, 58740.7062964691, 68846.65576672084, 75736.03654940006, 78568.0, 10137.290389672653, 10408.47957602132, 10576.365418074633, 10778.89221107779, 10857.131429485464, 44429.0, 48047.0, 48919.0, 51213.0, 52292.0, 60017.79964400775, 62654.37345626485, 63103.6844815773, 63640.68179659072, 63997.36002639914, 12870.16343937641, 12960.157589756636, 13099.476020959055, 13475.32623368808, 13912.652183695489, 14288.481116380537, 14476.288751854036, 14601.0, 14638.707225855636, 15330.310136044129, 13069.803952940523, 13128.146670466771, 13414.731705366034, 13490.32548372597, 13528.323583820573, 12890.96872250199, 13372.892035503559, 13522.163943211734, 13588.45646174142, 13629.795553066513, 12804.359782010675, 13158.0, 13251.867481325064, 13440.865591343962, 13597.932010339884, 10453.895461045293, 10535.367877927349, 10823.837642435212, 11087.722806929569, 11102.333859968428, 9248.84900662922, 10451.216158788273, 10599.788004239717, 10624.415657138932, 10648.680539583826], "value": 7347.564467379496, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1572.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 119.0, 119.0, 120.0, 119.0, 119.0, 118.0, 118.0, 120.0, 120.0], "value": 118.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 111.0, 112.0, 146.0, 114.0, 115.0, 111.0, 117.0, 133.0, 111.0], "value": 122.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [205.0, 200.0, 200.0, 203.0, 228.0, 281.0, 204.0, 199.0, 199.0, 199.0], "value": 211.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 61.0, 62.0, 87.0, 61.0, 60.0, 62.0, 76.0, 94.0, 83.0], "value": 72.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 72.0, 87.0, 76.0, 99.0, 101.0, 86.0, 70.0, 72.0, 83.0], "value": 83.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 89.0, 87.0, 91.0, 106.0, 118.0, 80.0, 136.0, 133.0], "value": 101.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 153.0, 101.0, 119.0, 96.0, 96.0, 116.0, 154.0, 105.0, 97.0], "value": 116.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 90.0, 92.0, 92.0, 95.0, 93.0, 89.0, 96.0, 92.0, 143.0], "value": 97.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 59.0, 60.0, 61.0, 67.0, 64.0, 61.0, 64.0, 64.0, 64.0], "value": 62.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 74.0, 69.0, 84.0, 77.0, 112.0, 92.0, 61.0, 104.0, 102.0], "value": 84.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 87.0, 88.0, 87.0, 80.0, 96.0, 105.0, 104.0, 105.0], "value": 92.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 141.0, 163.0, 150.0, 160.0, 162.0, 161.0, 144.0, 211.0, 183.0], "value": 161.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 172.0, 171.0, 172.0, 174.0, 170.0, 182.0, 232.0, 196.0, 174.0], "value": 181.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 67.0, 67.0, 67.0, 64.0, 69.0, 67.0, 68.0, 66.0], "value": 67.1, "unit": "score"}]}]} 09:42:32 INFO - Return code: 0 09:42:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:42:32 ERROR - # TBPL SUCCESS # 09:42:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:42:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:42:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:42:32 INFO - 2017-02-21 09:42:32.336 screenresolution[6584:121452] starting screenresolution argv=screenresolution get 09:42:32 INFO - 2017-02-21 09:42:32.339 screenresolution[6584:121452] Display 0: 1600x1200x32@60 09:42:32 INFO - 2017-02-21 09:42:32.345 screenresolution[6585:121455] starting screenresolution argv=screenresolution list 09:42:32 INFO - Available Modes on Display 0 09:42:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:42:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:42:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:42:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:42:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:42:32 INFO - Intel Iris: 09:42:32 INFO - Chipset Model: Intel Iris 09:42:32 INFO - Type: GPU 09:42:32 INFO - Bus: Built-In 09:42:32 INFO - VRAM (Dynamic, Max): 1536 MB 09:42:32 INFO - Vendor: Intel (0x8086) 09:42:32 INFO - Device ID: 0x0a2e 09:42:32 INFO - Revision ID: 0x0009 09:42:32 INFO - Displays: 09:42:32 INFO - Display: 09:42:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:42:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:42:32 INFO - Display Serial Number: 1600x1200 60 09:42:32 INFO - Main Display: Yes 09:42:32 INFO - Mirror: Off 09:42:32 INFO - Online: Yes 09:42:32 INFO - Rotation: Supported 09:42:32 INFO - Return code: 0 09:42:32 INFO - Running post-action listener: _resource_record_post_action 09:42:32 INFO - [mozharness: 2017-02-21 17:42:32.581855Z] Finished run-tests step (success) 09:42:32 INFO - Running post-run listener: _resource_record_post_run 09:42:32 INFO - Total resource usage - Wall time: 1198s; CPU: 55.0%; Read bytes: 1506304; Write bytes: 510467072; Read time: 18; Write time: 1635 09:42:32 INFO - TinderboxPrint: CPU usage
54.6% 09:42:32 INFO - TinderboxPrint: I/O read bytes / time
1,506,304 / 18 09:42:32 INFO - TinderboxPrint: I/O write bytes / time
510,467,072 / 1,635 09:42:32 INFO - TinderboxPrint: CPU idle
2,176.0 (45.4%) 09:42:32 INFO - TinderboxPrint: CPU system
968.1 (20.2%) 09:42:32 INFO - TinderboxPrint: CPU user
1,649.5 (34.4%) 09:42:32 INFO - TinderboxPrint: Swap in / out
210,161,664 / 0 09:42:32 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 205369856; Write bytes: 200689664; Read time: 15850; Write time: 444 09:42:32 INFO - run-tests - Wall time: 1179s; CPU: 55.0%; Read bytes: 1465344; Write bytes: 298804224; Read time: 16; Write time: 1151 09:42:33 INFO - Running post-run listener: _upload_blobber_files 09:42:33 INFO - Blob upload gear active. 09:42:33 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:42:33 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 09:42:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:42:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:42:33 INFO - (blobuploader) - INFO - Open directory for files ... 09:42:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:42:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:42:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:42:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:42:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:42:34 INFO - (blobuploader) - INFO - Done attempting. 09:42:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:42:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:42:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:42:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:42:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:42:35 INFO - (blobuploader) - INFO - Done attempting. 09:42:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:42:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:42:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:42:36 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:42:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:42:36 INFO - (blobuploader) - INFO - Done attempting. 09:42:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:42:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:42:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:42:38 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:42:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:42:38 INFO - (blobuploader) - INFO - Done attempting. 09:42:38 INFO - (blobuploader) - INFO - Iteration through files over. 09:42:38 INFO - Return code: 0 09:42:38 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:42:38 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:42:38 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/68c266b7161a4e64b0b3045e3238824577a0bf815bac5a1426c627e064269d72e871ce29b2de55f29e9b5ce3e83c77b8d7371c1849f678251dba572f7efedb34", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7ca3dd72024cd07cf08c237160a08a601838dc2c4ee11a33516b93d7cffd0dce07515ddd3757fcfd089e232430ab5910138b105bd0dabb11b2f544efd59a9357", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7d7fb38eb3e666ec4742ec2650b6e984cc7f711bca04f9c8685a918a4b44e893d3c4666f92c8a3fc2e184e9b08abb4b6305f2863486b955b924e4eb7cbff4b9b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3ce2c29efe6376f59de20e1967c79f9cf2dca28067dae1adbe081fdaacd487af908802db64cdcbf47339b806a138d300b92cd44fd56ffa747896cb57ebb06b47"} 09:42:38 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:42:38 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:42:38 INFO - Contents: 09:42:38 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/68c266b7161a4e64b0b3045e3238824577a0bf815bac5a1426c627e064269d72e871ce29b2de55f29e9b5ce3e83c77b8d7371c1849f678251dba572f7efedb34", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7ca3dd72024cd07cf08c237160a08a601838dc2c4ee11a33516b93d7cffd0dce07515ddd3757fcfd089e232430ab5910138b105bd0dabb11b2f544efd59a9357", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7d7fb38eb3e666ec4742ec2650b6e984cc7f711bca04f9c8685a918a4b44e893d3c4666f92c8a3fc2e184e9b08abb4b6305f2863486b955b924e4eb7cbff4b9b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3ce2c29efe6376f59de20e1967c79f9cf2dca28067dae1adbe081fdaacd487af908802db64cdcbf47339b806a138d300b92cd44fd56ffa747896cb57ebb06b47"} 09:42:38 INFO - Running post-run listener: copy_logs_to_upload_dir 09:42:38 INFO - Copying logs to upload dir... 09:42:38 INFO - mkdir: /builds/slave/test/build/upload/logs 09:42:38 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1259.875098 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 59 secs) (at 2017-02-21 09:42:38.859408) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-21 09:42:38.863053) ========= 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.zNbsW3fenL/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.5iwErZpzNE/Listeners TMPDIR=/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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/Mozilla-Aurora/sha512/68c266b7161a4e64b0b3045e3238824577a0bf815bac5a1426c627e064269d72e871ce29b2de55f29e9b5ce3e83c77b8d7371c1849f678251dba572f7efedb34", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7ca3dd72024cd07cf08c237160a08a601838dc2c4ee11a33516b93d7cffd0dce07515ddd3757fcfd089e232430ab5910138b105bd0dabb11b2f544efd59a9357", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7d7fb38eb3e666ec4742ec2650b6e984cc7f711bca04f9c8685a918a4b44e893d3c4666f92c8a3fc2e184e9b08abb4b6305f2863486b955b924e4eb7cbff4b9b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3ce2c29efe6376f59de20e1967c79f9cf2dca28067dae1adbe081fdaacd487af908802db64cdcbf47339b806a138d300b92cd44fd56ffa747896cb57ebb06b47"} build_url:https://queue.taskcluster.net/v1/task/Z-B1Cr5mTo-VI8rcud0sQg/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011614 build_url: 'https://queue.taskcluster.net/v1/task/Z-B1Cr5mTo-VI8rcud0sQg/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/68c266b7161a4e64b0b3045e3238824577a0bf815bac5a1426c627e064269d72e871ce29b2de55f29e9b5ce3e83c77b8d7371c1849f678251dba572f7efedb34", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7ca3dd72024cd07cf08c237160a08a601838dc2c4ee11a33516b93d7cffd0dce07515ddd3757fcfd089e232430ab5910138b105bd0dabb11b2f544efd59a9357", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7d7fb38eb3e666ec4742ec2650b6e984cc7f711bca04f9c8685a918a4b44e893d3c4666f92c8a3fc2e184e9b08abb4b6305f2863486b955b924e4eb7cbff4b9b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3ce2c29efe6376f59de20e1967c79f9cf2dca28067dae1adbe081fdaacd487af908802db64cdcbf47339b806a138d300b92cd44fd56ffa747896cb57ebb06b47"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-21 09:42:38.898584) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-21 09:42:38.898891) ========= 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.zNbsW3fenL/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.5iwErZpzNE/Listeners TMPDIR=/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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.005356 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-21 09:42:38.947297) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-21 09:42:38.947586) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-21 09:42:38.947900) ========= ========= Total master_lag: 0.19 =========