builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0120 starttime: 1472301275.24 results: success (0) buildid: 20160827030436 builduid: 87000f71599a4d9ba69a1b6044284593 revision: c724ae8bb7b867b6bc65efa1049c0322457ff4b0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-27 05:34:35.243355) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-27 05:34:35.243765) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-27 05:34:35.244122) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-27 05:34:35.404482) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-27 05:34:35.404782) ========= 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.rQLV1K6GKo/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.DJVXuFIQvE/Listeners TMPDIR=/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-08-27 05:34:35-- 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: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 35.7M=0s 2016-08-27 05:34:35 (35.7 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.155847 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-27 05:34:35.595342) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-27 05:34:35.595675) ========= 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.rQLV1K6GKo/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.DJVXuFIQvE/Listeners TMPDIR=/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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.028290 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-27 05:34:35.646350) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-27 05:34:35.646894) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c724ae8bb7b867b6bc65efa1049c0322457ff4b0 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev c724ae8bb7b867b6bc65efa1049c0322457ff4b0 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rQLV1K6GKo/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.DJVXuFIQvE/Listeners TMPDIR=/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-08-27 05:34:35,726 truncating revision to first 12 chars 2016-08-27 05:34:35,726 Setting DEBUG logging. 2016-08-27 05:34:35,726 attempt 1/10 2016-08-27 05:34:35,726 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c724ae8bb7b8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-27 05:34:37,432 unpacking tar archive at: mozilla-central-c724ae8bb7b8/testing/mozharness/ program finished with exit code 0 elapsedTime=1.959326 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-27 05:34:37.634875) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-27 05:34:37.635376) ========= script_repo_revision: c724ae8bb7b867b6bc65efa1049c0322457ff4b0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-27 05:34:37.635805) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-27 05:34:37.636126) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-27 05:34:37.655573) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-08-27 05:34:37.656283) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rQLV1K6GKo/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.DJVXuFIQvE/Listeners TMPDIR=/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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 05:34:37 INFO - MultiFileLogger online at 20160827 05:34:37 in /builds/slave/test 05:34:37 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:34:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:34:37 INFO - {'append_to_log': False, 05:34:37 INFO - 'base_work_dir': '/builds/slave/test', 05:34:37 INFO - 'blob_upload_branch': 'Firefox', 05:34:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:34:37 INFO - 'branch': 'Firefox', 05:34:37 INFO - 'buildbot_json_path': 'buildprops.json', 05:34:37 INFO - 'config_files': ('talos/mac_config.py',), 05:34:37 INFO - 'default_actions': ('clobber', 05:34:37 INFO - 'read-buildbot-config', 05:34:37 INFO - 'download-and-extract', 05:34:37 INFO - 'populate-webroot', 05:34:37 INFO - 'create-virtualenv', 05:34:37 INFO - 'install', 05:34:37 INFO - 'run-tests'), 05:34:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:34:37 INFO - 'download_minidump_stackwalk': True, 05:34:37 INFO - 'download_symbols': 'ondemand', 05:34:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:34:37 INFO - 'tooltool.py': '/tools/tooltool.py', 05:34:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:34:37 INFO - '/tools/misc-python/virtualenv.py')}, 05:34:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:34:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:34:37 INFO - 'installer_path': 'installer.exe', 05:34:37 INFO - 'log_level': 'info', 05:34:37 INFO - 'log_name': 'talos', 05:34:37 INFO - 'log_to_console': True, 05:34:37 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:34:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:34:37 INFO - 'opt_config_files': (), 05:34:37 INFO - 'pip_index': False, 05:34:37 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:34:37 INFO - 'cmd': ('bash', 05:34:37 INFO - '-c', 05:34:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:34:37 INFO - 'enabled': True, 05:34:37 INFO - 'halt_on_failure': False, 05:34:37 INFO - 'name': 'check_screen_resolution'},), 05:34:37 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:34:37 INFO - 'cmd': ('bash', 05:34:37 INFO - '-c', 05:34:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:34:37 INFO - 'enabled': True, 05:34:37 INFO - 'halt_on_failure': False, 05:34:37 INFO - 'name': 'check_screen_resolution'},), 05:34:37 INFO - 'run_cmd_checks_enabled': True, 05:34:37 INFO - 'sps_profile': False, 05:34:37 INFO - 'sps_profile_interval': 0, 05:34:37 INFO - 'suite': 'dromaeojs', 05:34:37 INFO - 'system_bits': '32', 05:34:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:34:37 INFO - 'title': 't-yosemite-r7-0120', 05:34:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:34:37 INFO - 'use_talos_json': True, 05:34:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:34:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:34:37 INFO - 'work_dir': 'build'} 05:34:37 INFO - [mozharness: 2016-08-27 12:34:37.859368Z] Running clobber step. 05:34:37 INFO - Running pre-action listener: _resource_record_pre_action 05:34:37 INFO - Running main action method: clobber 05:34:37 INFO - rmtree: /builds/slave/test/build 05:34:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:34:39 INFO - Running post-action listener: _resource_record_post_action 05:34:39 INFO - [mozharness: 2016-08-27 12:34:39.491827Z] Finished clobber step (success) 05:34:39 INFO - [mozharness: 2016-08-27 12:34:39.492055Z] Running read-buildbot-config step. 05:34:39 INFO - Running pre-action listener: _resource_record_pre_action 05:34:39 INFO - Running main action method: read_buildbot_config 05:34:39 INFO - Using buildbot properties: 05:34:39 INFO - { 05:34:39 INFO - "project": "", 05:34:39 INFO - "product": "firefox", 05:34:39 INFO - "script_repo_revision": "production", 05:34:39 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 05:34:39 INFO - "repository": "", 05:34:39 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 05:34:39 INFO - "buildid": "20160827030436", 05:34:39 INFO - "stage_platform": "macosx64", 05:34:39 INFO - "basedir": "/builds/slave/test", 05:34:39 INFO - "nightly_build": "True", 05:34:39 INFO - "buildnumber": 77, 05:34:39 INFO - "slavename": "t-yosemite-r7-0120", 05:34:39 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 05:34:39 INFO - "platform": "yosemite_r7", 05:34:39 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:34:39 INFO - "branch": "mozilla-central", 05:34:39 INFO - "repo_path": "mozilla-central", 05:34:39 INFO - "revision": "c724ae8bb7b867b6bc65efa1049c0322457ff4b0", 05:34:39 INFO - "pgo_build": "False", 05:34:39 INFO - "builduid": "87000f71599a4d9ba69a1b6044284593", 05:34:39 INFO - "slavebuilddir": "test" 05:34:39 INFO - } 05:34:39 INFO - Found installer url https://queue.taskcluster.net/v1/task/KXenkkpHSBm7k-GoCRm1bA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 05:34:39 INFO - Running post-action listener: _resource_record_post_action 05:34:39 INFO - [mozharness: 2016-08-27 12:34:39.498020Z] Finished read-buildbot-config step (success) 05:34:39 INFO - [mozharness: 2016-08-27 12:34:39.498177Z] Running download-and-extract step. 05:34:39 INFO - Running pre-action listener: _resource_record_pre_action 05:34:39 INFO - Running main action method: download_and_extract 05:34:39 INFO - mkdir: /builds/slave/test/build/tests 05:34:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:34:39 INFO - trying https://queue.taskcluster.net/v1/task/KXenkkpHSBm7k-GoCRm1bA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 05:34:39 INFO - Downloading https://queue.taskcluster.net/v1/task/KXenkkpHSBm7k-GoCRm1bA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 05:34:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KXenkkpHSBm7k-GoCRm1bA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 05:34:41 INFO - Downloaded 1199 bytes. 05:34:41 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 05:34:41 INFO - Using the following test package requirements: 05:34:41 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 05:34:41 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:34:41 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 05:34:41 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:34:41 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 05:34:41 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:34:41 INFO - u'jsshell-mac.zip'], 05:34:41 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:34:41 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 05:34:41 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 05:34:41 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:34:41 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 05:34:41 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:34:41 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 05:34:41 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:34:41 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 05:34:41 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:34:41 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 05:34:41 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:34:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:34:41 INFO - trying https://queue.taskcluster.net/v1/task/KXenkkpHSBm7k-GoCRm1bA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 05:34:41 INFO - Downloading https://queue.taskcluster.net/v1/task/KXenkkpHSBm7k-GoCRm1bA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 05:34:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KXenkkpHSBm7k-GoCRm1bA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:34:41 INFO - Downloaded 16369484 bytes. 05:34:41 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 05:34:49 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:34:49 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')]} 05:34:49 INFO - trying https://queue.taskcluster.net/v1/task/KXenkkpHSBm7k-GoCRm1bA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 05:34:49 INFO - Downloading https://queue.taskcluster.net/v1/task/KXenkkpHSBm7k-GoCRm1bA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 05:34:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KXenkkpHSBm7k-GoCRm1bA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:34:49 INFO - Downloaded 16369484 bytes. 05:34:49 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 05:34:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:34:56 INFO - trying https://queue.taskcluster.net/v1/task/KXenkkpHSBm7k-GoCRm1bA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 05:34:56 INFO - Downloading https://queue.taskcluster.net/v1/task/KXenkkpHSBm7k-GoCRm1bA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip 05:34:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KXenkkpHSBm7k-GoCRm1bA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:34:58 INFO - Downloaded 14413998 bytes. 05:34:58 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 05:34:58 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')]} 05:34:58 INFO - trying https://queue.taskcluster.net/v1/task/KXenkkpHSBm7k-GoCRm1bA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:34:58 INFO - Downloading https://queue.taskcluster.net/v1/task/KXenkkpHSBm7k-GoCRm1bA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 05:34:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KXenkkpHSBm7k-GoCRm1bA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 05:35:02 INFO - Downloaded 111685050 bytes. 05:35:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KXenkkpHSBm7k-GoCRm1bA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:35:02 INFO - mkdir: /builds/slave/test/properties 05:35:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:35:02 INFO - Writing to file /builds/slave/test/properties/build_url 05:35:02 INFO - Contents: 05:35:02 INFO - build_url:https://queue.taskcluster.net/v1/task/KXenkkpHSBm7k-GoCRm1bA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:35:02 INFO - Running post-action listener: _resource_record_post_action 05:35:02 INFO - Running post-action listener: set_extra_try_arguments 05:35:02 INFO - [mozharness: 2016-08-27 12:35:02.872311Z] Finished download-and-extract step (success) 05:35:02 INFO - [mozharness: 2016-08-27 12:35:02.872436Z] Running populate-webroot step. 05:35:02 INFO - Running pre-action listener: _resource_record_pre_action 05:35:02 INFO - Running main action method: populate_webroot 05:35:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:35:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:35:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:35:02 INFO - '--tppagecycles', 05:35:02 INFO - '7'], 05:35:02 INFO - 'tests': ['tsvgm']}}, 05:35:02 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:35:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:35:02 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:35:02 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:35:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:35:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:35:02 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:35:02 INFO - 'talos_options': ['--disable-e10s'], 05:35:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:35:02 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:35:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:35:02 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:35:02 INFO - 'talos_options': ['--disable-e10s'], 05:35:02 INFO - 'tests': ['damp', 'tps']}, 05:35:02 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:35:02 INFO - 'tests': ['damp', 'tps']}, 05:35:02 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:35:02 INFO - 'tests': ['dromaeo_dom']}, 05:35:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:35:02 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:35:02 INFO - 'tests': ['basic_compositor_video']}, 05:35:02 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:35:02 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:35:02 INFO - 'tests': ['a11yr', 05:35:02 INFO - 'ts_paint', 05:35:02 INFO - 'tpaint', 05:35:02 INFO - 'sessionrestore', 05:35:02 INFO - 'sessionrestore_no_auto_restore', 05:35:02 INFO - 'tabpaint']}, 05:35:02 INFO - 'other-e10s': {'tests': ['a11yr', 05:35:02 INFO - 'ts_paint', 05:35:02 INFO - 'tpaint', 05:35:02 INFO - 'sessionrestore', 05:35:02 INFO - 'sessionrestore_no_auto_restore', 05:35:02 INFO - 'tabpaint']}, 05:35:02 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:35:02 INFO - 'ts_paint', 05:35:02 INFO - 'tpaint', 05:35:02 INFO - 'sessionrestore', 05:35:02 INFO - 'sessionrestore_no_auto_restore', 05:35:02 INFO - 'tabpaint']}, 05:35:02 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:35:02 INFO - 'ts_paint', 05:35:02 INFO - 'tpaint', 05:35:02 INFO - 'sessionrestore', 05:35:02 INFO - 'sessionrestore_no_auto_restore', 05:35:02 INFO - 'tabpaint']}, 05:35:02 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:35:02 INFO - 'tests': ['a11yr', 05:35:02 INFO - 'ts_paint', 05:35:02 INFO - 'tpaint', 05:35:02 INFO - 'sessionrestore', 05:35:02 INFO - 'sessionrestore_no_auto_restore', 05:35:02 INFO - 'tabpaint']}, 05:35:02 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:35:02 INFO - 'tests': ['a11yr', 05:35:02 INFO - 'ts_paint', 05:35:02 INFO - 'tpaint', 05:35:02 INFO - 'sessionrestore', 05:35:02 INFO - 'sessionrestore_no_auto_restore', 05:35:02 INFO - 'tabpaint']}, 05:35:02 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:35:02 INFO - 'tests': ['tsvgx', 05:35:02 INFO - 'tsvgr_opacity', 05:35:02 INFO - 'tart', 05:35:02 INFO - 'tscrollx', 05:35:02 INFO - 'cart']}, 05:35:02 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:35:02 INFO - 'tsvgr_opacity', 05:35:02 INFO - 'tart', 05:35:02 INFO - 'tscrollx', 05:35:02 INFO - 'cart']}, 05:35:02 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:35:02 INFO - 'talos_options': ['--disable-e10s'], 05:35:02 INFO - 'tests': ['tp5o']}, 05:35:02 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:35:02 INFO - 'tests': ['tp5o']}, 05:35:02 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:35:02 INFO - 'talos_options': ['--disable-e10s', 05:35:02 INFO - '--xperf_path', 05:35:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:35:02 INFO - 'tests': ['tp5n']}, 05:35:02 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:35:02 INFO - 'talos_options': ['--xperf_path', 05:35:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:35:02 INFO - 'tests': ['tp5n']}}, 05:35:02 INFO - 'talos.zip': {'path': '', 05:35:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:35:02 INFO - Running post-action listener: _resource_record_post_action 05:35:02 INFO - [mozharness: 2016-08-27 12:35:02.891447Z] Finished populate-webroot step (success) 05:35:02 INFO - [mozharness: 2016-08-27 12:35:02.891591Z] Running create-virtualenv step. 05:35:02 INFO - Running pre-action listener: _resource_record_pre_action 05:35:02 INFO - Running main action method: create_virtualenv 05:35:02 INFO - Creating virtualenv /builds/slave/test/build/venv 05:35:02 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 05:35:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:35:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:35:03 INFO - Using real prefix '/tools/python27' 05:35:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:35:03 INFO - Installing distribute.............................................................................................................................................................................................done. 05:35:06 INFO - Installing pip.................done. 05:35:06 INFO - Return code: 0 05:35:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:35:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:35:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:35:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:35:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:35:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e0eba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e180618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb7956f7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e04fd30>, '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 0x10ddb4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e047b20>, '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.rQLV1K6GKo/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.DJVXuFIQvE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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 05:35:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:35:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:35:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rQLV1K6GKo/Render', 05:35:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:35:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:35:06 INFO - 'HOME': '/Users/cltbld', 05:35:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:35:06 INFO - 'LOGNAME': 'cltbld', 05:35:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:35:06 INFO - 'MOZ_NO_REMOTE': '1', 05:35:06 INFO - 'NO_EM_RESTART': '1', 05:35:06 INFO - 'PAGER': '/bin/cat', 05:35:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:35:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:35:06 INFO - 'PWD': '/builds/slave/test', 05:35:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:35:06 INFO - 'SHELL': '/bin/bash', 05:35:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DJVXuFIQvE/Listeners', 05:35:06 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 05:35:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:35:06 INFO - 'USER': 'cltbld', 05:35:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:35:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:35:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:35:06 INFO - 'XPC_FLAGS': '0x0', 05:35:06 INFO - 'XPC_SERVICE_NAME': '0', 05:35:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:35:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:35:06 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 05:35:06 INFO - Cleaning up... 05:35:06 INFO - Return code: 0 05:35:06 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:35:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:35:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:35:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:35:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:35:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e0eba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e180618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb7956f7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e04fd30>, '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 0x10ddb4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e047b20>, '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.rQLV1K6GKo/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.DJVXuFIQvE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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 05:35:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:35:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:35:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rQLV1K6GKo/Render', 05:35:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:35:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:35:06 INFO - 'HOME': '/Users/cltbld', 05:35:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:35:06 INFO - 'LOGNAME': 'cltbld', 05:35:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:35:06 INFO - 'MOZ_NO_REMOTE': '1', 05:35:06 INFO - 'NO_EM_RESTART': '1', 05:35:06 INFO - 'PAGER': '/bin/cat', 05:35:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:35:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:35:06 INFO - 'PWD': '/builds/slave/test', 05:35:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:35:06 INFO - 'SHELL': '/bin/bash', 05:35:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DJVXuFIQvE/Listeners', 05:35:06 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 05:35:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:35:06 INFO - 'USER': 'cltbld', 05:35:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:35:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:35:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:35:06 INFO - 'XPC_FLAGS': '0x0', 05:35:06 INFO - 'XPC_SERVICE_NAME': '0', 05:35:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:35:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:35:07 INFO - Downloading/unpacking psutil>=3.1.1 05:35:07 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 05:35:07 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 05:35:07 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 05:35:07 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 05:35:07 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 05:35:07 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 05:35:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:35:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:35:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:35:11 INFO - Installing collected packages: psutil 05:35:11 INFO - Running setup.py install for psutil 05:35:11 INFO - building 'psutil._psutil_osx' extension 05:35:11 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 05:35:11 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 05:35:11 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 05:35:11 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 05:35:12 INFO - building 'psutil._psutil_posix' extension 05:35:12 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 05:35:12 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:35:12 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:35:12 INFO - ^ 05:35:12 INFO - 1 warning generated. 05:35:12 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 05:35:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:35:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:35:12 INFO - Successfully installed psutil 05:35:12 INFO - Cleaning up... 05:35:12 INFO - Return code: 0 05:35:12 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:35:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:35:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:35:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:35:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:35:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e0eba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e180618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb7956f7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e04fd30>, '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 0x10ddb4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e047b20>, '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.rQLV1K6GKo/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.DJVXuFIQvE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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 05:35:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:35:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:35:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rQLV1K6GKo/Render', 05:35:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:35:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:35:12 INFO - 'HOME': '/Users/cltbld', 05:35:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:35:12 INFO - 'LOGNAME': 'cltbld', 05:35:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:35:12 INFO - 'MOZ_NO_REMOTE': '1', 05:35:12 INFO - 'NO_EM_RESTART': '1', 05:35:12 INFO - 'PAGER': '/bin/cat', 05:35:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:35:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:35:12 INFO - 'PWD': '/builds/slave/test', 05:35:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:35:12 INFO - 'SHELL': '/bin/bash', 05:35:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DJVXuFIQvE/Listeners', 05:35:12 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 05:35:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:35:12 INFO - 'USER': 'cltbld', 05:35:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:35:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:35:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:35:12 INFO - 'XPC_FLAGS': '0x0', 05:35:12 INFO - 'XPC_SERVICE_NAME': '0', 05:35:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:35:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:35:12 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:35:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:35:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:35:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:35:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:35:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:35:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:35:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:35:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:35:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:35:15 INFO - Installing collected packages: mozsystemmonitor 05:35:15 INFO - Running setup.py install for mozsystemmonitor 05:35:15 INFO - Successfully installed mozsystemmonitor 05:35:15 INFO - Cleaning up... 05:35:15 INFO - Return code: 0 05:35:15 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:35: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')]} 05:35:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:35:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35: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')]} 05:35:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:35:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e0eba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e180618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb7956f7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e04fd30>, '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 0x10ddb4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e047b20>, '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.rQLV1K6GKo/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.DJVXuFIQvE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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 05:35:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:35:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:35:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rQLV1K6GKo/Render', 05:35:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:35:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:35:15 INFO - 'HOME': '/Users/cltbld', 05:35:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:35:15 INFO - 'LOGNAME': 'cltbld', 05:35:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:35:15 INFO - 'MOZ_NO_REMOTE': '1', 05:35:15 INFO - 'NO_EM_RESTART': '1', 05:35:15 INFO - 'PAGER': '/bin/cat', 05:35:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:35:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:35:15 INFO - 'PWD': '/builds/slave/test', 05:35:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:35:15 INFO - 'SHELL': '/bin/bash', 05:35:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DJVXuFIQvE/Listeners', 05:35:15 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 05:35:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:35:15 INFO - 'USER': 'cltbld', 05:35:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:35:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:35:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:35:15 INFO - 'XPC_FLAGS': '0x0', 05:35:15 INFO - 'XPC_SERVICE_NAME': '0', 05:35:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:35:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:35:15 INFO - Downloading/unpacking jsonschema==2.5.1 05:35:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:35:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:35:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:35:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:35:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:35:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:35:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:35:18 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:35:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:35:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:35:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:35:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:35:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:35:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:35:18 INFO - Downloading functools32-3.2.3-2.tar.gz 05:35:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:35:18 INFO - warning: no files found matching '*.txt' 05:35:18 INFO - no previously-included directories found matching 'build' 05:35:18 INFO - no previously-included directories found matching 'dist' 05:35:18 INFO - no previously-included directories found matching '.git*' 05:35:18 INFO - Installing collected packages: jsonschema, functools32 05:35:18 INFO - Running setup.py install for functools32 05:35:18 INFO - warning: no files found matching '*.txt' 05:35:18 INFO - no previously-included directories found matching 'build' 05:35:18 INFO - no previously-included directories found matching 'dist' 05:35:18 INFO - no previously-included directories found matching '.git*' 05:35:18 INFO - Successfully installed jsonschema functools32 05:35:18 INFO - Cleaning up... 05:35:18 INFO - Return code: 0 05:35:18 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:35:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:35:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:35:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:35:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:35:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e0eba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e180618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb7956f7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e04fd30>, '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 0x10ddb4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e047b20>, '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.rQLV1K6GKo/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.DJVXuFIQvE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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 05:35:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:35:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:35:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rQLV1K6GKo/Render', 05:35:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:35:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:35:18 INFO - 'HOME': '/Users/cltbld', 05:35:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:35:18 INFO - 'LOGNAME': 'cltbld', 05:35:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:35:18 INFO - 'MOZ_NO_REMOTE': '1', 05:35:18 INFO - 'NO_EM_RESTART': '1', 05:35:18 INFO - 'PAGER': '/bin/cat', 05:35:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:35:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:35:18 INFO - 'PWD': '/builds/slave/test', 05:35:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:35:18 INFO - 'SHELL': '/bin/bash', 05:35:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DJVXuFIQvE/Listeners', 05:35:18 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 05:35:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:35:18 INFO - 'USER': 'cltbld', 05:35:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:35:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:35:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:35:18 INFO - 'XPC_FLAGS': '0x0', 05:35:18 INFO - 'XPC_SERVICE_NAME': '0', 05:35:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:35:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:35:19 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:35:19 INFO - Cleaning up... 05:35:19 INFO - Return code: 0 05:35:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:35:19 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')]} 05:35:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:35:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:19 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')]} 05:35:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:35:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:19 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 0x10e0eba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e180618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb7956f7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e04fd30>, '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 0x10ddb4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e047b20>, '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.rQLV1K6GKo/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.DJVXuFIQvE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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 05:35:19 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 05:35:19 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 05:35:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rQLV1K6GKo/Render', 05:35:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:35:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:35:19 INFO - 'HOME': '/Users/cltbld', 05:35:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:35:19 INFO - 'LOGNAME': 'cltbld', 05:35:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:35:19 INFO - 'MOZ_NO_REMOTE': '1', 05:35:19 INFO - 'NO_EM_RESTART': '1', 05:35:19 INFO - 'PAGER': '/bin/cat', 05:35:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:35:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:35:19 INFO - 'PWD': '/builds/slave/test', 05:35:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:35:19 INFO - 'SHELL': '/bin/bash', 05:35:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DJVXuFIQvE/Listeners', 05:35:19 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 05:35:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:35:19 INFO - 'USER': 'cltbld', 05:35:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:35:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:35:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:35:19 INFO - 'XPC_FLAGS': '0x0', 05:35:19 INFO - 'XPC_SERVICE_NAME': '0', 05:35:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:35:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:35:19 INFO - Downloading/unpacking blobuploader==1.2.4 05:35:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:35:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:35:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:35:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:35:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:35:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:35:21 INFO - Downloading blobuploader-1.2.4.tar.gz 05:35:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:35:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:35:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:35:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:35:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:35:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:35:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:35:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:35:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:35:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:35:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:35:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:35:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:35:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:35:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:35:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:35:22 INFO - Downloading docopt-0.6.1.tar.gz 05:35:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:35:23 INFO - Installing collected packages: blobuploader, requests, docopt 05:35:23 INFO - Running setup.py install for blobuploader 05:35:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:35:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:35:23 INFO - Running setup.py install for requests 05:35:23 INFO - Running setup.py install for docopt 05:35:23 INFO - Successfully installed blobuploader requests docopt 05:35:23 INFO - Cleaning up... 05:35:24 INFO - Return code: 0 05:35:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:35:24 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')]} 05:35:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:35:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:24 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')]} 05:35:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:35:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:24 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 0x10e0eba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e180618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb7956f7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e04fd30>, '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 0x10ddb4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e047b20>, '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.rQLV1K6GKo/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.DJVXuFIQvE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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 05:35:24 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 05:35:24 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 05:35:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rQLV1K6GKo/Render', 05:35:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:35:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:35:24 INFO - 'HOME': '/Users/cltbld', 05:35:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:35:24 INFO - 'LOGNAME': 'cltbld', 05:35:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:35:24 INFO - 'MOZ_NO_REMOTE': '1', 05:35:24 INFO - 'NO_EM_RESTART': '1', 05:35:24 INFO - 'PAGER': '/bin/cat', 05:35:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:35:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:35:24 INFO - 'PWD': '/builds/slave/test', 05:35:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:35:24 INFO - 'SHELL': '/bin/bash', 05:35:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DJVXuFIQvE/Listeners', 05:35:24 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 05:35:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:35:24 INFO - 'USER': 'cltbld', 05:35:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:35:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:35:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:35:24 INFO - 'XPC_FLAGS': '0x0', 05:35:24 INFO - 'XPC_SERVICE_NAME': '0', 05:35:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:35:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:35:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:35:24 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-vWFTGK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:35:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:35:24 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-CK5L7a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:35:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:35:24 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-r2Ncrk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:35:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:35:24 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-CUPXXh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:35:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:35:24 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-ZlO3bX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:35:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:35:24 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-Ax0gYh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:35:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:35:24 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-aD9LpR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:35:25 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-NmoW42-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:35:25 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-H0aMlU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:35:25 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-6o29L1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:35:25 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-pyHHoN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:35:25 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-YQ38On-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:35:25 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-Dntx4m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:35:25 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-hr8UnQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:35:25 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-yYhWBc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:35:25 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-eWo66F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:35:25 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-KEpHxQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:35:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:35:26 INFO - Running setup.py install for manifestparser 05:35:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:35:26 INFO - Running setup.py install for mozcrash 05:35:26 INFO - Running setup.py install for mozdebug 05:35:26 INFO - Running setup.py install for mozdevice 05:35:26 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:35:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:35:26 INFO - Running setup.py install for mozfile 05:35:26 INFO - Running setup.py install for mozhttpd 05:35:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:35:26 INFO - Running setup.py install for mozinfo 05:35:27 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:35:27 INFO - Running setup.py install for mozInstall 05:35:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:35:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:35:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:35:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:35:27 INFO - Running setup.py install for mozleak 05:35:27 INFO - Running setup.py install for mozlog 05:35:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:35:27 INFO - Running setup.py install for moznetwork 05:35:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:35:27 INFO - Running setup.py install for mozprocess 05:35:27 INFO - Running setup.py install for mozprofile 05:35:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:35:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:35:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:35:28 INFO - Running setup.py install for mozrunner 05:35:28 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:35:28 INFO - Running setup.py install for mozscreenshot 05:35:28 INFO - Running setup.py install for moztest 05:35:28 INFO - Running setup.py install for mozversion 05:35:28 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:35:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:35:28 INFO - Cleaning up... 05:35:28 INFO - Return code: 0 05:35:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:35:28 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')]} 05:35:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:35:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:28 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')]} 05:35:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:35:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:28 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 0x10e0eba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e180618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb7956f7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e04fd30>, '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 0x10ddb4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e047b20>, '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.rQLV1K6GKo/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.DJVXuFIQvE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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 05:35:28 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 05:35:28 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 05:35:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rQLV1K6GKo/Render', 05:35:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:35:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:35:28 INFO - 'HOME': '/Users/cltbld', 05:35:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:35:28 INFO - 'LOGNAME': 'cltbld', 05:35:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:35:28 INFO - 'MOZ_NO_REMOTE': '1', 05:35:28 INFO - 'NO_EM_RESTART': '1', 05:35:28 INFO - 'PAGER': '/bin/cat', 05:35:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:35:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:35:28 INFO - 'PWD': '/builds/slave/test', 05:35:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:35:28 INFO - 'SHELL': '/bin/bash', 05:35:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DJVXuFIQvE/Listeners', 05:35:28 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 05:35:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:35:28 INFO - 'USER': 'cltbld', 05:35:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:35:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:35:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:35:28 INFO - 'XPC_FLAGS': '0x0', 05:35:28 INFO - 'XPC_SERVICE_NAME': '0', 05:35:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:35:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:35:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:35:28 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-zvgmuB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:35:29 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)) 05:35:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:35:29 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-UTFLmC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:35:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 05:35:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:35:29 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-Q4DNfv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:35:29 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)) 05:35:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:35:29 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-HUsRCT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:35:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:35:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:35:29 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-4FLR61-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:35:29 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)) 05:35:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:35:29 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-V3qA6q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:35:29 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)) 05:35:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:35:29 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-9qFIwe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:35:29 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)) 05:35:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:35:29 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-dcZpMR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:35:29 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)) 05:35:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:35:29 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-kSEU7R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:35:29 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)) 05:35:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:35:29 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-D8h91a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:35:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 05:35:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:35:29 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-RUyaP5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:35:30 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)) 05:35:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:35:30 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-PhRXZF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:35:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:35:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:35:30 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-oAzPVB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:35:30 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)) 05:35:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:35:30 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-LwtJLc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:35:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 05:35:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:35:30 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-_d49r1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:35:30 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)) 05:35:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:35:30 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-NeVSd4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:35:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:35:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:35:30 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-OeUOue-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:35:30 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)) 05:35:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:35:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:35:30 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)) 05:35:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:35:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:35:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:35:30 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 05:35:30 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 05:35:30 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 05:35:30 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 05:35:30 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 05:35:30 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 05:35:33 INFO - Downloading blessings-1.6.tar.gz 05:35:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:35:33 INFO - Installing collected packages: blessings 05:35:33 INFO - Running setup.py install for blessings 05:35:33 INFO - Successfully installed blessings 05:35:33 INFO - Cleaning up... 05:35:33 INFO - Return code: 0 05:35:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:35:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:35:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:35:33 INFO - Reading from file tmpfile_stdout 05:35:33 INFO - Current package versions: 05:35:33 INFO - blessings == 1.6 05:35:33 INFO - blobuploader == 1.2.4 05:35:33 INFO - docopt == 0.6.1 05:35:33 INFO - functools32 == 3.2.3-2 05:35:33 INFO - jsonschema == 2.5.1 05:35:33 INFO - manifestparser == 1.1 05:35:33 INFO - mozInstall == 1.12 05:35:33 INFO - mozcrash == 0.17 05:35:33 INFO - mozdebug == 0.1 05:35:33 INFO - mozdevice == 0.48 05:35:33 INFO - mozfile == 1.2 05:35:33 INFO - mozhttpd == 0.7 05:35:33 INFO - mozinfo == 0.9 05:35:33 INFO - mozleak == 0.1 05:35:33 INFO - mozlog == 3.3 05:35:33 INFO - moznetwork == 0.27 05:35:33 INFO - mozprocess == 0.23 05:35:33 INFO - mozprofile == 0.28 05:35:33 INFO - mozrunner == 6.12 05:35:33 INFO - mozscreenshot == 0.1 05:35:33 INFO - mozsystemmonitor == 0.3 05:35:33 INFO - moztest == 0.7 05:35:33 INFO - mozversion == 1.4 05:35:33 INFO - psutil == 3.1.1 05:35:33 INFO - requests == 1.2.3 05:35:33 INFO - wsgiref == 0.1.2 05:35:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:35:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:35:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:35:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:35:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:35:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:33 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 0x10e0eba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e180618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb7956f7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e04fd30>, '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 0x10ddb4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e047b20>, '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.rQLV1K6GKo/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.DJVXuFIQvE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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 05:35:33 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 05:35:33 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 05:35:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rQLV1K6GKo/Render', 05:35:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:35:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:35:33 INFO - 'HOME': '/Users/cltbld', 05:35:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:35:33 INFO - 'LOGNAME': 'cltbld', 05:35:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:35:33 INFO - 'MOZ_NO_REMOTE': '1', 05:35:33 INFO - 'NO_EM_RESTART': '1', 05:35:33 INFO - 'PAGER': '/bin/cat', 05:35:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:35:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:35:33 INFO - 'PWD': '/builds/slave/test', 05:35:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:35:33 INFO - 'SHELL': '/bin/bash', 05:35:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DJVXuFIQvE/Listeners', 05:35:33 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 05:35:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:35:33 INFO - 'USER': 'cltbld', 05:35:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:35:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:35:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:35:33 INFO - 'XPC_FLAGS': '0x0', 05:35:33 INFO - 'XPC_SERVICE_NAME': '0', 05:35:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:35:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:35:34 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)) 05:35:34 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)) 05:35:34 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)) 05:35:34 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)) 05:35:34 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)) 05:35:34 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)) 05:35:34 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)) 05:35:34 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)) 05:35:34 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)) 05:35:34 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)) 05:35:34 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)) 05:35:34 INFO - Cleaning up... 05:35:34 INFO - Return code: 0 05:35:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:35:34 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')]} 05:35:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:35:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:34 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')]} 05:35:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:35:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:34 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 0x10e0eba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e180618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb7956f7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e04fd30>, '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 0x10ddb4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e047b20>, '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.rQLV1K6GKo/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.DJVXuFIQvE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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 05:35:34 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 05:35:34 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 05:35:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rQLV1K6GKo/Render', 05:35:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:35:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:35:34 INFO - 'HOME': '/Users/cltbld', 05:35:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:35:34 INFO - 'LOGNAME': 'cltbld', 05:35:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:35:34 INFO - 'MOZ_NO_REMOTE': '1', 05:35:34 INFO - 'NO_EM_RESTART': '1', 05:35:34 INFO - 'PAGER': '/bin/cat', 05:35:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:35:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:35:34 INFO - 'PWD': '/builds/slave/test', 05:35:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:35:34 INFO - 'SHELL': '/bin/bash', 05:35:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DJVXuFIQvE/Listeners', 05:35:34 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 05:35:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:35:34 INFO - 'USER': 'cltbld', 05:35:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:35:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:35:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:35:34 INFO - 'XPC_FLAGS': '0x0', 05:35:34 INFO - 'XPC_SERVICE_NAME': '0', 05:35:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:35:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:35:34 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:35:34 INFO - Cleaning up... 05:35:34 INFO - Return code: 0 05:35:34 INFO - Running post-action listener: _resource_record_post_action 05:35:34 INFO - Running post-action listener: _start_resource_monitoring 05:35:34 INFO - Starting resource monitoring. 05:35:34 INFO - [mozharness: 2016-08-27 12:35:34.484875Z] Finished create-virtualenv step (success) 05:35:34 INFO - [mozharness: 2016-08-27 12:35:34.485949Z] Running install step. 05:35:34 INFO - Running pre-action listener: _resource_record_pre_action 05:35:34 INFO - Running main action method: install 05:35:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:35:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:35:34 INFO - Reading from file tmpfile_stdout 05:35:34 INFO - Detecting whether we're running mozinstall >=1.0... 05:35:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:35:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:35:34 INFO - Reading from file tmpfile_stdout 05:35:34 INFO - Output received: 05:35:34 INFO - Usage: mozinstall [options] installer 05:35:34 INFO - Options: 05:35:34 INFO - -h, --help show this help message and exit 05:35:34 INFO - -d DEST, --destination=DEST 05:35:34 INFO - Directory to install application into. [default: 05:35:34 INFO - "/builds/slave/test"] 05:35:34 INFO - --app=APP Application being installed. [default: firefox] 05:35:34 INFO - mkdir: /builds/slave/test/build/application 05:35:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:35:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:36:08 INFO - Reading from file tmpfile_stdout 05:36:08 INFO - Output received: 05:36:08 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:36:08 INFO - Running post-action listener: _resource_record_post_action 05:36:08 INFO - [mozharness: 2016-08-27 12:36:08.973739Z] Finished install step (success) 05:36:08 INFO - [mozharness: 2016-08-27 12:36:08.973881Z] Running run-tests step. 05:36:08 INFO - Running pre-action listener: _resource_record_pre_action 05:36:08 INFO - Running main action method: run_tests 05:36:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:36:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:36:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:36:08 INFO - 2016-08-27 05:36:08.988 screenresolution[1642:8067] starting screenresolution argv=screenresolution get 05:36:08 INFO - 2016-08-27 05:36:08.991 screenresolution[1642:8067] Display 0: 1600x1200x32@60 05:36:08 INFO - 2016-08-27 05:36:08.997 screenresolution[1643:8069] starting screenresolution argv=screenresolution list 05:36:09 INFO - Available Modes on Display 0 05:36:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:36:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:36:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:36:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:36:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:36:09 INFO - Intel Iris: 05:36:09 INFO - Chipset Model: Intel Iris 05:36:09 INFO - Type: GPU 05:36:09 INFO - Bus: Built-In 05:36:09 INFO - VRAM (Dynamic, Max): 1536 MB 05:36:09 INFO - Vendor: Intel (0x8086) 05:36:09 INFO - Device ID: 0x0a2e 05:36:09 INFO - Revision ID: 0x0009 05:36:09 INFO - Displays: 05:36:09 INFO - Display: 05:36:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:36:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:36:09 INFO - Display Serial Number: 1600x1200 60 05:36:09 INFO - Main Display: Yes 05:36:09 INFO - Mirror: Off 05:36:09 INFO - Online: Yes 05:36:09 INFO - Rotation: Supported 05:36:09 INFO - Return code: 0 05:36:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:36:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:36:09 INFO - Python 2.7.3 05:36:09 INFO - Return code: 0 05:36:09 INFO - grabbing minidump binary from tooltool 05:36: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')]} 05:36:09 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 0x10e04fd30>, '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 0x10ddb4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e047b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:36:09 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 05:36:09 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 05:36:09 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 05:36:09 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:36:09 INFO - Return code: 0 05:36:09 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:36:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:36:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:36:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:36:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:36:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KXenkkpHSBm7k-GoCRm1bA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0120', '--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 05:36:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/KXenkkpHSBm7k-GoCRm1bA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0120 --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 05:36:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rQLV1K6GKo/Render', 05:36:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:36:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:36:09 INFO - 'HOME': '/Users/cltbld', 05:36:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:36:09 INFO - 'LOGNAME': 'cltbld', 05:36:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:36:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:36:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:36:09 INFO - 'MOZ_NO_REMOTE': '1', 05:36:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:36:09 INFO - 'NO_EM_RESTART': '1', 05:36:09 INFO - 'PAGER': '/bin/cat', 05:36:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:36:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:36:09 INFO - 'PWD': '/builds/slave/test', 05:36:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:36:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:36:09 INFO - 'SHELL': '/bin/bash', 05:36:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DJVXuFIQvE/Listeners', 05:36:09 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 05:36:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:36:09 INFO - 'USER': 'cltbld', 05:36:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:36:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:36:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:36:09 INFO - 'XPC_FLAGS': '0x0', 05:36:09 INFO - 'XPC_SERVICE_NAME': '0', 05:36:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:36:09 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KXenkkpHSBm7k-GoCRm1bA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0120', '--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 05:36:09 INFO - mozversion application_buildid: 20160827030436 05:36:09 INFO - mozversion application_changeset: c724ae8bb7b867b6bc65efa1049c0322457ff4b0 05:36:09 INFO - mozversion application_display_name: Nightly 05:36:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:36:09 INFO - mozversion application_name: Firefox 05:36:09 INFO - mozversion application_remotingname: firefox 05:36:09 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:36:09 INFO - mozversion application_vendor: Mozilla 05:36:09 INFO - mozversion application_version: 51.0a1 05:36:09 INFO - mozversion platform_buildid: 20160827030436 05:36:09 INFO - mozversion platform_changeset: c724ae8bb7b867b6bc65efa1049c0322457ff4b0 05:36:09 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:36:09 INFO - mozversion platform_version: 51.0a1 05:36:09 INFO - using testdate: 1472301369 05:36:09 INFO - actual date: 1472301369 05:36:09 INFO - starting webserver on 'localhost:49270' 05:36:09 INFO - SUITE-START | Running 2 tests 05:36:09 INFO - TEST-START | dromaeo_css 05:36:09 INFO - operating with platform_type : mac_ 05:36:09 INFO - Initialising browser for dromaeo_css test... 05:36:09 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/tmpRiCABG/profile http://localhost:49270/getInfo.html) 05:36:12 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 05:36:12 INFO - PROCESS | 1653 | colorDepth:24 05:36:12 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 05:36:12 INFO - PROCESS | 1653 | __metrics 05:36:12 INFO - PROCESS | 1653 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:36:12 INFO - PROCESS | 1653 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:36:12 INFO - TEST-INFO | 1653: exit 0 05:36:12 INFO - Browser initialized. 05:36:12 INFO - Running cycle 1/1 for dromaeo_css test... 05:36:12 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/tmpRiCABG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:36:24 INFO - PROCESS | 1657 | RSS: Main: 233254912 05:36:24 INFO - PROCESS | 1657 | 05:37:16 INFO - PROCESS | 1657 | 1472301436237 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:39:27 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html) 05:39:29 INFO - PROCESS | 1657 | RSS: Main: 360423424 05:39:29 INFO - PROCESS | 1657 | 05:42:32 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html) 05:42:32 INFO - PROCESS | 1657 | RSS: Main: 371458048 05:42:32 INFO - PROCESS | 1657 | 05:45:35 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html) 05:45:35 INFO - PROCESS | 1657 | RSS: Main: 359563264 05:45:35 INFO - PROCESS | 1657 | 05:48:37 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html) 05:48:38 INFO - PROCESS | 1657 | RSS: Main: 351498240 05:48:38 INFO - PROCESS | 1657 | 05:51:41 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html) 05:51:42 INFO - PROCESS | 1657 | RSS: Main: 331550720 05:51:42 INFO - PROCESS | 1657 | 05:54:45 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html) 05:54:46 INFO - PROCESS | 1657 | RSS: Main: 511934464 05:54:46 INFO - PROCESS | 1657 | 05:54:46 INFO - PROCESS | 1657 | __start_tp_report 05:54:46 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 05:54:46 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 05:54:46 INFO - PROCESS | 1657 | |i|pagename|runs| 05:54:46 INFO - PROCESS | 1657 | |0;dojo.html;3118.5322201669755;3270.133414645118;3303.9008829735103;3344.665533446654;3379.0876463354894;5507.779688812448;5536.114221724525;5569.248151499546;5615.803446879355;5622.297212848399;5568.49883510484;5650.74571644276;5670.829875103743;5778.59549831512;5791.333996590393;5145.665531740444;5173.146430838907;5176.50823171798;5192.948070519285;5217.139172036609;5142.305788718531;5286.973565132169;5301.5228629423345;5333.226682131089;5355.437679043684;5143.562797162245;5226.085435048889;5241.135212689902;5251.2385704072885;5272;5118.744062796845;5203.427622961472;5211.869703257411;5222.843314700565;5236.319278493809;4688.765561721934;4820.939393333461;5119.308893299394;5142.07442660321;5164.483551644843;5570.805021824217;5573.386927437979;5626.915596266059;5636.210930469738;5656.632318899299;6067.193773979152;6095.81855422887;6097.298810636789;6122.540809439265;6136.948001697505;6172.444479996777;6186.84532886677;6188.81433556994;6251.249850017982;6273.62358258506;5660.349059858169;5678.290213723326;5738.454846789548;5755.107958266475;5798.420157984252;6172.8765424691255;6210.037444196157;6226.968865155645;6227.439530442282;6250.499960003189;6032.909506357408;6038.788642397495;6042.909356359658;6049.274087109575;6064.787732429344;6022.2171117754415;6093.66484843329;6176.413240742122;6178.845528861724;6207.131001659771;6071.736927998587;6142.754289828446;6148.569600128038;6160.7535698571555;6176.752929882754;6196.411340922605;6199.783007594712;6207.410296021871;6211.3167551569295;6234.844128896723;4295.506016808107;4797.832075877328;4957.950420495821;4979.900401992012;4996.6752161110135;4302.3546468030045;4408.0522687622315;4426.911461770811;4437.866863994085;4478.48497422794;5647.407022262685;5660.490555849993;5677.120046392815;5682.460166284196;5689.943100569024;6125.203723515914;6230.096635987757;6242.188515492957;6255.56211065221;6260.123582698426;5596.188552659842;5638.210650508932;5672.234248376499;5698.971505142447;5711.400302968212;5419.132938729785;5419.945800541944;5426.131818908955;5428.9185662215095;5447.073997420448;6176.6911654418;6210.50315974721;6231.9688401557705;6240.469560087444;6248.781292654735;6146.969265153645;6169.691515424301;6178.382161783786;6225.159603453476;6266.028765541349;6201.96899015502;6234.096056071753;6234.251889773256;6235.657038862724;6243.282022567281;6020.096985452217;6116.724747386467;6121.418465245794;6138.815835524942;6138.969305153446;6103.701349646989;6125.11336343068;6130.826205142415;6219.346968568235;6231.937680623317;6015.368386319374;6028.969855150696;6041.78853740117;6041.9395806041375;6093.6039157454625;5812.18629391877;5941.494972927349;5980.671063091398;6015.157877897185;6018.6689732063405;5825.76696932118;5840.678762667924;5899.557533184942;5906.586538942232;5978.133170690138;6078.6048906820915;6100.603460775035;6107.877842443215;6108.6945652716295;6120.724567394389;5696.287964004499;5763.049096898964;5775.797847075332;5848.0350742127075;5851.063829787214 05:54:46 INFO - PROCESS | 1657 | |1;ext.html;454.49324003735836;473.420060425978;477.30790353986686;533.0857579251583;544.5453046706;31334.843325783368;39396;40708.59291407081;40762;43529;41905.7904710476;42399;42416;42464.57535424645;42466.36300455488;8803.515806630632;9763.804723905518;9953;9956.85064724026;9972.6509572165;8794.472331660088;8897.68858089967;8899.599518021672;12041;12266.81599776004;12325.93837030818;12416.627501174979;12429.875701242961;12430.564930227443;12440.315782631951;12239.143259971806;12329.383530823423;12334.938325308405;12336.259824410565;12341.444634991447;12090.576829811002;12134.96852767507;12149.149559530837;12166.878331216663;12205.389730513527;40109.598904010876;40430.79784601088;40490.59509404898;40512;40604.187916241506;3398.609159946613;3402.0644322811177;3531.382008148589;3580.820958952042;3630.8729194478065;30831.84584077088;31251.218719531968;31845.044648660347;32099.83950080258;32296.677033229604;3139.089663997461;3196.2313093516673;3239.093053944899;3240.319532898094;3263.96736032639;468343;512202;514364;517298.41350793006;524303.378483109;59052.40947590555;59510.10734838981;59666;59741.40258597445;59742.70128649329;62011;62023.6898815503;62157.02743780486;62228;62236;13695.520656777164;16095.356185752438;16325.83674163243;16341.836581634032;16363.263653135638;12727.745445091232;12790.61628151157;12799;12815.16701414405;12848.743025139445;13010.674733131558;13145.868541314656;14254.643633909029;14611.92694036523;16529.83470165307;7080.468964827659;7220.566765994057;7224.135087728146;7269.709211631582;7309.159446663595;829.8464784014856;901.8917729872458;976.2385339435252;1032.3289861589876;1040.959040959041;31249.53125703116;31745;31852.522212166372;32068.83965580204;33311;1013.3919648211014;1044.6761503933806;1061.7027232374946;1100.6367898593444;1109.916756243285;14645.414183432544;15514.767278490832;15606.9219653901;15632.296546655427;16103.51689449295;516393;529812;530883;532638;533719.3314033482;48557.27164092541;59285;59966;60093.09860352098;60094.39905600975;1367.1455340412242;1395.762720337545;1409.0348111543626;1412.7598308287822;1416.199847086393;30110.805123924845;30767.384652307275;30847.383052339275;31606.209844754067;31613.8419307902;51355.74322128515;51940;51966.96066078733;52006.73996629992;52109.7394513025;522091;525764;528880.9783315285;529334;530207;37932.81033594815;38553.80723096479;38674;42776.57223427726;43342.566574333854;46736.7663161682;46885.82785430391;47991.520084800104;48111;48520.514794853014;31057.60240789125;39065.80467097646;39510.80244598759;39584.40623390594;40481;38660.80669596747;38802.6119738799;39216.34875759569;40057;40428.59571404366;38644;38755.80622096984;38943;39055;41161;31176.844115779277;31617.683823162395;34289.08285694514;34798.652013480554;34883.65116348804;25042.37394065163;25099;25283.11509097173;25347.366315842253;25757.742422575535 05:54:46 INFO - PROCESS | 1657 | |2;jquery.html;309.7135149986263;329.8795939482089;348.1696154671109;376.92461507698465;382.7091410527998;15233.31450084746;16057.357705691773;17287.394941177045;21124.89437552814;21194.57610847782;19397.418077457663;20974.895125524352;20994.580108397822;20999.47501312468;21025.47436314089;5753.338366087891;6031.517478601723;6031.577789554733;6033.8535678221215;6044.9697751511185;5782.53739700825;6087.573869829113;6177.691115444232;6179.5674302798825;6208.441240288374;5912.379200183983;6083.969580152093;6167.62994220346;6172.4136207060255;6189.412005859459;6156.045812899008;6187.288461826903;6192.659403732793;6192.845178870519;6200.968995155041;5752.309722833245;5973.6415815050805;6030.065339872327;6037.45662890342;6053.273607167124;13312.86687133126;13547.900075488315;13555.86444135556;21112.5777484451;26215;2471.653968444419;2582.5222333868296;2619.816612837102;2792.6369571955515;2969.213158512996;10396.532156052996;10441.530131144116;12327.260364378077;14529.346179421951;15059.397624095023;1813.3200049981258;1820.6995845685447;1827.259959716317;1853.6570734414177;1885.5097674604565;118331.40834295859;125901.74098258992;127568;128055.35972320172;129604.7039529602;10950.945245273722;11446.484908179153;11471.885281147082;11504.58403735225;11684.766304673858;14509.911756618085;14527.564173074825;14542.345594448063;14554.927225363805;14596.562103136923;8089.555074470843;8345.165483451729;8391.958040209882;8505.61724722389;8538.975322961285;8229.086675419328;8233.135520770353;8290.751277461686;8451.070382257947;8510;4337.544557821447;4433.645308375322;4465.665075119314;4515.209838278288;4534.59188673021;1785.758897566205;1821.0498991633638;1869.1962456143988;1875.4654923346782;1882.1530311359943;811.0713233347901;833.3791325462569;840.4075127035426;863.4991704811194;892.683097500391;18217.18022688982;20892.268770592957;20975.056122474525;21019;21407.822569758813;947.6410896380977;965.4979410706534;980.6665733650593;990.6384169778123;1013.3767732844248;10182.949085254526;10386.480675966171;10903.945480272549;11002.724931876766;11040.613578524873;568343;591036;596871;609792;611222;100738;123655.3817230908;128426;128794.69642428584;130426.34786826195;945.8817647794026;960.6541645007792;973.1339108193713;974.6101559376306;974.7124598243677;16037.679246414773;16859.288214353594;18558.164882580586;18671.81328186701;18747.625047499245;42421;53660;53869.730651346494;55155.89688206294;55483.612909677584;73148.26851731415;76392.61803690947;76695.61652191704;80427.79358309513;83075.58462207651;7892.644830982735;7905.209479052279;8177.304929080857;8219.383546234176;8375.12061233586;11409.600663977053;12610.936945315214;12906.161099528501;13340.86659133435;13381.19712817234;10815.863399614023;11040.5031773572;11553.7111572208;11569.01663358624;11618.419079045845;9307.813843722952;10935.45322733876;10975.615853445091;11503.884961150281;11592.942035289769;11165.832512512481;11479.770404592029;11485.19603627738;11862.228955118235;11956.163068585116;7981.600919953863;8126.268635822805;8130.75607731769;8240.546769927712;8380.832383352421;8309.626066826886;9277.747504086861;9761.9023809761;10904.836427453436;10973.286736362112 05:54:46 INFO - PROCESS | 1657 | |3;mootools.html;1500.7148641758065;1511.9924400378;1711.5635512944198;1842.5393651587103;1896.3552392186655;8347.582620868956;8882.511461869595;8887.555622218895;8894.332925030625;8895.866562001558;7789;8010.879836802453;8022.358211343093;8096.55468949209;8671.132886711326;2151.4728891421623;2305.700258966332;2383.92848214553;2385.2009576791797;2391.8445301055463;2345.988270058656;2363.3382652857226;2363.8108951283943;2370.348154257584;2373.0626402570974;2373.6558199061124;2376.5246950609862;2379.6549500322444;2379.95240095198;2383.761623837611;2345.472268739537;2389.211560185152;2390.5696974544576;2392.617181251009;2396.4607963208136;2347.319277409549;2360.362702070431;2370;2370.7273663528745;2377.500724847767;8637.438566493158;8717.128287171232;8752.912470875337;8770.824583508294;8825.646974121028;5140.948590514085;5317.680939143625;5323;5323.733813309358;5325.20121981702;3456.4296891012955;3566.928661426757;3633.745637805355;3634.418493041127;3636.2727454509204;1025.3898930136556;1071.4482041748506;1086.4187659602153;1095.5617752898825;1096.9341839489657;11578.884211157865;11591.072714182761;11595.246308989974;11595.304281743038;11628;3960.34654282046;4559.430071241095;4580.106879158532;4762.095201911626;4786.042791441726;9761.974992625814;9778.706638800848;9781.364211326241;9786.461744603972;9811.705648830546;5276.788928442896;5279.9208011879855;5280.762365693553;5283.920741188886;5303.575713942875;4294.82820687169;4398.604125628709;4637.25803871379;4836.12949669063;4859.222524396079;3619.5656521217097;3814.8855534334016;3871.032241939515;3920.6079392061138;3952.091019065631;1257.798752199662;1260.2690439545042;1264.1088032936757;1273.1024627637494;1278.1083995686436;697.2887654592288;904.0778406025817;911.8829433943366;943.9292053096045;955.6702937486556;4815.494373090845;5657.349404818417;5883.029185222913;5902.822915312461;6000.969995149996;852.815033344086;861.87117818987;926.5876684875301;932.9160375566096;936.2884208001967;3829.5787463379006;3885.902852428712;3925.2345792570745;3963.2667956428154;4002.579729128458;10138.493075346203;10616.522256498476;10652.946735266429;10666.306690065121;10689.9465502672;10067.395956242648;10568;10582;10616.363018218932;10630.415327157081;7948.801279967932;8177.877331840027;8196;8202.09776924526;8208.876866847002;5775.480206781242;5843.2403787507355;5870.882582348415;5998.640081595118;6182.258129024366;9497.667581634609;10002.949985250027;10011.399316041061;10011.749706257402;10028.047335503115;10035.69892903214;10159.593616255563;10160.491975401348;10166.339187952759;10217.540210690686;7351.963240183765;7454.552726836408;7539.962300188464;7547.16981132064;7547.28300811422;8064.395170362363;8066.717664881701;8222.794430139294;8236.135205803304;8245.257926786619;7326.487145899736;7370.5946172961;7388.815279617837;7432.2567743225245;7586.393088553013;7301;7307.525010874276;7308.451866109956;7366.116066072105;7408.925910740823;6606.174228221473;6884.48366372514;6936.237013928363;6987.545809522365;7006.369426751413;4921.163402221702;4952.77712502931;4992.675476094188;5006.098902197639;5098.056859481007;4911.63162762787;4931.728754918514;5027.446980832033;5083.237514372874;5175.534201921845 05:54:46 INFO - PROCESS | 1657 | |4;prototype.html;286.19721680685666;293.43220867621153;305.3145687930596;312.9624445066592;316.20316801659817;4836.564709176174;4920.950790492094;4937.777799998999;5156.664816786906;5171.482851714826;4457.598816106558;4762.5951794097455;4824.348712923755;5070.949290507094;5083.339165908426;3263.2494526258974;3892.338302488584;3916.4125381192766;3924.921501569981;3989.5412027616762;3384.7292216622736;3747.1381582236104;3927.273454410929;3929.4105884117466;3958.960410395888;3098.311420275944;3896.2012787378662;3910.765354078764;3933.6263055009726;3942.9605703943025;3885.9611403885883;3898.4152377143514;3902.512185976753;3909.550401703812;3928.332183528807;2820.3513191965963;3396.3886500429912;3399.6600339966053;3402.0984439123654;3408.164999575095;4724.362211101492;4947.724046938412;4989.977054703777;4992.800287988476;4994.175960966436;3803.9348982285005;4275.743455392655;4387.824487020515;4400.207962566737;4423.557644235583;5336.9733151334385;5342.358916929993;5346.251524786533;5363.122906982525;5373.957895999534;2691.838489690625;3638.490611314418;3684.5946945835935;3695.4087346024776;3725.5529336479526;5571.8885622287735;5588.407303918404;5603.327600687943;5623;5632.155176723524;3132.5301204819;3152.6374466936595;3740.495033170487;3742.120601658608;3758.229562939591;3177.698118678722;3362.7982321060326;3413.5050417689295;3670.8164591770305;3794.449804778347;4137.737989913046;4403.8458653946955;4422.557744225552;4424.823007079745;4468.843590474383;4539.863804085882;5394.029074766501;5407.945450637088;5412.134058550613;5440.80302333486;1206.1014544164655;1219.231883913129;1295.8963282937532;1312.5143696832042;1317.7957416600443;803.1767438375712;821.3880658909031;828.8052307707684;880.9559522023873;908.1327332397676;4163.458750362433;4198.9370159447635;4976.626752993468;4989.525995030466;5005.224190250517;4453.955460445354;4659.6952853201155;5021.799128034838;5379.843333683277;5430.972845135749;4589.954100458953;4592.517785632527;4779.808807647655;4966.677165984272;4988.90022199554;2805.9719402806118;2863.6849946505868;2879.7120287971456;2894.884204631833;2905.418916216765;4926.901461970812;5545.918545883595;5676.432356764373;5688.687122208318;5708.20085188074;4716.3632909557455;5297.364316282071;5566.55467562594;5648.661080335193;5657.20799088128;4918.90162196754;5359.944831446116;5643.802466913638;5662.943370566324;5722.141678748221;5153.45888681698;5419.729013549386;5467.644603100785;5523.972380138234;5557.36090349615;4220.662347012293;4758.714477131245;5260.289860868734;5381.408045114956;5474.972625137008;5328.57371410294;5526.253955715927;5754.338251101177;5793.304803423616;5806.796762113306;3186.426443240239;3323.2522682396266;3779.962200377961;3946.0923987482465;3952.110775075585;4411.750007496136;4718.551737585061;5075.847724568269;5260.605454590997;5548.916766248591;4456.933146002877;5342.8931421372135;5366.490183432567;5369.731513424391;5385.980257403474;4520.4575450946095;5343.9198412024625;5363.812266570651;5395.217693434509;5432.456754324536;4748.833790817304;4982.975085124551;5224.955008998139;5261.394939581997;5302.70835104073;4496.437945256843;4744.952550474451;5377.731113444391;5393.541548968382;5397.217403476552;4393.714408563433;4672.813087476598;5259.947400526099;5281.4190439052445;5329.173978033411 05:54:46 INFO - PROCESS | 1657 | |5;yui.html;715.9606221657807;737.727040994832;745.262190431473;781.1719577248114;788.6332855222322;15726.606834829136;15972.2013899305;15999.920000399981;16052.43816466424;16061.437849675263;14310.284485775695;14424.990250682456;14437.494687685934;14443.422263109489;14962.102273863577;651.7979426377838;669.7689297192461;831.5675848558745;843.7089204224533;845.121074082953;817.0807841178674;832.0514613340796;832.950022998619;837.7612380471567;838.1953324808161;894.2845723421269;896.923761480275;898.0749827677503;899.0739538275586;899.0964081098487;698.1587187439175;859.1451505751754;887.8357503861806;891.4873947480193;895.8790563273973;657.5430076097115;658.1772784019975;659.475716805141;659.656978371249;663.7842701122155;14756.409743610242;14874.851251487455;14875.925620371938;14888.553343399715;14982.400703971827;13542.390592423364;13797.931010344884;13797.931010344884;13831.571090931708;14059.859401405958;12170.208936419105;12197.817032744517;12249.816252756127;12305.323207223599;12606.306653134074;4372.825086996516;4377.409049778271;4450.976275456663;4569.954300456986;4575.58819706228;100765.48851767229;126232;127318;128346.35826820899;130334;10180;10826.67519974402;10887.18346124028;10932.56269749201;11052.005319521282;22594.435139121324;22616.773832261795;22625.88687056587;22629;22640;7700.422468314787;7719.382449404035;7898.960505197438;7989.480683755653;8061.072976607648;4159.251334759772;4261.296886013835;4303.182395344875;4435.689501734848;4447.777611119431;3466.4107101792756;3531.8234088295994;3582.8387722552548;3587.192881601619;3595.820208989592;546.2325432766969;621.0125899819219;626.2766504687094;631.2172905597026;644.0886146103305;555.761022760217;573.7848306884919;579.1862433281246;592.4697395830793;637.581559790646;7561.243875612505;8585.31317494599;8839.056061930329;8851.247643950208;8859.601317940707;552.8838943821843;559.8012705489492;575.1774961804576;612.2622240200548;637.5186734015789;4277.91444171121;4304.569543045733;4314.525402205755;4365.170617582649;4416.667495994404;157696.21151894398;158512.20743896207;161022;161737.3826261746;163384;131867;139608;139672;141627;145037.27481362526;113883.43058284822;115207.42396287965;116196.41901790605;119888.4005579984;121385;6686.866262674622;8035.799105022421;8512.574371281287;8518.70184543538;8543.700970466003;49868;60740;62912.37087629065;62980.68509657423;63001.68499157475;88174.11825881916;91223.54388228017;92686;94508.90023044079;95016;9737.415755054719;10937.890621093688;11324.37715925632;11343.546258149912;11731.824022639497;10427.113695335678;11158.311529392895;12272.877271227173;12452.439640216393;12514.93742531318;11877.881221187678;11893.821592675944;12128.696782580506;12147.028237741137;12451.49283281852;11763.705907352385;12105.273683579013;12342.13605047638;12483.875161248634;12486.750264994831;11616.593419230625;11970.521179152736;12025.63923082309;12200.51197952072;12350.258984460961;9821.901780982385;9856;9986;10043.748906277402;10285.588576456857;8780.956095219739;9148.209796012281;10010.549525271526;10072.496375181358;10081.596736130472 05:54:46 INFO - PROCESS | 1657 | __end_tp_report 05:54:46 INFO - PROCESS | 1657 | __start_cc_report 05:54:46 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,1189 05:54:46 INFO - PROCESS | 1657 | __end_cc_report 05:54:46 INFO - PROCESS | 1657 | __startTimestamp1472302486106__endTimestamp 05:54:46 INFO - PROCESS | 1657 | 05:54:46 INFO - PROCESS | 1657 | ------- Summary: start ------- 05:54:46 INFO - PROCESS | 1657 | Number of tests: 6 05:54:46 INFO - PROCESS | 1657 | 05:54:46 INFO - PROCESS | 1657 | [#0] dojo.html Cycles:165 Average:5688.73 Median:5851.06 stddev:636.85 (10.9%) stddev-sans-first:606.04 05:54:46 INFO - PROCESS | 1657 | Values: 3118.5 3270.1 3303.9 3344.7 3379.1 5507.8 5536.1 5569.2 5615.8 5622.3 5568.5 5650.7 5670.8 5778.6 5791.3 5145.7 5173.1 5176.5 5192.9 5217.1 5142.3 5287.0 5301.5 5333.2 5355.4 5143.6 5226.1 5241.1 5251.2 5272.0 5118.7 5203.4 5211.9 5222.8 5236.3 4688.8 4820.9 5119.3 5142.1 5164.5 5570.8 5573.4 5626.9 5636.2 5656.6 6067.2 6095.8 6097.3 6122.5 6136.9 6172.4 6186.8 6188.8 6251.2 6273.6 5660.3 5678.3 5738.5 5755.1 5798.4 6172.9 6210.0 6227.0 6227.4 6250.5 6032.9 6038.8 6042.9 6049.3 6064.8 6022.2 6093.7 6176.4 6178.8 6207.1 6071.7 6142.8 6148.6 6160.8 6176.8 6196.4 6199.8 6207.4 6211.3 6234.8 4295.5 4797.8 4958.0 4979.9 4996.7 4302.4 4408.1 4426.9 4437.9 4478.5 5647.4 5660.5 5677.1 5682.5 5689.9 6125.2 6230.1 6242.2 6255.6 6260.1 5596.2 5638.2 5672.2 5699.0 5711.4 5419.1 5419.9 5426.1 5428.9 5447.1 6176.7 6210.5 6232.0 6240.5 6248.8 6147.0 6169.7 6178.4 6225.2 6266.0 6202.0 6234.1 6234.3 6235.7 6243.3 6020.1 6116.7 6121.4 6138.8 6139.0 6103.7 6125.1 6130.8 6219.3 6231.9 6015.4 6029.0 6041.8 6041.9 6093.6 5812.2 5941.5 5980.7 6015.2 6018.7 5825.8 5840.7 5899.6 5906.6 5978.1 6078.6 6100.6 6107.9 6108.7 6120.7 5696.3 5763.0 5775.8 5848.0 5851.1 05:54:46 INFO - PROCESS | 1657 | 05:54:46 INFO - PROCESS | 1657 | [#1] ext.html Cycles:180 Average:66867.50 Median:38714.90 stddev:138582.77 (358.0%) stddev-sans-first:138881.82 05:54:46 INFO - PROCESS | 1657 | Values: 454.5 473.4 477.3 533.1 544.5 31334.8 39396.0 40708.6 40762.0 43529.0 41905.8 42399.0 42416.0 42464.6 42466.4 8803.5 9763.8 9953.0 9956.9 9972.7 8794.5 8897.7 8899.6 12041.0 12266.8 12325.9 12416.6 12429.9 12430.6 12440.3 12239.1 12329.4 12334.9 12336.3 12341.4 12090.6 12135.0 12149.1 12166.9 12205.4 40109.6 40430.8 40490.6 40512.0 40604.2 3398.6 3402.1 3531.4 3580.8 3630.9 30831.8 31251.2 31845.0 32099.8 32296.7 3139.1 3196.2 3239.1 3240.3 3264.0 468343.0 512202.0 514364.0 517298.4 524303.4 59052.4 59510.1 59666.0 59741.4 59742.7 62011.0 62023.7 62157.0 62228.0 62236.0 13695.5 16095.4 16325.8 16341.8 16363.3 12727.7 12790.6 12799.0 12815.2 12848.7 13010.7 13145.9 14254.6 14611.9 16529.8 7080.5 7220.6 7224.1 7269.7 7309.2 829.8 901.9 976.2 1032.3 1041.0 31249.5 31745.0 31852.5 32068.8 33311.0 1013.4 1044.7 1061.7 1100.6 1109.9 14645.4 15514.8 15606.9 15632.3 16103.5 516393.0 529812.0 530883.0 532638.0 533719.3 48557.3 59285.0 59966.0 60093.1 60094.4 1367.1 1395.8 1409.0 1412.8 1416.2 30110.8 30767.4 30847.4 31606.2 31613.8 51355.7 51940.0 51967.0 52006.7 52109.7 522091.0 525764.0 528881.0 529334.0 530207.0 37932.8 38553.8 38674.0 42776.6 43342.6 46736.8 46885.8 47991.5 48111.0 48520.5 31057.6 39065.8 39510.8 39584.4 40481.0 38660.8 38802.6 39216.3 40057.0 40428.6 38644.0 38755.8 38943.0 39055.0 41161.0 31176.8 31617.7 34289.1 34798.7 34883.7 25042.4 25099.0 25283.1 25347.4 25757.7 05:54:46 INFO - PROCESS | 1657 | 05:54:46 INFO - PROCESS | 1657 | [#2] jquery.html Cycles:180 Average:34760.19 Median:2880.93 stddev:99537.96 (3455.1%) stddev-sans-first:99783.58 05:54:46 INFO - PROCESS | 1657 | Values: 309.7 329.9 348.2 376.9 382.7 15233.3 16057.4 17287.4 21124.9 21194.6 19397.4 20974.9 20994.6 20999.5 21025.5 5753.3 6031.5 6031.6 6033.9 6045.0 5782.5 6087.6 6177.7 6179.6 6208.4 5912.4 6084.0 6167.6 6172.4 6189.4 6156.0 6187.3 6192.7 6192.8 6201.0 5752.3 5973.6 6030.1 6037.5 6053.3 13312.9 13547.9 13555.9 21112.6 26215.0 2471.7 2582.5 2619.8 2792.6 2969.2 10396.5 10441.5 12327.3 14529.3 15059.4 1813.3 1820.7 1827.3 1853.7 1885.5 118331.4 125901.7 127568.0 128055.4 129604.7 10950.9 11446.5 11471.9 11504.6 11684.8 14509.9 14527.6 14542.3 14554.9 14596.6 8089.6 8345.2 8392.0 8505.6 8539.0 8229.1 8233.1 8290.8 8451.1 8510.0 4337.5 4433.6 4465.7 4515.2 4534.6 1785.8 1821.0 1869.2 1875.5 1882.2 811.1 833.4 840.4 863.5 892.7 18217.2 20892.3 20975.1 21019.0 21407.8 947.6 965.5 980.7 990.6 1013.4 10182.9 10386.5 10903.9 11002.7 11040.6 568343.0 591036.0 596871.0 609792.0 611222.0 100738.0 123655.4 128426.0 128794.7 130426.3 945.9 960.7 973.1 974.6 974.7 16037.7 16859.3 18558.2 18671.8 18747.6 42421.0 53660.0 53869.7 55155.9 55483.6 73148.3 76392.6 76695.6 80427.8 83075.6 7892.6 7905.2 8177.3 8219.4 8375.1 11409.6 12610.9 12906.2 13340.9 13381.2 10815.9 11040.5 11553.7 11569.0 11618.4 9307.8 10935.5 10975.6 11503.9 11592.9 11165.8 11479.8 11485.2 11862.2 11956.2 7981.6 8126.3 8130.8 8240.5 8380.8 8309.6 9277.7 9761.9 10904.8 10973.3 05:54:46 INFO - PROCESS | 1657 | 05:54:46 INFO - PROCESS | 1657 | [#3] mootools.html Cycles:180 Average:5662.75 Median:4916.40 stddev:3158.60 (64.2%) stddev-sans-first:3151.98 05:54:46 INFO - PROCESS | 1657 | Values: 1500.7 1512.0 1711.6 1842.5 1896.4 8347.6 8882.5 8887.6 8894.3 8895.9 7789.0 8010.9 8022.4 8096.6 8671.1 2151.5 2305.7 2383.9 2385.2 2391.8 2346.0 2363.3 2363.8 2370.3 2373.1 2373.7 2376.5 2379.7 2380.0 2383.8 2345.5 2389.2 2390.6 2392.6 2396.5 2347.3 2360.4 2370.0 2370.7 2377.5 8637.4 8717.1 8752.9 8770.8 8825.6 5140.9 5317.7 5323.0 5323.7 5325.2 3456.4 3566.9 3633.7 3634.4 3636.3 1025.4 1071.4 1086.4 1095.6 1096.9 11578.9 11591.1 11595.2 11595.3 11628.0 3960.3 4559.4 4580.1 4762.1 4786.0 9762.0 9778.7 9781.4 9786.5 9811.7 5276.8 5279.9 5280.8 5283.9 5303.6 4294.8 4398.6 4637.3 4836.1 4859.2 3619.6 3814.9 3871.0 3920.6 3952.1 1257.8 1260.3 1264.1 1273.1 1278.1 697.3 904.1 911.9 943.9 955.7 4815.5 5657.3 5883.0 5902.8 6001.0 852.8 861.9 926.6 932.9 936.3 3829.6 3885.9 3925.2 3963.3 4002.6 10138.5 10616.5 10652.9 10666.3 10689.9 10067.4 10568.0 10582.0 10616.4 10630.4 7948.8 8177.9 8196.0 8202.1 8208.9 5775.5 5843.2 5870.9 5998.6 6182.3 9497.7 10002.9 10011.4 10011.7 10028.0 10035.7 10159.6 10160.5 10166.3 10217.5 7352.0 7454.6 7540.0 7547.2 7547.3 8064.4 8066.7 8222.8 8236.1 8245.3 7326.5 7370.6 7388.8 7432.3 7586.4 7301.0 7307.5 7308.5 7366.1 7408.9 6606.2 6884.5 6936.2 6987.5 7006.4 4921.2 4952.8 4992.7 5006.1 5098.1 4911.6 4931.7 5027.4 5083.2 5175.5 05:54:46 INFO - PROCESS | 1657 | 05:54:46 INFO - PROCESS | 1657 | [#4] prototype.html Cycles:180 Average:4305.10 Median:4760.65 stddev:1329.16 (27.9%) stddev-sans-first:1298.20 05:54:46 INFO - PROCESS | 1657 | Values: 286.2 293.4 305.3 313.0 316.2 4836.6 4921.0 4937.8 5156.7 5171.5 4457.6 4762.6 4824.3 5070.9 5083.3 3263.2 3892.3 3916.4 3924.9 3989.5 3384.7 3747.1 3927.3 3929.4 3959.0 3098.3 3896.2 3910.8 3933.6 3943.0 3886.0 3898.4 3902.5 3909.6 3928.3 2820.4 3396.4 3399.7 3402.1 3408.2 4724.4 4947.7 4990.0 4992.8 4994.2 3803.9 4275.7 4387.8 4400.2 4423.6 5337.0 5342.4 5346.3 5363.1 5374.0 2691.8 3638.5 3684.6 3695.4 3725.6 5571.9 5588.4 5603.3 5623.0 5632.2 3132.5 3152.6 3740.5 3742.1 3758.2 3177.7 3362.8 3413.5 3670.8 3794.4 4137.7 4403.8 4422.6 4424.8 4468.8 4539.9 5394.0 5407.9 5412.1 5440.8 1206.1 1219.2 1295.9 1312.5 1317.8 803.2 821.4 828.8 881.0 908.1 4163.5 4198.9 4976.6 4989.5 5005.2 4454.0 4659.7 5021.8 5379.8 5431.0 4590.0 4592.5 4779.8 4966.7 4988.9 2806.0 2863.7 2879.7 2894.9 2905.4 4926.9 5545.9 5676.4 5688.7 5708.2 4716.4 5297.4 5566.6 5648.7 5657.2 4918.9 5359.9 5643.8 5662.9 5722.1 5153.5 5419.7 5467.6 5524.0 5557.4 4220.7 4758.7 5260.3 5381.4 5475.0 5328.6 5526.3 5754.3 5793.3 5806.8 3186.4 3323.3 3780.0 3946.1 3952.1 4411.8 4718.6 5075.8 5260.6 5548.9 4456.9 5342.9 5366.5 5369.7 5386.0 4520.5 5343.9 5363.8 5395.2 5432.5 4748.8 4983.0 5225.0 5261.4 5302.7 4496.4 4745.0 5377.7 5393.5 5397.2 4393.7 4672.8 5259.9 5281.4 5329.2 05:54:46 INFO - PROCESS | 1657 | 05:54:46 INFO - PROCESS | 1657 | [#5] yui.html Cycles:180 Average:25652.60 Median:4269.61 stddev:42758.91 (1001.5%) stddev-sans-first:42837.86 05:54:46 INFO - PROCESS | 1657 | Values: 716.0 737.7 745.3 781.2 788.6 15726.6 15972.2 15999.9 16052.4 16061.4 14310.3 14425.0 14437.5 14443.4 14962.1 651.8 669.8 831.6 843.7 845.1 817.1 832.1 833.0 837.8 838.2 894.3 896.9 898.1 899.1 899.1 698.2 859.1 887.8 891.5 895.9 657.5 658.2 659.5 659.7 663.8 14756.4 14874.9 14875.9 14888.6 14982.4 13542.4 13797.9 13797.9 13831.6 14059.9 12170.2 12197.8 12249.8 12305.3 12606.3 4372.8 4377.4 4451.0 4570.0 4575.6 100765.5 126232.0 127318.0 128346.4 130334.0 10180.0 10826.7 10887.2 10932.6 11052.0 22594.4 22616.8 22625.9 22629.0 22640.0 7700.4 7719.4 7899.0 7989.5 8061.1 4159.3 4261.3 4303.2 4435.7 4447.8 3466.4 3531.8 3582.8 3587.2 3595.8 546.2 621.0 626.3 631.2 644.1 555.8 573.8 579.2 592.5 637.6 7561.2 8585.3 8839.1 8851.2 8859.6 552.9 559.8 575.2 612.3 637.5 4277.9 4304.6 4314.5 4365.2 4416.7 157696.2 158512.2 161022.0 161737.4 163384.0 131867.0 139608.0 139672.0 141627.0 145037.3 113883.4 115207.4 116196.4 119888.4 121385.0 6686.9 8035.8 8512.6 8518.7 8543.7 49868.0 60740.0 62912.4 62980.7 63001.7 88174.1 91223.5 92686.0 94508.9 95016.0 9737.4 10937.9 11324.4 11343.5 11731.8 10427.1 11158.3 12272.9 12452.4 12514.9 11877.9 11893.8 12128.7 12147.0 12451.5 11763.7 12105.3 12342.1 12483.9 12486.8 11616.6 11970.5 12025.6 12200.5 12350.3 9821.9 9856.0 9986.0 10043.7 10285.6 8781.0 9148.2 10010.5 10072.5 10081.6 05:54:46 INFO - PROCESS | 1657 | -------- Summary: end -------- 05:54:46 INFO - PROCESS | 1657 | 05:54:46 INFO - TEST-INFO | 1657: exit 0 05:54:46 INFO - TEST-OK | dromaeo_css | took 1117153ms 05:54:46 INFO - TEST-START | kraken 05:54:46 INFO - operating with platform_type : mac_ 05:54:46 INFO - Initialising browser for kraken test... 05:54:46 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/tmpNR5TnM/profile http://localhost:49270/getInfo.html) 05:54:48 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 05:54:48 INFO - PROCESS | 1663 | colorDepth:24 05:54:48 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 05:54:48 INFO - PROCESS | 1663 | __metrics 05:54:49 INFO - PROCESS | 1663 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:54:49 INFO - PROCESS | 1663 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:54:49 INFO - TEST-INFO | 1663: exit 0 05:54:49 INFO - Browser initialized. 05:54:49 INFO - Running cycle 1/1 for kraken test... 05:54:49 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/tmpNR5TnM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:55:01 INFO - PROCESS | 1665 | RSS: Main: 233226240 05:55:01 INFO - PROCESS | 1665 | 05:55:04 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection) 05:55:05 INFO - PROCESS | 1665 | RSS: Main: 291217408 05:55:05 INFO - PROCESS | 1665 | 05:55:08 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-dft) 05:55:08 INFO - PROCESS | 1665 | RSS: Main: 286785536 05:55:08 INFO - PROCESS | 1665 | 05:55:12 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-fft) 05:55:12 INFO - PROCESS | 1665 | RSS: Main: 376983552 05:55:12 INFO - PROCESS | 1665 | 05:55:14 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator) 05:55:15 INFO - PROCESS | 1665 | RSS: Main: 279724032 05:55:15 INFO - PROCESS | 1665 | 05:55:17 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:55:18 INFO - PROCESS | 1665 | RSS: Main: 281911296 05:55:18 INFO - PROCESS | 1665 | 05:55:20 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom) 05:55:21 INFO - PROCESS | 1665 | RSS: Main: 322310144 05:55:21 INFO - PROCESS | 1665 | 05:55:24 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate) 05:55:25 INFO - PROCESS | 1665 | RSS: Main: 323076096 05:55:25 INFO - PROCESS | 1665 | 05:55:27 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial) 05:55:28 INFO - PROCESS | 1665 | RSS: Main: 321081344 05:55:28 INFO - PROCESS | 1665 | 05:55:30 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:55:30 INFO - PROCESS | 1665 | RSS: Main: 296644608 05:55:30 INFO - PROCESS | 1665 | 05:55:32 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:55:33 INFO - PROCESS | 1665 | RSS: Main: 303206400 05:55:33 INFO - PROCESS | 1665 | 05:55:35 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:55:36 INFO - PROCESS | 1665 | RSS: Main: 298627072 05:55:36 INFO - PROCESS | 1665 | 05:55:39 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:55:39 INFO - PROCESS | 1665 | RSS: Main: 317321216 05:55:39 INFO - PROCESS | 1665 | 05:55:42 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:55:43 INFO - PROCESS | 1665 | RSS: Main: 313479168 05:55:43 INFO - PROCESS | 1665 | 05:55:44 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49270/tests/kraken/driver.html?testName=ai-astar) 05:55:45 INFO - PROCESS | 1665 | RSS: Main: 357031936 05:55:45 INFO - PROCESS | 1665 | 05:55:45 INFO - PROCESS | 1665 | __start_tp_report 05:55:45 INFO - PROCESS | 1665 | _x_x_mozilla_page_load 05:55:45 INFO - PROCESS | 1665 | _x_x_mozilla_page_load_details 05:55:45 INFO - PROCESS | 1665 | |i|pagename|runs| 05:55:45 INFO - PROCESS | 1665 | |0;ai-astar;119;163;182;113;113;114;113;113;114;112 05:55:45 INFO - PROCESS | 1665 | |1;audio-beat-detection;143;108;119;180;139;160;152;106;161;108 05:55:45 INFO - PROCESS | 1665 | |2;audio-dft;186;184;182;183;184;209;183;184;182;182 05:55:45 INFO - PROCESS | 1665 | |3;audio-fft;77;74;76;153;57;57;57;64;140;56 05:55:45 INFO - PROCESS | 1665 | |4;audio-oscillator;81;81;130;69;70;89;119;67;69;117 05:55:45 INFO - PROCESS | 1665 | |5;imaging-gaussian-blur;86;86;86;83;88;84;87;83;87;83 05:55:45 INFO - PROCESS | 1665 | |6;imaging-darkroom;96;95;96;96;96;97;94;96;98;95 05:55:45 INFO - PROCESS | 1665 | |7;imaging-desaturate;90;92;91;86;89;87;86;88;86;84 05:55:45 INFO - PROCESS | 1665 | |8;json-parse-financial;56;57;55;53;52;54;52;56;54;53 05:55:45 INFO - PROCESS | 1665 | |9;json-stringify-tinderbox;68;70;69;80;79;75;71;73;72;73 05:55:45 INFO - PROCESS | 1665 | |10;stanford-crypto-aes;86;87;85;84;85;87;107;81;89;87 05:55:45 INFO - PROCESS | 1665 | |11;stanford-crypto-ccm;138;136;140;152;149;151;140;140;185;134 05:55:45 INFO - PROCESS | 1665 | |12;stanford-crypto-pbkdf2;172;171;173;173;172;171;173;171;172;172 05:55:45 INFO - PROCESS | 1665 | |13;stanford-crypto-sha256-iterative;69;66;68;68;69;70;67;68;68;67 05:55:45 INFO - PROCESS | 1665 | __end_tp_report 05:55:45 INFO - PROCESS | 1665 | __start_cc_report 05:55:45 INFO - PROCESS | 1665 | _x_x_mozilla_cycle_collect,2006 05:55:45 INFO - PROCESS | 1665 | __end_cc_report 05:55:45 INFO - PROCESS | 1665 | __startTimestamp1472302545136__endTimestamp 05:55:45 INFO - PROCESS | 1665 | 05:55:45 INFO - PROCESS | 1665 | ------- Summary: start ------- 05:55:45 INFO - PROCESS | 1665 | Number of tests: 14 05:55:45 INFO - PROCESS | 1665 | 05:55:45 INFO - PROCESS | 1665 | [#0] ai-astar Cycles:10 Average:125.60 Median:114.00 stddev:25.19 (22.1%) stddev-sans-first:26.61 05:55:45 INFO - PROCESS | 1665 | Values: 119.0 163.0 182.0 113.0 113.0 114.0 113.0 113.0 114.0 112.0 05:55:45 INFO - PROCESS | 1665 | 05:55:45 INFO - PROCESS | 1665 | [#1] audio-beat-detection Cycles:10 Average:137.60 Median:147.50 stddev:26.21 (17.8%) stddev-sans-first:27.73 05:55:45 INFO - PROCESS | 1665 | Values: 143.0 108.0 119.0 180.0 139.0 160.0 152.0 106.0 161.0 108.0 05:55:45 INFO - PROCESS | 1665 | 05:55:45 INFO - PROCESS | 1665 | [#2] audio-dft Cycles:10 Average:185.90 Median:184.00 stddev:8.21 (4.5%) stddev-sans-first:8.71 05:55:45 INFO - PROCESS | 1665 | Values: 186.0 184.0 182.0 183.0 184.0 209.0 183.0 184.0 182.0 182.0 05:55:45 INFO - PROCESS | 1665 | 05:55:45 INFO - PROCESS | 1665 | [#3] audio-fft Cycles:10 Average:81.10 Median:60.50 stddev:35.59 (58.8%) stddev-sans-first:37.71 05:55:45 INFO - PROCESS | 1665 | Values: 77.0 74.0 76.0 153.0 57.0 57.0 57.0 64.0 140.0 56.0 05:55:45 INFO - PROCESS | 1665 | 05:55:45 INFO - PROCESS | 1665 | [#4] audio-oscillator Cycles:10 Average:89.20 Median:69.50 stddev:23.89 (34.4%) stddev-sans-first:25.16 05:55:45 INFO - PROCESS | 1665 | Values: 81.0 81.0 130.0 69.0 70.0 89.0 119.0 67.0 69.0 117.0 05:55:45 INFO - PROCESS | 1665 | 05:55:45 INFO - PROCESS | 1665 | [#5] imaging-gaussian-blur Cycles:10 Average:85.30 Median:86.00 stddev:1.89 (2.2%) stddev-sans-first:1.99 05:55:45 INFO - PROCESS | 1665 | Values: 86.0 86.0 86.0 83.0 88.0 84.0 87.0 83.0 87.0 83.0 05:55:45 INFO - PROCESS | 1665 | 05:55:45 INFO - PROCESS | 1665 | [#6] imaging-darkroom Cycles:10 Average:95.90 Median:96.00 stddev:1.10 (1.1%) stddev-sans-first:1.17 05:55:45 INFO - PROCESS | 1665 | Values: 96.0 95.0 96.0 96.0 96.0 97.0 94.0 96.0 98.0 95.0 05:55:45 INFO - PROCESS | 1665 | 05:55:45 INFO - PROCESS | 1665 | [#7] imaging-desaturate Cycles:10 Average:87.90 Median:88.50 stddev:2.56 (2.9%) stddev-sans-first:2.60 05:55:45 INFO - PROCESS | 1665 | Values: 90.0 92.0 91.0 86.0 89.0 87.0 86.0 88.0 86.0 84.0 05:55:45 INFO - PROCESS | 1665 | 05:55:45 INFO - PROCESS | 1665 | [#8] json-parse-financial Cycles:10 Average:54.20 Median:54.50 stddev:1.75 (3.2%) stddev-sans-first:1.73 05:55:45 INFO - PROCESS | 1665 | Values: 56.0 57.0 55.0 53.0 52.0 54.0 52.0 56.0 54.0 53.0 05:55:45 INFO - PROCESS | 1665 | 05:55:45 INFO - PROCESS | 1665 | [#9] json-stringify-tinderbox Cycles:10 Average:73.00 Median:73.00 stddev:4.00 (5.5%) stddev-sans-first:3.81 05:55:45 INFO - PROCESS | 1665 | Values: 68.0 70.0 69.0 80.0 79.0 75.0 71.0 73.0 72.0 73.0 05:55:45 INFO - PROCESS | 1665 | 05:55:45 INFO - PROCESS | 1665 | [#10] stanford-crypto-aes Cycles:10 Average:87.80 Median:86.50 stddev:7.08 (8.2%) stddev-sans-first:7.48 05:55:45 INFO - PROCESS | 1665 | Values: 86.0 87.0 85.0 84.0 85.0 87.0 107.0 81.0 89.0 87.0 05:55:45 INFO - PROCESS | 1665 | 05:55:45 INFO - PROCESS | 1665 | [#11] stanford-crypto-ccm Cycles:10 Average:146.50 Median:144.50 stddev:14.92 (10.3%) stddev-sans-first:15.51 05:55:45 INFO - PROCESS | 1665 | Values: 138.0 136.0 140.0 152.0 149.0 151.0 140.0 140.0 185.0 134.0 05:55:45 INFO - PROCESS | 1665 | 05:55:45 INFO - PROCESS | 1665 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.00 Median:172.00 stddev:0.82 (0.5%) stddev-sans-first:0.87 05:55:45 INFO - PROCESS | 1665 | Values: 172.0 171.0 173.0 173.0 172.0 171.0 173.0 171.0 172.0 172.0 05:55:45 INFO - PROCESS | 1665 | 05:55:45 INFO - PROCESS | 1665 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.00 Median:68.00 stddev:1.15 (1.7%) stddev-sans-first:1.17 05:55:45 INFO - PROCESS | 1665 | Values: 69.0 66.0 68.0 68.0 69.0 70.0 67.0 68.0 68.0 67.0 05:55:45 INFO - PROCESS | 1665 | -------- Summary: end -------- 05:55:45 INFO - PROCESS | 1665 | 05:55:45 INFO - TEST-INFO | 1665: exit 0 05:55:45 INFO - TEST-OK | kraken | took 59228ms 05:55:45 INFO - SUITE-END | took 1176s 05:55:46 INFO - Completed test suite (00:19:36) 05:55:46 INFO - javascript benchmark 05:55:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3118.5322201669755, 3270.133414645118, 3303.9008829735103, 3344.665533446654, 3379.0876463354894, 5507.779688812448, 5536.114221724525, 5569.248151499546, 5615.803446879355, 5622.297212848399, 5568.49883510484, 5650.74571644276, 5670.829875103743, 5778.59549831512, 5791.333996590393, 5145.665531740444, 5173.146430838907, 5176.50823171798, 5192.948070519285, 5217.139172036609, 5142.305788718531, 5286.973565132169, 5301.5228629423345, 5333.226682131089, 5355.437679043684, 5143.562797162245, 5226.085435048889, 5241.135212689902, 5251.2385704072885, 5272.0, 5118.744062796845, 5203.427622961472, 5211.869703257411, 5222.843314700565, 5236.319278493809, 4688.765561721934, 4820.939393333461, 5119.308893299394, 5142.07442660321, 5164.483551644843, 5570.805021824217, 5573.386927437979, 5626.915596266059, 5636.210930469738, 5656.632318899299, 6067.193773979152, 6095.81855422887, 6097.298810636789, 6122.540809439265, 6136.948001697505, 6172.444479996777, 6186.84532886677, 6188.81433556994, 6251.249850017982, 6273.62358258506, 5660.349059858169, 5678.290213723326, 5738.454846789548, 5755.107958266475, 5798.420157984252, 6172.8765424691255, 6210.037444196157, 6226.968865155645, 6227.439530442282, 6250.499960003189, 6032.909506357408, 6038.788642397495, 6042.909356359658, 6049.274087109575, 6064.787732429344, 6022.2171117754415, 6093.66484843329, 6176.413240742122, 6178.845528861724, 6207.131001659771, 6071.736927998587, 6142.754289828446, 6148.569600128038, 6160.7535698571555, 6176.752929882754, 6196.411340922605, 6199.783007594712, 6207.410296021871, 6211.3167551569295, 6234.844128896723, 4295.506016808107, 4797.832075877328, 4957.950420495821, 4979.900401992012, 4996.6752161110135, 4302.3546468030045, 4408.0522687622315, 4426.911461770811, 4437.866863994085, 4478.48497422794, 5647.407022262685, 5660.490555849993, 5677.120046392815, 5682.460166284196, 5689.943100569024, 6125.203723515914, 6230.096635987757, 6242.188515492957, 6255.56211065221, 6260.123582698426, 5596.188552659842, 5638.210650508932, 5672.234248376499, 5698.971505142447, 5711.400302968212, 5419.132938729785, 5419.945800541944, 5426.131818908955, 5428.9185662215095, 5447.073997420448, 6176.6911654418, 6210.50315974721, 6231.9688401557705, 6240.469560087444, 6248.781292654735, 6146.969265153645, 6169.691515424301, 6178.382161783786, 6225.159603453476, 6266.028765541349, 6201.96899015502, 6234.096056071753, 6234.251889773256, 6235.657038862724, 6243.282022567281, 6020.096985452217, 6116.724747386467, 6121.418465245794, 6138.815835524942, 6138.969305153446, 6103.701349646989, 6125.11336343068, 6130.826205142415, 6219.346968568235, 6231.937680623317, 6015.368386319374, 6028.969855150696, 6041.78853740117, 6041.9395806041375, 6093.6039157454625, 5812.18629391877, 5941.494972927349, 5980.671063091398, 6015.157877897185, 6018.6689732063405, 5825.76696932118, 5840.678762667924, 5899.557533184942, 5906.586538942232, 5978.133170690138, 6078.6048906820915, 6100.603460775035, 6107.877842443215, 6108.6945652716295, 6120.724567394389, 5696.287964004499, 5763.049096898964, 5775.797847075332, 5848.0350742127075, 5851.063829787214], "value": 5646.67655667179, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [454.49324003735836, 473.420060425978, 477.30790353986686, 533.0857579251583, 544.5453046706, 31334.843325783368, 39396.0, 40708.59291407081, 40762.0, 43529.0, 41905.7904710476, 42399.0, 42416.0, 42464.57535424645, 42466.36300455488, 8803.515806630632, 9763.804723905518, 9953.0, 9956.85064724026, 9972.6509572165, 8794.472331660088, 8897.68858089967, 8899.599518021672, 12041.0, 12266.81599776004, 12325.93837030818, 12416.627501174979, 12429.875701242961, 12430.564930227443, 12440.315782631951, 12239.143259971806, 12329.383530823423, 12334.938325308405, 12336.259824410565, 12341.444634991447, 12090.576829811002, 12134.96852767507, 12149.149559530837, 12166.878331216663, 12205.389730513527, 40109.598904010876, 40430.79784601088, 40490.59509404898, 40512.0, 40604.187916241506, 3398.609159946613, 3402.0644322811177, 3531.382008148589, 3580.820958952042, 3630.8729194478065, 30831.84584077088, 31251.218719531968, 31845.044648660347, 32099.83950080258, 32296.677033229604, 3139.089663997461, 3196.2313093516673, 3239.093053944899, 3240.319532898094, 3263.96736032639, 468343.0, 512202.0, 514364.0, 517298.41350793006, 524303.378483109, 59052.40947590555, 59510.10734838981, 59666.0, 59741.40258597445, 59742.70128649329, 62011.0, 62023.6898815503, 62157.02743780486, 62228.0, 62236.0, 13695.520656777164, 16095.356185752438, 16325.83674163243, 16341.836581634032, 16363.263653135638, 12727.745445091232, 12790.61628151157, 12799.0, 12815.16701414405, 12848.743025139445, 13010.674733131558, 13145.868541314656, 14254.643633909029, 14611.92694036523, 16529.83470165307, 7080.468964827659, 7220.566765994057, 7224.135087728146, 7269.709211631582, 7309.159446663595, 829.8464784014856, 901.8917729872458, 976.2385339435252, 1032.3289861589876, 1040.959040959041, 31249.53125703116, 31745.0, 31852.522212166372, 32068.83965580204, 33311.0, 1013.3919648211014, 1044.6761503933806, 1061.7027232374946, 1100.6367898593444, 1109.916756243285, 14645.414183432544, 15514.767278490832, 15606.9219653901, 15632.296546655427, 16103.51689449295, 516393.0, 529812.0, 530883.0, 532638.0, 533719.3314033482, 48557.27164092541, 59285.0, 59966.0, 60093.09860352098, 60094.39905600975, 1367.1455340412242, 1395.762720337545, 1409.0348111543626, 1412.7598308287822, 1416.199847086393, 30110.805123924845, 30767.384652307275, 30847.383052339275, 31606.209844754067, 31613.8419307902, 51355.74322128515, 51940.0, 51966.96066078733, 52006.73996629992, 52109.7394513025, 522091.0, 525764.0, 528880.9783315285, 529334.0, 530207.0, 37932.81033594815, 38553.80723096479, 38674.0, 42776.57223427726, 43342.566574333854, 46736.7663161682, 46885.82785430391, 47991.520084800104, 48111.0, 48520.514794853014, 31057.60240789125, 39065.80467097646, 39510.80244598759, 39584.40623390594, 40481.0, 38660.80669596747, 38802.6119738799, 39216.34875759569, 40057.0, 40428.59571404366, 38644.0, 38755.80622096984, 38943.0, 39055.0, 41161.0, 31176.844115779277, 31617.683823162395, 34289.08285694514, 34798.652013480554, 34883.65116348804, 25042.37394065163, 25099.0, 25283.11509097173, 25347.366315842253, 25757.742422575535], "value": 20435.084687742077, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [309.7135149986263, 329.8795939482089, 348.1696154671109, 376.92461507698465, 382.7091410527998, 15233.31450084746, 16057.357705691773, 17287.394941177045, 21124.89437552814, 21194.57610847782, 19397.418077457663, 20974.895125524352, 20994.580108397822, 20999.47501312468, 21025.47436314089, 5753.338366087891, 6031.517478601723, 6031.577789554733, 6033.8535678221215, 6044.9697751511185, 5782.53739700825, 6087.573869829113, 6177.691115444232, 6179.5674302798825, 6208.441240288374, 5912.379200183983, 6083.969580152093, 6167.62994220346, 6172.4136207060255, 6189.412005859459, 6156.045812899008, 6187.288461826903, 6192.659403732793, 6192.845178870519, 6200.968995155041, 5752.309722833245, 5973.6415815050805, 6030.065339872327, 6037.45662890342, 6053.273607167124, 13312.86687133126, 13547.900075488315, 13555.86444135556, 21112.5777484451, 26215.0, 2471.653968444419, 2582.5222333868296, 2619.816612837102, 2792.6369571955515, 2969.213158512996, 10396.532156052996, 10441.530131144116, 12327.260364378077, 14529.346179421951, 15059.397624095023, 1813.3200049981258, 1820.6995845685447, 1827.259959716317, 1853.6570734414177, 1885.5097674604565, 118331.40834295859, 125901.74098258992, 127568.0, 128055.35972320172, 129604.7039529602, 10950.945245273722, 11446.484908179153, 11471.885281147082, 11504.58403735225, 11684.766304673858, 14509.911756618085, 14527.564173074825, 14542.345594448063, 14554.927225363805, 14596.562103136923, 8089.555074470843, 8345.165483451729, 8391.958040209882, 8505.61724722389, 8538.975322961285, 8229.086675419328, 8233.135520770353, 8290.751277461686, 8451.070382257947, 8510.0, 4337.544557821447, 4433.645308375322, 4465.665075119314, 4515.209838278288, 4534.59188673021, 1785.758897566205, 1821.0498991633638, 1869.1962456143988, 1875.4654923346782, 1882.1530311359943, 811.0713233347901, 833.3791325462569, 840.4075127035426, 863.4991704811194, 892.683097500391, 18217.18022688982, 20892.268770592957, 20975.056122474525, 21019.0, 21407.822569758813, 947.6410896380977, 965.4979410706534, 980.6665733650593, 990.6384169778123, 1013.3767732844248, 10182.949085254526, 10386.480675966171, 10903.945480272549, 11002.724931876766, 11040.613578524873, 568343.0, 591036.0, 596871.0, 609792.0, 611222.0, 100738.0, 123655.3817230908, 128426.0, 128794.69642428584, 130426.34786826195, 945.8817647794026, 960.6541645007792, 973.1339108193713, 974.6101559376306, 974.7124598243677, 16037.679246414773, 16859.288214353594, 18558.164882580586, 18671.81328186701, 18747.625047499245, 42421.0, 53660.0, 53869.730651346494, 55155.89688206294, 55483.612909677584, 73148.26851731415, 76392.61803690947, 76695.61652191704, 80427.79358309513, 83075.58462207651, 7892.644830982735, 7905.209479052279, 8177.304929080857, 8219.383546234176, 8375.12061233586, 11409.600663977053, 12610.936945315214, 12906.161099528501, 13340.86659133435, 13381.19712817234, 10815.863399614023, 11040.5031773572, 11553.7111572208, 11569.01663358624, 11618.419079045845, 9307.813843722952, 10935.45322733876, 10975.615853445091, 11503.884961150281, 11592.942035289769, 11165.832512512481, 11479.770404592029, 11485.19603627738, 11862.228955118235, 11956.163068585116, 7981.600919953863, 8126.268635822805, 8130.75607731769, 8240.546769927712, 8380.832383352421, 8309.626066826886, 9277.747504086861, 9761.9023809761, 10904.836427453436, 10973.286736362112], "value": 9496.773872901389, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1500.7148641758065, 1511.9924400378, 1711.5635512944198, 1842.5393651587103, 1896.3552392186655, 8347.582620868956, 8882.511461869595, 8887.555622218895, 8894.332925030625, 8895.866562001558, 7789.0, 8010.879836802453, 8022.358211343093, 8096.55468949209, 8671.132886711326, 2151.4728891421623, 2305.700258966332, 2383.92848214553, 2385.2009576791797, 2391.8445301055463, 2345.988270058656, 2363.3382652857226, 2363.8108951283943, 2370.348154257584, 2373.0626402570974, 2373.6558199061124, 2376.5246950609862, 2379.6549500322444, 2379.95240095198, 2383.761623837611, 2345.472268739537, 2389.211560185152, 2390.5696974544576, 2392.617181251009, 2396.4607963208136, 2347.319277409549, 2360.362702070431, 2370.0, 2370.7273663528745, 2377.500724847767, 8637.438566493158, 8717.128287171232, 8752.912470875337, 8770.824583508294, 8825.646974121028, 5140.948590514085, 5317.680939143625, 5323.0, 5323.733813309358, 5325.20121981702, 3456.4296891012955, 3566.928661426757, 3633.745637805355, 3634.418493041127, 3636.2727454509204, 1025.3898930136556, 1071.4482041748506, 1086.4187659602153, 1095.5617752898825, 1096.9341839489657, 11578.884211157865, 11591.072714182761, 11595.246308989974, 11595.304281743038, 11628.0, 3960.34654282046, 4559.430071241095, 4580.106879158532, 4762.095201911626, 4786.042791441726, 9761.974992625814, 9778.706638800848, 9781.364211326241, 9786.461744603972, 9811.705648830546, 5276.788928442896, 5279.9208011879855, 5280.762365693553, 5283.920741188886, 5303.575713942875, 4294.82820687169, 4398.604125628709, 4637.25803871379, 4836.12949669063, 4859.222524396079, 3619.5656521217097, 3814.8855534334016, 3871.032241939515, 3920.6079392061138, 3952.091019065631, 1257.798752199662, 1260.2690439545042, 1264.1088032936757, 1273.1024627637494, 1278.1083995686436, 697.2887654592288, 904.0778406025817, 911.8829433943366, 943.9292053096045, 955.6702937486556, 4815.494373090845, 5657.349404818417, 5883.029185222913, 5902.822915312461, 6000.969995149996, 852.815033344086, 861.87117818987, 926.5876684875301, 932.9160375566096, 936.2884208001967, 3829.5787463379006, 3885.902852428712, 3925.2345792570745, 3963.2667956428154, 4002.579729128458, 10138.493075346203, 10616.522256498476, 10652.946735266429, 10666.306690065121, 10689.9465502672, 10067.395956242648, 10568.0, 10582.0, 10616.363018218932, 10630.415327157081, 7948.801279967932, 8177.877331840027, 8196.0, 8202.09776924526, 8208.876866847002, 5775.480206781242, 5843.2403787507355, 5870.882582348415, 5998.640081595118, 6182.258129024366, 9497.667581634609, 10002.949985250027, 10011.399316041061, 10011.749706257402, 10028.047335503115, 10035.69892903214, 10159.593616255563, 10160.491975401348, 10166.339187952759, 10217.540210690686, 7351.963240183765, 7454.552726836408, 7539.962300188464, 7547.16981132064, 7547.28300811422, 8064.395170362363, 8066.717664881701, 8222.794430139294, 8236.135205803304, 8245.257926786619, 7326.487145899736, 7370.5946172961, 7388.815279617837, 7432.2567743225245, 7586.393088553013, 7301.0, 7307.525010874276, 7308.451866109956, 7366.116066072105, 7408.925910740823, 6606.174228221473, 6884.48366372514, 6936.237013928363, 6987.545809522365, 7006.369426751413, 4921.163402221702, 4952.77712502931, 4992.675476094188, 5006.098902197639, 5098.056859481007, 4911.63162762787, 4931.728754918514, 5027.446980832033, 5083.237514372874, 5175.534201921845], "value": 4559.786692045512, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [286.19721680685666, 293.43220867621153, 305.3145687930596, 312.9624445066592, 316.20316801659817, 4836.564709176174, 4920.950790492094, 4937.777799998999, 5156.664816786906, 5171.482851714826, 4457.598816106558, 4762.5951794097455, 4824.348712923755, 5070.949290507094, 5083.339165908426, 3263.2494526258974, 3892.338302488584, 3916.4125381192766, 3924.921501569981, 3989.5412027616762, 3384.7292216622736, 3747.1381582236104, 3927.273454410929, 3929.4105884117466, 3958.960410395888, 3098.311420275944, 3896.2012787378662, 3910.765354078764, 3933.6263055009726, 3942.9605703943025, 3885.9611403885883, 3898.4152377143514, 3902.512185976753, 3909.550401703812, 3928.332183528807, 2820.3513191965963, 3396.3886500429912, 3399.6600339966053, 3402.0984439123654, 3408.164999575095, 4724.362211101492, 4947.724046938412, 4989.977054703777, 4992.800287988476, 4994.175960966436, 3803.9348982285005, 4275.743455392655, 4387.824487020515, 4400.207962566737, 4423.557644235583, 5336.9733151334385, 5342.358916929993, 5346.251524786533, 5363.122906982525, 5373.957895999534, 2691.838489690625, 3638.490611314418, 3684.5946945835935, 3695.4087346024776, 3725.5529336479526, 5571.8885622287735, 5588.407303918404, 5603.327600687943, 5623.0, 5632.155176723524, 3132.5301204819, 3152.6374466936595, 3740.495033170487, 3742.120601658608, 3758.229562939591, 3177.698118678722, 3362.7982321060326, 3413.5050417689295, 3670.8164591770305, 3794.449804778347, 4137.737989913046, 4403.8458653946955, 4422.557744225552, 4424.823007079745, 4468.843590474383, 4539.863804085882, 5394.029074766501, 5407.945450637088, 5412.134058550613, 5440.80302333486, 1206.1014544164655, 1219.231883913129, 1295.8963282937532, 1312.5143696832042, 1317.7957416600443, 803.1767438375712, 821.3880658909031, 828.8052307707684, 880.9559522023873, 908.1327332397676, 4163.458750362433, 4198.9370159447635, 4976.626752993468, 4989.525995030466, 5005.224190250517, 4453.955460445354, 4659.6952853201155, 5021.799128034838, 5379.843333683277, 5430.972845135749, 4589.954100458953, 4592.517785632527, 4779.808807647655, 4966.677165984272, 4988.90022199554, 2805.9719402806118, 2863.6849946505868, 2879.7120287971456, 2894.884204631833, 2905.418916216765, 4926.901461970812, 5545.918545883595, 5676.432356764373, 5688.687122208318, 5708.20085188074, 4716.3632909557455, 5297.364316282071, 5566.55467562594, 5648.661080335193, 5657.20799088128, 4918.90162196754, 5359.944831446116, 5643.802466913638, 5662.943370566324, 5722.141678748221, 5153.45888681698, 5419.729013549386, 5467.644603100785, 5523.972380138234, 5557.36090349615, 4220.662347012293, 4758.714477131245, 5260.289860868734, 5381.408045114956, 5474.972625137008, 5328.57371410294, 5526.253955715927, 5754.338251101177, 5793.304803423616, 5806.796762113306, 3186.426443240239, 3323.2522682396266, 3779.962200377961, 3946.0923987482465, 3952.110775075585, 4411.750007496136, 4718.551737585061, 5075.847724568269, 5260.605454590997, 5548.916766248591, 4456.933146002877, 5342.8931421372135, 5366.490183432567, 5369.731513424391, 5385.980257403474, 4520.4575450946095, 5343.9198412024625, 5363.812266570651, 5395.217693434509, 5432.456754324536, 4748.833790817304, 4982.975085124551, 5224.955008998139, 5261.394939581997, 5302.70835104073, 4496.437945256843, 4744.952550474451, 5377.731113444391, 5393.541548968382, 5397.217403476552, 4393.714408563433, 4672.813087476598, 5259.947400526099, 5281.4190439052445, 5329.173978033411], "value": 3888.5198312511934, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [715.9606221657807, 737.727040994832, 745.262190431473, 781.1719577248114, 788.6332855222322, 15726.606834829136, 15972.2013899305, 15999.920000399981, 16052.43816466424, 16061.437849675263, 14310.284485775695, 14424.990250682456, 14437.494687685934, 14443.422263109489, 14962.102273863577, 651.7979426377838, 669.7689297192461, 831.5675848558745, 843.7089204224533, 845.121074082953, 817.0807841178674, 832.0514613340796, 832.950022998619, 837.7612380471567, 838.1953324808161, 894.2845723421269, 896.923761480275, 898.0749827677503, 899.0739538275586, 899.0964081098487, 698.1587187439175, 859.1451505751754, 887.8357503861806, 891.4873947480193, 895.8790563273973, 657.5430076097115, 658.1772784019975, 659.475716805141, 659.656978371249, 663.7842701122155, 14756.409743610242, 14874.851251487455, 14875.925620371938, 14888.553343399715, 14982.400703971827, 13542.390592423364, 13797.931010344884, 13797.931010344884, 13831.571090931708, 14059.859401405958, 12170.208936419105, 12197.817032744517, 12249.816252756127, 12305.323207223599, 12606.306653134074, 4372.825086996516, 4377.409049778271, 4450.976275456663, 4569.954300456986, 4575.58819706228, 100765.48851767229, 126232.0, 127318.0, 128346.35826820899, 130334.0, 10180.0, 10826.67519974402, 10887.18346124028, 10932.56269749201, 11052.005319521282, 22594.435139121324, 22616.773832261795, 22625.88687056587, 22629.0, 22640.0, 7700.422468314787, 7719.382449404035, 7898.960505197438, 7989.480683755653, 8061.072976607648, 4159.251334759772, 4261.296886013835, 4303.182395344875, 4435.689501734848, 4447.777611119431, 3466.4107101792756, 3531.8234088295994, 3582.8387722552548, 3587.192881601619, 3595.820208989592, 546.2325432766969, 621.0125899819219, 626.2766504687094, 631.2172905597026, 644.0886146103305, 555.761022760217, 573.7848306884919, 579.1862433281246, 592.4697395830793, 637.581559790646, 7561.243875612505, 8585.31317494599, 8839.056061930329, 8851.247643950208, 8859.601317940707, 552.8838943821843, 559.8012705489492, 575.1774961804576, 612.2622240200548, 637.5186734015789, 4277.91444171121, 4304.569543045733, 4314.525402205755, 4365.170617582649, 4416.667495994404, 157696.21151894398, 158512.20743896207, 161022.0, 161737.3826261746, 163384.0, 131867.0, 139608.0, 139672.0, 141627.0, 145037.27481362526, 113883.43058284822, 115207.42396287965, 116196.41901790605, 119888.4005579984, 121385.0, 6686.866262674622, 8035.799105022421, 8512.574371281287, 8518.70184543538, 8543.700970466003, 49868.0, 60740.0, 62912.37087629065, 62980.68509657423, 63001.68499157475, 88174.11825881916, 91223.54388228017, 92686.0, 94508.90023044079, 95016.0, 9737.415755054719, 10937.890621093688, 11324.37715925632, 11343.546258149912, 11731.824022639497, 10427.113695335678, 11158.311529392895, 12272.877271227173, 12452.439640216393, 12514.93742531318, 11877.881221187678, 11893.821592675944, 12128.696782580506, 12147.028237741137, 12451.49283281852, 11763.705907352385, 12105.273683579013, 12342.13605047638, 12483.875161248634, 12486.750264994831, 11616.593419230625, 11970.521179152736, 12025.63923082309, 12200.51197952072, 12350.258984460961, 9821.901780982385, 9856.0, 9986.0, 10043.748906277402, 10285.588576456857, 8780.956095219739, 9148.209796012281, 10010.549525271526, 10072.496375181358, 10081.596736130472], "value": 7582.214039851038, "unit": "score"}], "name": "dromaeo_css", "value": 7267.526868782522, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 163.0, 182.0, 113.0, 113.0, 114.0, 113.0, 113.0, 114.0, 112.0], "value": 125.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 108.0, 119.0, 180.0, 139.0, 160.0, 152.0, 106.0, 161.0, 108.0], "value": 137.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 184.0, 182.0, 183.0, 184.0, 209.0, 183.0, 184.0, 182.0, 182.0], "value": 185.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 74.0, 76.0, 153.0, 57.0, 57.0, 57.0, 64.0, 140.0, 56.0], "value": 81.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 130.0, 69.0, 70.0, 89.0, 119.0, 67.0, 69.0, 117.0], "value": 89.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 86.0, 83.0, 88.0, 84.0, 87.0, 83.0, 87.0, 83.0], "value": 85.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 96.0, 96.0, 96.0, 97.0, 94.0, 96.0, 98.0, 95.0], "value": 95.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 92.0, 91.0, 86.0, 89.0, 87.0, 86.0, 88.0, 86.0, 84.0], "value": 87.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 57.0, 55.0, 53.0, 52.0, 54.0, 52.0, 56.0, 54.0, 53.0], "value": 54.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 70.0, 69.0, 80.0, 79.0, 75.0, 71.0, 73.0, 72.0, 73.0], "value": 73.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 85.0, 84.0, 85.0, 87.0, 107.0, 81.0, 89.0, 87.0], "value": 87.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 136.0, 140.0, 152.0, 149.0, 151.0, 140.0, 140.0, 185.0, 134.0], "value": 146.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 171.0, 173.0, 173.0, 172.0, 171.0, 173.0, 171.0, 172.0, 172.0], "value": 172.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 66.0, 68.0, 68.0, 69.0, 70.0, 67.0, 68.0, 68.0, 67.0], "value": 68.0, "unit": "score"}], "name": "kraken", "value": 1490.0, "alertThreshold": 2.0}]} 05:55:46 INFO - Return code: 0 05:55:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:55:46 INFO - # TBPL SUCCESS # 05:55:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:55:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:55:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:55:46 INFO - 2016-08-27 05:55:46.477 screenresolution[1669:10455] starting screenresolution argv=screenresolution get 05:55:46 INFO - 2016-08-27 05:55:46.480 screenresolution[1669:10455] Display 0: 1600x1200x32@60 05:55:46 INFO - 2016-08-27 05:55:46.486 screenresolution[1670:10458] starting screenresolution argv=screenresolution list 05:55:46 INFO - Available Modes on Display 0 05:55:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:55:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:55:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:55:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:55:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:55:46 INFO - Intel Iris: 05:55:46 INFO - Chipset Model: Intel Iris 05:55:46 INFO - Type: GPU 05:55:46 INFO - Bus: Built-In 05:55:46 INFO - VRAM (Dynamic, Max): 1536 MB 05:55:46 INFO - Vendor: Intel (0x8086) 05:55:46 INFO - Device ID: 0x0a2e 05:55:46 INFO - Revision ID: 0x0009 05:55:46 INFO - Displays: 05:55:46 INFO - Display: 05:55:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:55:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:55:46 INFO - Display Serial Number: 1600x1200 60 05:55:46 INFO - Main Display: Yes 05:55:46 INFO - Mirror: Off 05:55:46 INFO - Online: Yes 05:55:46 INFO - Rotation: Supported 05:55:46 INFO - Return code: 0 05:55:46 INFO - Running post-action listener: _resource_record_post_action 05:55:46 INFO - [mozharness: 2016-08-27 12:55:46.721612Z] Finished run-tests step (success) 05:55:46 INFO - Running post-run listener: _resource_record_post_run 05:55:46 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 32984064; Write bytes: 707293184; Read time: 318; Write time: 2310 05:55:46 INFO - TinderboxPrint: CPU usage
53.4% 05:55:46 INFO - TinderboxPrint: I/O read bytes / time
32,984,064 / 318 05:55:46 INFO - TinderboxPrint: I/O write bytes / time
707,293,184 / 2,310 05:55:46 INFO - TinderboxPrint: CPU idle
2,256.0 (46.6%) 05:55:46 INFO - TinderboxPrint: CPU system
966.9 (20.0%) 05:55:46 INFO - TinderboxPrint: CPU user
1,622.8 (33.5%) 05:55:46 INFO - TinderboxPrint: Swap in / out
435,048,448 / 0 05:55:46 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 398750208; Write bytes: 391837696; Read time: 29442; Write time: 706 05:55:46 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 30989312; Write bytes: 296736768; Read time: 293; Write time: 1563 05:55:47 INFO - Running post-run listener: _upload_blobber_files 05:55:47 INFO - Blob upload gear active. 05:55:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:55:47 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 05:55:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:55:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:55:47 INFO - (blobuploader) - INFO - Open directory for files ... 05:55:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:55:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:55:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:55:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:55:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:55:48 INFO - (blobuploader) - INFO - Done attempting. 05:55:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:55:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:55:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:55:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:55:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:55:49 INFO - (blobuploader) - INFO - Done attempting. 05:55:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:55:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:55:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:55:51 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:55:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:55:51 INFO - (blobuploader) - INFO - Done attempting. 05:55:51 INFO - (blobuploader) - INFO - Iteration through files over. 05:55:51 INFO - Return code: 0 05:55:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:55:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:55:51 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9bc585a774738415187d77fff76a00c112bd602fb5437d24a6650a92aaf2017fa9e0cf781425842c06061b3255fed22ed79ecee89b761a776be4b276d0644bc5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5854c82e8cae1cdcb84bd78253052a2a0befd5f685152dee1894bafd444902dbac98cf6e008d640d9e21068221b49afe407a51870424ece8be6bcbe9ca61ad37", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/55ab1f5aa163ee27c46f480677d643ecc660d3f3033ce5f407dec913265eca067f81fc71d9b02dd63a27b7947b1dc6af17c4243b3350e734de4d6b2c6ab4ea37"} 05:55:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:55:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:55:51 INFO - Contents: 05:55:51 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9bc585a774738415187d77fff76a00c112bd602fb5437d24a6650a92aaf2017fa9e0cf781425842c06061b3255fed22ed79ecee89b761a776be4b276d0644bc5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5854c82e8cae1cdcb84bd78253052a2a0befd5f685152dee1894bafd444902dbac98cf6e008d640d9e21068221b49afe407a51870424ece8be6bcbe9ca61ad37", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/55ab1f5aa163ee27c46f480677d643ecc660d3f3033ce5f407dec913265eca067f81fc71d9b02dd63a27b7947b1dc6af17c4243b3350e734de4d6b2c6ab4ea37"} 05:55:51 INFO - Running post-run listener: copy_logs_to_upload_dir 05:55:51 INFO - Copying logs to upload dir... 05:55:51 INFO - mkdir: /builds/slave/test/build/upload/logs 05:55:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.103012 ========= master_lag: 2.34 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-08-27 05:55:54.094486) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-27 05:55:54.098321) ========= 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.rQLV1K6GKo/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.DJVXuFIQvE/Listeners TMPDIR=/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9bc585a774738415187d77fff76a00c112bd602fb5437d24a6650a92aaf2017fa9e0cf781425842c06061b3255fed22ed79ecee89b761a776be4b276d0644bc5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5854c82e8cae1cdcb84bd78253052a2a0befd5f685152dee1894bafd444902dbac98cf6e008d640d9e21068221b49afe407a51870424ece8be6bcbe9ca61ad37", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/55ab1f5aa163ee27c46f480677d643ecc660d3f3033ce5f407dec913265eca067f81fc71d9b02dd63a27b7947b1dc6af17c4243b3350e734de4d6b2c6ab4ea37"} build_url:https://queue.taskcluster.net/v1/task/KXenkkpHSBm7k-GoCRm1bA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011124 build_url: 'https://queue.taskcluster.net/v1/task/KXenkkpHSBm7k-GoCRm1bA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9bc585a774738415187d77fff76a00c112bd602fb5437d24a6650a92aaf2017fa9e0cf781425842c06061b3255fed22ed79ecee89b761a776be4b276d0644bc5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5854c82e8cae1cdcb84bd78253052a2a0befd5f685152dee1894bafd444902dbac98cf6e008d640d9e21068221b49afe407a51870424ece8be6bcbe9ca61ad37", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/55ab1f5aa163ee27c46f480677d643ecc660d3f3033ce5f407dec913265eca067f81fc71d9b02dd63a27b7947b1dc6af17c4243b3350e734de4d6b2c6ab4ea37"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-27 05:55:54.141531) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-27 05:55:54.142201) ========= 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.rQLV1K6GKo/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.DJVXuFIQvE/Listeners TMPDIR=/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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.006095 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-27 05:55:54.197725) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-27 05:55:54.198484) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-27 05:55:54.199115) ========= ========= Total master_lag: 2.50 =========