builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0029 starttime: 1486136657.94 results: success (0) buildid: 20170203065708 builduid: efc477eccffd4a508452f1e35f7983ae revision: c02020dd7e6e190b046e0bdc992a75b647c4cffa ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-03 07:44:17.937889) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-03 07:44:17.938388) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-03 07:44:17.938671) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-03 07:44:17.965331) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-03 07:44:17.965767) ========= 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.sz2ZY8pry3/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.MPbJ6b6wp5/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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-03 07:44:17-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 24.8M=0s 2017-02-03 07:44:18 (24.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.141744 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-03 07:44:18.132032) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-03 07:44:18.132347) ========= 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.sz2ZY8pry3/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.MPbJ6b6wp5/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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.026502 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-03 07:44:18.181318) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-02-03 07:44:18.181775) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev c02020dd7e6e190b046e0bdc992a75b647c4cffa --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 c02020dd7e6e190b046e0bdc992a75b647c4cffa --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.sz2ZY8pry3/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.MPbJ6b6wp5/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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-03 07:44:18,284 truncating revision to first 12 chars 2017-02-03 07:44:18,284 Setting DEBUG logging. 2017-02-03 07:44:18,284 attempt 1/10 2017-02-03 07:44:18,284 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/c02020dd7e6e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-03 07:44:20,348 unpacking tar archive at: mozilla-aurora-c02020dd7e6e/testing/mozharness/ program finished with exit code 0 elapsedTime=2.346077 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-02-03 07:44:20.551068) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-03 07:44:20.551388) ========= script_repo_revision: c02020dd7e6e190b046e0bdc992a75b647c4cffa ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-03 07:44:20.551783) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-03 07:44:20.552064) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-03 07:44:20.572549) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-02-03 07:44:20.573320) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.sz2ZY8pry3/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.MPbJ6b6wp5/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 07:44:20 INFO - MultiFileLogger online at 20170203 07:44:20 in /builds/slave/test 07:44:20 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 07:44:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:44:20 INFO - {'append_to_log': False, 07:44:20 INFO - 'base_work_dir': '/builds/slave/test', 07:44:20 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 07:44:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:44:20 INFO - 'branch': 'Mozilla-Aurora', 07:44:20 INFO - 'buildbot_json_path': 'buildprops.json', 07:44:20 INFO - 'config_files': ('talos/mac_config.py',), 07:44:20 INFO - 'default_actions': ('clobber', 07:44:20 INFO - 'read-buildbot-config', 07:44:20 INFO - 'download-and-extract', 07:44:20 INFO - 'populate-webroot', 07:44:20 INFO - 'create-virtualenv', 07:44:20 INFO - 'install', 07:44:20 INFO - 'run-tests'), 07:44:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:44:20 INFO - 'download_minidump_stackwalk': True, 07:44:20 INFO - 'download_symbols': 'ondemand', 07:44:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:44:20 INFO - 'tooltool.py': '/tools/tooltool.py', 07:44:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:44:20 INFO - '/tools/misc-python/virtualenv.py')}, 07:44:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:44:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:44:20 INFO - 'installer_path': 'installer.exe', 07:44:20 INFO - 'log_level': 'info', 07:44:20 INFO - 'log_name': 'talos', 07:44:20 INFO - 'log_to_console': True, 07:44:20 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:44:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:44:20 INFO - 'opt_config_files': (), 07:44:20 INFO - 'pip_index': False, 07:44:20 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:44:20 INFO - 'cmd': ('bash', 07:44:20 INFO - '-c', 07:44:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:44:20 INFO - 'enabled': True, 07:44:20 INFO - 'halt_on_failure': False, 07:44:20 INFO - 'name': 'check_screen_resolution'},), 07:44:20 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:44:20 INFO - 'cmd': ('bash', 07:44:20 INFO - '-c', 07:44:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:44:20 INFO - 'enabled': True, 07:44:20 INFO - 'halt_on_failure': False, 07:44:20 INFO - 'name': 'check_screen_resolution'},), 07:44:20 INFO - 'run_cmd_checks_enabled': True, 07:44:20 INFO - 'sps_profile': False, 07:44:20 INFO - 'sps_profile_interval': 0, 07:44:20 INFO - 'suite': 'dromaeojs', 07:44:20 INFO - 'system_bits': '32', 07:44:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:44:20 INFO - 'title': 't-yosemite-r7-0029', 07:44:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:44:20 INFO - 'use_talos_json': True, 07:44:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:44:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:44:20 INFO - 'work_dir': 'build'} 07:44:20 INFO - [mozharness: 2017-02-03 15:44:20.794051Z] Running clobber step. 07:44:20 INFO - Running pre-action listener: _resource_record_pre_action 07:44:20 INFO - Running main action method: clobber 07:44:20 INFO - rmtree: /builds/slave/test/build 07:44:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:44:23 INFO - Running post-action listener: _resource_record_post_action 07:44:23 INFO - [mozharness: 2017-02-03 15:44:23.135365Z] Finished clobber step (success) 07:44:23 INFO - [mozharness: 2017-02-03 15:44:23.135490Z] Running read-buildbot-config step. 07:44:23 INFO - Running pre-action listener: _resource_record_pre_action 07:44:23 INFO - Running main action method: read_buildbot_config 07:44:23 INFO - Using buildbot properties: 07:44:23 INFO - { 07:44:23 INFO - "project": "", 07:44:23 INFO - "product": "firefox", 07:44:23 INFO - "script_repo_revision": "production", 07:44:23 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 07:44:23 INFO - "repository": "", 07:44:23 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 07:44:23 INFO - "buildid": "20170203065708", 07:44:23 INFO - "pgo_build": "False", 07:44:23 INFO - "basedir": "/builds/slave/test", 07:44:23 INFO - "buildnumber": 14, 07:44:23 INFO - "slavename": "t-yosemite-r7-0029", 07:44:23 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 07:44:23 INFO - "platform": "yosemite_r7", 07:44:23 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 07:44:23 INFO - "branch": "mozilla-aurora", 07:44:23 INFO - "repo_path": "releases/mozilla-aurora", 07:44:23 INFO - "revision": "c02020dd7e6e190b046e0bdc992a75b647c4cffa", 07:44:23 INFO - "stage_platform": "macosx64", 07:44:23 INFO - "builduid": "efc477eccffd4a508452f1e35f7983ae", 07:44:23 INFO - "slavebuilddir": "test" 07:44:23 INFO - } 07:44:23 INFO - Found installer url https://queue.taskcluster.net/v1/task/Pwh3ouL5TqeqOocueeVbKQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg. 07:44:23 INFO - Running post-action listener: _resource_record_post_action 07:44:23 INFO - [mozharness: 2017-02-03 15:44:23.138781Z] Finished read-buildbot-config step (success) 07:44:23 INFO - [mozharness: 2017-02-03 15:44:23.138919Z] Running download-and-extract step. 07:44:23 INFO - Running pre-action listener: _resource_record_pre_action 07:44:23 INFO - Running main action method: download_and_extract 07:44:23 INFO - mkdir: /builds/slave/test/build/tests 07:44:23 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')]} 07:44:23 INFO - trying https://queue.taskcluster.net/v1/task/Pwh3ouL5TqeqOocueeVbKQ/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json 07:44:23 INFO - Downloading https://queue.taskcluster.net/v1/task/Pwh3ouL5TqeqOocueeVbKQ/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 07:44:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Pwh3ouL5TqeqOocueeVbKQ/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 07:44:24 INFO - Downloaded 1199 bytes. 07:44:24 INFO - Reading from file /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 07:44:24 INFO - Using the following test package requirements: 07:44:24 INFO - {u'common': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 07:44:24 INFO - u'cppunittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 07:44:24 INFO - u'firefox-53.0a2.en-US.mac.cppunittest.tests.zip'], 07:44:24 INFO - u'gtest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 07:44:24 INFO - u'firefox-53.0a2.en-US.mac.gtest.tests.zip'], 07:44:24 INFO - u'jittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 07:44:24 INFO - u'jsshell-mac.zip'], 07:44:24 INFO - u'mochitest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 07:44:24 INFO - u'firefox-53.0a2.en-US.mac.mochitest.tests.zip'], 07:44:24 INFO - u'mozbase': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 07:44:24 INFO - u'reftest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 07:44:24 INFO - u'firefox-53.0a2.en-US.mac.reftest.tests.zip'], 07:44:24 INFO - u'talos': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 07:44:24 INFO - u'firefox-53.0a2.en-US.mac.talos.tests.zip'], 07:44:24 INFO - u'web-platform': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 07:44:24 INFO - u'firefox-53.0a2.en-US.mac.web-platform.tests.zip'], 07:44:24 INFO - u'xpcshell': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 07:44:24 INFO - u'firefox-53.0a2.en-US.mac.xpcshell.tests.zip']} 07:44:24 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip'] for test suite category: common 07:44:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Pwh3ouL5TqeqOocueeVbKQ/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 07:44:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Pwh3ouL5TqeqOocueeVbKQ/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 07:44:24 INFO - Fetch https://queue.taskcluster.net/v1/task/Pwh3ouL5TqeqOocueeVbKQ/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 07:44:25 INFO - Expected file size: 16356354 07:44:25 INFO - Obtained file size: 16356354 07:44:31 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 07:44:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Pwh3ouL5TqeqOocueeVbKQ/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 07:44:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Pwh3ouL5TqeqOocueeVbKQ/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 07:44:31 INFO - Fetch https://queue.taskcluster.net/v1/task/Pwh3ouL5TqeqOocueeVbKQ/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 07:44:32 INFO - Expected file size: 16356354 07:44:32 INFO - Obtained file size: 16356354 07:44:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Pwh3ouL5TqeqOocueeVbKQ/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip 07:44:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Pwh3ouL5TqeqOocueeVbKQ/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip'}, attempt #1 07:44:38 INFO - Fetch https://queue.taskcluster.net/v1/task/Pwh3ouL5TqeqOocueeVbKQ/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip into memory 07:44:39 INFO - Expected file size: 14403485 07:44:39 INFO - Obtained file size: 14403485 07:44:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:44:40 INFO - trying https://queue.taskcluster.net/v1/task/Pwh3ouL5TqeqOocueeVbKQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 07:44:40 INFO - Downloading https://queue.taskcluster.net/v1/task/Pwh3ouL5TqeqOocueeVbKQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg 07:44:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Pwh3ouL5TqeqOocueeVbKQ/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 07:44:42 INFO - Downloaded 73831810 bytes. 07:44:42 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Pwh3ouL5TqeqOocueeVbKQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 07:44:42 INFO - mkdir: /builds/slave/test/properties 07:44:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:44:42 INFO - Writing to file /builds/slave/test/properties/build_url 07:44:42 INFO - Contents: 07:44:42 INFO - build_url:https://queue.taskcluster.net/v1/task/Pwh3ouL5TqeqOocueeVbKQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 07:44:42 INFO - Running post-action listener: _resource_record_post_action 07:44:42 INFO - Running post-action listener: set_extra_try_arguments 07:44:42 INFO - [mozharness: 2017-02-03 15:44:42.877795Z] Finished download-and-extract step (success) 07:44:42 INFO - [mozharness: 2017-02-03 15:44:42.877917Z] Running populate-webroot step. 07:44:42 INFO - Running pre-action listener: _resource_record_pre_action 07:44:42 INFO - Running main action method: populate_webroot 07:44:42 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:44:42 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:44:42 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:44:42 INFO - '--tppagecycles', 07:44:42 INFO - '7'], 07:44:42 INFO - 'tests': ['tsvgm']}}, 07:44:42 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:44:42 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:44:42 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:44:42 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:44:42 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:44:42 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:44:42 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 07:44:42 INFO - 'talos_options': ['--disable-e10s'], 07:44:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:44:42 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:44:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:44:42 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 07:44:42 INFO - 'talos_options': ['--disable-e10s'], 07:44:42 INFO - 'tests': ['damp', 'tps']}, 07:44:42 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:44:42 INFO - 'tests': ['damp', 'tps']}, 07:44:42 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:44:42 INFO - 'tests': ['dromaeo_dom']}, 07:44:42 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:44:42 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:44:42 INFO - 'tests': ['basic_compositor_video']}, 07:44:42 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:44:42 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:44:42 INFO - 'tests': ['a11yr', 07:44:42 INFO - 'ts_paint', 07:44:42 INFO - 'tpaint', 07:44:42 INFO - 'sessionrestore', 07:44:42 INFO - 'sessionrestore_no_auto_restore', 07:44:42 INFO - 'tabpaint']}, 07:44:42 INFO - 'other-e10s': {'tests': ['a11yr', 07:44:42 INFO - 'ts_paint', 07:44:42 INFO - 'tpaint', 07:44:42 INFO - 'sessionrestore', 07:44:42 INFO - 'sessionrestore_no_auto_restore', 07:44:42 INFO - 'tabpaint']}, 07:44:42 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:44:42 INFO - 'ts_paint', 07:44:42 INFO - 'tpaint', 07:44:42 INFO - 'sessionrestore', 07:44:42 INFO - 'sessionrestore_no_auto_restore', 07:44:42 INFO - 'tabpaint']}, 07:44:42 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:44:42 INFO - 'ts_paint', 07:44:42 INFO - 'tpaint', 07:44:42 INFO - 'sessionrestore', 07:44:42 INFO - 'sessionrestore_no_auto_restore', 07:44:42 INFO - 'tabpaint']}, 07:44:42 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:44:42 INFO - 'tests': ['a11yr', 07:44:42 INFO - 'ts_paint', 07:44:42 INFO - 'tpaint', 07:44:42 INFO - 'sessionrestore', 07:44:42 INFO - 'sessionrestore_no_auto_restore', 07:44:42 INFO - 'tabpaint']}, 07:44:42 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:44:42 INFO - 'tests': ['a11yr', 07:44:42 INFO - 'ts_paint', 07:44:42 INFO - 'tpaint', 07:44:42 INFO - 'sessionrestore', 07:44:42 INFO - 'sessionrestore_no_auto_restore', 07:44:42 INFO - 'tabpaint']}, 07:44:42 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:44:42 INFO - 'tests': ['tsvgx', 07:44:42 INFO - 'tsvgr_opacity', 07:44:42 INFO - 'tart', 07:44:42 INFO - 'tscrollx', 07:44:42 INFO - 'cart', 07:44:42 INFO - 'tsvg_static']}, 07:44:42 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:44:42 INFO - 'tsvgr_opacity', 07:44:42 INFO - 'tart', 07:44:42 INFO - 'tscrollx', 07:44:42 INFO - 'cart', 07:44:42 INFO - 'tsvg_static']}, 07:44:42 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 07:44:42 INFO - 'talos_options': ['--disable-e10s'], 07:44:42 INFO - 'tests': ['tp5o']}, 07:44:42 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:44:42 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 07:44:42 INFO - 'talos_options': ['--disable-e10s', 07:44:42 INFO - '--xperf_path', 07:44:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:44:42 INFO - 'tests': ['tp5n']}, 07:44:42 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:44:42 INFO - 'talos_options': ['--xperf_path', 07:44:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:44:42 INFO - 'tests': ['tp5n']}}, 07:44:42 INFO - 'talos.zip': {'path': '', 07:44:42 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:44:42 INFO - Running post-action listener: _resource_record_post_action 07:44:42 INFO - [mozharness: 2017-02-03 15:44:42.896120Z] Finished populate-webroot step (success) 07:44:42 INFO - [mozharness: 2017-02-03 15:44:42.896236Z] Running create-virtualenv step. 07:44:42 INFO - Running pre-action listener: _resource_record_pre_action 07:44:42 INFO - Running main action method: create_virtualenv 07:44:42 INFO - Creating virtualenv /builds/slave/test/build/venv 07:44:42 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 07:44:42 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:44:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:44:43 INFO - Using real prefix '/tools/python27' 07:44:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:44:43 INFO - Installing distribute.............................................................................................................................................................................................done. 07:44:46 INFO - Installing pip.................done. 07:44:46 INFO - Return code: 0 07:44:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:44:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:44:46 INFO - Reading from file tmpfile_stdout 07:44:46 INFO - Output received: 07:44:46 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) 07:44:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:44:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:44:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:44:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:44:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:44:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:44:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:44:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f950b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f953768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6f2c684f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f72ed30>, '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 0x10f655db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f723be8>, '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.sz2ZY8pry3/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.MPbJ6b6wp5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 07:44:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:44:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:44:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sz2ZY8pry3/Render', 07:44:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:44:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:44:46 INFO - 'HOME': '/Users/cltbld', 07:44:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:44:46 INFO - 'LOGNAME': 'cltbld', 07:44:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:44:46 INFO - 'MOZ_NO_REMOTE': '1', 07:44:46 INFO - 'NO_EM_RESTART': '1', 07:44:46 INFO - 'PAGER': '/bin/cat', 07:44:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:44:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:44:46 INFO - 'PWD': '/builds/slave/test', 07:44:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:44:46 INFO - 'SHELL': '/bin/bash', 07:44:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MPbJ6b6wp5/Listeners', 07:44:46 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 07:44:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:44:46 INFO - 'USER': 'cltbld', 07:44:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:44:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:44:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:44:46 INFO - 'XPC_FLAGS': '0x0', 07:44:46 INFO - 'XPC_SERVICE_NAME': '0', 07:44:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:44:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:44:47 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 07:44:47 INFO - Cleaning up... 07:44:47 INFO - Return code: 0 07:44:47 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:44:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:44:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:44:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:44:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:44:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:44:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:44:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10f950b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f953768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6f2c684f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f72ed30>, '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 0x10f655db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f723be8>, '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.sz2ZY8pry3/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.MPbJ6b6wp5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 07:44:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:44:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:44:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sz2ZY8pry3/Render', 07:44:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:44:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:44:47 INFO - 'HOME': '/Users/cltbld', 07:44:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:44:47 INFO - 'LOGNAME': 'cltbld', 07:44:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:44:47 INFO - 'MOZ_NO_REMOTE': '1', 07:44:47 INFO - 'NO_EM_RESTART': '1', 07:44:47 INFO - 'PAGER': '/bin/cat', 07:44:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:44:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:44:47 INFO - 'PWD': '/builds/slave/test', 07:44:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:44:47 INFO - 'SHELL': '/bin/bash', 07:44:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MPbJ6b6wp5/Listeners', 07:44:47 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 07:44:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:44:47 INFO - 'USER': 'cltbld', 07:44:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:44:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:44:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:44:47 INFO - 'XPC_FLAGS': '0x0', 07:44:47 INFO - 'XPC_SERVICE_NAME': '0', 07:44:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:44:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:44:47 INFO - Downloading/unpacking psutil>=3.1.1 07:44:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:44:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:44:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:44:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:44:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:44:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:44:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:44:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:44:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:44:50 INFO - Installing collected packages: psutil 07:44:50 INFO - Running setup.py install for psutil 07:44:51 INFO - building 'psutil._psutil_osx' extension 07:44:51 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 07:44:51 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 07:44:51 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 07:44:51 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 07:44:51 INFO - building 'psutil._psutil_posix' extension 07:44:51 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 07:44:51 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:44:51 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:44:51 INFO - ^ 07:44:52 INFO - 1 warning generated. 07:44:52 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 07:44:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:44:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:44:52 INFO - Successfully installed psutil 07:44:52 INFO - Cleaning up... 07:44:52 INFO - Return code: 0 07:44:52 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:44:52 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')]} 07:44:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:44:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:44:52 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')]} 07:44:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:44:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:44:52 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 0x10f950b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f953768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6f2c684f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f72ed30>, '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 0x10f655db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f723be8>, '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.sz2ZY8pry3/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.MPbJ6b6wp5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 07:44:52 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 07:44:52 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 07:44:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sz2ZY8pry3/Render', 07:44:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:44:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:44:52 INFO - 'HOME': '/Users/cltbld', 07:44:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:44:52 INFO - 'LOGNAME': 'cltbld', 07:44:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:44:52 INFO - 'MOZ_NO_REMOTE': '1', 07:44:52 INFO - 'NO_EM_RESTART': '1', 07:44:52 INFO - 'PAGER': '/bin/cat', 07:44:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:44:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:44:52 INFO - 'PWD': '/builds/slave/test', 07:44:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:44:52 INFO - 'SHELL': '/bin/bash', 07:44:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MPbJ6b6wp5/Listeners', 07:44:52 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 07:44:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:44:52 INFO - 'USER': 'cltbld', 07:44:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:44:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:44:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:44:52 INFO - 'XPC_FLAGS': '0x0', 07:44:52 INFO - 'XPC_SERVICE_NAME': '0', 07:44:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:44:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:44:52 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:44:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:44:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:44:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:44:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:44:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:44:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:44:55 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:44:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:44:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:44:55 INFO - Installing collected packages: mozsystemmonitor 07:44:55 INFO - Running setup.py install for mozsystemmonitor 07:44:55 INFO - Successfully installed mozsystemmonitor 07:44:55 INFO - Cleaning up... 07:44:55 INFO - Return code: 0 07:44:55 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:44:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:44:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:44:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:44:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:44:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:44:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:44:55 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 0x10f950b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f953768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6f2c684f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f72ed30>, '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 0x10f655db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f723be8>, '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.sz2ZY8pry3/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.MPbJ6b6wp5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 07:44:55 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 07:44:55 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 07:44:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sz2ZY8pry3/Render', 07:44:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:44:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:44:55 INFO - 'HOME': '/Users/cltbld', 07:44:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:44:55 INFO - 'LOGNAME': 'cltbld', 07:44:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:44:55 INFO - 'MOZ_NO_REMOTE': '1', 07:44:55 INFO - 'NO_EM_RESTART': '1', 07:44:55 INFO - 'PAGER': '/bin/cat', 07:44:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:44:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:44:55 INFO - 'PWD': '/builds/slave/test', 07:44:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:44:55 INFO - 'SHELL': '/bin/bash', 07:44:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MPbJ6b6wp5/Listeners', 07:44:55 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 07:44:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:44:55 INFO - 'USER': 'cltbld', 07:44:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:44:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:44:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:44:55 INFO - 'XPC_FLAGS': '0x0', 07:44:55 INFO - 'XPC_SERVICE_NAME': '0', 07:44:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:44:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:44:55 INFO - Downloading/unpacking jsonschema==2.5.1 07:44:55 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 07:44:55 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 07:44:55 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 07:44:55 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 07:44:55 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 07:44:55 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 07:44:58 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:44:58 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:44:58 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 07:44:58 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 07:44:58 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 07:44:58 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 07:44:58 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 07:44:58 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 07:44:58 INFO - Downloading functools32-3.2.3-2.tar.gz 07:44:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:44:58 INFO - warning: no files found matching '*.txt' 07:44:58 INFO - no previously-included directories found matching 'build' 07:44:58 INFO - no previously-included directories found matching 'dist' 07:44:58 INFO - no previously-included directories found matching '.git*' 07:44:58 INFO - Installing collected packages: jsonschema, functools32 07:44:58 INFO - Running setup.py install for functools32 07:44:59 INFO - warning: no files found matching '*.txt' 07:44:59 INFO - no previously-included directories found matching 'build' 07:44:59 INFO - no previously-included directories found matching 'dist' 07:44:59 INFO - no previously-included directories found matching '.git*' 07:44:59 INFO - Successfully installed jsonschema functools32 07:44:59 INFO - Cleaning up... 07:44:59 INFO - Return code: 0 07:44:59 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:44:59 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')]} 07:44:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:44:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:44:59 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')]} 07:44:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:44:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:44:59 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 0x10f950b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f953768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6f2c684f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f72ed30>, '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 0x10f655db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f723be8>, '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.sz2ZY8pry3/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.MPbJ6b6wp5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 07:44:59 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 07:44:59 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 07:44:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sz2ZY8pry3/Render', 07:44:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:44:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:44:59 INFO - 'HOME': '/Users/cltbld', 07:44:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:44:59 INFO - 'LOGNAME': 'cltbld', 07:44:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:44:59 INFO - 'MOZ_NO_REMOTE': '1', 07:44:59 INFO - 'NO_EM_RESTART': '1', 07:44:59 INFO - 'PAGER': '/bin/cat', 07:44:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:44:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:44:59 INFO - 'PWD': '/builds/slave/test', 07:44:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:44:59 INFO - 'SHELL': '/bin/bash', 07:44:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MPbJ6b6wp5/Listeners', 07:44:59 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 07:44:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:44:59 INFO - 'USER': 'cltbld', 07:44:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:44:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:44:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:44:59 INFO - 'XPC_FLAGS': '0x0', 07:44:59 INFO - 'XPC_SERVICE_NAME': '0', 07:44:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:44:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:44:59 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:44:59 INFO - Cleaning up... 07:44:59 INFO - Return code: 0 07:44:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:44:59 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')]} 07:44:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:44:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:44:59 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')]} 07:44:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:44:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:44:59 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 0x10f950b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f953768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6f2c684f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f72ed30>, '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 0x10f655db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f723be8>, '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.sz2ZY8pry3/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.MPbJ6b6wp5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 07:44:59 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 07:44:59 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 07:44:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sz2ZY8pry3/Render', 07:44:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:44:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:44:59 INFO - 'HOME': '/Users/cltbld', 07:44:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:44:59 INFO - 'LOGNAME': 'cltbld', 07:44:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:44:59 INFO - 'MOZ_NO_REMOTE': '1', 07:44:59 INFO - 'NO_EM_RESTART': '1', 07:44:59 INFO - 'PAGER': '/bin/cat', 07:44:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:44:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:44:59 INFO - 'PWD': '/builds/slave/test', 07:44:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:44:59 INFO - 'SHELL': '/bin/bash', 07:44:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MPbJ6b6wp5/Listeners', 07:44:59 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 07:44:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:44:59 INFO - 'USER': 'cltbld', 07:44:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:44:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:44:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:44:59 INFO - 'XPC_FLAGS': '0x0', 07:44:59 INFO - 'XPC_SERVICE_NAME': '0', 07:44:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:44:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:44:59 INFO - Downloading/unpacking blobuploader==1.2.4 07:44:59 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 07:44:59 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 07:44:59 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 07:44:59 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 07:44:59 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 07:44:59 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 07:45:02 INFO - Downloading blobuploader-1.2.4.tar.gz 07:45:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:45:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:45:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:45:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:45:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:45:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:45:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:45:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:45:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:45:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:45:03 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 07:45:03 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 07:45:03 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 07:45:03 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 07:45:03 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 07:45:03 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 07:45:03 INFO - Downloading docopt-0.6.1.tar.gz 07:45:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:45:03 INFO - Installing collected packages: blobuploader, requests, docopt 07:45:03 INFO - Running setup.py install for blobuploader 07:45:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:45:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:45:03 INFO - Running setup.py install for requests 07:45:04 INFO - Running setup.py install for docopt 07:45:04 INFO - Successfully installed blobuploader requests docopt 07:45:04 INFO - Cleaning up... 07:45:04 INFO - Return code: 0 07:45:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:45: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')]} 07:45:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:45:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:45: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')]} 07:45:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:45:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:45:04 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 0x10f950b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f953768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6f2c684f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f72ed30>, '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 0x10f655db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f723be8>, '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.sz2ZY8pry3/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.MPbJ6b6wp5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 07:45:04 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 07:45:04 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 07:45:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sz2ZY8pry3/Render', 07:45:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:45:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:45:04 INFO - 'HOME': '/Users/cltbld', 07:45:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:45:04 INFO - 'LOGNAME': 'cltbld', 07:45:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:45:04 INFO - 'MOZ_NO_REMOTE': '1', 07:45:04 INFO - 'NO_EM_RESTART': '1', 07:45:04 INFO - 'PAGER': '/bin/cat', 07:45:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:45:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:45:04 INFO - 'PWD': '/builds/slave/test', 07:45:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:45:04 INFO - 'SHELL': '/bin/bash', 07:45:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MPbJ6b6wp5/Listeners', 07:45:04 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 07:45:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:45:04 INFO - 'USER': 'cltbld', 07:45:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:45:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:45:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:45:04 INFO - 'XPC_FLAGS': '0x0', 07:45:04 INFO - 'XPC_SERVICE_NAME': '0', 07:45:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:45:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:45:04 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-U8w6QX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:45:04 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-L8lFpG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:45:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:45:05 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-Q4swaq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:45:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:45:05 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-YxuI4y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:45:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:45:05 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-ZKucRj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:45:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:45:05 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-HNwCN0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:45:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:45:05 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-dUiv3o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:45:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:45:05 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-UnwrRQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:45:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:45:05 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-p0Cww1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:45:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:45:05 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-TLacaK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:45:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:45:05 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-j_LfXX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:45:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:45:06 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-fPmevT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:45:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:45:06 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-fIyJP9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:45:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:45:06 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-sTekdS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:45:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:45:06 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-0ZHPe4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:45:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:45:06 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-y8bnjP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:45:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:45:06 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-3Xcq6i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:45:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:45:06 INFO - Running setup.py install for manifestparser 07:45:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:45:06 INFO - Running setup.py install for mozcrash 07:45:06 INFO - Running setup.py install for mozdebug 07:45:07 INFO - Running setup.py install for mozdevice 07:45:07 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:45:07 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:45:07 INFO - Running setup.py install for mozfile 07:45:07 INFO - Running setup.py install for mozhttpd 07:45:07 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:45:07 INFO - Running setup.py install for mozinfo 07:45:07 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:45:07 INFO - Running setup.py install for mozInstall 07:45:07 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:45:07 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:45:07 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:45:07 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:45:07 INFO - Running setup.py install for mozleak 07:45:07 INFO - Running setup.py install for mozlog 07:45:08 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:45:08 INFO - Running setup.py install for moznetwork 07:45:08 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:45:08 INFO - Running setup.py install for mozprocess 07:45:08 INFO - Running setup.py install for mozprofile 07:45:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:45:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:45:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:45:08 INFO - Running setup.py install for mozrunner 07:45:08 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:45:08 INFO - Running setup.py install for mozscreenshot 07:45:08 INFO - Running setup.py install for moztest 07:45:09 INFO - Running setup.py install for mozversion 07:45:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:45:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:45:09 INFO - Cleaning up... 07:45:09 INFO - Return code: 0 07:45:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:45: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')]} 07:45:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:45:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:45: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')]} 07:45:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:45:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:45:09 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 0x10f950b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f953768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6f2c684f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f72ed30>, '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 0x10f655db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f723be8>, '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.sz2ZY8pry3/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.MPbJ6b6wp5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 07:45:09 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 07:45:09 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 07:45:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sz2ZY8pry3/Render', 07:45:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:45:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:45:09 INFO - 'HOME': '/Users/cltbld', 07:45:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:45:09 INFO - 'LOGNAME': 'cltbld', 07:45:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:45:09 INFO - 'MOZ_NO_REMOTE': '1', 07:45:09 INFO - 'NO_EM_RESTART': '1', 07:45:09 INFO - 'PAGER': '/bin/cat', 07:45:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:45:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:45:09 INFO - 'PWD': '/builds/slave/test', 07:45:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:45:09 INFO - 'SHELL': '/bin/bash', 07:45:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MPbJ6b6wp5/Listeners', 07:45:09 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 07:45:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:45:09 INFO - 'USER': 'cltbld', 07:45:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:45:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:45:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:45:09 INFO - 'XPC_FLAGS': '0x0', 07:45:09 INFO - 'XPC_SERVICE_NAME': '0', 07:45:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:45:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:45:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:45:09 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-1muXhi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:45:09 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)) 07:45:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:45:09 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-rxFyD4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:45:09 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)) 07:45:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:45:09 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-zZaCBv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:45:09 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)) 07:45:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:45:09 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-6bbbv2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:45:09 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)) 07:45:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:45:09 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-MB4mXO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:45:10 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)) 07:45:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:45:10 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-XDZnbs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:45:10 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)) 07:45:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:45:10 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-nKlqZG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:45:10 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)) 07:45:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:45:10 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-OyB_Wo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:45:10 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)) 07:45:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:45:10 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-KSZKtJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:45:10 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)) 07:45:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:45:10 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-BZzcHr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:45:10 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)) 07:45:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:45:10 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-IbW1x9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:45:10 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)) 07:45:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:45:10 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-YCW74X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:45:10 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)) 07:45:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:45:10 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-yflm4T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:45:10 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)) 07:45:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:45:10 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-6d6pvb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:45:10 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)) 07:45:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:45:11 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-BESm_l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:45:11 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)) 07:45:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:45:11 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-nrCPJe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:45:11 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)) 07:45:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:45:11 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-iUY4MK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:45:11 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)) 07:45:11 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)) 07:45:11 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)) 07:45:11 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)) 07:45:11 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)) 07:45:11 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)) 07:45:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:45:11 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 07:45:11 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 07:45:11 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 07:45:11 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 07:45:11 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 07:45:11 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 07:45:13 INFO - Downloading blessings-1.6.tar.gz 07:45:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:45:14 INFO - Installing collected packages: blessings 07:45:14 INFO - Running setup.py install for blessings 07:45:14 INFO - Successfully installed blessings 07:45:14 INFO - Cleaning up... 07:45:14 INFO - Return code: 0 07:45:14 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:45:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:45:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:45:14 INFO - Reading from file tmpfile_stdout 07:45:14 INFO - Current package versions: 07:45:14 INFO - blessings == 1.6 07:45:14 INFO - blobuploader == 1.2.4 07:45:14 INFO - docopt == 0.6.1 07:45:14 INFO - functools32 == 3.2.3-2 07:45:14 INFO - jsonschema == 2.5.1 07:45:14 INFO - manifestparser == 1.1 07:45:14 INFO - mozInstall == 1.12 07:45:14 INFO - mozcrash == 1.0 07:45:14 INFO - mozdebug == 0.1 07:45:14 INFO - mozdevice == 0.49 07:45:14 INFO - mozfile == 1.2 07:45:14 INFO - mozhttpd == 0.7 07:45:14 INFO - mozinfo == 0.9 07:45:14 INFO - mozleak == 0.1 07:45:14 INFO - mozlog == 3.4 07:45:14 INFO - moznetwork == 0.27 07:45:14 INFO - mozprocess == 0.24 07:45:14 INFO - mozprofile == 0.28 07:45:14 INFO - mozrunner == 6.13 07:45:14 INFO - mozscreenshot == 0.1 07:45:14 INFO - mozsystemmonitor == 0.3 07:45:14 INFO - moztest == 0.8 07:45:14 INFO - mozversion == 1.4 07:45:14 INFO - psutil == 3.1.1 07:45:14 INFO - requests == 1.2.3 07:45:14 INFO - wsgiref == 0.1.2 07:45:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:45:14 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')]} 07:45:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:45:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:45:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:45:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:45:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:45:15 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 0x10f950b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f953768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6f2c684f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f72ed30>, '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 0x10f655db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f723be8>, '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.sz2ZY8pry3/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.MPbJ6b6wp5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 07:45:15 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 07:45:15 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 07:45:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sz2ZY8pry3/Render', 07:45:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:45:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:45:15 INFO - 'HOME': '/Users/cltbld', 07:45:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:45:15 INFO - 'LOGNAME': 'cltbld', 07:45:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:45:15 INFO - 'MOZ_NO_REMOTE': '1', 07:45:15 INFO - 'NO_EM_RESTART': '1', 07:45:15 INFO - 'PAGER': '/bin/cat', 07:45:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:45:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:45:15 INFO - 'PWD': '/builds/slave/test', 07:45:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:45:15 INFO - 'SHELL': '/bin/bash', 07:45:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MPbJ6b6wp5/Listeners', 07:45:15 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 07:45:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:45:15 INFO - 'USER': 'cltbld', 07:45:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:45:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:45:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:45:15 INFO - 'XPC_FLAGS': '0x0', 07:45:15 INFO - 'XPC_SERVICE_NAME': '0', 07:45:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:45:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:45:15 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)) 07:45:15 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)) 07:45:15 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)) 07:45:15 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)) 07:45:15 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)) 07:45:15 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)) 07:45:15 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)) 07:45:15 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)) 07:45:15 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)) 07:45:15 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)) 07:45:15 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)) 07:45:15 INFO - Cleaning up... 07:45:15 INFO - Return code: 0 07:45:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:45:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:45:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:45:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:45:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:45:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:45:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:45: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f950b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f953768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6f2c684f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f72ed30>, '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 0x10f655db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f723be8>, '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.sz2ZY8pry3/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.MPbJ6b6wp5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 07:45: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', 'jsonschema'] in /builds/slave/test/build 07:45: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 jsonschema 07:45:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sz2ZY8pry3/Render', 07:45:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:45:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:45:16 INFO - 'HOME': '/Users/cltbld', 07:45:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:45:16 INFO - 'LOGNAME': 'cltbld', 07:45:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:45:16 INFO - 'MOZ_NO_REMOTE': '1', 07:45:16 INFO - 'NO_EM_RESTART': '1', 07:45:16 INFO - 'PAGER': '/bin/cat', 07:45:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:45:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:45:16 INFO - 'PWD': '/builds/slave/test', 07:45:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:45:16 INFO - 'SHELL': '/bin/bash', 07:45:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MPbJ6b6wp5/Listeners', 07:45:16 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 07:45:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:45:16 INFO - 'USER': 'cltbld', 07:45:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:45:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:45:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:45:16 INFO - 'XPC_FLAGS': '0x0', 07:45:16 INFO - 'XPC_SERVICE_NAME': '0', 07:45:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:45:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:45:16 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:45:16 INFO - Cleaning up... 07:45:16 INFO - Return code: 0 07:45:16 INFO - Running post-action listener: _resource_record_post_action 07:45:16 INFO - Running post-action listener: _start_resource_monitoring 07:45:16 INFO - Starting resource monitoring. 07:45:16 INFO - [mozharness: 2017-02-03 15:45:16.400701Z] Finished create-virtualenv step (success) 07:45:16 INFO - [mozharness: 2017-02-03 15:45:16.401674Z] Running install step. 07:45:16 INFO - Running pre-action listener: _resource_record_pre_action 07:45:16 INFO - Running main action method: install 07:45:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:45:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:45:16 INFO - Reading from file tmpfile_stdout 07:45:16 INFO - Detecting whether we're running mozinstall >=1.0... 07:45:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:45:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:45:16 INFO - Reading from file tmpfile_stdout 07:45:16 INFO - Output received: 07:45:16 INFO - Usage: mozinstall [options] installer 07:45:16 INFO - Options: 07:45:16 INFO - -h, --help show this help message and exit 07:45:16 INFO - -d DEST, --destination=DEST 07:45:16 INFO - Directory to install application into. [default: 07:45:16 INFO - "/builds/slave/test"] 07:45:16 INFO - --app=APP Application being installed. [default: firefox] 07:45:16 INFO - mkdir: /builds/slave/test/build/application 07:45:16 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'] 07:45:16 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 07:45:37 INFO - Reading from file tmpfile_stdout 07:45:37 INFO - Output received: 07:45:37 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 07:45:37 INFO - Running post-action listener: _resource_record_post_action 07:45:37 INFO - [mozharness: 2017-02-03 15:45:37.080752Z] Finished install step (success) 07:45:37 INFO - [mozharness: 2017-02-03 15:45:37.080906Z] Running run-tests step. 07:45:37 INFO - Running pre-action listener: _resource_record_pre_action 07:45:37 INFO - Running main action method: run_tests 07:45:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:45:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:45:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:45:37 INFO - 2017-02-03 07:45:37.094 screenresolution[1624:7370] starting screenresolution argv=screenresolution get 07:45:37 INFO - 2017-02-03 07:45:37.097 screenresolution[1624:7370] Display 0: 1600x1200x32@60 07:45:37 INFO - 2017-02-03 07:45:37.103 screenresolution[1625:7372] starting screenresolution argv=screenresolution list 07:45:37 INFO - Available Modes on Display 0 07:45:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:45:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:45:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:45:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:45:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:45:37 INFO - Intel Iris: 07:45:37 INFO - Chipset Model: Intel Iris 07:45:37 INFO - Type: GPU 07:45:37 INFO - Bus: Built-In 07:45:37 INFO - VRAM (Dynamic, Max): 1536 MB 07:45:37 INFO - Vendor: Intel (0x8086) 07:45:37 INFO - Device ID: 0x0a2e 07:45:37 INFO - Revision ID: 0x0009 07:45:37 INFO - Displays: 07:45:37 INFO - Display: 07:45:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:45:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:45:37 INFO - Display Serial Number: 1600x1200 60 07:45:37 INFO - Main Display: Yes 07:45:37 INFO - Mirror: Off 07:45:37 INFO - Online: Yes 07:45:37 INFO - Rotation: Supported 07:45:37 INFO - Return code: 0 07:45:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:45:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:45:37 INFO - Python 2.7.3 07:45:37 INFO - Return code: 0 07:45:37 INFO - grabbing minidump binary from tooltool 07:45:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:45:37 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 0x10f72ed30>, '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 0x10f655db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f723be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:45:37 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 07:45:37 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 07:45:37 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 07:45:37 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:45:37 INFO - Return code: 0 07:45:37 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:45:37 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:45:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:45:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:45:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:45:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Pwh3ouL5TqeqOocueeVbKQ/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0029', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 07:45:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Pwh3ouL5TqeqOocueeVbKQ/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0029 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 07:45:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sz2ZY8pry3/Render', 07:45:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:45:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:45:37 INFO - 'HOME': '/Users/cltbld', 07:45:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:45:37 INFO - 'LOGNAME': 'cltbld', 07:45:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:45:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:45:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:45:37 INFO - 'MOZ_NO_REMOTE': '1', 07:45:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:45:37 INFO - 'NO_EM_RESTART': '1', 07:45:37 INFO - 'PAGER': '/bin/cat', 07:45:37 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:45:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:45:37 INFO - 'PWD': '/builds/slave/test', 07:45:37 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:45:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:45:37 INFO - 'SHELL': '/bin/bash', 07:45:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MPbJ6b6wp5/Listeners', 07:45:37 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 07:45:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:45:37 INFO - 'USER': 'cltbld', 07:45:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:45:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:45:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:45:37 INFO - 'XPC_FLAGS': '0x0', 07:45:37 INFO - 'XPC_SERVICE_NAME': '0', 07:45:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:45:37 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Pwh3ouL5TqeqOocueeVbKQ/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0029', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 07:45:37 INFO - mozversion application_buildid: 20170203065708 07:45:37 INFO - mozversion application_changeset: c02020dd7e6e190b046e0bdc992a75b647c4cffa 07:45:37 INFO - mozversion application_display_name: FirefoxDeveloperEdition 07:45:37 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:45:37 INFO - mozversion application_name: Firefox 07:45:37 INFO - mozversion application_remotingname: firefox-dev 07:45:37 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:45:37 INFO - mozversion application_vendor: Mozilla 07:45:37 INFO - mozversion application_version: 53.0a2 07:45:37 INFO - mozversion platform_buildid: 20170203065708 07:45:37 INFO - mozversion platform_changeset: c02020dd7e6e190b046e0bdc992a75b647c4cffa 07:45:37 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:45:37 INFO - mozversion platform_version: 53.0a2 07:45:37 INFO - using testdate: 1486136737 07:45:37 INFO - actual date: 1486136737 07:45:37 INFO - starting webserver on 'localhost:49240' 07:45:37 INFO - SUITE-START | Running 2 tests 07:45:37 INFO - TEST-START | dromaeo_css 07:45:37 INFO - operating with platform_type : mac_ 07:45:37 INFO - Initialising browser for dromaeo_css test... 07:45:37 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmpFqsy2D/profile http://localhost:49240/getInfo.html) 07:45:40 INFO - PROCESS | 1635 | __metrics Screen width/height:1600/1200 07:45:40 INFO - PROCESS | 1635 | colorDepth:24 07:45:40 INFO - PROCESS | 1635 | Browser inner width/height: 1024/689 07:45:40 INFO - PROCESS | 1635 | __metrics 07:45:40 INFO - PROCESS | 1635 | 1486136740316 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 07:45:40 INFO - PROCESS | 1635 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:45:40 INFO - PROCESS | 1635 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:45:41 INFO - TEST-INFO | 1635: exit 0 07:45:41 INFO - Browser initialized. 07:45:41 INFO - Running cycle 1/1 for dromaeo_css test... 07:45:41 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmpFqsy2D/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:45:53 INFO - PROCESS | 1637 | RSS: Main: 213049344 07:45:53 INFO - PROCESS | 1637 | 07:48:56 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49240/tests/dromaeo/cssquery-ext.html) 07:48:58 INFO - PROCESS | 1637 | RSS: Main: 345120768 07:48:58 INFO - PROCESS | 1637 | 07:50:43 INFO - PROCESS | 1637 | ************************* 07:50:43 INFO - PROCESS | 1637 | A coding exception was thrown and uncaught in a Task. 07:50:43 INFO - PROCESS | 1637 | 07:50:43 ERROR - PROCESS | 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 07:50:43 INFO - PROCESS | 1637 | Full stack: 07:50:43 INFO - PROCESS | 1637 | ************************* 07:52:00 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-ext.html (next: http://localhost:49240/tests/dromaeo/cssquery-jquery.html) 07:52:01 INFO - PROCESS | 1637 | RSS: Main: 355475456 07:52:01 INFO - PROCESS | 1637 | 07:55:03 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49240/tests/dromaeo/cssquery-mootools.html) 07:55:04 INFO - PROCESS | 1637 | RSS: Main: 322912256 07:55:04 INFO - PROCESS | 1637 | 07:58:06 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49240/tests/dromaeo/cssquery-prototype.html) 07:58:07 INFO - PROCESS | 1637 | RSS: Main: 333897728 07:58:07 INFO - PROCESS | 1637 | 08:01:10 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49240/tests/dromaeo/cssquery-yui.html) 08:01:11 INFO - PROCESS | 1637 | RSS: Main: 313233408 08:01:11 INFO - PROCESS | 1637 | 08:04:14 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-yui.html (next: http://localhost:49240/tests/dromaeo/cssquery-dojo.html) 08:04:15 INFO - PROCESS | 1637 | RSS: Main: 472625152 08:04:15 INFO - PROCESS | 1637 | 08:04:15 INFO - PROCESS | 1637 | __start_tp_report 08:04:15 INFO - PROCESS | 1637 | _x_x_mozilla_page_load 08:04:15 INFO - PROCESS | 1637 | _x_x_mozilla_page_load_details 08:04:15 INFO - PROCESS | 1637 | |i|pagename|runs| 08:04:15 INFO - PROCESS | 1637 | |0;dojo.html;3335.7998520088795;3353.4131526982774;3357.1271469417943;3359.916002099947;3398.405279076161;4290.420793192918;5604.719764011804;5719.170720245567;5740.942590574093;5765.855853603657;5673.319201695792;5719.170720245562;5762.942370576293;5765.279340082489;5769.798057068004;5237.13587258102;5261.710605916692;5262.605304602151;5269.578433725312;5318.414974352818;5163.225516172567;5179.663321884084;5196.454372290912;5202.08963431398;5316.840494785162;4062.6546743526687;5257.23770053342;5284.2866213061125;5297.496737809921;5314.92027619586;4190.685698572619;5236.2931004314505;5280.867978300535;5283.207518872162;5289.259503669489;5086.720230387327;5100.923486147711;5207.973960130213;5213.4004589472315;5228.398734145546;5648.350439699405;5662.433756624346;5718.113692377647;5725.599208055438;5770.653760774367;6265.686715664198;6286.151369565096;6302.338254483305;6307.96846015767;6313.989761638162;5534.308211473566;6252.687365631746;6284.340144284875;6329.145565348665;6332.905006424908;5648.039833228361;5669.574781891333;5731.684757338387;5751.597388182787;5773.018586840163;6250.281217659936;6263.71813268404;6266.905996410058;6270.592411493239;6272.435480806749;6134.9079763803575;6149.985252433388;6152.723127459186;6157.568970172135;6163.291221509494;6133.977404287646;6142.846428839315;6186.566940314225;6191.380861913773;6215.409536094064;6209.441150296495;6229.657368844757;6235.3452887446165;6242.5942313749465;6271.78048768291;6261.405166509175;6279;6292.842678933063;6296.496280297565;6296.716647750863;4867.805287788519;4956.8760780980765;4993.226049962262;5012.198048312325;5059.418166910778;4266.850660226877;4276.5295817460055;4438.156750217513;4515.819367225275;4525.977370113128;5639.915401268984;5769.451902069297;5781.971090144522;5782.3639399666;5832.096025116114;6171.537134714825;6213.844653883689;6215.06773983897;6244.906326405107;6258.843528911813;4828.734419606885;5715.085586306171;5768.538516918637;5776.133579962955;5788.855278617984;5403.378611459654;5430.809921652803;5436.728163591804;5441.5102640762525;5467.371252306036;6243.750249990131;6255.43701066897;6260;6288.339724328971;6295.370462953668;6279.748810047547;6316.778912738032;6319.494440444845;6323.9683801580695;6330.9050364245495;6269.655168965751;6278.748850045947;6330.208723909512;6331.240251169889;6354.904676429765;6059.0608455689435;6087.756489740361;6113.816585502442;6169.81490555284;6265.749370025148;6226.40849119333;6304.527160463075;6321.273053598711;6329.2404911409085;6330.683465826783;6167.876642467036;6178.598391104564;6183.876322473615;6184.443400094013;6197.597156185014;5928.79249226275;5968.731407086605;6102.847428814138;6150.907736384047;6164.753409863734;4873.658843880894;5887.087501437284;5900.232969714081;6004.849878753066;6037.245344331958;5083.644144909821;6165.5375846810775;6166.691665416622;6169.444749972524;6172.413620706206;5790.449907258803;5957.970210149095;5984.850378740392;5988.760449581968;6012.579119461596 08:04:15 INFO - PROCESS | 1637 | |1;ext.html;446.30599417905165;453.2498714627292;492.3968139029688;514.9845504634858;520.7084032941553;31582.526262106087;41924.58075419245;43763.34354984678;43803.99999999996;44027.57848318341;44725.77637111818;44954;44977.55022449783;44994.55005449944;45182.0963580729;8547.230749232567;9309.53452327386;9514.952425237863;9521.714348569554;9523.047695230456;8534.445261058043;11304.886951130466;11601.07191424684;11615.303081815118;11779.057675385991;11569;11634.883651163507;11636.476358563885;11642.592509262178;11681.415929203506;8431.241188293083;8652.3510736695;11541.961223489885;11609.883901160965;11613.419329033515;11122.165837562216;11417;11443.771124577463;11651;11656.242344247597;42485.93785155396;42836;42836.35745463821;42848.143037139394;42853;4026.8993275168355;4033.296061622562;4086.8978275543054;4111.157212771374;4167.666586673089;29539.556906646416;29948.550771738443;30013.399732005237;31506.36987260265;32255.516167257276;3264.39608672394;3268.2319654881085;3309.966900331014;3380.881669141568;3401.098708842159;503603;504098.99999999633;505140;505354;505739;66640;67053.65892682217;67205.66397168081;67344;67348;70662;70768.64615676888;70806;70814;70820.64589677019;16634.75047874283;17215.91392043057;17219.56951076208;17220.65558688819;17242.741358879626;11818.349990750592;12114.21257618252;12795.168314059743;12964.546240881524;13014.869851301555;15437.614059648598;18650.906745466185;18732.719009214874;18948.43154705361;18989.810101898805;7538.962305188439;7643.74509519693;7646.08847788053;7715.92284077152;7772.727954521678;791.9585744745756;882.3867412148624;894.5571941888677;897.5781382750097;903.9412438191497;28909.42181156365;30081.398372032876;30310;30634;31522.369552609278;956.3783540698463;1025.692142839352;1048.6277371532997;1079.5573814736078;1089.934603923767;14305.213213273162;14633.268336583129;14644.12135271887;15099.47151849702;15108.39566417353;627166;631661;632701;632823;634658;53958;54174.91650167054;54266.728666356415;54327;54354.91290174254;1415.5045733993227;1449.7535418978798;1454.3783681195391;1459.751842186852;1463.8243410790774;27258;27698.307542311602;27843.582346265226;28676.13971580856;28737.56893646555;48352;48609.5139048605;48703.56482175948;48726;48764.512354875995;399654;400213.9978600177;400277;402144;403055;24616.507669846862;25126.49747005013;25178.62232066484;25195.740212989644;25389.873050635368;42283.36574951317;42509;43001;43237;43456;30446.391072178874;36249.63750362568;36850.44724329084;36859.815700921325;37070;36351;36521.53176606142;36688.44967325439;36706.26587468289;36924.44613330749;35842.4623630651;36113.63886361103;36682.26635467329;36817.447738284485;36823.44764828583;30417.391652167276;32569.511457327684;33065.338693226484;33158.502622461165;33661.83169084139;24327.51344973126;24409.26772196837;24553;24984.875075624506;25083.122090726738 08:04:15 INFO - PROCESS | 1637 | |2;jquery.html;333.89482313071386;369.5842177550256;386.5091334005813;399.45075521158407;423.1030215942201;15147.469838555653;15943.681126377465;18422.631547369045;20799.584008319824;21024.369268921917;21284.680729789066;21483.248086316944;22169.556608867813;22782;22942;5732.2548068751;5772.191893134954;5865.8240252792275;5946.851328716773;6008.75964961401;4215.262329092412;5762.279715035621;5875.647461152324;5879.088741245114;5880.147378630096;4420.513743488214;6184.752609895599;6434.453071488929;6483.384078512534;6554.508411869129;6320.873582528369;6513.283538810727;6531.640759758211;6548.738050477975;6590.769323073718;5805.071188609844;5807.738651760681;5809.883802323972;5811.6803575803315;5813.82558523245;21084;26627;26654.86672566644;26873;26933.730662693317;2810.3957649105337;2814.7326004029583;2824.209221418006;2825.5055365311196;2829.1229718787035;14056.859431405657;16106.516804495766;16117.274722637509;16178.35286588535;16813.579660508585;1564.1788061267812;1801.7567628370264;1806.846418054467;1807.4577626711934;1820.1536285627114;102494;128589;132310.6768932308;133416;136204;11297.322160670386;11702.35637039971;11735.119866010084;11735.652869426149;11901.285922844658;15189;15231.314590843438;15244.847551524565;15249.161296128597;15263.923680381527;8779.517126558101;8861.601227944759;8907.777305567413;9008.279337652972;9069.138431848964;9038.005819359865;9052.366334356531;9056.230220431336;9199.218066464291;9210.815783684422;7371.520851144658;7539.962300188464;7562.281583249583;7587.748021576378;7634.580098094658;1660.3313669761812;1923.240320073563;1972.8323092537296;1979.1489659446577;1979.218208807513;838.4717627894388;861.2076889261895;873.2446433834688;881.9735407937644;887.1483375959023;18161.27354905789;19709.11308991099;20655.79344206569;21245.150193992376;21458.817441322157;901.454619954931;904.9502277374808;907.609727817045;921.433318509112;937.1070437715777;10459.00639439252;11068;11086.778264434666;11103.722406939729;11111;731216;732653;742149;760366;764661;111280.44359778149;136258;142338.2883085578;144118;144925;972.5040229482873;981.3032746749888;998.9350692204983;1001.6544292219319;1004.251832384878;17611.295548177928;18249.270029199215;18660.813391865908;18708.812911870707;18766.780159289603;57131.14303285371;59124;59260;59791.70104149451;61373;82006;84673;85902.85242868729;87129.1287087121;88698;5484.862878428071;6158.291796443287;6169;6241.157443745218;6390.9041364378645;10074.8488772667;12492.375381231084;12833.037522185687;13083.476660933457;13099.279539625031;10490.632827850988;10529.262951593315;10708.357498550113;10761.139108871428;10783.848218436115;8731.65073397057;10734.355938643706;10785.568577256821;11091.389973551532;11094.334339939629;10936.56253749841;11068.723281918017;11100.944495277472;11250.7749845001;11474;6144.109104179958;6169.938300616936;6277.466415354746;6324.713101689535;6404.039394090737;8342.332613391036;11119.777604447703;11140.275882067639;11553.075753939833;11571.479283432098 08:04:15 INFO - PROCESS | 1637 | |3;mootools.html;1430.7639239525479;1439.7346916485458;1450.1371683848113;1451.1945870042123;1458.67179884526;8032.518048917062;8451.788705282372;8457.830843383128;8459.91540084599;8472.703455379064;7148.213696493394;7427.220141885092;7437.814054648637;7964.964554607907;8191.467554608947;2290.4388424835897;2321.7097862767155;2325.0351104291785;2325.662778897059;2328.2898715891647;2319.4549280919055;2341.309313752439;2350.0247397330086;2350.8354415190943;2352.153224839065;2293.5986202414592;2304.2165663674346;2326.29048140318;2327.2203811723093;2329.487512747193;2246.2250523569423;2248.7413947396094;2264.116994372196;2278.6354183330754;2283.9657405138937;2027.3107143571256;2264.4565304327007;2298.8850407552322;2332.346942855986;2338.0530884991435;8260.173982601751;8347.499150051017;8348.666053357858;8351.624176912053;8357.83284334316;5008.649394542384;5177.042247184246;5178.533931946105;5183.61122915779;5185;3267.8202698851555;3297.4559197732347;3479.3563190809527;3553.07620018797;3554.0581745837376;1176.3236139219982;1185.2296007595046;1211.7515909238584;1220.1215125109907;1240.2496489623827;10731.302465339728;10807.837882431691;10811.405372704578;10821.350718956888;10821.404822734747;4405.537418571068;4645.790939407735;4703.223968045303;4717.268823332389;4777.092352453023;9447.196988255937;9452.810943781085;9453.621855125717;9476.95753467118;9485.05149485046;5231.503007214308;5235.58115350771;5238.5547228486;5238.711870847063;5241.135212689864;4193.643540299047;4321.58944900234;4463.509014008457;4570.268756998864;4743.810247590058;3181.761367897417;3797.2595343907674;3888.572257051722;3914.8629797957506;3918.7648741075627;1069.6416700405296;1268.3975111821994;1271.7965125579863;1277.565627686591;1281.8397700287464;774.264448773656;789.5578476053429;799.0491315334733;803.1767438375595;808.6846130009301;5432.1580155075235;5454;5540.63985840919;5641.041023026013;5746.655200687972;552.9834104976857;578.8292453726161;579.2845835393285;583.6264790534061;584.7134903897145;3329.783564068328;3692.833822477995;3755.08000539864;3780.5085338905765;3814.1990182061522;9628.277879159092;9913.80172396548;10014.148797352162;10021.09810117093;10040.297179197532;9626.700395446665;9717.17404020652;9749.902500975042;9929.404235745735;9997.250206234561;7585.734499292608;7817.335526480313;7823.765287041398;7832.84334313322;7863.646135923897;4500.774961251859;5605.523530499953;5674.290713660793;5779.595428319977;5903.852403689943;9062.410943288665;9155.038720934206;9171.862422063808;9348.345615806827;9431.764205894908;9311.76720581991;9406.529673516434;9485.146336829719;9522.904770952202;9552.331336806357;6533.248676402274;6755.42578880781;7035.226125126334;7042.154941407064;7166.641667916479;7889.68441262366;8093.797655058671;8105.351571874354;8115.594220288844;8120.553369564731;7040.225575186828;7096.148462184571;7109.21798602143;7156.534825236343;7269.236730143471;6911;6960.547564408297;6982.912343970975;7144.3570078693165;7148.857022859618;6439.935600643934;6446.935530644633;6490.545661803629;6491.058796474579;6510.414062734377;5001.874953126201;5045.2432135180015;5047.520485553867;5064.873378165576;5097.260897169964;4953.455119936876;4971.6784259167825;5083.542481176712;5148.9742551287;5167.819126330561 08:04:15 INFO - PROCESS | 1637 | |4;prototype.html;345.73551233306523;355.1476456504389;358.63777584639513;369.73194434035344;370.73862926636724;4569.360289559464;4820.758962051897;4898.412190537136;5017.04676111539;5126.615503837208;4789.497102804199;4847.563719265266;5020.92050209205;5118.488151184889;5137.942923960247;3393.0838673558123;3406.3308978600553;3786.75386099904;4068.4100805383277;4069.308217603;3449.5167078156383;3766.0396598867387;4061.512618485786;4091.3044782387065;4094.303968325392;3881.9029524261837;4014.6313674931785;4034.213328400963;4036.858709945138;4046;3322.65112163223;4005.218982298453;4024.7182697211206;4042.535108462535;4056.634902858757;3595.280943811248;3598.3882739934274;3608.332458495187;3615.3853844846176;3626.6192049834654;4011.3324639114953;4318.805653745558;4769.737664428455;5002.59979201663;5094.745262736848;3887.669548088416;4635.5364463553715;4639.932815452465;4697.35954635157;4698.157614643126;5382.84686125549;5403.594730395197;5409.43200963901;5446.591505637053;5461.344638643349;3156.8105913645254;3354.681305275995;3957.960420395788;3966.86115985942;3988.282109220339;4897.877553061202;5675.205471234071;5681.744321505542;5699.914501282484;5749.425057494259;4014.578469260686;4029.1538776857187;4042.0501182222156;4061.045654271244;4070.9185816284103;3456.6024907135497;3821.293060783764;3872.670822980022;4080.510338759368;4114.362273847559;4421.889452763642;4601.332806743004;4629.675922685447;4645.86062418128;4653.790579423934;4998.225275082368;5105.617078719111;5474.698891561003;5528.640638358494;5537.556995440355;1285.8234715235642;1302.5310888080282;1313.3761463304854;1323.8014297855207;1334.8320219807667;871.1549796697194;895.3374502867961;896.2471523919809;937.6718148647918;959.2182371367453;4304.461942257218;4730.249807571221;4744.715317080986;4761.976190119097;4763.880902977384;4531.640507847633;4844.456660253395;5113.931641015361;5193;5210;4098.3852422136915;4297.02336925018;4330.805113769888;4658.464276608233;4730.432348118179;2697.9190624281305;2711.9558969796376;2715.8370497770197;2719.7552220300267;2725.4957832800997;5244.606654500928;5372.603123188;5377.612615269704;5408.242846001563;5415.268938693304;4937.506249375106;5175.818846340435;5316.574674026068;5356.839294821161;5398.62209645321;5196.558292545176;5371.75827087782;5433.809816656398;5444.346678398539;5480.616356854982;4561.722717639066;4572.954270457386;5012.3233363495465;5134.8973020540125;5211.977053309148;4636.83771068011;4824.986752781815;4848.418244758669;4916.844541532665;4952.801887924443;5257.63196576236;5343.782330332869;5366.980273747976;5474.193516139744;5487.094629386266;3257.4625186843905;3581.7613991255466;3588.748787584844;3748.8500459981296;3782.59566390251;4561.680682352204;4597.540315930961;5212.452692467236;5248.9737551312;5300.1714408529;4600.493945665907;4938.802447902043;5083.415407228216;5139.922901156411;5158.252053452304;4342.848000319974;4566.132434837503;4798.880027999328;5054.848354549369;5445.319335083114;5098.694078355311;5147.40804807452;5246.97376513115;5335;5336.317940620959;4537.863864084083;4694.920168361365;5351.892962140813;5358.812441564526;5376.596755243294;5145.974270128775;5342.732863356739;5348.625596208228;5361.882056587704;5390 08:04:15 INFO - PROCESS | 1637 | |5;yui.html;708.8653155900382;710.8720430322539;762.4434163060965;822.9753107406777;835.0730688935282;14563.927180364097;15215.847841521581;15585;15591.922040389796;15600.219989000532;14286.78569821451;14310.499132530364;14337.426502939896;14415.207163605997;14488.927555362236;678.2268214235775;695.0686080651925;696.947728920333;840.155643578203;857.2756021162139;781.2851241114373;826.3760860550276;856.9871451928226;863.6286396849353;870.5429649434035;929.251952178496;930.2976252929029;932.1470854168427;932.8274269260174;934.5000424772747;707.4517249131912;724.9669221359542;892.2505095719627;925.3198898809403;925.4447331601019;685.8628274345151;691.5712258399774;691.8132104331853;691.8616276744672;692.8856738638119;14892.70214595702;15175.013624114397;15179;15186.772198417033;15284.235788210655;13948.93025534876;13957.930210348984;14086.654905361349;14099.436022559084;14172.291385430688;12279.324637144953;12618.74762504754;12622.684432889251;12751.808722869164;12813;4479.977600111979;4606.147862645421;4639.721616703008;4652.255639097762;4660.347551342815;86641.56679216564;109192.4540377301;110872;112222;114377.85622143756;10493.685189444177;10838.891611083789;11024.834627480594;11160.553577856796;11183.888161118284;22546.774532254796;22548.098076076774;22585.209517666804;22588;22593.548129037656;7486.812829679193;7719.34385577221;7866.7639970800965;7945.086315073725;7960.323372513285;4301.182775272689;4342.031040180528;4358.782060897006;4394.143142087262;4479.440069991251;2745.7803375729895;3293.75296852737;3295.423300922377;3300.4389253826907;3302.6862448067395;630.7287866217479;639.9872002559923;650.6251637686872;652.9216494020748;661.566673828643;535.2987586262009;626.6208943589154;642.2581917884851;656.4190691238289;659.6240143118376;8301.916980830236;8888.555572221363;9749.658761943298;9856.01439856023;9937.055979681918;630.2437075509407;633.2875515045574;633.338161621107;669.718718138383;686.6635348679212;4594.862154135381;4739.312799644033;4743.525647435229;4745.620350372031;4780.95219047812;82710;84795.5760221195;104599;105142;107419.24032658976;64222.35777642164;72605.6369718148;73554;73683.89474157892;77892.61053694696;57979;70041.64979175174;73020;77093;79905.60047199727;10571.894281057399;11013.504392302206;11041.503132359223;11044.889551104387;11081.722956926142;41788.373174403016;49269.014619706686;49867;51435.228471573704;52707.20939185992;54096.918061639335;64854.35145648672;65029;65763.34236657571;65916;10949.890501094887;11243.269187502787;11590.014848737614;12567.497300107892;13191.142575732547;12540.686482838002;12675.93662031684;13245.470181192644;13366.398512067175;14756.557303280919;9477.620895164391;12319.9384003083;13209.207447553177;13484.797728034284;13755.809885551342;13076.476940922637;13310;13778.155588365646;13983.230922299372;14293.785593216318;8243.381746368927;12730.554430594884;13000.894923931572;13147.412349751126;13957.930210348883;9622.9037709622;10539.42439739918;10688.625898093529;10769.353838769699;10939.945300273448;8799.092712220876;9824.06671366219;10246.59013639476;11544.422778860855;11765.941170294436 08:04:15 INFO - PROCESS | 1637 | __end_tp_report 08:04:15 INFO - PROCESS | 1637 | __start_cc_report 08:04:15 INFO - PROCESS | 1637 | _x_x_mozilla_cycle_collect,1350 08:04:15 INFO - PROCESS | 1637 | __end_cc_report 08:04:15 INFO - PROCESS | 1637 | __startTimestamp1486137855097__endTimestamp 08:04:15 INFO - PROCESS | 1637 | 08:04:15 INFO - PROCESS | 1637 | ------- Summary: start ------- 08:04:15 INFO - PROCESS | 1637 | Number of tests: 6 08:04:15 INFO - PROCESS | 1637 | 08:04:15 INFO - PROCESS | 1637 | [#0] dojo.html Cycles:165 Average:5722.58 Median:5957.97 stddev:679.17 (11.4%) stddev-sans-first:654.94 08:04:15 INFO - PROCESS | 1637 | Values: 3335.8 3353.4 3357.1 3359.9 3398.4 4290.4 5604.7 5719.2 5740.9 5765.9 5673.3 5719.2 5762.9 5765.3 5769.8 5237.1 5261.7 5262.6 5269.6 5318.4 5163.2 5179.7 5196.5 5202.1 5316.8 4062.7 5257.2 5284.3 5297.5 5314.9 4190.7 5236.3 5280.9 5283.2 5289.3 5086.7 5100.9 5208.0 5213.4 5228.4 5648.4 5662.4 5718.1 5725.6 5770.7 6265.7 6286.2 6302.3 6308.0 6314.0 5534.3 6252.7 6284.3 6329.1 6332.9 5648.0 5669.6 5731.7 5751.6 5773.0 6250.3 6263.7 6266.9 6270.6 6272.4 6134.9 6150.0 6152.7 6157.6 6163.3 6134.0 6142.8 6186.6 6191.4 6215.4 6209.4 6229.7 6235.3 6242.6 6271.8 6261.4 6279.0 6292.8 6296.5 6296.7 4867.8 4956.9 4993.2 5012.2 5059.4 4266.9 4276.5 4438.2 4515.8 4526.0 5639.9 5769.5 5782.0 5782.4 5832.1 6171.5 6213.8 6215.1 6244.9 6258.8 4828.7 5715.1 5768.5 5776.1 5788.9 5403.4 5430.8 5436.7 5441.5 5467.4 6243.8 6255.4 6260.0 6288.3 6295.4 6279.7 6316.8 6319.5 6324.0 6330.9 6269.7 6278.7 6330.2 6331.2 6354.9 6059.1 6087.8 6113.8 6169.8 6265.7 6226.4 6304.5 6321.3 6329.2 6330.7 6167.9 6178.6 6183.9 6184.4 6197.6 5928.8 5968.7 6102.8 6150.9 6164.8 4873.7 5887.1 5900.2 6004.8 6037.2 5083.6 6165.5 6166.7 6169.4 6172.4 5790.4 5958.0 5984.9 5988.8 6012.6 08:04:15 INFO - PROCESS | 1637 | 08:04:15 INFO - PROCESS | 1637 | [#1] ext.html Cycles:180 Average:65699.13 Median:36761.86 stddev:139054.48 (378.3%) stddev-sans-first:139358.25 08:04:15 INFO - PROCESS | 1637 | Values: 446.3 453.2 492.4 515.0 520.7 31582.5 41924.6 43763.3 43804.0 44027.6 44725.8 44954.0 44977.6 44994.6 45182.1 8547.2 9309.5 9515.0 9521.7 9523.0 8534.4 11304.9 11601.1 11615.3 11779.1 11569.0 11634.9 11636.5 11642.6 11681.4 8431.2 8652.4 11542.0 11609.9 11613.4 11122.2 11417.0 11443.8 11651.0 11656.2 42485.9 42836.0 42836.4 42848.1 42853.0 4026.9 4033.3 4086.9 4111.2 4167.7 29539.6 29948.6 30013.4 31506.4 32255.5 3264.4 3268.2 3310.0 3380.9 3401.1 503603.0 504099.0 505140.0 505354.0 505739.0 66640.0 67053.7 67205.7 67344.0 67348.0 70662.0 70768.6 70806.0 70814.0 70820.6 16634.8 17215.9 17219.6 17220.7 17242.7 11818.3 12114.2 12795.2 12964.5 13014.9 15437.6 18650.9 18732.7 18948.4 18989.8 7539.0 7643.7 7646.1 7715.9 7772.7 792.0 882.4 894.6 897.6 903.9 28909.4 30081.4 30310.0 30634.0 31522.4 956.4 1025.7 1048.6 1079.6 1089.9 14305.2 14633.3 14644.1 15099.5 15108.4 627166.0 631661.0 632701.0 632823.0 634658.0 53958.0 54174.9 54266.7 54327.0 54354.9 1415.5 1449.8 1454.4 1459.8 1463.8 27258.0 27698.3 27843.6 28676.1 28737.6 48352.0 48609.5 48703.6 48726.0 48764.5 399654.0 400214.0 400277.0 402144.0 403055.0 24616.5 25126.5 25178.6 25195.7 25389.9 42283.4 42509.0 43001.0 43237.0 43456.0 30446.4 36249.6 36850.4 36859.8 37070.0 36351.0 36521.5 36688.4 36706.3 36924.4 35842.5 36113.6 36682.3 36817.4 36823.4 30417.4 32569.5 33065.3 33158.5 33661.8 24327.5 24409.3 24553.0 24984.9 25083.1 08:04:15 INFO - PROCESS | 1637 | 08:04:15 INFO - PROCESS | 1637 | [#2] jquery.html Cycles:180 Average:40129.93 Median:2812.56 stddev:123811.14 (4402.1%) stddev-sans-first:124122.40 08:04:15 INFO - PROCESS | 1637 | Values: 333.9 369.6 386.5 399.5 423.1 15147.5 15943.7 18422.6 20799.6 21024.4 21284.7 21483.2 22169.6 22782.0 22942.0 5732.3 5772.2 5865.8 5946.9 6008.8 4215.3 5762.3 5875.6 5879.1 5880.1 4420.5 6184.8 6434.5 6483.4 6554.5 6320.9 6513.3 6531.6 6548.7 6590.8 5805.1 5807.7 5809.9 5811.7 5813.8 21084.0 26627.0 26654.9 26873.0 26933.7 2810.4 2814.7 2824.2 2825.5 2829.1 14056.9 16106.5 16117.3 16178.4 16813.6 1564.2 1801.8 1806.8 1807.5 1820.2 102494.0 128589.0 132310.7 133416.0 136204.0 11297.3 11702.4 11735.1 11735.7 11901.3 15189.0 15231.3 15244.8 15249.2 15263.9 8779.5 8861.6 8907.8 9008.3 9069.1 9038.0 9052.4 9056.2 9199.2 9210.8 7371.5 7540.0 7562.3 7587.7 7634.6 1660.3 1923.2 1972.8 1979.1 1979.2 838.5 861.2 873.2 882.0 887.1 18161.3 19709.1 20655.8 21245.2 21458.8 901.5 905.0 907.6 921.4 937.1 10459.0 11068.0 11086.8 11103.7 11111.0 731216.0 732653.0 742149.0 760366.0 764661.0 111280.4 136258.0 142338.3 144118.0 144925.0 972.5 981.3 998.9 1001.7 1004.3 17611.3 18249.3 18660.8 18708.8 18766.8 57131.1 59124.0 59260.0 59791.7 61373.0 82006.0 84673.0 85902.9 87129.1 88698.0 5484.9 6158.3 6169.0 6241.2 6390.9 10074.8 12492.4 12833.0 13083.5 13099.3 10490.6 10529.3 10708.4 10761.1 10783.8 8731.7 10734.4 10785.6 11091.4 11094.3 10936.6 11068.7 11100.9 11250.8 11474.0 6144.1 6169.9 6277.5 6324.7 6404.0 8342.3 11119.8 11140.3 11553.1 11571.5 08:04:15 INFO - PROCESS | 1637 | 08:04:15 INFO - PROCESS | 1637 | [#3] mootools.html Cycles:180 Average:5407.70 Median:5237.07 stddev:2972.73 (56.8%) stddev-sans-first:2966.05 08:04:15 INFO - PROCESS | 1637 | Values: 1430.8 1439.7 1450.1 1451.2 1458.7 8032.5 8451.8 8457.8 8459.9 8472.7 7148.2 7427.2 7437.8 7965.0 8191.5 2290.4 2321.7 2325.0 2325.7 2328.3 2319.5 2341.3 2350.0 2350.8 2352.2 2293.6 2304.2 2326.3 2327.2 2329.5 2246.2 2248.7 2264.1 2278.6 2284.0 2027.3 2264.5 2298.9 2332.3 2338.1 8260.2 8347.5 8348.7 8351.6 8357.8 5008.6 5177.0 5178.5 5183.6 5185.0 3267.8 3297.5 3479.4 3553.1 3554.1 1176.3 1185.2 1211.8 1220.1 1240.2 10731.3 10807.8 10811.4 10821.4 10821.4 4405.5 4645.8 4703.2 4717.3 4777.1 9447.2 9452.8 9453.6 9477.0 9485.1 5231.5 5235.6 5238.6 5238.7 5241.1 4193.6 4321.6 4463.5 4570.3 4743.8 3181.8 3797.3 3888.6 3914.9 3918.8 1069.6 1268.4 1271.8 1277.6 1281.8 774.3 789.6 799.0 803.2 808.7 5432.2 5454.0 5540.6 5641.0 5746.7 553.0 578.8 579.3 583.6 584.7 3329.8 3692.8 3755.1 3780.5 3814.2 9628.3 9913.8 10014.1 10021.1 10040.3 9626.7 9717.2 9749.9 9929.4 9997.3 7585.7 7817.3 7823.8 7832.8 7863.6 4500.8 5605.5 5674.3 5779.6 5903.9 9062.4 9155.0 9171.9 9348.3 9431.8 9311.8 9406.5 9485.1 9522.9 9552.3 6533.2 6755.4 7035.2 7042.2 7166.6 7889.7 8093.8 8105.4 8115.6 8120.6 7040.2 7096.1 7109.2 7156.5 7269.2 6911.0 6960.5 6982.9 7144.4 7148.9 6439.9 6446.9 6490.5 6491.1 6510.4 5001.9 5045.2 5047.5 5064.9 5097.3 4953.5 4971.7 5083.5 5149.0 5167.8 08:04:15 INFO - PROCESS | 1637 | 08:04:15 INFO - PROCESS | 1637 | [#4] prototype.html Cycles:180 Average:4308.96 Median:4737.57 stddev:1258.75 (26.6%) stddev-sans-first:1226.63 08:04:15 INFO - PROCESS | 1637 | Values: 345.7 355.1 358.6 369.7 370.7 4569.4 4820.8 4898.4 5017.0 5126.6 4789.5 4847.6 5020.9 5118.5 5137.9 3393.1 3406.3 3786.8 4068.4 4069.3 3449.5 3766.0 4061.5 4091.3 4094.3 3881.9 4014.6 4034.2 4036.9 4046.0 3322.7 4005.2 4024.7 4042.5 4056.6 3595.3 3598.4 3608.3 3615.4 3626.6 4011.3 4318.8 4769.7 5002.6 5094.7 3887.7 4635.5 4639.9 4697.4 4698.2 5382.8 5403.6 5409.4 5446.6 5461.3 3156.8 3354.7 3958.0 3966.9 3988.3 4897.9 5675.2 5681.7 5699.9 5749.4 4014.6 4029.2 4042.1 4061.0 4070.9 3456.6 3821.3 3872.7 4080.5 4114.4 4421.9 4601.3 4629.7 4645.9 4653.8 4998.2 5105.6 5474.7 5528.6 5537.6 1285.8 1302.5 1313.4 1323.8 1334.8 871.2 895.3 896.2 937.7 959.2 4304.5 4730.2 4744.7 4762.0 4763.9 4531.6 4844.5 5113.9 5193.0 5210.0 4098.4 4297.0 4330.8 4658.5 4730.4 2697.9 2712.0 2715.8 2719.8 2725.5 5244.6 5372.6 5377.6 5408.2 5415.3 4937.5 5175.8 5316.6 5356.8 5398.6 5196.6 5371.8 5433.8 5444.3 5480.6 4561.7 4573.0 5012.3 5134.9 5212.0 4636.8 4825.0 4848.4 4916.8 4952.8 5257.6 5343.8 5367.0 5474.2 5487.1 3257.5 3581.8 3588.7 3748.9 3782.6 4561.7 4597.5 5212.5 5249.0 5300.2 4600.5 4938.8 5083.4 5139.9 5158.3 4342.8 4566.1 4798.9 5054.8 5445.3 5098.7 5147.4 5247.0 5335.0 5336.3 4537.9 4694.9 5351.9 5358.8 5376.6 5146.0 5342.7 5348.6 5361.9 5390.0 08:04:15 INFO - PROCESS | 1637 | 08:04:15 INFO - PROCESS | 1637 | [#5] yui.html Cycles:180 Average:19472.14 Median:4622.93 stddev:27591.73 (596.8%) stddev-sans-first:27633.16 08:04:15 INFO - PROCESS | 1637 | Values: 708.9 710.9 762.4 823.0 835.1 14563.9 15215.8 15585.0 15591.9 15600.2 14286.8 14310.5 14337.4 14415.2 14488.9 678.2 695.1 696.9 840.2 857.3 781.3 826.4 857.0 863.6 870.5 929.3 930.3 932.1 932.8 934.5 707.5 725.0 892.3 925.3 925.4 685.9 691.6 691.8 691.9 692.9 14892.7 15175.0 15179.0 15186.8 15284.2 13948.9 13957.9 14086.7 14099.4 14172.3 12279.3 12618.7 12622.7 12751.8 12813.0 4480.0 4606.1 4639.7 4652.3 4660.3 86641.6 109192.5 110872.0 112222.0 114377.9 10493.7 10838.9 11024.8 11160.6 11183.9 22546.8 22548.1 22585.2 22588.0 22593.5 7486.8 7719.3 7866.8 7945.1 7960.3 4301.2 4342.0 4358.8 4394.1 4479.4 2745.8 3293.8 3295.4 3300.4 3302.7 630.7 640.0 650.6 652.9 661.6 535.3 626.6 642.3 656.4 659.6 8301.9 8888.6 9749.7 9856.0 9937.1 630.2 633.3 633.3 669.7 686.7 4594.9 4739.3 4743.5 4745.6 4781.0 82710.0 84795.6 104599.0 105142.0 107419.2 64222.4 72605.6 73554.0 73683.9 77892.6 57979.0 70041.6 73020.0 77093.0 79905.6 10571.9 11013.5 11041.5 11044.9 11081.7 41788.4 49269.0 49867.0 51435.2 52707.2 54096.9 64854.4 65029.0 65763.3 65916.0 10949.9 11243.3 11590.0 12567.5 13191.1 12540.7 12675.9 13245.5 13366.4 14756.6 9477.6 12319.9 13209.2 13484.8 13755.8 13076.5 13310.0 13778.2 13983.2 14293.8 8243.4 12730.6 13000.9 13147.4 13957.9 9622.9 10539.4 10688.6 10769.4 10939.9 8799.1 9824.1 10246.6 11544.4 11765.9 08:04:15 INFO - PROCESS | 1637 | -------- Summary: end -------- 08:04:15 INFO - PROCESS | 1637 | 08:04:15 INFO - TEST-INFO | 1637: exit 0 08:04:15 INFO - TEST-OK | dromaeo_css | took 1118181ms 08:04:15 INFO - TEST-START | kraken 08:04:15 INFO - operating with platform_type : mac_ 08:04:15 INFO - Initialising browser for kraken test... 08:04:15 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmpefNYOD/profile http://localhost:49240/getInfo.html) 08:04:18 INFO - PROCESS | 1646 | __metrics Screen width/height:1600/1200 08:04:18 INFO - PROCESS | 1646 | colorDepth:24 08:04:18 INFO - PROCESS | 1646 | Browser inner width/height: 1024/689 08:04:18 INFO - PROCESS | 1646 | __metrics 08:04:18 INFO - PROCESS | 1646 | 1486137858123 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 08:04:18 INFO - PROCESS | 1646 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:04:18 INFO - PROCESS | 1646 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:04:18 INFO - TEST-INFO | 1646: exit 0 08:04:18 INFO - Browser initialized. 08:04:18 INFO - Running cycle 1/1 for kraken test... 08:04:18 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmpefNYOD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:04:30 INFO - PROCESS | 1647 | RSS: Main: 213520384 08:04:30 INFO - PROCESS | 1647 | 08:04:33 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection) 08:04:34 INFO - PROCESS | 1647 | RSS: Main: 265764864 08:04:34 INFO - PROCESS | 1647 | 08:04:37 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-dft) 08:04:37 INFO - PROCESS | 1647 | RSS: Main: 274919424 08:04:37 INFO - PROCESS | 1647 | 08:04:40 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-fft) 08:04:41 INFO - PROCESS | 1647 | RSS: Main: 373370880 08:04:41 INFO - PROCESS | 1647 | 08:04:43 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator) 08:04:44 INFO - PROCESS | 1647 | RSS: Main: 274853888 08:04:44 INFO - PROCESS | 1647 | 08:04:46 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:04:46 INFO - PROCESS | 1647 | RSS: Main: 276275200 08:04:46 INFO - PROCESS | 1647 | 08:04:49 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom) 08:04:50 INFO - PROCESS | 1647 | RSS: Main: 316637184 08:04:50 INFO - PROCESS | 1647 | 08:04:53 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate) 08:04:53 INFO - PROCESS | 1647 | RSS: Main: 318783488 08:04:53 INFO - PROCESS | 1647 | 08:04:56 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial) 08:04:57 INFO - PROCESS | 1647 | RSS: Main: 318025728 08:04:57 INFO - PROCESS | 1647 | 08:04:58 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:04:59 INFO - PROCESS | 1647 | RSS: Main: 294928384 08:04:59 INFO - PROCESS | 1647 | 08:05:01 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:05:02 INFO - PROCESS | 1647 | RSS: Main: 297295872 08:05:02 INFO - PROCESS | 1647 | 08:05:04 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:05:04 INFO - PROCESS | 1647 | RSS: Main: 299638784 08:05:04 INFO - PROCESS | 1647 | 08:05:07 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:05:08 INFO - PROCESS | 1647 | RSS: Main: 315871232 08:05:08 INFO - PROCESS | 1647 | 08:05:11 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:05:12 INFO - PROCESS | 1647 | RSS: Main: 309800960 08:05:12 INFO - PROCESS | 1647 | 08:05:13 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49240/tests/kraken/driver.html?testName=ai-astar) 08:05:13 INFO - PROCESS | 1647 | RSS: Main: 354410496 08:05:13 INFO - PROCESS | 1647 | 08:05:13 INFO - PROCESS | 1647 | __start_tp_report 08:05:13 INFO - PROCESS | 1647 | _x_x_mozilla_page_load 08:05:13 INFO - PROCESS | 1647 | _x_x_mozilla_page_load_details 08:05:13 INFO - PROCESS | 1647 | |i|pagename|runs| 08:05:13 INFO - PROCESS | 1647 | |0;ai-astar;114;115;115;114;114;115;114;114;115;115 08:05:13 INFO - PROCESS | 1647 | |1;audio-beat-detection;172;141;110;141;111;111;108;108;128;110 08:05:13 INFO - PROCESS | 1647 | |2;audio-dft;185;182;184;186;182;183;181;184;182;183 08:05:13 INFO - PROCESS | 1647 | |3;audio-fft;79;58;60;89;60;56;58;58;77;82 08:05:13 INFO - PROCESS | 1647 | |4;audio-oscillator;84;71;87;70;68;70;82;68;69;82 08:05:13 INFO - PROCESS | 1647 | |5;imaging-gaussian-blur;89;86;89;86;89;87;90;88;89;85 08:05:13 INFO - PROCESS | 1647 | |6;imaging-darkroom;95;94;94;106;131;94;109;149;107;94 08:05:13 INFO - PROCESS | 1647 | |7;imaging-desaturate;132;132;84;85;88;87;87;87;86;85 08:05:13 INFO - PROCESS | 1647 | |8;json-parse-financial;58;56;54;54;56;57;58;58;57;57 08:05:13 INFO - PROCESS | 1647 | |9;json-stringify-tinderbox;65;75;74;74;74;68;69;69;71;70 08:05:13 INFO - PROCESS | 1647 | |10;stanford-crypto-aes;86;83;83;82;84;85;86;84;84;84 08:05:13 INFO - PROCESS | 1647 | |11;stanford-crypto-ccm;138;159;151;122;141;156;153;149;172;162 08:05:13 INFO - PROCESS | 1647 | |12;stanford-crypto-pbkdf2;206;192;167;167;170;167;166;167;167;168 08:05:13 INFO - PROCESS | 1647 | |13;stanford-crypto-sha256-iterative;67;67;66;67;65;65;64;68;66;66 08:05:13 INFO - PROCESS | 1647 | __end_tp_report 08:05:13 INFO - PROCESS | 1647 | __start_cc_report 08:05:13 INFO - PROCESS | 1647 | _x_x_mozilla_cycle_collect,2002 08:05:13 INFO - PROCESS | 1647 | __end_cc_report 08:05:13 INFO - PROCESS | 1647 | __startTimestamp1486137913963__endTimestamp 08:05:13 INFO - PROCESS | 1647 | 08:05:13 INFO - PROCESS | 1647 | ------- Summary: start ------- 08:05:13 INFO - PROCESS | 1647 | Number of tests: 14 08:05:13 INFO - PROCESS | 1647 | 08:05:13 INFO - PROCESS | 1647 | [#0] ai-astar Cycles:10 Average:114.50 Median:115.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 08:05:13 INFO - PROCESS | 1647 | Values: 114.0 115.0 115.0 114.0 114.0 115.0 114.0 114.0 115.0 115.0 08:05:13 INFO - PROCESS | 1647 | 08:05:13 INFO - PROCESS | 1647 | [#1] audio-beat-detection Cycles:10 Average:124.00 Median:119.50 stddev:21.45 (17.9%) stddev-sans-first:14.05 08:05:13 INFO - PROCESS | 1647 | Values: 172.0 141.0 110.0 141.0 111.0 111.0 108.0 108.0 128.0 110.0 08:05:13 INFO - PROCESS | 1647 | 08:05:13 INFO - PROCESS | 1647 | [#2] audio-dft Cycles:10 Average:183.20 Median:183.50 stddev:1.55 (0.8%) stddev-sans-first:1.50 08:05:13 INFO - PROCESS | 1647 | Values: 185.0 182.0 184.0 186.0 182.0 183.0 181.0 184.0 182.0 183.0 08:05:13 INFO - PROCESS | 1647 | 08:05:13 INFO - PROCESS | 1647 | [#3] audio-fft Cycles:10 Average:67.70 Median:68.50 stddev:12.52 (18.3%) stddev-sans-first:12.59 08:05:13 INFO - PROCESS | 1647 | Values: 79.0 58.0 60.0 89.0 60.0 56.0 58.0 58.0 77.0 82.0 08:05:13 INFO - PROCESS | 1647 | 08:05:13 INFO - PROCESS | 1647 | [#4] audio-oscillator Cycles:10 Average:75.10 Median:76.50 stddev:7.62 (10.0%) stddev-sans-first:7.37 08:05:13 INFO - PROCESS | 1647 | Values: 84.0 71.0 87.0 70.0 68.0 70.0 82.0 68.0 69.0 82.0 08:05:13 INFO - PROCESS | 1647 | 08:05:13 INFO - PROCESS | 1647 | [#5] imaging-gaussian-blur Cycles:10 Average:87.80 Median:89.00 stddev:1.69 (1.9%) stddev-sans-first:1.73 08:05:13 INFO - PROCESS | 1647 | Values: 89.0 86.0 89.0 86.0 89.0 87.0 90.0 88.0 89.0 85.0 08:05:13 INFO - PROCESS | 1647 | 08:05:13 INFO - PROCESS | 1647 | [#6] imaging-darkroom Cycles:10 Average:107.30 Median:94.00 stddev:18.75 (19.9%) stddev-sans-first:19.35 08:05:13 INFO - PROCESS | 1647 | Values: 95.0 94.0 94.0 106.0 131.0 94.0 109.0 149.0 107.0 94.0 08:05:13 INFO - PROCESS | 1647 | 08:05:13 INFO - PROCESS | 1647 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:86.50 stddev:19.38 (22.4%) stddev-sans-first:15.34 08:05:13 INFO - PROCESS | 1647 | Values: 132.0 132.0 84.0 85.0 88.0 87.0 87.0 87.0 86.0 85.0 08:05:13 INFO - PROCESS | 1647 | 08:05:13 INFO - PROCESS | 1647 | [#8] json-parse-financial Cycles:10 Average:56.50 Median:57.00 stddev:1.51 (2.6%) stddev-sans-first:1.50 08:05:13 INFO - PROCESS | 1647 | Values: 58.0 56.0 54.0 54.0 56.0 57.0 58.0 58.0 57.0 57.0 08:05:13 INFO - PROCESS | 1647 | 08:05:13 INFO - PROCESS | 1647 | [#9] json-stringify-tinderbox Cycles:10 Average:70.90 Median:72.50 stddev:3.28 (4.5%) stddev-sans-first:2.70 08:05:13 INFO - PROCESS | 1647 | Values: 65.0 75.0 74.0 74.0 74.0 68.0 69.0 69.0 71.0 70.0 08:05:13 INFO - PROCESS | 1647 | 08:05:13 INFO - PROCESS | 1647 | [#10] stanford-crypto-aes Cycles:10 Average:84.10 Median:84.00 stddev:1.29 (1.5%) stddev-sans-first:1.17 08:05:13 INFO - PROCESS | 1647 | Values: 86.0 83.0 83.0 82.0 84.0 85.0 86.0 84.0 84.0 84.0 08:05:13 INFO - PROCESS | 1647 | 08:05:13 INFO - PROCESS | 1647 | [#11] stanford-crypto-ccm Cycles:10 Average:150.30 Median:154.50 stddev:14.00 (9.1%) stddev-sans-first:14.12 08:05:13 INFO - PROCESS | 1647 | Values: 138.0 159.0 151.0 122.0 141.0 156.0 153.0 149.0 172.0 162.0 08:05:13 INFO - PROCESS | 1647 | 08:05:13 INFO - PROCESS | 1647 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.70 Median:167.50 stddev:13.78 (8.2%) stddev-sans-first:8.28 08:05:13 INFO - PROCESS | 1647 | Values: 206.0 192.0 167.0 167.0 170.0 167.0 166.0 167.0 167.0 168.0 08:05:13 INFO - PROCESS | 1647 | 08:05:13 INFO - PROCESS | 1647 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.10 Median:66.50 stddev:1.20 (1.8%) stddev-sans-first:1.22 08:05:13 INFO - PROCESS | 1647 | Values: 67.0 67.0 66.0 67.0 65.0 65.0 64.0 68.0 66.0 66.0 08:05:13 INFO - PROCESS | 1647 | -------- Summary: end -------- 08:05:13 INFO - PROCESS | 1647 | 08:05:14 INFO - TEST-INFO | 1647: exit 0 08:05:14 INFO - TEST-OK | kraken | took 58979ms 08:05:14 INFO - SUITE-END | took 1177s 08:05:15 INFO - Completed test suite (00:19:37) 08:05:15 INFO - javascript benchmark 08:05:15 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3335.7998520088795, 3353.4131526982774, 3357.1271469417943, 3359.916002099947, 3398.405279076161, 4290.420793192918, 5604.719764011804, 5719.170720245567, 5740.942590574093, 5765.855853603657, 5673.319201695792, 5719.170720245562, 5762.942370576293, 5765.279340082489, 5769.798057068004, 5237.13587258102, 5261.710605916692, 5262.605304602151, 5269.578433725312, 5318.414974352818, 5163.225516172567, 5179.663321884084, 5196.454372290912, 5202.08963431398, 5316.840494785162, 4062.6546743526687, 5257.23770053342, 5284.2866213061125, 5297.496737809921, 5314.92027619586, 4190.685698572619, 5236.2931004314505, 5280.867978300535, 5283.207518872162, 5289.259503669489, 5086.720230387327, 5100.923486147711, 5207.973960130213, 5213.4004589472315, 5228.398734145546, 5648.350439699405, 5662.433756624346, 5718.113692377647, 5725.599208055438, 5770.653760774367, 6265.686715664198, 6286.151369565096, 6302.338254483305, 6307.96846015767, 6313.989761638162, 5534.308211473566, 6252.687365631746, 6284.340144284875, 6329.145565348665, 6332.905006424908, 5648.039833228361, 5669.574781891333, 5731.684757338387, 5751.597388182787, 5773.018586840163, 6250.281217659936, 6263.71813268404, 6266.905996410058, 6270.592411493239, 6272.435480806749, 6134.9079763803575, 6149.985252433388, 6152.723127459186, 6157.568970172135, 6163.291221509494, 6133.977404287646, 6142.846428839315, 6186.566940314225, 6191.380861913773, 6215.409536094064, 6209.441150296495, 6229.657368844757, 6235.3452887446165, 6242.5942313749465, 6271.78048768291, 6261.405166509175, 6279.0, 6292.842678933063, 6296.496280297565, 6296.716647750863, 4867.805287788519, 4956.8760780980765, 4993.226049962262, 5012.198048312325, 5059.418166910778, 4266.850660226877, 4276.5295817460055, 4438.156750217513, 4515.819367225275, 4525.977370113128, 5639.915401268984, 5769.451902069297, 5781.971090144522, 5782.3639399666, 5832.096025116114, 6171.537134714825, 6213.844653883689, 6215.06773983897, 6244.906326405107, 6258.843528911813, 4828.734419606885, 5715.085586306171, 5768.538516918637, 5776.133579962955, 5788.855278617984, 5403.378611459654, 5430.809921652803, 5436.728163591804, 5441.5102640762525, 5467.371252306036, 6243.750249990131, 6255.43701066897, 6260.0, 6288.339724328971, 6295.370462953668, 6279.748810047547, 6316.778912738032, 6319.494440444845, 6323.9683801580695, 6330.9050364245495, 6269.655168965751, 6278.748850045947, 6330.208723909512, 6331.240251169889, 6354.904676429765, 6059.0608455689435, 6087.756489740361, 6113.816585502442, 6169.81490555284, 6265.749370025148, 6226.40849119333, 6304.527160463075, 6321.273053598711, 6329.2404911409085, 6330.683465826783, 6167.876642467036, 6178.598391104564, 6183.876322473615, 6184.443400094013, 6197.597156185014, 5928.79249226275, 5968.731407086605, 6102.847428814138, 6150.907736384047, 6164.753409863734, 4873.658843880894, 5887.087501437284, 5900.232969714081, 6004.849878753066, 6037.245344331958, 5083.644144909821, 6165.5375846810775, 6166.691665416622, 6169.444749972524, 6172.413620706206, 5790.449907258803, 5957.970210149095, 5984.850378740392, 5988.760449581968, 6012.579119461596], "value": 5679.752408267444, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [446.30599417905165, 453.2498714627292, 492.3968139029688, 514.9845504634858, 520.7084032941553, 31582.526262106087, 41924.58075419245, 43763.34354984678, 43803.99999999996, 44027.57848318341, 44725.77637111818, 44954.0, 44977.55022449783, 44994.55005449944, 45182.0963580729, 8547.230749232567, 9309.53452327386, 9514.952425237863, 9521.714348569554, 9523.047695230456, 8534.445261058043, 11304.886951130466, 11601.07191424684, 11615.303081815118, 11779.057675385991, 11569.0, 11634.883651163507, 11636.476358563885, 11642.592509262178, 11681.415929203506, 8431.241188293083, 8652.3510736695, 11541.961223489885, 11609.883901160965, 11613.419329033515, 11122.165837562216, 11417.0, 11443.771124577463, 11651.0, 11656.242344247597, 42485.93785155396, 42836.0, 42836.35745463821, 42848.143037139394, 42853.0, 4026.8993275168355, 4033.296061622562, 4086.8978275543054, 4111.157212771374, 4167.666586673089, 29539.556906646416, 29948.550771738443, 30013.399732005237, 31506.36987260265, 32255.516167257276, 3264.39608672394, 3268.2319654881085, 3309.966900331014, 3380.881669141568, 3401.098708842159, 503603.0, 504098.99999999633, 505140.0, 505354.0, 505739.0, 66640.0, 67053.65892682217, 67205.66397168081, 67344.0, 67348.0, 70662.0, 70768.64615676888, 70806.0, 70814.0, 70820.64589677019, 16634.75047874283, 17215.91392043057, 17219.56951076208, 17220.65558688819, 17242.741358879626, 11818.349990750592, 12114.21257618252, 12795.168314059743, 12964.546240881524, 13014.869851301555, 15437.614059648598, 18650.906745466185, 18732.719009214874, 18948.43154705361, 18989.810101898805, 7538.962305188439, 7643.74509519693, 7646.08847788053, 7715.92284077152, 7772.727954521678, 791.9585744745756, 882.3867412148624, 894.5571941888677, 897.5781382750097, 903.9412438191497, 28909.42181156365, 30081.398372032876, 30310.0, 30634.0, 31522.369552609278, 956.3783540698463, 1025.692142839352, 1048.6277371532997, 1079.5573814736078, 1089.934603923767, 14305.213213273162, 14633.268336583129, 14644.12135271887, 15099.47151849702, 15108.39566417353, 627166.0, 631661.0, 632701.0, 632823.0, 634658.0, 53958.0, 54174.91650167054, 54266.728666356415, 54327.0, 54354.91290174254, 1415.5045733993227, 1449.7535418978798, 1454.3783681195391, 1459.751842186852, 1463.8243410790774, 27258.0, 27698.307542311602, 27843.582346265226, 28676.13971580856, 28737.56893646555, 48352.0, 48609.5139048605, 48703.56482175948, 48726.0, 48764.512354875995, 399654.0, 400213.9978600177, 400277.0, 402144.0, 403055.0, 24616.507669846862, 25126.49747005013, 25178.62232066484, 25195.740212989644, 25389.873050635368, 42283.36574951317, 42509.0, 43001.0, 43237.0, 43456.0, 30446.391072178874, 36249.63750362568, 36850.44724329084, 36859.815700921325, 37070.0, 36351.0, 36521.53176606142, 36688.44967325439, 36706.26587468289, 36924.44613330749, 35842.4623630651, 36113.63886361103, 36682.26635467329, 36817.447738284485, 36823.44764828583, 30417.391652167276, 32569.511457327684, 33065.338693226484, 33158.502622461165, 33661.83169084139, 24327.51344973126, 24409.26772196837, 24553.0, 24984.875075624506, 25083.122090726738], "value": 20033.902424674317, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [333.89482313071386, 369.5842177550256, 386.5091334005813, 399.45075521158407, 423.1030215942201, 15147.469838555653, 15943.681126377465, 18422.631547369045, 20799.584008319824, 21024.369268921917, 21284.680729789066, 21483.248086316944, 22169.556608867813, 22782.0, 22942.0, 5732.2548068751, 5772.191893134954, 5865.8240252792275, 5946.851328716773, 6008.75964961401, 4215.262329092412, 5762.279715035621, 5875.647461152324, 5879.088741245114, 5880.147378630096, 4420.513743488214, 6184.752609895599, 6434.453071488929, 6483.384078512534, 6554.508411869129, 6320.873582528369, 6513.283538810727, 6531.640759758211, 6548.738050477975, 6590.769323073718, 5805.071188609844, 5807.738651760681, 5809.883802323972, 5811.6803575803315, 5813.82558523245, 21084.0, 26627.0, 26654.86672566644, 26873.0, 26933.730662693317, 2810.3957649105337, 2814.7326004029583, 2824.209221418006, 2825.5055365311196, 2829.1229718787035, 14056.859431405657, 16106.516804495766, 16117.274722637509, 16178.35286588535, 16813.579660508585, 1564.1788061267812, 1801.7567628370264, 1806.846418054467, 1807.4577626711934, 1820.1536285627114, 102494.0, 128589.0, 132310.6768932308, 133416.0, 136204.0, 11297.322160670386, 11702.35637039971, 11735.119866010084, 11735.652869426149, 11901.285922844658, 15189.0, 15231.314590843438, 15244.847551524565, 15249.161296128597, 15263.923680381527, 8779.517126558101, 8861.601227944759, 8907.777305567413, 9008.279337652972, 9069.138431848964, 9038.005819359865, 9052.366334356531, 9056.230220431336, 9199.218066464291, 9210.815783684422, 7371.520851144658, 7539.962300188464, 7562.281583249583, 7587.748021576378, 7634.580098094658, 1660.3313669761812, 1923.240320073563, 1972.8323092537296, 1979.1489659446577, 1979.218208807513, 838.4717627894388, 861.2076889261895, 873.2446433834688, 881.9735407937644, 887.1483375959023, 18161.27354905789, 19709.11308991099, 20655.79344206569, 21245.150193992376, 21458.817441322157, 901.454619954931, 904.9502277374808, 907.609727817045, 921.433318509112, 937.1070437715777, 10459.00639439252, 11068.0, 11086.778264434666, 11103.722406939729, 11111.0, 731216.0, 732653.0, 742149.0, 760366.0, 764661.0, 111280.44359778149, 136258.0, 142338.2883085578, 144118.0, 144925.0, 972.5040229482873, 981.3032746749888, 998.9350692204983, 1001.6544292219319, 1004.251832384878, 17611.295548177928, 18249.270029199215, 18660.813391865908, 18708.812911870707, 18766.780159289603, 57131.14303285371, 59124.0, 59260.0, 59791.70104149451, 61373.0, 82006.0, 84673.0, 85902.85242868729, 87129.1287087121, 88698.0, 5484.862878428071, 6158.291796443287, 6169.0, 6241.157443745218, 6390.9041364378645, 10074.8488772667, 12492.375381231084, 12833.037522185687, 13083.476660933457, 13099.279539625031, 10490.632827850988, 10529.262951593315, 10708.357498550113, 10761.139108871428, 10783.848218436115, 8731.65073397057, 10734.355938643706, 10785.568577256821, 11091.389973551532, 11094.334339939629, 10936.56253749841, 11068.723281918017, 11100.944495277472, 11250.7749845001, 11474.0, 6144.109104179958, 6169.938300616936, 6277.466415354746, 6324.713101689535, 6404.039394090737, 8342.332613391036, 11119.777604447703, 11140.275882067639, 11553.075753939833, 11571.479283432098], "value": 9874.075977610803, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1430.7639239525479, 1439.7346916485458, 1450.1371683848113, 1451.1945870042123, 1458.67179884526, 8032.518048917062, 8451.788705282372, 8457.830843383128, 8459.91540084599, 8472.703455379064, 7148.213696493394, 7427.220141885092, 7437.814054648637, 7964.964554607907, 8191.467554608947, 2290.4388424835897, 2321.7097862767155, 2325.0351104291785, 2325.662778897059, 2328.2898715891647, 2319.4549280919055, 2341.309313752439, 2350.0247397330086, 2350.8354415190943, 2352.153224839065, 2293.5986202414592, 2304.2165663674346, 2326.29048140318, 2327.2203811723093, 2329.487512747193, 2246.2250523569423, 2248.7413947396094, 2264.116994372196, 2278.6354183330754, 2283.9657405138937, 2027.3107143571256, 2264.4565304327007, 2298.8850407552322, 2332.346942855986, 2338.0530884991435, 8260.173982601751, 8347.499150051017, 8348.666053357858, 8351.624176912053, 8357.83284334316, 5008.649394542384, 5177.042247184246, 5178.533931946105, 5183.61122915779, 5185.0, 3267.8202698851555, 3297.4559197732347, 3479.3563190809527, 3553.07620018797, 3554.0581745837376, 1176.3236139219982, 1185.2296007595046, 1211.7515909238584, 1220.1215125109907, 1240.2496489623827, 10731.302465339728, 10807.837882431691, 10811.405372704578, 10821.350718956888, 10821.404822734747, 4405.537418571068, 4645.790939407735, 4703.223968045303, 4717.268823332389, 4777.092352453023, 9447.196988255937, 9452.810943781085, 9453.621855125717, 9476.95753467118, 9485.05149485046, 5231.503007214308, 5235.58115350771, 5238.5547228486, 5238.711870847063, 5241.135212689864, 4193.643540299047, 4321.58944900234, 4463.509014008457, 4570.268756998864, 4743.810247590058, 3181.761367897417, 3797.2595343907674, 3888.572257051722, 3914.8629797957506, 3918.7648741075627, 1069.6416700405296, 1268.3975111821994, 1271.7965125579863, 1277.565627686591, 1281.8397700287464, 774.264448773656, 789.5578476053429, 799.0491315334733, 803.1767438375595, 808.6846130009301, 5432.1580155075235, 5454.0, 5540.63985840919, 5641.041023026013, 5746.655200687972, 552.9834104976857, 578.8292453726161, 579.2845835393285, 583.6264790534061, 584.7134903897145, 3329.783564068328, 3692.833822477995, 3755.08000539864, 3780.5085338905765, 3814.1990182061522, 9628.277879159092, 9913.80172396548, 10014.148797352162, 10021.09810117093, 10040.297179197532, 9626.700395446665, 9717.17404020652, 9749.902500975042, 9929.404235745735, 9997.250206234561, 7585.734499292608, 7817.335526480313, 7823.765287041398, 7832.84334313322, 7863.646135923897, 4500.774961251859, 5605.523530499953, 5674.290713660793, 5779.595428319977, 5903.852403689943, 9062.410943288665, 9155.038720934206, 9171.862422063808, 9348.345615806827, 9431.764205894908, 9311.76720581991, 9406.529673516434, 9485.146336829719, 9522.904770952202, 9552.331336806357, 6533.248676402274, 6755.42578880781, 7035.226125126334, 7042.154941407064, 7166.641667916479, 7889.68441262366, 8093.797655058671, 8105.351571874354, 8115.594220288844, 8120.553369564731, 7040.225575186828, 7096.148462184571, 7109.21798602143, 7156.534825236343, 7269.236730143471, 6911.0, 6960.547564408297, 6982.912343970975, 7144.3570078693165, 7148.857022859618, 6439.935600643934, 6446.935530644633, 6490.545661803629, 6491.058796474579, 6510.414062734377, 5001.874953126201, 5045.2432135180015, 5047.520485553867, 5064.873378165576, 5097.260897169964, 4953.455119936876, 4971.6784259167825, 5083.542481176712, 5148.9742551287, 5167.819126330561], "value": 4334.545812193398, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [345.73551233306523, 355.1476456504389, 358.63777584639513, 369.73194434035344, 370.73862926636724, 4569.360289559464, 4820.758962051897, 4898.412190537136, 5017.04676111539, 5126.615503837208, 4789.497102804199, 4847.563719265266, 5020.92050209205, 5118.488151184889, 5137.942923960247, 3393.0838673558123, 3406.3308978600553, 3786.75386099904, 4068.4100805383277, 4069.308217603, 3449.5167078156383, 3766.0396598867387, 4061.512618485786, 4091.3044782387065, 4094.303968325392, 3881.9029524261837, 4014.6313674931785, 4034.213328400963, 4036.858709945138, 4046.0, 3322.65112163223, 4005.218982298453, 4024.7182697211206, 4042.535108462535, 4056.634902858757, 3595.280943811248, 3598.3882739934274, 3608.332458495187, 3615.3853844846176, 3626.6192049834654, 4011.3324639114953, 4318.805653745558, 4769.737664428455, 5002.59979201663, 5094.745262736848, 3887.669548088416, 4635.5364463553715, 4639.932815452465, 4697.35954635157, 4698.157614643126, 5382.84686125549, 5403.594730395197, 5409.43200963901, 5446.591505637053, 5461.344638643349, 3156.8105913645254, 3354.681305275995, 3957.960420395788, 3966.86115985942, 3988.282109220339, 4897.877553061202, 5675.205471234071, 5681.744321505542, 5699.914501282484, 5749.425057494259, 4014.578469260686, 4029.1538776857187, 4042.0501182222156, 4061.045654271244, 4070.9185816284103, 3456.6024907135497, 3821.293060783764, 3872.670822980022, 4080.510338759368, 4114.362273847559, 4421.889452763642, 4601.332806743004, 4629.675922685447, 4645.86062418128, 4653.790579423934, 4998.225275082368, 5105.617078719111, 5474.698891561003, 5528.640638358494, 5537.556995440355, 1285.8234715235642, 1302.5310888080282, 1313.3761463304854, 1323.8014297855207, 1334.8320219807667, 871.1549796697194, 895.3374502867961, 896.2471523919809, 937.6718148647918, 959.2182371367453, 4304.461942257218, 4730.249807571221, 4744.715317080986, 4761.976190119097, 4763.880902977384, 4531.640507847633, 4844.456660253395, 5113.931641015361, 5193.0, 5210.0, 4098.3852422136915, 4297.02336925018, 4330.805113769888, 4658.464276608233, 4730.432348118179, 2697.9190624281305, 2711.9558969796376, 2715.8370497770197, 2719.7552220300267, 2725.4957832800997, 5244.606654500928, 5372.603123188, 5377.612615269704, 5408.242846001563, 5415.268938693304, 4937.506249375106, 5175.818846340435, 5316.574674026068, 5356.839294821161, 5398.62209645321, 5196.558292545176, 5371.75827087782, 5433.809816656398, 5444.346678398539, 5480.616356854982, 4561.722717639066, 4572.954270457386, 5012.3233363495465, 5134.8973020540125, 5211.977053309148, 4636.83771068011, 4824.986752781815, 4848.418244758669, 4916.844541532665, 4952.801887924443, 5257.63196576236, 5343.782330332869, 5366.980273747976, 5474.193516139744, 5487.094629386266, 3257.4625186843905, 3581.7613991255466, 3588.748787584844, 3748.8500459981296, 3782.59566390251, 4561.680682352204, 4597.540315930961, 5212.452692467236, 5248.9737551312, 5300.1714408529, 4600.493945665907, 4938.802447902043, 5083.415407228216, 5139.922901156411, 5158.252053452304, 4342.848000319974, 4566.132434837503, 4798.880027999328, 5054.848354549369, 5445.319335083114, 5098.694078355311, 5147.40804807452, 5246.97376513115, 5335.0, 5336.317940620959, 4537.863864084083, 4694.920168361365, 5351.892962140813, 5358.812441564526, 5376.596755243294, 5145.974270128775, 5342.732863356739, 5348.625596208228, 5361.882056587704, 5390.0], "value": 3929.977825383433, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [708.8653155900382, 710.8720430322539, 762.4434163060965, 822.9753107406777, 835.0730688935282, 14563.927180364097, 15215.847841521581, 15585.0, 15591.922040389796, 15600.219989000532, 14286.78569821451, 14310.499132530364, 14337.426502939896, 14415.207163605997, 14488.927555362236, 678.2268214235775, 695.0686080651925, 696.947728920333, 840.155643578203, 857.2756021162139, 781.2851241114373, 826.3760860550276, 856.9871451928226, 863.6286396849353, 870.5429649434035, 929.251952178496, 930.2976252929029, 932.1470854168427, 932.8274269260174, 934.5000424772747, 707.4517249131912, 724.9669221359542, 892.2505095719627, 925.3198898809403, 925.4447331601019, 685.8628274345151, 691.5712258399774, 691.8132104331853, 691.8616276744672, 692.8856738638119, 14892.70214595702, 15175.013624114397, 15179.0, 15186.772198417033, 15284.235788210655, 13948.93025534876, 13957.930210348984, 14086.654905361349, 14099.436022559084, 14172.291385430688, 12279.324637144953, 12618.74762504754, 12622.684432889251, 12751.808722869164, 12813.0, 4479.977600111979, 4606.147862645421, 4639.721616703008, 4652.255639097762, 4660.347551342815, 86641.56679216564, 109192.4540377301, 110872.0, 112222.0, 114377.85622143756, 10493.685189444177, 10838.891611083789, 11024.834627480594, 11160.553577856796, 11183.888161118284, 22546.774532254796, 22548.098076076774, 22585.209517666804, 22588.0, 22593.548129037656, 7486.812829679193, 7719.34385577221, 7866.7639970800965, 7945.086315073725, 7960.323372513285, 4301.182775272689, 4342.031040180528, 4358.782060897006, 4394.143142087262, 4479.440069991251, 2745.7803375729895, 3293.75296852737, 3295.423300922377, 3300.4389253826907, 3302.6862448067395, 630.7287866217479, 639.9872002559923, 650.6251637686872, 652.9216494020748, 661.566673828643, 535.2987586262009, 626.6208943589154, 642.2581917884851, 656.4190691238289, 659.6240143118376, 8301.916980830236, 8888.555572221363, 9749.658761943298, 9856.01439856023, 9937.055979681918, 630.2437075509407, 633.2875515045574, 633.338161621107, 669.718718138383, 686.6635348679212, 4594.862154135381, 4739.312799644033, 4743.525647435229, 4745.620350372031, 4780.95219047812, 82710.0, 84795.5760221195, 104599.0, 105142.0, 107419.24032658976, 64222.35777642164, 72605.6369718148, 73554.0, 73683.89474157892, 77892.61053694696, 57979.0, 70041.64979175174, 73020.0, 77093.0, 79905.60047199727, 10571.894281057399, 11013.504392302206, 11041.503132359223, 11044.889551104387, 11081.722956926142, 41788.373174403016, 49269.014619706686, 49867.0, 51435.228471573704, 52707.20939185992, 54096.918061639335, 64854.35145648672, 65029.0, 65763.34236657571, 65916.0, 10949.890501094887, 11243.269187502787, 11590.014848737614, 12567.497300107892, 13191.142575732547, 12540.686482838002, 12675.93662031684, 13245.470181192644, 13366.398512067175, 14756.557303280919, 9477.620895164391, 12319.9384003083, 13209.207447553177, 13484.797728034284, 13755.809885551342, 13076.476940922637, 13310.0, 13778.155588365646, 13983.230922299372, 14293.785593216318, 8243.381746368927, 12730.554430594884, 13000.894923931572, 13147.412349751126, 13957.930210348883, 9622.9037709622, 10539.42439739918, 10688.625898093529, 10769.353838769699, 10939.945300273448, 8799.092712220876, 9824.06671366219, 10246.59013639476, 11544.422778860855, 11765.941170294436], "value": 7341.080864228295, "unit": "score"}], "name": "dromaeo_css", "value": 7210.343439340951, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 115.0, 114.0, 114.0, 115.0, 114.0, 114.0, 115.0, 115.0], "value": 114.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 141.0, 110.0, 141.0, 111.0, 111.0, 108.0, 108.0, 128.0, 110.0], "value": 124.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 182.0, 184.0, 186.0, 182.0, 183.0, 181.0, 184.0, 182.0, 183.0], "value": 183.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 58.0, 60.0, 89.0, 60.0, 56.0, 58.0, 58.0, 77.0, 82.0], "value": 67.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 71.0, 87.0, 70.0, 68.0, 70.0, 82.0, 68.0, 69.0, 82.0], "value": 75.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 89.0, 86.0, 89.0, 87.0, 90.0, 88.0, 89.0, 85.0], "value": 87.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 106.0, 131.0, 94.0, 109.0, 149.0, 107.0, 94.0], "value": 107.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 132.0, 84.0, 85.0, 88.0, 87.0, 87.0, 87.0, 86.0, 85.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 56.0, 54.0, 54.0, 56.0, 57.0, 58.0, 58.0, 57.0, 57.0], "value": 56.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 75.0, 74.0, 74.0, 74.0, 68.0, 69.0, 69.0, 71.0, 70.0], "value": 70.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 83.0, 82.0, 84.0, 85.0, 86.0, 84.0, 84.0, 84.0], "value": 84.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 159.0, 151.0, 122.0, 141.0, 156.0, 153.0, 149.0, 172.0, 162.0], "value": 150.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [206.0, 192.0, 167.0, 167.0, 170.0, 167.0, 166.0, 167.0, 167.0, 168.0], "value": 173.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 66.0, 67.0, 65.0, 65.0, 64.0, 68.0, 66.0, 66.0], "value": 66.1, "unit": "score"}], "name": "kraken", "value": 1456.4999999999998, "alertThreshold": 2.0}]} 08:05:15 INFO - Return code: 0 08:05:15 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:05:15 ERROR - # TBPL SUCCESS # 08:05:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:05:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:05:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:05:15 INFO - 2017-02-03 08:05:15.263 screenresolution[1651:9751] starting screenresolution argv=screenresolution get 08:05:15 INFO - 2017-02-03 08:05:15.266 screenresolution[1651:9751] Display 0: 1600x1200x32@60 08:05:15 INFO - 2017-02-03 08:05:15.272 screenresolution[1652:9754] starting screenresolution argv=screenresolution list 08:05:15 INFO - Available Modes on Display 0 08:05:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:05:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:05:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:05:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:05:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:05:15 INFO - Intel Iris: 08:05:15 INFO - Chipset Model: Intel Iris 08:05:15 INFO - Type: GPU 08:05:15 INFO - Bus: Built-In 08:05:15 INFO - VRAM (Dynamic, Max): 1536 MB 08:05:15 INFO - Vendor: Intel (0x8086) 08:05:15 INFO - Device ID: 0x0a2e 08:05:15 INFO - Revision ID: 0x0009 08:05:15 INFO - Displays: 08:05:15 INFO - Display: 08:05:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:05:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:05:15 INFO - Display Serial Number: 1600x1200 60 08:05:15 INFO - Main Display: Yes 08:05:15 INFO - Mirror: Off 08:05:15 INFO - Online: Yes 08:05:15 INFO - Rotation: Supported 08:05:15 INFO - Return code: 0 08:05:15 INFO - Running post-action listener: _resource_record_post_action 08:05:15 INFO - [mozharness: 2017-02-03 16:05:15.508776Z] Finished run-tests step (success) 08:05:15 INFO - Running post-run listener: _resource_record_post_run 08:05:15 INFO - Total resource usage - Wall time: 1198s; CPU: 54.0%; Read bytes: 38990848; Write bytes: 507759104; Read time: 381; Write time: 1642 08:05:15 INFO - TinderboxPrint: CPU usage
53.6% 08:05:15 INFO - TinderboxPrint: I/O read bytes / time
38,990,848 / 381 08:05:15 INFO - TinderboxPrint: I/O write bytes / time
507,759,104 / 1,642 08:05:15 INFO - TinderboxPrint: CPU idle
2,225.2 (46.4%) 08:05:15 INFO - TinderboxPrint: CPU system
959.3 (20.0%) 08:05:15 INFO - TinderboxPrint: CPU user
1,609.1 (33.6%) 08:05:15 INFO - TinderboxPrint: Swap in / out
246,018,048 / 0 08:05:15 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 201181696; Write bytes: 198688768; Read time: 15703; Write time: 352 08:05:15 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 37188608; Write bytes: 298392064; Read time: 360; Write time: 1261 08:05:15 INFO - Running post-run listener: _upload_blobber_files 08:05:15 INFO - Blob upload gear active. 08:05:15 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:05:15 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 08:05:15 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'] 08:05:15 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 08:05:16 INFO - (blobuploader) - INFO - Open directory for files ... 08:05:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:05:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:05:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:05:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:05:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:05:17 INFO - (blobuploader) - INFO - Done attempting. 08:05:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:05:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:05:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:05:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:05:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:05:18 INFO - (blobuploader) - INFO - Done attempting. 08:05:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:05:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:05:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:05:19 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:05:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:05:19 INFO - (blobuploader) - INFO - Done attempting. 08:05:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:05:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:05:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:05:21 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:05:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:05:21 INFO - (blobuploader) - INFO - Done attempting. 08:05:21 INFO - (blobuploader) - INFO - Iteration through files over. 08:05:21 INFO - Return code: 0 08:05:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:05:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:05:21 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dab73121d5fa588891e66aa154555cf2f453ffba8b7df1de348c5afb7fb86acd0a7558bdefc404d3027c2b9c823bd8f278372f6b59d3291bbadb4af02985a27d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c59bd4674852e8fc3b5301bf115c05e0a306ec5a8e076a4410894edbaef266cac22c88583a01df386b2d4a904e21cee092be12680df87676e59a5a44c25c423a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b3f70f6a29954e040e96c4a25a347fdde0c2431964c97f6dca8619ab136cc11939445343f9b9c24241afeb2fcc82b933c69edbfbe2d644e9412ec74a9a517a0e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e48e2bc3a6e2fa24db5c3ae72f9ed5aa69a0ffd8f612189fe07977587427b5f0cbf9d51e6a079eb679f0296a3b592fedd326f95a319b88af7bbb691b97cced67"} 08:05:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:05:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:05:21 INFO - Contents: 08:05:21 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dab73121d5fa588891e66aa154555cf2f453ffba8b7df1de348c5afb7fb86acd0a7558bdefc404d3027c2b9c823bd8f278372f6b59d3291bbadb4af02985a27d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c59bd4674852e8fc3b5301bf115c05e0a306ec5a8e076a4410894edbaef266cac22c88583a01df386b2d4a904e21cee092be12680df87676e59a5a44c25c423a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b3f70f6a29954e040e96c4a25a347fdde0c2431964c97f6dca8619ab136cc11939445343f9b9c24241afeb2fcc82b933c69edbfbe2d644e9412ec74a9a517a0e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e48e2bc3a6e2fa24db5c3ae72f9ed5aa69a0ffd8f612189fe07977587427b5f0cbf9d51e6a079eb679f0296a3b592fedd326f95a319b88af7bbb691b97cced67"} 08:05:21 INFO - Running post-run listener: copy_logs_to_upload_dir 08:05:21 INFO - Copying logs to upload dir... 08:05:21 INFO - mkdir: /builds/slave/test/build/upload/logs 08:05:21 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1260.971622 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-02-03 08:05:21.589106) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-03 08:05:21.592359) ========= 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.sz2ZY8pry3/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.MPbJ6b6wp5/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dab73121d5fa588891e66aa154555cf2f453ffba8b7df1de348c5afb7fb86acd0a7558bdefc404d3027c2b9c823bd8f278372f6b59d3291bbadb4af02985a27d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c59bd4674852e8fc3b5301bf115c05e0a306ec5a8e076a4410894edbaef266cac22c88583a01df386b2d4a904e21cee092be12680df87676e59a5a44c25c423a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b3f70f6a29954e040e96c4a25a347fdde0c2431964c97f6dca8619ab136cc11939445343f9b9c24241afeb2fcc82b933c69edbfbe2d644e9412ec74a9a517a0e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e48e2bc3a6e2fa24db5c3ae72f9ed5aa69a0ffd8f612189fe07977587427b5f0cbf9d51e6a079eb679f0296a3b592fedd326f95a319b88af7bbb691b97cced67"} build_url:https://queue.taskcluster.net/v1/task/Pwh3ouL5TqeqOocueeVbKQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011153 build_url: 'https://queue.taskcluster.net/v1/task/Pwh3ouL5TqeqOocueeVbKQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dab73121d5fa588891e66aa154555cf2f453ffba8b7df1de348c5afb7fb86acd0a7558bdefc404d3027c2b9c823bd8f278372f6b59d3291bbadb4af02985a27d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c59bd4674852e8fc3b5301bf115c05e0a306ec5a8e076a4410894edbaef266cac22c88583a01df386b2d4a904e21cee092be12680df87676e59a5a44c25c423a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b3f70f6a29954e040e96c4a25a347fdde0c2431964c97f6dca8619ab136cc11939445343f9b9c24241afeb2fcc82b933c69edbfbe2d644e9412ec74a9a517a0e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e48e2bc3a6e2fa24db5c3ae72f9ed5aa69a0ffd8f612189fe07977587427b5f0cbf9d51e6a079eb679f0296a3b592fedd326f95a319b88af7bbb691b97cced67"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-03 08:05:21.627913) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-03 08:05:21.628349) ========= 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.sz2ZY8pry3/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.MPbJ6b6wp5/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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.004973 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-03 08:05:21.677222) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-03 08:05:21.677582) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-03 08:05:21.677982) ========= ========= Total master_lag: 0.18 =========