builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0365 starttime: 1470417649.41 results: success (0) buildid: 20160805092231 builduid: 070dc65b1e0446c99585b636576b0b2d revision: 8a08c6285367caa12ac3c1ce45e1746b9ba6e1b4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-05 10:20:49.408766) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-05 10:20:49.409129) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-05 10:20:49.409406) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-05 10:20:49.454408) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-05 10:20:49.454680) ========= 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.kWSnLWHwAp/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.1QLBIrZTaN/Listeners TMPDIR=/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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-05 10:20:49-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 29.4M=0s 2016-08-05 10:20:49 (29.4 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.521340 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-05 10:20:50.001430) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-05 10:20:50.001702) ========= 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.kWSnLWHwAp/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.1QLBIrZTaN/Listeners TMPDIR=/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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.025792 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-05 10:20:50.050160) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-05 10:20:50.050472) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 8a08c6285367caa12ac3c1ce45e1746b9ba6e1b4 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 8a08c6285367caa12ac3c1ce45e1746b9ba6e1b4 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.kWSnLWHwAp/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.1QLBIrZTaN/Listeners TMPDIR=/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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-05 10:20:50,133 truncating revision to first 12 chars 2016-08-05 10:20:50,133 Setting DEBUG logging. 2016-08-05 10:20:50,133 attempt 1/10 2016-08-05 10:20:50,133 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/8a08c6285367?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-05 10:20:51,327 unpacking tar archive at: autoland-8a08c6285367/testing/mozharness/ program finished with exit code 0 elapsedTime=1.460924 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-05 10:20:51.533134) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-05 10:20:51.533449) ========= script_repo_revision: 8a08c6285367caa12ac3c1ce45e1746b9ba6e1b4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-05 10:20:51.533839) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-05 10:20:51.534100) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-05 10:20:51.553808) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-08-05 10:20:51.554068) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 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', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.kWSnLWHwAp/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.1QLBIrZTaN/Listeners TMPDIR=/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 10:20:51 INFO - MultiFileLogger online at 20160805 10:20:51 in /builds/slave/test 10:20:51 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 10:20:51 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:20:51 INFO - {'append_to_log': False, 10:20:51 INFO - 'base_work_dir': '/builds/slave/test', 10:20:51 INFO - 'blob_upload_branch': 'Autoland', 10:20:51 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:20:51 INFO - 'branch': 'Autoland', 10:20:51 INFO - 'buildbot_json_path': 'buildprops.json', 10:20:51 INFO - 'config_files': ('talos/mac_config.py',), 10:20:51 INFO - 'default_actions': ('clobber', 10:20:51 INFO - 'read-buildbot-config', 10:20:51 INFO - 'download-and-extract', 10:20:51 INFO - 'populate-webroot', 10:20:51 INFO - 'create-virtualenv', 10:20:51 INFO - 'install', 10:20:51 INFO - 'run-tests'), 10:20:51 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:20:51 INFO - 'download_minidump_stackwalk': True, 10:20:51 INFO - 'download_symbols': 'ondemand', 10:20:51 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:20:51 INFO - 'tooltool.py': '/tools/tooltool.py', 10:20:51 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:20:51 INFO - '/tools/misc-python/virtualenv.py')}, 10:20:51 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:20:51 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:20:51 INFO - 'installer_path': 'installer.exe', 10:20:51 INFO - 'log_level': 'info', 10:20:51 INFO - 'log_name': 'talos', 10:20:51 INFO - 'log_to_console': True, 10:20:51 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:20:51 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:20:51 INFO - 'opt_config_files': (), 10:20:51 INFO - 'pip_index': False, 10:20:51 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:20:51 INFO - 'cmd': ('bash', 10:20:51 INFO - '-c', 10:20:51 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:20:51 INFO - 'enabled': True, 10:20:51 INFO - 'halt_on_failure': False, 10:20:51 INFO - 'name': 'check_screen_resolution'},), 10:20:51 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:20:51 INFO - 'cmd': ('bash', 10:20:51 INFO - '-c', 10:20:51 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:20:51 INFO - 'enabled': True, 10:20:51 INFO - 'halt_on_failure': False, 10:20:51 INFO - 'name': 'check_screen_resolution'},), 10:20:51 INFO - 'run_cmd_checks_enabled': True, 10:20:51 INFO - 'sps_profile': False, 10:20:51 INFO - 'sps_profile_interval': 0, 10:20:51 INFO - 'suite': 'dromaeojs', 10:20:51 INFO - 'system_bits': '32', 10:20:51 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:20:51 INFO - 'title': 't-yosemite-r7-0365', 10:20:51 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:20:51 INFO - 'use_talos_json': True, 10:20:51 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:20:51 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:20:51 INFO - 'work_dir': 'build'} 10:20:51 INFO - [mozharness: 2016-08-05 17:20:51.750378Z] Running clobber step. 10:20:51 INFO - Running pre-action listener: _resource_record_pre_action 10:20:51 INFO - Running main action method: clobber 10:20:51 INFO - rmtree: /builds/slave/test/build 10:20:51 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:20:53 INFO - Running post-action listener: _resource_record_post_action 10:20:53 INFO - [mozharness: 2016-08-05 17:20:53.637790Z] Finished clobber step (success) 10:20:53 INFO - [mozharness: 2016-08-05 17:20:53.637947Z] Running read-buildbot-config step. 10:20:53 INFO - Running pre-action listener: _resource_record_pre_action 10:20:53 INFO - Running main action method: read_buildbot_config 10:20:53 INFO - Using buildbot properties: 10:20:53 INFO - { 10:20:53 INFO - "project": "", 10:20:53 INFO - "product": "firefox", 10:20:53 INFO - "script_repo_revision": "production", 10:20:53 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 10:20:53 INFO - "repository": "", 10:20:53 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 10:20:53 INFO - "buildid": "20160805092231", 10:20:53 INFO - "pgo_build": "False", 10:20:53 INFO - "basedir": "/builds/slave/test", 10:20:53 INFO - "buildnumber": 99, 10:20:53 INFO - "slavename": "t-yosemite-r7-0365", 10:20:53 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 10:20:53 INFO - "platform": "yosemite_r7", 10:20:53 INFO - "scheduler": "tests-autoland-macosx64-talos", 10:20:53 INFO - "branch": "autoland", 10:20:53 INFO - "repo_path": "integration/autoland", 10:20:53 INFO - "revision": "8a08c6285367caa12ac3c1ce45e1746b9ba6e1b4", 10:20:53 INFO - "stage_platform": "macosx64", 10:20:53 INFO - "builduid": "070dc65b1e0446c99585b636576b0b2d", 10:20:53 INFO - "slavebuilddir": "test" 10:20:53 INFO - } 10:20:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/fX8evGAVS_2lxijLRJt0rA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 10:20:53 INFO - Running post-action listener: _resource_record_post_action 10:20:53 INFO - [mozharness: 2016-08-05 17:20:53.641194Z] Finished read-buildbot-config step (success) 10:20:53 INFO - [mozharness: 2016-08-05 17:20:53.641324Z] Running download-and-extract step. 10:20:53 INFO - Running pre-action listener: _resource_record_pre_action 10:20:53 INFO - Running main action method: download_and_extract 10:20:53 INFO - mkdir: /builds/slave/test/build/tests 10:20:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:20:53 INFO - trying https://queue.taskcluster.net/v1/task/fX8evGAVS_2lxijLRJt0rA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 10:20:53 INFO - Downloading https://queue.taskcluster.net/v1/task/fX8evGAVS_2lxijLRJt0rA/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 10:20:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fX8evGAVS_2lxijLRJt0rA/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 10:20:54 INFO - Downloaded 1199 bytes. 10:20:54 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 10:20:54 INFO - Using the following test package requirements: 10:20:54 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 10:20:54 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:20:54 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 10:20:54 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:20:54 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 10:20:54 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:20:54 INFO - u'jsshell-mac.zip'], 10:20:54 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:20:54 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 10:20:54 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 10:20:54 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:20:54 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 10:20:54 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:20:54 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 10:20:54 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:20:54 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 10:20:54 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:20:54 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 10:20:54 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:20:54 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')]} 10:20:54 INFO - trying https://queue.taskcluster.net/v1/task/fX8evGAVS_2lxijLRJt0rA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 10:20:54 INFO - Downloading https://queue.taskcluster.net/v1/task/fX8evGAVS_2lxijLRJt0rA/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 10:20:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fX8evGAVS_2lxijLRJt0rA/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 10:20:55 INFO - Downloaded 16321087 bytes. 10:20:55 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 10:21:02 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 10:21:02 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')]} 10:21:02 INFO - trying https://queue.taskcluster.net/v1/task/fX8evGAVS_2lxijLRJt0rA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 10:21:02 INFO - Downloading https://queue.taskcluster.net/v1/task/fX8evGAVS_2lxijLRJt0rA/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 10:21:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fX8evGAVS_2lxijLRJt0rA/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 10:21:03 INFO - Downloaded 16321087 bytes. 10:21:03 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 10:21:10 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')]} 10:21:10 INFO - trying https://queue.taskcluster.net/v1/task/fX8evGAVS_2lxijLRJt0rA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 10:21:10 INFO - Downloading https://queue.taskcluster.net/v1/task/fX8evGAVS_2lxijLRJt0rA/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 10:21:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fX8evGAVS_2lxijLRJt0rA/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 10:21:11 INFO - Downloaded 14413723 bytes. 10:21:11 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 10:21: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')]} 10:21:12 INFO - trying https://queue.taskcluster.net/v1/task/fX8evGAVS_2lxijLRJt0rA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 10:21:12 INFO - Downloading https://queue.taskcluster.net/v1/task/fX8evGAVS_2lxijLRJt0rA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 10:21:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fX8evGAVS_2lxijLRJt0rA/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 10:21:18 INFO - Downloaded 111432851 bytes. 10:21:18 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fX8evGAVS_2lxijLRJt0rA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 10:21:18 INFO - mkdir: /builds/slave/test/properties 10:21:18 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:21:18 INFO - Writing to file /builds/slave/test/properties/build_url 10:21:18 INFO - Contents: 10:21:18 INFO - build_url:https://queue.taskcluster.net/v1/task/fX8evGAVS_2lxijLRJt0rA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 10:21:19 INFO - Running post-action listener: _resource_record_post_action 10:21:19 INFO - Running post-action listener: set_extra_try_arguments 10:21:19 INFO - [mozharness: 2016-08-05 17:21:19.531838Z] Finished download-and-extract step (success) 10:21:19 INFO - [mozharness: 2016-08-05 17:21:19.531973Z] Running populate-webroot step. 10:21:19 INFO - Running pre-action listener: _resource_record_pre_action 10:21:19 INFO - Running main action method: populate_webroot 10:21:19 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:21:19 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:21:19 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:21:19 INFO - '--tppagecycles', 10:21:19 INFO - '7'], 10:21:19 INFO - 'tests': ['tsvgm']}}, 10:21:19 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:21:19 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:21:19 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:21:19 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:21:19 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:21:19 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:21:19 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:21:19 INFO - 'talos_options': ['--disable-e10s'], 10:21:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:21:19 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:21:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:21:19 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:21:19 INFO - 'talos_options': ['--disable-e10s'], 10:21:19 INFO - 'tests': ['damp', 'tps']}, 10:21:19 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:21:19 INFO - 'tests': ['damp', 'tps']}, 10:21:19 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:21:19 INFO - 'tests': ['dromaeo_dom']}, 10:21:19 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:21:19 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:21:19 INFO - 'tests': ['basic_compositor_video']}, 10:21:19 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:21:19 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:21:19 INFO - 'tests': ['a11yr', 10:21:19 INFO - 'ts_paint', 10:21:19 INFO - 'tpaint', 10:21:19 INFO - 'sessionrestore', 10:21:19 INFO - 'sessionrestore_no_auto_restore', 10:21:19 INFO - 'tabpaint']}, 10:21:19 INFO - 'other-e10s': {'tests': ['a11yr', 10:21:19 INFO - 'ts_paint', 10:21:19 INFO - 'tpaint', 10:21:19 INFO - 'sessionrestore', 10:21:19 INFO - 'sessionrestore_no_auto_restore', 10:21:19 INFO - 'tabpaint']}, 10:21:19 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:21:19 INFO - 'ts_paint', 10:21:19 INFO - 'tpaint', 10:21:19 INFO - 'sessionrestore', 10:21:19 INFO - 'sessionrestore_no_auto_restore', 10:21:19 INFO - 'tabpaint']}, 10:21:19 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:21:19 INFO - 'ts_paint', 10:21:19 INFO - 'tpaint', 10:21:19 INFO - 'sessionrestore', 10:21:19 INFO - 'sessionrestore_no_auto_restore', 10:21:19 INFO - 'tabpaint']}, 10:21:19 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:21:19 INFO - 'tests': ['a11yr', 10:21:19 INFO - 'ts_paint', 10:21:19 INFO - 'tpaint', 10:21:19 INFO - 'sessionrestore', 10:21:19 INFO - 'sessionrestore_no_auto_restore', 10:21:19 INFO - 'tabpaint']}, 10:21:19 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:21:19 INFO - 'tests': ['a11yr', 10:21:19 INFO - 'ts_paint', 10:21:19 INFO - 'tpaint', 10:21:19 INFO - 'sessionrestore', 10:21:19 INFO - 'sessionrestore_no_auto_restore', 10:21:19 INFO - 'tabpaint']}, 10:21:19 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:21:19 INFO - 'tests': ['tsvgx', 10:21:19 INFO - 'tsvgr_opacity', 10:21:19 INFO - 'tart', 10:21:19 INFO - 'tscrollx', 10:21:19 INFO - 'cart']}, 10:21:19 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:21:19 INFO - 'tsvgr_opacity', 10:21:19 INFO - 'tart', 10:21:19 INFO - 'tscrollx', 10:21:19 INFO - 'cart']}, 10:21:19 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:21:19 INFO - 'talos_options': ['--disable-e10s'], 10:21:19 INFO - 'tests': ['tp5o']}, 10:21:19 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:21:19 INFO - 'tests': ['tp5o']}, 10:21:19 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:21:19 INFO - 'talos_options': ['--disable-e10s', 10:21:19 INFO - '--xperf_path', 10:21:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:21:19 INFO - 'tests': ['tp5n']}, 10:21:19 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:21:19 INFO - 'talos_options': ['--xperf_path', 10:21:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:21:19 INFO - 'tests': ['tp5n']}}, 10:21:19 INFO - 'talos.zip': {'path': '', 10:21:19 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:21:19 INFO - Running post-action listener: _resource_record_post_action 10:21:19 INFO - [mozharness: 2016-08-05 17:21:19.550986Z] Finished populate-webroot step (success) 10:21:19 INFO - [mozharness: 2016-08-05 17:21:19.551118Z] Running create-virtualenv step. 10:21:19 INFO - Running pre-action listener: _resource_record_pre_action 10:21:19 INFO - Running main action method: create_virtualenv 10:21:19 INFO - Creating virtualenv /builds/slave/test/build/venv 10:21:19 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 10:21:19 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:21:19 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:21:19 INFO - Using real prefix '/tools/python27' 10:21:19 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:21:20 INFO - Installing distribute.............................................................................................................................................................................................done. 10:21:23 INFO - Installing pip.................done. 10:21:23 INFO - Return code: 0 10:21:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:21:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:21:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:21:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:21:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:21:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:21:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:21:23 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 0x10f8b2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f947618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdad2c75b50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f816d30>, '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 0x10f57bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f80eb20>, '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.kWSnLWHwAp/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.1QLBIrZTaN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 10:21:23 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 10:21:23 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 10:21:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kWSnLWHwAp/Render', 10:21:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:21:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:21:23 INFO - 'HOME': '/Users/cltbld', 10:21:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:21:23 INFO - 'LOGNAME': 'cltbld', 10:21:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:21:23 INFO - 'MOZ_NO_REMOTE': '1', 10:21:23 INFO - 'NO_EM_RESTART': '1', 10:21:23 INFO - 'PAGER': '/bin/cat', 10:21:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:21:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:21:23 INFO - 'PWD': '/builds/slave/test', 10:21:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:21:23 INFO - 'SHELL': '/bin/bash', 10:21:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1QLBIrZTaN/Listeners', 10:21:23 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 10:21:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:21:23 INFO - 'USER': 'cltbld', 10:21:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:21:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:21:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:21:23 INFO - 'XPC_FLAGS': '0x0', 10:21:23 INFO - 'XPC_SERVICE_NAME': '0', 10:21:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:21:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:21:23 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 10:21:23 INFO - Cleaning up... 10:21:23 INFO - Return code: 0 10:21:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:21:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:21:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:21:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:21:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:21:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:21:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:21:23 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 0x10f8b2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f947618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdad2c75b50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f816d30>, '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 0x10f57bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f80eb20>, '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.kWSnLWHwAp/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.1QLBIrZTaN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 10:21:23 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 10:21:23 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 10:21:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kWSnLWHwAp/Render', 10:21:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:21:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:21:23 INFO - 'HOME': '/Users/cltbld', 10:21:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:21:23 INFO - 'LOGNAME': 'cltbld', 10:21:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:21:23 INFO - 'MOZ_NO_REMOTE': '1', 10:21:23 INFO - 'NO_EM_RESTART': '1', 10:21:23 INFO - 'PAGER': '/bin/cat', 10:21:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:21:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:21:23 INFO - 'PWD': '/builds/slave/test', 10:21:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:21:23 INFO - 'SHELL': '/bin/bash', 10:21:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1QLBIrZTaN/Listeners', 10:21:23 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 10:21:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:21:23 INFO - 'USER': 'cltbld', 10:21:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:21:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:21:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:21:23 INFO - 'XPC_FLAGS': '0x0', 10:21:23 INFO - 'XPC_SERVICE_NAME': '0', 10:21:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:21:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:21:23 INFO - Downloading/unpacking psutil>=3.1.1 10:21:23 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 10:21:23 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 10:21:23 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 10:21:23 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 10:21:23 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 10:21:23 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 10:21:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:21:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:21:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:21:27 INFO - Installing collected packages: psutil 10:21:27 INFO - Running setup.py install for psutil 10:21:27 INFO - building 'psutil._psutil_osx' extension 10:21:27 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 10:21:27 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 10:21:27 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 10:21:27 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 10:21:27 INFO - building 'psutil._psutil_posix' extension 10:21:27 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 10:21:28 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:21:28 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:21:28 INFO - ^ 10:21:28 INFO - 1 warning generated. 10:21:28 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 10:21:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:21:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:21:28 INFO - Successfully installed psutil 10:21:28 INFO - Cleaning up... 10:21:28 INFO - Return code: 0 10:21:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:21: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')]} 10:21:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:21:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:21: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')]} 10:21:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:21:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:21:28 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 0x10f8b2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f947618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdad2c75b50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f816d30>, '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 0x10f57bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f80eb20>, '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.kWSnLWHwAp/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.1QLBIrZTaN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 10:21:28 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 10:21:28 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 10:21:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kWSnLWHwAp/Render', 10:21:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:21:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:21:28 INFO - 'HOME': '/Users/cltbld', 10:21:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:21:28 INFO - 'LOGNAME': 'cltbld', 10:21:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:21:28 INFO - 'MOZ_NO_REMOTE': '1', 10:21:28 INFO - 'NO_EM_RESTART': '1', 10:21:28 INFO - 'PAGER': '/bin/cat', 10:21:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:21:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:21:28 INFO - 'PWD': '/builds/slave/test', 10:21:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:21:28 INFO - 'SHELL': '/bin/bash', 10:21:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1QLBIrZTaN/Listeners', 10:21:28 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 10:21:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:21:28 INFO - 'USER': 'cltbld', 10:21:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:21:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:21:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:21:28 INFO - 'XPC_FLAGS': '0x0', 10:21:28 INFO - 'XPC_SERVICE_NAME': '0', 10:21:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:21:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:21:28 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:21:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:21:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:21:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:21:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:21:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:21:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:21:30 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:21:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:21:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:21:31 INFO - Installing collected packages: mozsystemmonitor 10:21:31 INFO - Running setup.py install for mozsystemmonitor 10:21:31 INFO - Successfully installed mozsystemmonitor 10:21:31 INFO - Cleaning up... 10:21:31 INFO - Return code: 0 10:21:31 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:21:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:21:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:21:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:21:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:21:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:21:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:21:31 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 0x10f8b2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f947618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdad2c75b50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f816d30>, '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 0x10f57bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f80eb20>, '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.kWSnLWHwAp/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.1QLBIrZTaN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 10:21:31 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 10:21:31 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 10:21:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kWSnLWHwAp/Render', 10:21:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:21:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:21:31 INFO - 'HOME': '/Users/cltbld', 10:21:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:21:31 INFO - 'LOGNAME': 'cltbld', 10:21:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:21:31 INFO - 'MOZ_NO_REMOTE': '1', 10:21:31 INFO - 'NO_EM_RESTART': '1', 10:21:31 INFO - 'PAGER': '/bin/cat', 10:21:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:21:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:21:31 INFO - 'PWD': '/builds/slave/test', 10:21:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:21:31 INFO - 'SHELL': '/bin/bash', 10:21:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1QLBIrZTaN/Listeners', 10:21:31 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 10:21:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:21:31 INFO - 'USER': 'cltbld', 10:21:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:21:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:21:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:21:31 INFO - 'XPC_FLAGS': '0x0', 10:21:31 INFO - 'XPC_SERVICE_NAME': '0', 10:21:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:21:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:21:31 INFO - Downloading/unpacking jsonschema==2.5.1 10:21:31 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 10:21:31 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 10:21:31 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 10:21:31 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 10:21:31 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 10:21:31 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 10:21:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:21:33 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:21:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:21:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:21:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:21:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:21:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:21:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:21:34 INFO - Downloading functools32-3.2.3-2.tar.gz 10:21:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:21:34 INFO - warning: no files found matching '*.txt' 10:21:34 INFO - no previously-included directories found matching 'build' 10:21:34 INFO - no previously-included directories found matching 'dist' 10:21:34 INFO - no previously-included directories found matching '.git*' 10:21:34 INFO - Installing collected packages: jsonschema, functools32 10:21:34 INFO - Running setup.py install for functools32 10:21:34 INFO - warning: no files found matching '*.txt' 10:21:34 INFO - no previously-included directories found matching 'build' 10:21:34 INFO - no previously-included directories found matching 'dist' 10:21:34 INFO - no previously-included directories found matching '.git*' 10:21:34 INFO - Successfully installed jsonschema functools32 10:21:34 INFO - Cleaning up... 10:21:34 INFO - Return code: 0 10:21:34 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:21: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')]} 10:21:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:21:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:21: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')]} 10:21:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:21:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:21: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', '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 0x10f8b2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f947618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdad2c75b50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f816d30>, '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 0x10f57bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f80eb20>, '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.kWSnLWHwAp/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.1QLBIrZTaN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 10:21: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 10:21: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 functools32==3.2.3-2 10:21:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kWSnLWHwAp/Render', 10:21:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:21:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:21:34 INFO - 'HOME': '/Users/cltbld', 10:21:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:21:34 INFO - 'LOGNAME': 'cltbld', 10:21:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:21:34 INFO - 'MOZ_NO_REMOTE': '1', 10:21:34 INFO - 'NO_EM_RESTART': '1', 10:21:34 INFO - 'PAGER': '/bin/cat', 10:21:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:21:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:21:34 INFO - 'PWD': '/builds/slave/test', 10:21:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:21:34 INFO - 'SHELL': '/bin/bash', 10:21:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1QLBIrZTaN/Listeners', 10:21:34 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 10:21:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:21:34 INFO - 'USER': 'cltbld', 10:21:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:21:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:21:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:21:34 INFO - 'XPC_FLAGS': '0x0', 10:21:34 INFO - 'XPC_SERVICE_NAME': '0', 10:21:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:21:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:21:35 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:21:35 INFO - Cleaning up... 10:21:35 INFO - Return code: 0 10:21:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:21:35 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')]} 10:21:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:21:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:21:35 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')]} 10:21:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:21:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:21:35 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 0x10f8b2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f947618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdad2c75b50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f816d30>, '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 0x10f57bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f80eb20>, '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.kWSnLWHwAp/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.1QLBIrZTaN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 10:21:35 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 10:21:35 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 10:21:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kWSnLWHwAp/Render', 10:21:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:21:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:21:35 INFO - 'HOME': '/Users/cltbld', 10:21:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:21:35 INFO - 'LOGNAME': 'cltbld', 10:21:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:21:35 INFO - 'MOZ_NO_REMOTE': '1', 10:21:35 INFO - 'NO_EM_RESTART': '1', 10:21:35 INFO - 'PAGER': '/bin/cat', 10:21:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:21:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:21:35 INFO - 'PWD': '/builds/slave/test', 10:21:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:21:35 INFO - 'SHELL': '/bin/bash', 10:21:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1QLBIrZTaN/Listeners', 10:21:35 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 10:21:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:21:35 INFO - 'USER': 'cltbld', 10:21:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:21:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:21:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:21:35 INFO - 'XPC_FLAGS': '0x0', 10:21:35 INFO - 'XPC_SERVICE_NAME': '0', 10:21:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:21:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:21:35 INFO - Downloading/unpacking blobuploader==1.2.4 10:21:35 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 10:21:35 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 10:21:35 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 10:21:35 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 10:21:35 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 10:21:35 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 10:21:37 INFO - Downloading blobuploader-1.2.4.tar.gz 10:21:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:21:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:21:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:21:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:21:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:21:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:21:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:21:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:21:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:21:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:21:38 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 10:21:38 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 10:21:38 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 10:21:38 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 10:21:38 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 10:21:38 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 10:21:38 INFO - Downloading docopt-0.6.1.tar.gz 10:21:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:21:38 INFO - Installing collected packages: blobuploader, requests, docopt 10:21:38 INFO - Running setup.py install for blobuploader 10:21:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:21:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:21:39 INFO - Running setup.py install for requests 10:21:39 INFO - Running setup.py install for docopt 10:21:39 INFO - Successfully installed blobuploader requests docopt 10:21:39 INFO - Cleaning up... 10:21:39 INFO - Return code: 0 10:21:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:21:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:21:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:21:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:21:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:21:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:21:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:21:39 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 0x10f8b2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f947618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdad2c75b50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f816d30>, '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 0x10f57bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f80eb20>, '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.kWSnLWHwAp/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.1QLBIrZTaN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 10:21:39 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 10:21:39 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 10:21:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kWSnLWHwAp/Render', 10:21:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:21:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:21:39 INFO - 'HOME': '/Users/cltbld', 10:21:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:21:39 INFO - 'LOGNAME': 'cltbld', 10:21:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:21:39 INFO - 'MOZ_NO_REMOTE': '1', 10:21:39 INFO - 'NO_EM_RESTART': '1', 10:21:39 INFO - 'PAGER': '/bin/cat', 10:21:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:21:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:21:39 INFO - 'PWD': '/builds/slave/test', 10:21:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:21:39 INFO - 'SHELL': '/bin/bash', 10:21:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1QLBIrZTaN/Listeners', 10:21:39 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 10:21:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:21:39 INFO - 'USER': 'cltbld', 10:21:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:21:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:21:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:21:39 INFO - 'XPC_FLAGS': '0x0', 10:21:39 INFO - 'XPC_SERVICE_NAME': '0', 10:21:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:21:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:21:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:21:40 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-Zm54GQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:21:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:21:40 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-0KCCkU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:21:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:21:40 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-XYs1yX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:21:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:21:40 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-gqtWJ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:21:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:21:40 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-KbS5X3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:21:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:21:40 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-Cu7XnH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:21:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:21:40 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-GdwxoD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:21:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:21:40 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-bwqBAp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:21:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:21:40 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-7JUuW7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:21:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:21:41 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-2XogmR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:21:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:21:41 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-_CQihw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:21:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:21:41 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-VLuraw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:21:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:21:41 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-IFdqyb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:21:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:21:41 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-2SnsGW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:21:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:21:41 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-LSLNJf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:21:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:21:41 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-jZvJG3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:21:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:21:41 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-XYHp86-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:21:41 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:21:41 INFO - Running setup.py install for manifestparser 10:21:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:21:42 INFO - Running setup.py install for mozcrash 10:21:42 INFO - Running setup.py install for mozdebug 10:21:42 INFO - Running setup.py install for mozdevice 10:21:42 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:21:42 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:21:42 INFO - Running setup.py install for mozfile 10:21:42 INFO - Running setup.py install for mozhttpd 10:21:42 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:21:42 INFO - Running setup.py install for mozinfo 10:21:42 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:21:42 INFO - Running setup.py install for mozInstall 10:21:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:21:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:21:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:21:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:21:43 INFO - Running setup.py install for mozleak 10:21:43 INFO - Running setup.py install for mozlog 10:21:43 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:21:43 INFO - Running setup.py install for moznetwork 10:21:43 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:21:43 INFO - Running setup.py install for mozprocess 10:21:43 INFO - Running setup.py install for mozprofile 10:21:43 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:21:43 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:21:43 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:21:43 INFO - Running setup.py install for mozrunner 10:21:43 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:21:43 INFO - Running setup.py install for mozscreenshot 10:21:44 INFO - Running setup.py install for moztest 10:21:44 INFO - Running setup.py install for mozversion 10:21:44 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:21:44 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:21:44 INFO - Cleaning up... 10:21:44 INFO - Return code: 0 10:21:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:21:44 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')]} 10:21:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:21:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:21:44 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')]} 10:21:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:21:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:21:44 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 0x10f8b2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f947618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdad2c75b50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f816d30>, '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 0x10f57bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f80eb20>, '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.kWSnLWHwAp/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.1QLBIrZTaN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 10:21:44 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 10:21:44 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 10:21:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kWSnLWHwAp/Render', 10:21:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:21:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:21:44 INFO - 'HOME': '/Users/cltbld', 10:21:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:21:44 INFO - 'LOGNAME': 'cltbld', 10:21:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:21:44 INFO - 'MOZ_NO_REMOTE': '1', 10:21:44 INFO - 'NO_EM_RESTART': '1', 10:21:44 INFO - 'PAGER': '/bin/cat', 10:21:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:21:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:21:44 INFO - 'PWD': '/builds/slave/test', 10:21:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:21:44 INFO - 'SHELL': '/bin/bash', 10:21:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1QLBIrZTaN/Listeners', 10:21:44 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 10:21:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:21:44 INFO - 'USER': 'cltbld', 10:21:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:21:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:21:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:21:44 INFO - 'XPC_FLAGS': '0x0', 10:21:44 INFO - 'XPC_SERVICE_NAME': '0', 10:21:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:21:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:21:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:21:44 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-eqOVnz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:21:44 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)) 10:21:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:21:44 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-nZtqbC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:21:44 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)) 10:21:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:21:44 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-hi29BG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:21:45 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)) 10:21:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:21:45 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-etMbVP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:21:45 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)) 10:21:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:21:45 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-laz0WO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:21:45 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)) 10:21:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:21:45 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-DeID7O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:21:45 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)) 10:21:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:21:45 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-f8sOOu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:21:45 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)) 10:21:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:21:45 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-mSXH5s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:21:45 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)) 10:21:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:21:45 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-30WC9H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:21:45 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)) 10:21:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:21:45 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-9KDWSn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:21:45 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)) 10:21:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:21:45 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-yuZF7R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:21:45 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)) 10:21:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:21:45 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-2SHlxb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:21:45 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)) 10:21:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:21:45 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-ph5aok-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:21:46 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)) 10:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:21:46 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-IUSGtJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:21:46 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)) 10:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:21:46 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-DY0G9h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:21:46 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)) 10:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:21:46 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-RfmR73-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:21:46 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)) 10:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:21:46 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-6OSXaH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:21:46 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)) 10:21:46 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)) 10:21:46 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)) 10:21:46 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)) 10:21:46 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)) 10:21:46 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)) 10:21:46 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:21:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:21:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:21:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:21:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:21:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:21:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:21:48 INFO - Downloading blessings-1.6.tar.gz 10:21:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:21:49 INFO - Installing collected packages: blessings 10:21:49 INFO - Running setup.py install for blessings 10:21:49 INFO - Successfully installed blessings 10:21:49 INFO - Cleaning up... 10:21:49 INFO - Return code: 0 10:21:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:21:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:21:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:21:49 INFO - Reading from file tmpfile_stdout 10:21:49 INFO - Current package versions: 10:21:49 INFO - blessings == 1.6 10:21:49 INFO - blobuploader == 1.2.4 10:21:49 INFO - docopt == 0.6.1 10:21:49 INFO - functools32 == 3.2.3-2 10:21:49 INFO - jsonschema == 2.5.1 10:21:49 INFO - manifestparser == 1.1 10:21:49 INFO - mozInstall == 1.12 10:21:49 INFO - mozcrash == 0.17 10:21:49 INFO - mozdebug == 0.1 10:21:49 INFO - mozdevice == 0.48 10:21:49 INFO - mozfile == 1.2 10:21:49 INFO - mozhttpd == 0.7 10:21:49 INFO - mozinfo == 0.9 10:21:49 INFO - mozleak == 0.1 10:21:49 INFO - mozlog == 3.3 10:21:49 INFO - moznetwork == 0.27 10:21:49 INFO - mozprocess == 0.23 10:21:49 INFO - mozprofile == 0.28 10:21:49 INFO - mozrunner == 6.12 10:21:49 INFO - mozscreenshot == 0.1 10:21:49 INFO - mozsystemmonitor == 0.3 10:21:49 INFO - moztest == 0.7 10:21:49 INFO - mozversion == 1.4 10:21:49 INFO - psutil == 3.1.1 10:21:49 INFO - requests == 1.2.3 10:21:49 INFO - wsgiref == 0.1.2 10:21:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:21: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')]} 10:21:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:21:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:21: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')]} 10:21:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:21:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:21:49 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 0x10f8b2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f947618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdad2c75b50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f816d30>, '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 0x10f57bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f80eb20>, '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.kWSnLWHwAp/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.1QLBIrZTaN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 10:21:49 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 10:21:49 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 10:21:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kWSnLWHwAp/Render', 10:21:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:21:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:21:49 INFO - 'HOME': '/Users/cltbld', 10:21:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:21:49 INFO - 'LOGNAME': 'cltbld', 10:21:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:21:49 INFO - 'MOZ_NO_REMOTE': '1', 10:21:49 INFO - 'NO_EM_RESTART': '1', 10:21:49 INFO - 'PAGER': '/bin/cat', 10:21:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:21:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:21:49 INFO - 'PWD': '/builds/slave/test', 10:21:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:21:49 INFO - 'SHELL': '/bin/bash', 10:21:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1QLBIrZTaN/Listeners', 10:21:49 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 10:21:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:21:49 INFO - 'USER': 'cltbld', 10:21:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:21:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:21:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:21:49 INFO - 'XPC_FLAGS': '0x0', 10:21:49 INFO - 'XPC_SERVICE_NAME': '0', 10:21:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:21:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:21:49 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)) 10:21:49 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)) 10:21:49 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)) 10:21:49 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)) 10:21:49 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)) 10:21:49 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)) 10:21:49 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)) 10:21:49 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)) 10:21:49 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)) 10:21:49 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)) 10:21:49 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)) 10:21:49 INFO - Cleaning up... 10:21:49 INFO - Return code: 0 10:21:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:21: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')]} 10:21:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:21:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:21: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')]} 10:21:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:21:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:21:49 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 0x10f8b2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f947618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdad2c75b50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f816d30>, '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 0x10f57bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f80eb20>, '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.kWSnLWHwAp/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.1QLBIrZTaN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 10:21:49 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 10:21:49 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 10:21:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kWSnLWHwAp/Render', 10:21:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:21:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:21:49 INFO - 'HOME': '/Users/cltbld', 10:21:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:21:49 INFO - 'LOGNAME': 'cltbld', 10:21:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:21:49 INFO - 'MOZ_NO_REMOTE': '1', 10:21:49 INFO - 'NO_EM_RESTART': '1', 10:21:49 INFO - 'PAGER': '/bin/cat', 10:21:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:21:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:21:49 INFO - 'PWD': '/builds/slave/test', 10:21:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:21:49 INFO - 'SHELL': '/bin/bash', 10:21:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1QLBIrZTaN/Listeners', 10:21:49 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 10:21:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:21:49 INFO - 'USER': 'cltbld', 10:21:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:21:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:21:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:21:49 INFO - 'XPC_FLAGS': '0x0', 10:21:49 INFO - 'XPC_SERVICE_NAME': '0', 10:21:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:21:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:21:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:21:50 INFO - Cleaning up... 10:21:50 INFO - Return code: 0 10:21:50 INFO - Running post-action listener: _resource_record_post_action 10:21:50 INFO - Running post-action listener: _start_resource_monitoring 10:21:50 INFO - Starting resource monitoring. 10:21:50 INFO - [mozharness: 2016-08-05 17:21:50.105803Z] Finished create-virtualenv step (success) 10:21:50 INFO - [mozharness: 2016-08-05 17:21:50.106753Z] Running install step. 10:21:50 INFO - Running pre-action listener: _resource_record_pre_action 10:21:50 INFO - Running main action method: install 10:21:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:21:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:21:50 INFO - Reading from file tmpfile_stdout 10:21:50 INFO - Detecting whether we're running mozinstall >=1.0... 10:21:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:21:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:21:50 INFO - Reading from file tmpfile_stdout 10:21:50 INFO - Output received: 10:21:50 INFO - Usage: mozinstall [options] installer 10:21:50 INFO - Options: 10:21:50 INFO - -h, --help show this help message and exit 10:21:50 INFO - -d DEST, --destination=DEST 10:21:50 INFO - Directory to install application into. [default: 10:21:50 INFO - "/builds/slave/test"] 10:21:50 INFO - --app=APP Application being installed. [default: firefox] 10:21:50 INFO - mkdir: /builds/slave/test/build/application 10:21:50 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'] 10:21:50 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 10:22:24 INFO - Reading from file tmpfile_stdout 10:22:24 INFO - Output received: 10:22:24 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:22:24 INFO - Running post-action listener: _resource_record_post_action 10:22:24 INFO - [mozharness: 2016-08-05 17:22:24.966353Z] Finished install step (success) 10:22:24 INFO - [mozharness: 2016-08-05 17:22:24.966487Z] Running run-tests step. 10:22:24 INFO - Running pre-action listener: _resource_record_pre_action 10:22:24 INFO - Running main action method: run_tests 10:22:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:22:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:22:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:22:24 INFO - 2016-08-05 10:22:24.980 screenresolution[1642:8002] starting screenresolution argv=screenresolution get 10:22:24 INFO - 2016-08-05 10:22:24.983 screenresolution[1642:8002] Display 0: 1600x1200x32@60 10:22:24 INFO - 2016-08-05 10:22:24.989 screenresolution[1643:8004] starting screenresolution argv=screenresolution list 10:22:24 INFO - Available Modes on Display 0 10:22:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:22:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:22:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:22:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:22:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:22:25 INFO - Intel Iris: 10:22:25 INFO - Chipset Model: Intel Iris 10:22:25 INFO - Type: GPU 10:22:25 INFO - Bus: Built-In 10:22:25 INFO - VRAM (Dynamic, Max): 1536 MB 10:22:25 INFO - Vendor: Intel (0x8086) 10:22:25 INFO - Device ID: 0x0a2e 10:22:25 INFO - Revision ID: 0x0009 10:22:25 INFO - Displays: 10:22:25 INFO - Display: 10:22:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:22:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:22:25 INFO - Display Serial Number: 1600x1200 60 10:22:25 INFO - Main Display: Yes 10:22:25 INFO - Mirror: Off 10:22:25 INFO - Online: Yes 10:22:25 INFO - Rotation: Supported 10:22:25 INFO - Return code: 0 10:22:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:22:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:22:25 INFO - Python 2.7.3 10:22:25 INFO - Return code: 0 10:22:25 INFO - grabbing minidump binary from tooltool 10:22:25 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')]} 10:22:25 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 0x10f816d30>, '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 0x10f57bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f80eb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:22:25 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 10:22:25 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 10:22:25 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 10:22:25 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:22:25 INFO - Return code: 0 10:22:25 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:22:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:22:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:22:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:22:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:22:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fX8evGAVS_2lxijLRJt0rA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0365', '--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 10:22:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fX8evGAVS_2lxijLRJt0rA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0365 --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 10:22:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kWSnLWHwAp/Render', 10:22:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:22:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:22:25 INFO - 'HOME': '/Users/cltbld', 10:22:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:22:25 INFO - 'LOGNAME': 'cltbld', 10:22:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:22:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:22:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:22:25 INFO - 'MOZ_NO_REMOTE': '1', 10:22:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:22:25 INFO - 'NO_EM_RESTART': '1', 10:22:25 INFO - 'PAGER': '/bin/cat', 10:22:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:22:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:22:25 INFO - 'PWD': '/builds/slave/test', 10:22:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:22:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:22:25 INFO - 'SHELL': '/bin/bash', 10:22:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1QLBIrZTaN/Listeners', 10:22:25 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 10:22:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:22:25 INFO - 'USER': 'cltbld', 10:22:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:22:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:22:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:22:25 INFO - 'XPC_FLAGS': '0x0', 10:22:25 INFO - 'XPC_SERVICE_NAME': '0', 10:22:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:22:25 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fX8evGAVS_2lxijLRJt0rA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0365', '--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 10:22:25 INFO - mozversion application_buildid: 20160805092231 10:22:25 INFO - mozversion application_changeset: 8a08c6285367caa12ac3c1ce45e1746b9ba6e1b4 10:22:25 INFO - mozversion application_display_name: Nightly 10:22:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:22:25 INFO - mozversion application_name: Firefox 10:22:25 INFO - mozversion application_remotingname: firefox 10:22:25 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 10:22:25 INFO - mozversion application_vendor: Mozilla 10:22:25 INFO - mozversion application_version: 51.0a1 10:22:25 INFO - mozversion platform_buildid: 20160805092231 10:22:25 INFO - mozversion platform_changeset: 8a08c6285367caa12ac3c1ce45e1746b9ba6e1b4 10:22:25 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 10:22:25 INFO - mozversion platform_version: 51.0a1 10:22:25 INFO - using testdate: 1470417745 10:22:25 INFO - actual date: 1470417745 10:22:25 INFO - starting webserver on 'localhost:49271' 10:22:25 INFO - SUITE-START | Running 2 tests 10:22:25 INFO - TEST-START | dromaeo_css 10:22:25 INFO - operating with platform_type : mac_ 10:22:25 INFO - Initialising browser for dromaeo_css test... 10:22:25 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/tmpGC5Di1/profile http://localhost:49271/getInfo.html) 10:22:28 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 10:22:28 INFO - PROCESS | 1653 | colorDepth:24 10:22:28 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 10:22:28 INFO - PROCESS | 1653 | __metrics 10:22:28 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" 10:22:28 INFO - PROCESS | 1653 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:22:29 INFO - TEST-INFO | 1653: exit 0 10:22:29 INFO - Browser initialized. 10:22:29 INFO - Running cycle 1/1 for dromaeo_css test... 10:22:29 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/tmpGC5Di1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:22:40 INFO - PROCESS | 1657 | RSS: Main: 243400704 10:22:40 INFO - PROCESS | 1657 | 10:23:32 INFO - PROCESS | 1657 | 1470417812277 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] 10:25:45 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 10:25:46 INFO - PROCESS | 1657 | RSS: Main: 319229952 10:25:46 INFO - PROCESS | 1657 | 10:28:48 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 10:28:49 INFO - PROCESS | 1657 | RSS: Main: 394444800 10:28:49 INFO - PROCESS | 1657 | 10:31:51 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 10:31:52 INFO - PROCESS | 1657 | RSS: Main: 357208064 10:31:52 INFO - PROCESS | 1657 | 10:34:54 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 10:34:55 INFO - PROCESS | 1657 | RSS: Main: 362356736 10:34:55 INFO - PROCESS | 1657 | 10:37:58 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 10:37:59 INFO - PROCESS | 1657 | RSS: Main: 347123712 10:37:59 INFO - PROCESS | 1657 | 10:41:03 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 10:41:03 INFO - PROCESS | 1657 | RSS: Main: 393707520 10:41:03 INFO - PROCESS | 1657 | 10:41:03 INFO - PROCESS | 1657 | __start_tp_report 10:41:03 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 10:41:03 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 10:41:03 INFO - PROCESS | 1657 | |i|pagename|runs| 10:41:03 INFO - PROCESS | 1657 | |0;dojo.html;2883.466558686643;3367.4443716786727;3382.4926261060837;3393.8472768725405;3432.639572844851;5563.972180139093;5677.630953987988;5695.259616249887;5774.797882074128;5780.393058728836;5645.520130788877;5691.402402747714;5695.971520142404;5710.257666503348;5720.056190728525;5139.306193663854;5169.405518365398;5196.58427325815;5200.453952335007;5259.289995850569;5214.270002199695;5219.373675158986;5228.215767634866;5277.102892508264;5280.102382594949;5241.39723931747;5245.423003469616;5272.209168624699;5286.920696189561;5288.788448462057;4500.774961251925;5277.7361131943635;5280.392754833205;5282.286891269668;5289.86775330616;5116.590672746172;5127;5158.174692049254;5191.844244672666;5207.973960130213;5600.971995139998;5605.943940560624;5651.632643878176;5653.886922261573;5693.316801983747;6175.62946223228;6183.2889217740085;6195.5663103582765;6199.039148931923;6203.596766210182;5839.190930300796;6135.312788983021;6173.135760993465;6233.750649973996;6238.844028899269;5810.448007439287;5813.6221145625395;5838.5621078418435;5839.240898683144;5845.035569131046;5376.811811586654;6216.129741836146;6216.937830621636;6224.719887605069;6233.906491402633;6064.3935606439;6091.623519327842;6112;6129.1419201311855;6138.633639632459;6214;6219.191505104308;6226.221722284715;6226.25284965807;6233.906491402633;6132.6933653317155;6191.1641928339095;6192.690365481708;6193.783217587363;6201.534884883562;6097.25246653012;6169.444749972435;6232.470240029648;6237.251529816451;6249.78125765596;4880.560749532559;4916.237983112624;4923.286123512071;4927.926081108786;4969.3539839821315;4344.283193273074;4405.273129933525;4424.535423780521;4425.3140763181755;4464.129494748493;5610.438956104357;5677.120046392815;5764.596478246483;5776.393478684761;5800.1299805029585;5801.50687191585;6136.427173609184;6199.380061993855;6205.751769929243;6209.472194863397;5742.109972954198;5753.51095156908;5760.423957604206;5764.971175144097;5788.797392091256;5376.623636345418;5400.891982160335;5425.891482170335;5435.619506634576;5444.564434845203;6180.165677633552;6208.099825525183;6210.72051757681;6216.005439129717;6227.346128656426;6036.789629130346;6199.194104766351;6200.379962003944;6211.3167551570195;6215.378462153929;5660.377358490645;6229.408206220402;6251.124842522141;6296.779612713533;6309.305976342507;6035.155078289131;6087.026075827846;6190.628562286277;6194.946859033885;6199;4825.372701548777;6167.414095660905;6209.875802483834;6264.812055638338;6265.8120256392385;6082.2701275847185;6092.045358001277;6097.75608975636;6098.115773212948;6115.327313995546;5928.140419639214;6038.064100064497;6041;6076.635401875725;6101.633901965896;5896.439838215363;5938.61399009063;5991.490723288569;6036.879262414639;6122.326544080237;5983.311919129356;6090.147379366981;6099.9390006099375;6125.050617154348;6126;5854.414558544111;5875.823725288248;5937.168796368425;5937.168796368598;5961.701914904324 10:41:03 INFO - PROCESS | 1657 | |1;ext.html;486.3312944701036;486.3920099875156;520.2820108250613;530.1014779947988;532.2654736463679;28901.56647650284;29586.40827183455;30915.22711932197;30956;31009.37981240374;33251;33814.647414103405;44195;45752.627421177334;45773.7711311443;9772.706818795448;9939;9940.353876998008;9942.502874856264;9946.104850563448;9827.705168844946;9920.90079099207;9925.75185620358;9931.453770042644;9931.602735890556;8463.619137138843;9847.65533206338;9860.457674827881;9891.90108098917;10001.749956251078;9808.558614862313;9848.409095454295;9874.605015799396;9878.160356369717;9887.456189909552;9650.517474126336;9736.685547451145;9744.805103897954;9746.366486178376;9759.170470510015;40989.590104098876;41286.587134128575;41293.380599291035;41316.58683413157;41328;3331.3836940165907;3378.6283508814013;3393.355262500117;3397.9830100849586;3549.8935031948827;31074.378512429852;32399.676003239903;32562.837185814155;32570.674293257238;32712.345753085043;3175.841207939617;3224;3299.4555898276994;3321.153105957965;3376.18971446851;481112;482397;483183;483392;483911.9999999965;59085.70457147687;59158.11262831061;59548;59605.403945960854;59721.70139149277;62156;62792.372076279564;62832;62851;62934.68532657399;15767.684646307009;15996.600084997735;16015.919920400556;16027;16056.839431605767;12356.62930112098;12415.44130514129;12450.439730212162;12463.688407789878;12487.93756031214;15795.921020395055;16362.836371636131;16370.017798932102;16384.918075409783;16395.754063689055;6620.3710647488415;6633.2371777246235;6856.520043597;7222.458315626349;7295.161056478573;844.7888027993001;857.4083882121316;925.8240934222611;1011.7723512209841;1068.4764465412047;29997.850010750244;30980;31005;31048.22379440487;31188.220294492818;909;966.21253678252;1054.773223756896;1123.7752449509967;1134.1550544844144;15154.939154259097;15334;15443.922780386027;15612.687746245012;15754.684906302038;625135;633423;634220;634722;635014;58077.709611451675;58198;58369.4163058364;58379.70810145922;58422.707886460295;1268.0743057568027;1314.9145305555107;1387.50743486061;1414.610981979943;1440.819897512811;25137.622935656345;29693.5545966815;30813.537796933513;31680.683193167773;31720;50994.74502627463;51667.483325166264;51673.7416312916;51706.74146629393;51708;509351;510901;511834;512292;513104;23464;26315.21054368372;27059.729402706507;27083.86458067776;27915.581266280617;47129.293060603435;48010.51989480061;48245;48526.272105919146;48667.75666121647;31001.844990775804;32852.014439566854;35639;35738.28523429569;37059.62940370562;38139.80930095332;38259.42610860895;38529.80735096418;39948.40077398899;40137;38329.6167038326;38418.231635367694;39308.6069139305;39542.802285989535;41645.1670966585;31929.61407718512;32159.874404395734;32464.56696830546;35708;35826.462603061496;26232.737672623793;26264.8686756565;26412.471750565266;26773.732262677902;26813.508229991872 10:41:03 INFO - PROCESS | 1657 | |2;jquery.html;319.2195083022011;352.8147722445717;357.2729495476705;375.56059410489723;402.3200790663778;15532.378704851806;17389.913050434745;20283.695744563844;20775.584488310225;20889.373318800423;20392.89803550984;20592;20908.68636970443;21118.155273789067;21205;5821.09772985188;5833.300003999505;6030.6080104793045;6063.545234107438;6070.119832624267;5892.351841297454;6055.788047418341;6058.212432383784;6060.909086363708;6062.939370606282;4156.521999969997;6306.180196574439;6561.311062338457;6631.27056023837;6693.832654183636;6377.298497165308;6566;6633.86732265352;6656.667166641672;6673.299303573103;5747.195392645033;5749.080147176432;5753.597248192628;5757.942420575782;5761.9135712964335;24270.029198832024;25261;26482.47035059288;26784.196474105807;26997.46005079907;1926.2391355414534;2291.1293708390704;2375.750546192642;2390.5298241581477;2462.876856157185;10051.396916185053;10113.342632728923;10494.212934029978;10512.421816800073;12105.213161144586;1878.1360574135913;1879.52072221584;2004.2383894120287;2015.486051056971;2053.8973051347375;55315.72342138304;72782.63608681975;73575.89636155462;88190.55904720436;99923.99999999927;10712.78574428507;10913;10922.945385273182;11104.389258590694;11210.103191744642;13810.309484525733;13818.792718109236;13822.792658110136;13835.239061851695;13836.446542138201;7877.527348359117;8182.549959752152;8189.590520473953;8271;8305.37709671777;8081.757547273592;8104.8379032419025;8133;8272.8345433091;8303.792405189919;3754.8685795997008;4327.221100201994;4335.653147748172;4349.173657005159;4420.911581768347;1817.155022914351;1861.8456556934516;1862.577628151814;1879.1731638079202;1881.3509339277928;700.4466471487569;772.3743767548303;777.8755399136111;795.2802713544252;858.3347905373386;18203.27186912535;20155.193792248145;20934.37196884096;21163.259285925225;21288.25491107804;842.5450256861349;848.8811566380712;850.3452341696859;877.0484024833128;889.6041261638638;10814.243002989715;10973.725656858642;11026;11053.889461105447;11056.447177641086;599915;616527;618631;619108.9044554839;620373;127555.72444275438;130750;132137;132318;134917;974.9853752193723;977.1205914676848;1021.6730646193296;1039.194624166262;1042.8696412948382;15798.9210053949;18738.625227495646;18802.153903074668;19442;19640.901795491503;57232.42767572271;57388.71305643445;57798.13302800377;58036.709816450646;58369.5407614813;82291.17708823075;82328.58835705783;83816.58091709747;84528.57735711528;85821;6958.756443524452;7261.564306141649;7360.815979600338;7409.925560793762;8037.357011439188;8682.262007729414;11122.443877805916;12802.935985320013;13002.869971300166;13188.208707477583;10888.346699197757;11077.778444431227;11343.659690209306;11385;11445.198836081394;9552.617895284111;10903.781924361627;10927.235093543375;11239.60661376848;11424.772169426882;11019.669409917715;11153.051990580892;11567.76864462723;11675.649730508097;11811.822822657496;7883.369330453665;7892.565908875067;8130.430869839054;8311.542865142475;8316.043654979512;10215.642452514126;10930.56277748881;10970.890291096986;11366;11534.250273732181 10:41:03 INFO - PROCESS | 1657 | |3;mootools.html;1367.979480307795;1511.05559025609;1534.3862455017993;1720.131333676493;1888.1125870840708;7732.613369331532;8468.491890486575;8791.604377802998;8799.648014079437;8926.2412694921;8666.52334121623;8726.607302671378;8747.12528747125;8747.562621868912;8755.124487551242;1822.2164469278239;2070.9585808283823;2141.41111194422;2142.8500004999655;2151.87088774674;1955.6382069317222;2396.1733202045266;2396.4727759892794;2398.640203969401;2406.302172370011;2318.93043208704;2348.3307257431634;2360.221127028085;2366.2546297916188;2372.857628542284;2302.5625131225183;2344.78896899279;2346.6010778167665;2350.9882450587806;2394.5330660521204;2396.916107936231;2418.3349578865955;2420.1771397724683;2421.7941474974473;2429.2105065853493;8864.379493435463;8917.955410222972;8935.151160639729;8940.552972351357;8950.686725964624;5186.559142472895;5348.893022139574;5360.3299587551555;5363.168708850135;5363.973180134113;3477.6696213859896;3503.7372197085065;3664.615215402371;3671.7429779915415;3680.907977300562;1083.685731137969;1089.4824958144818;1097.5554900265406;1105.6682995101437;1111.827666711661;11522.827157592643;11598.420078996101;11608.187426880122;11624.88375116255;11629.651110466699;4322.37325587788;4378.912421751547;4539.5460453955;4540.818367265272;4549.112922979987;9083.500407477652;9095;9100.22648074921;9102.089791020977;9110.362274640844;5135.435102138837;5137.12668846297;5141.922871156936;5148.2020286855595;5151.587872970228;4094.5086589609436;4330.35044743291;4464.575865292792;4809.519048095232;4810.855674329776;3316.7180789633153;3721.181340105227;3817.160224750606;3835.175437280998;3836.1368692044066;1042;1118.5693507999479;1122.9606963756394;1161.0943464098018;1164.138537482257;758.9772306830804;836.7029704454952;882.43965082172;883.6597909804643;898.8247291778041;5499.890002199933;5850.385709500526;5980.13288073227;5988.850278743066;6070.332263451017;934.0799312677008;938.394735395666;942.5758408716105;962.6438217859437;991.5438898106952;3795.690486782056;3796.430535419709;3802.16352402471;3866.7679939203736;4006.399040144002;9639.662611808553;10855.185861060452;10859.18556108295;10898.509567069501;10918.962698543626;10855.620053298097;10856.565737370574;10857.45712714361;10864.619738309122;10893.019628233496;7728.4590078693955;8094.635741391651;8265.669373225004;8272.090070092285;8280.8343833123;5625.662460252398;5856.795012174553;5937.317208521075;5937.435943585352;5963.2844058713235;7809;9587.856182157413;9608.567614457506;9824.901750982684;9898.455584942609;8754.562271886252;9877.345382208629;10071.546780394754;10119.898801011896;10183.7454063649;8155.755327340189;8185.058718247477;8358.623861926106;8360.414770965974;8362.498250105013;8015.358771298399;8134.389920755849;8227.13615070409;8228.341732661493;8232.300254478436;7328.3404493595835;7376.262373762581;7436.182019977907;7460.29127232912;7511.849763004819;7301.671424786004;7327;7333.889991650023;7393.667284972082;7402.629868506446;6776.661166941732;6823.215330237086;6885.965570172117;6914.688839002157;6924.965375173092;4881.219004959189;4886.804527818989;4938.333325001079;4940.481249468898;5035.84892453227;4835.540623640749;4948.529889660476;4969.105560999054;5001.399832020181;5009.998000399862 10:41:03 INFO - PROCESS | 1657 | |4;prototype.html;293.4629627781161;298.56707773728095;307.7922402378394;310.0435157538992;314.82684523512063;3987.909370238067;4611.838585649499;5062.443131255559;5204.609654275931;5303.681779093257;4670.392848929634;4843.321934929113;4919.212925931852;5093.796248150079;5113.974430127853;3305.6529064448246;3376.4766461198556;3584.0143960410965;3874.8956547384;4002.959970400288;3467.549218601578;3968.464257325267;4003.579624139467;4016.196760647882;4053;3735.7772125585234;3761.661450469457;3815.4086116651965;3958.44581758554;3973.483447151866;3937.940930886039;3968.6825053995613;3974.622410870977;3984.5019372578427;3999.820053084347;2910.621619189492;3455.7710276871276;3463.5843698756057;3480.756347055707;3491.8952431427097;4163.8959026024595;4823.445303790074;4993.101241776479;5023.397192336906;5107.744612769384;3896;4357.716748411343;4471.971446567308;4510.503844577127;4643.9767801161115;5376.569874410077;5429.891402171974;5444.482774136451;5468.343798744137;5473.808416705436;3223.580934478526;3816.313063648542;3817.923641527154;3826.330392181385;3828.980855095707;5448.863778405572;5545.611807173499;5606.21512988182;5631.971840140855;5728.42715728428;3821.178446633987;3826.1773718650065;3837.597052309523;3838.692904567628;3841.769493830379;3737.495438115874;3914.8825535233987;3954.8615798446917;3971.384435412516;3996.2407142643387;4139.441175441337;4497.190505708938;4545.36364908913;4561.201789686792;4610.20202121173;4494.4606647202545;4559.772011399416;5398.541124004425;5404.513593776501;5436.592255580847;1214.3078445286103;1238.4365113873166;1294.825198598196;1310.2531557012412;1364.4883168811696;840.9369297302727;857.8241460500583;863.7883718488885;892.7277180460022;895.7772640345881;5021;5026.421961474404;5032.370953630796;5049.26785616091;5152.407473140637;5502.889942201214;5540.227127319219;5542.334919809648;5558.388577256498;5572.275604171513;4628.884277893079;4635.420572428447;4687.742174180453;4998.225275082368;4999.125677599889;2849.017089104297;2910.632002958606;2928.4771918602632;2943.867525961337;2944.720251576097;4597.471290801601;5062.936923161435;5345.289507357686;5348.486211480598;5561.833145005657;5089.465606111379;5354.571634269249;5619.662820230799;5745.511631511368;5830.766769329264;5680.630759000652;5824.330202026652;5932;5940.970295148497;5969.880602387928;3893.8247778849454;4824.782884770124;5285.63000589955;5635.239242702347;5730.484256416942;4344.196323680129;5594.720263986703;5645.858853528694;5652.152177173457;5659.405762394889;5896.380880007538;5898.351181370132;5911.645301281937;5913.526917846648;5943.762249509971;3556.1998550327003;3795.823294778517;3805.2389522095136;3811.618838116166;3821.2739579479808;5613.859653508695;5629.943700562942;5630.943690563206;5632.324121105494;5654.349749778664;5471.398146203835;5479.671219726829;5480.287562616834;5481.671099734029;5503.504684578407;4612.400387949545;5111.974440127776;5457.808976685956;5459.426760190282;5552.833414997556;5344.893102137858;5356.892862142813;5372.946270537244;5374.704391258519;5379.569634429158;5188.912597004776;5352.839414817562;5412.621116521806;5518.42056584069;5527.281453411031;5396.46035396473;5396.625484786681;5402.8379148624035;5405.3513578369275;5413.079776437936 10:41:03 INFO - PROCESS | 1657 | |5;yui.html;714.6819665248964;727.247299045488;773.3967505345829;815.5514467043125;815.7634286057043;14906.850931490695;16314.347426102942;16331.75502367464;16355.01869887806;16360.509184724448;14598.635034124125;14754.778678319833;14768.187749673763;14768.261586920638;14808.481703140393;687.4981263677519;710.6730903784265;712.9536580122301;713.8679344321317;832.7293629570398;705.2876594639425;708.5713143548156;760.7831767946136;841.2933136165619;843.7468759372194;881.1364862434817;890.7149712092133;892.4243862708549;894.5661354243186;898.5507246376811;892.2906289499798;893.3612467086065;893.9553022348889;894.0612357025097;894.2667013049302;668.0713807807152;668.5620918298474;668.6155460610169;668.812732434919;670.3598063849062;11898.524059037629;14501.564953051426;14672.486462973744;14796.112233266038;15379;14029.719405611831;14500.564983050524;14563.781543276858;14643.853561464462;14760.926195369062;11907.642770716893;11929.880701192964;11987.520499180022;12245.693857653541;12300.986442007452;3787.7508863575413;3807.1814559869485;3818.6754125899333;3833.137544052594;3934.1148241645687;113821.43089284583;116407.41796290965;116467;116595;116794;11154.609588664358;11159.721006974729;11170.329780213213;11199.272047316897;11620.244684095507;23071.307860764206;23126.65310020351;23129.653055204184;23148;23153;7390.408767298603;8097.190280971973;8125.3499720022255;8147.103818579952;8364.916350836535;4477.888052798641;4509.828408721919;4522.276435770326;4727.858164255009;4730.195866702668;3558.181618227822;3676.0809797550614;3678.3562876496508;3683.9500742288856;3692.7415080944616;629.5467263570222;662.8972509261072;669.4343279928453;672.2873753820966;691.0774116554355;643.7618081309945;677.9966100169468;691.9031335613018;694.4617921111179;712.9714811407589;8405.285550728135;9383.00650988449;9448.48033358172;9474.621015159455;9903.752406189902;641.2273210780997;655.1300348874047;666.1972323350351;689.8196926624671;694.9131358580178;4573.862784116482;4603.102395033004;4619.353290539327;4630.050839577879;4751.714897106184;154045;157836;160271;166304;166348;136727.6327236715;138601;140338;142689;145150;77900;94177;94430;117944.41027794978;118354;8326.417150799385;8945.46327220369;9082.772930676785;9146.862797058182;9796.706098817047;50642;55821.720891395285;56750.162491876064;57820.241684290384;62218.75562488634;66137.33862661505;83673.58163209145;92774;97970.530442042;100935.49532252585;13403.463861445432;13411.865881341451;13458.596242112751;13594.048416610742;14122.94077944179;15224.771628425804;15526;16073.517794465717;16157.67684646324;16282.674346512766;14413.567592972227;14567.92716036413;14716.117032978056;14891;14935.178565178585;12803.71962803712;13712.211614956166;14362.425502979764;14386.280685965869;14692.853071469575;12250.75498490043;13260.469581216643;13479.663008424868;14108.506202282872;14682.192479413734;11498.13763967689;11619.302841829518;11646.184767066225;11796.94101529487;11997.880021199677;11389;11452;11558.595449159238;11636.941815291208;11733.413329333329 10:41:03 INFO - PROCESS | 1657 | __end_tp_report 10:41:03 INFO - PROCESS | 1657 | __start_cc_report 10:41:03 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,975 10:41:03 INFO - PROCESS | 1657 | __end_cc_report 10:41:03 INFO - PROCESS | 1657 | __startTimestamp1470418863393__endTimestamp 10:41:03 INFO - PROCESS | 1657 | 10:41:03 INFO - PROCESS | 1657 | ------- Summary: start ------- 10:41:03 INFO - PROCESS | 1657 | Number of tests: 6 10:41:03 INFO - PROCESS | 1657 | 10:41:03 INFO - PROCESS | 1657 | [#0] dojo.html Cycles:165 Average:5706.75 Median:5896.44 stddev:636.53 (10.8%) stddev-sans-first:598.71 10:41:03 INFO - PROCESS | 1657 | Values: 2883.5 3367.4 3382.5 3393.8 3432.6 5564.0 5677.6 5695.3 5774.8 5780.4 5645.5 5691.4 5696.0 5710.3 5720.1 5139.3 5169.4 5196.6 5200.5 5259.3 5214.3 5219.4 5228.2 5277.1 5280.1 5241.4 5245.4 5272.2 5286.9 5288.8 4500.8 5277.7 5280.4 5282.3 5289.9 5116.6 5127.0 5158.2 5191.8 5208.0 5601.0 5605.9 5651.6 5653.9 5693.3 6175.6 6183.3 6195.6 6199.0 6203.6 5839.2 6135.3 6173.1 6233.8 6238.8 5810.4 5813.6 5838.6 5839.2 5845.0 5376.8 6216.1 6216.9 6224.7 6233.9 6064.4 6091.6 6112.0 6129.1 6138.6 6214.0 6219.2 6226.2 6226.3 6233.9 6132.7 6191.2 6192.7 6193.8 6201.5 6097.3 6169.4 6232.5 6237.3 6249.8 4880.6 4916.2 4923.3 4927.9 4969.4 4344.3 4405.3 4424.5 4425.3 4464.1 5610.4 5677.1 5764.6 5776.4 5800.1 5801.5 6136.4 6199.4 6205.8 6209.5 5742.1 5753.5 5760.4 5765.0 5788.8 5376.6 5400.9 5425.9 5435.6 5444.6 6180.2 6208.1 6210.7 6216.0 6227.3 6036.8 6199.2 6200.4 6211.3 6215.4 5660.4 6229.4 6251.1 6296.8 6309.3 6035.2 6087.0 6190.6 6194.9 6199.0 4825.4 6167.4 6209.9 6264.8 6265.8 6082.3 6092.0 6097.8 6098.1 6115.3 5928.1 6038.1 6041.0 6076.6 6101.6 5896.4 5938.6 5991.5 6036.9 6122.3 5983.3 6090.1 6099.9 6125.1 6126.0 5854.4 5875.8 5937.2 5937.2 5961.7 10:41:03 INFO - PROCESS | 1657 | 10:41:03 INFO - PROCESS | 1657 | [#1] ext.html Cycles:180 Average:67719.23 Median:40042.70 stddev:145797.77 (364.1%) stddev-sans-first:146119.38 10:41:03 INFO - PROCESS | 1657 | Values: 486.3 486.4 520.3 530.1 532.3 28901.6 29586.4 30915.2 30956.0 31009.4 33251.0 33814.6 44195.0 45752.6 45773.8 9772.7 9939.0 9940.4 9942.5 9946.1 9827.7 9920.9 9925.8 9931.5 9931.6 8463.6 9847.7 9860.5 9891.9 10001.7 9808.6 9848.4 9874.6 9878.2 9887.5 9650.5 9736.7 9744.8 9746.4 9759.2 40989.6 41286.6 41293.4 41316.6 41328.0 3331.4 3378.6 3393.4 3398.0 3549.9 31074.4 32399.7 32562.8 32570.7 32712.3 3175.8 3224.0 3299.5 3321.2 3376.2 481112.0 482397.0 483183.0 483392.0 483912.0 59085.7 59158.1 59548.0 59605.4 59721.7 62156.0 62792.4 62832.0 62851.0 62934.7 15767.7 15996.6 16015.9 16027.0 16056.8 12356.6 12415.4 12450.4 12463.7 12487.9 15795.9 16362.8 16370.0 16384.9 16395.8 6620.4 6633.2 6856.5 7222.5 7295.2 844.8 857.4 925.8 1011.8 1068.5 29997.9 30980.0 31005.0 31048.2 31188.2 909.0 966.2 1054.8 1123.8 1134.2 15154.9 15334.0 15443.9 15612.7 15754.7 625135.0 633423.0 634220.0 634722.0 635014.0 58077.7 58198.0 58369.4 58379.7 58422.7 1268.1 1314.9 1387.5 1414.6 1440.8 25137.6 29693.6 30813.5 31680.7 31720.0 50994.7 51667.5 51673.7 51706.7 51708.0 509351.0 510901.0 511834.0 512292.0 513104.0 23464.0 26315.2 27059.7 27083.9 27915.6 47129.3 48010.5 48245.0 48526.3 48667.8 31001.8 32852.0 35639.0 35738.3 37059.6 38139.8 38259.4 38529.8 39948.4 40137.0 38329.6 38418.2 39308.6 39542.8 41645.2 31929.6 32159.9 32464.6 35708.0 35826.5 26232.7 26264.9 26412.5 26773.7 26813.5 10:41:03 INFO - PROCESS | 1657 | 10:41:03 INFO - PROCESS | 1657 | [#2] jquery.html Cycles:180 Average:34690.03 Median:13658.34 stddev:102033.64 (747.0%) stddev-sans-first:102287.23 10:41:03 INFO - PROCESS | 1657 | Values: 319.2 352.8 357.3 375.6 402.3 15532.4 17389.9 20283.7 20775.6 20889.4 20392.9 20592.0 20908.7 21118.2 21205.0 5821.1 5833.3 6030.6 6063.5 6070.1 5892.4 6055.8 6058.2 6060.9 6062.9 4156.5 6306.2 6561.3 6631.3 6693.8 6377.3 6566.0 6633.9 6656.7 6673.3 5747.2 5749.1 5753.6 5757.9 5761.9 24270.0 25261.0 26482.5 26784.2 26997.5 1926.2 2291.1 2375.8 2390.5 2462.9 10051.4 10113.3 10494.2 10512.4 12105.2 1878.1 1879.5 2004.2 2015.5 2053.9 55315.7 72782.6 73575.9 88190.6 99924.0 10712.8 10913.0 10922.9 11104.4 11210.1 13810.3 13818.8 13822.8 13835.2 13836.4 7877.5 8182.5 8189.6 8271.0 8305.4 8081.8 8104.8 8133.0 8272.8 8303.8 3754.9 4327.2 4335.7 4349.2 4420.9 1817.2 1861.8 1862.6 1879.2 1881.4 700.4 772.4 777.9 795.3 858.3 18203.3 20155.2 20934.4 21163.3 21288.3 842.5 848.9 850.3 877.0 889.6 10814.2 10973.7 11026.0 11053.9 11056.4 599915.0 616527.0 618631.0 619108.9 620373.0 127555.7 130750.0 132137.0 132318.0 134917.0 975.0 977.1 1021.7 1039.2 1042.9 15798.9 18738.6 18802.2 19442.0 19640.9 57232.4 57388.7 57798.1 58036.7 58369.5 82291.2 82328.6 83816.6 84528.6 85821.0 6958.8 7261.6 7360.8 7409.9 8037.4 8682.3 11122.4 12802.9 13002.9 13188.2 10888.3 11077.8 11343.7 11385.0 11445.2 9552.6 10903.8 10927.2 11239.6 11424.8 11019.7 11153.1 11567.8 11675.6 11811.8 7883.4 7892.6 8130.4 8311.5 8316.0 10215.6 10930.6 10970.9 11366.0 11534.3 10:41:03 INFO - PROCESS | 1657 | 10:41:03 INFO - PROCESS | 1657 | [#3] mootools.html Cycles:180 Average:5641.83 Median:5022.92 stddev:3178.36 (63.3%) stddev-sans-first:3171.05 10:41:03 INFO - PROCESS | 1657 | Values: 1368.0 1511.1 1534.4 1720.1 1888.1 7732.6 8468.5 8791.6 8799.6 8926.2 8666.5 8726.6 8747.1 8747.6 8755.1 1822.2 2071.0 2141.4 2142.9 2151.9 1955.6 2396.2 2396.5 2398.6 2406.3 2318.9 2348.3 2360.2 2366.3 2372.9 2302.6 2344.8 2346.6 2351.0 2394.5 2396.9 2418.3 2420.2 2421.8 2429.2 8864.4 8918.0 8935.2 8940.6 8950.7 5186.6 5348.9 5360.3 5363.2 5364.0 3477.7 3503.7 3664.6 3671.7 3680.9 1083.7 1089.5 1097.6 1105.7 1111.8 11522.8 11598.4 11608.2 11624.9 11629.7 4322.4 4378.9 4539.5 4540.8 4549.1 9083.5 9095.0 9100.2 9102.1 9110.4 5135.4 5137.1 5141.9 5148.2 5151.6 4094.5 4330.4 4464.6 4809.5 4810.9 3316.7 3721.2 3817.2 3835.2 3836.1 1042.0 1118.6 1123.0 1161.1 1164.1 759.0 836.7 882.4 883.7 898.8 5499.9 5850.4 5980.1 5988.9 6070.3 934.1 938.4 942.6 962.6 991.5 3795.7 3796.4 3802.2 3866.8 4006.4 9639.7 10855.2 10859.2 10898.5 10919.0 10855.6 10856.6 10857.5 10864.6 10893.0 7728.5 8094.6 8265.7 8272.1 8280.8 5625.7 5856.8 5937.3 5937.4 5963.3 7809.0 9587.9 9608.6 9824.9 9898.5 8754.6 9877.3 10071.5 10119.9 10183.7 8155.8 8185.1 8358.6 8360.4 8362.5 8015.4 8134.4 8227.1 8228.3 8232.3 7328.3 7376.3 7436.2 7460.3 7511.8 7301.7 7327.0 7333.9 7393.7 7402.6 6776.7 6823.2 6886.0 6914.7 6925.0 4881.2 4886.8 4938.3 4940.5 5035.8 4835.5 4948.5 4969.1 5001.4 5010.0 10:41:03 INFO - PROCESS | 1657 | 10:41:03 INFO - PROCESS | 1657 | [#4] prototype.html Cycles:180 Average:4435.47 Median:5022.20 stddev:1363.28 (27.1%) stddev-sans-first:1331.18 10:41:03 INFO - PROCESS | 1657 | Values: 293.5 298.6 307.8 310.0 314.8 3987.9 4611.8 5062.4 5204.6 5303.7 4670.4 4843.3 4919.2 5093.8 5114.0 3305.7 3376.5 3584.0 3874.9 4003.0 3467.5 3968.5 4003.6 4016.2 4053.0 3735.8 3761.7 3815.4 3958.4 3973.5 3937.9 3968.7 3974.6 3984.5 3999.8 2910.6 3455.8 3463.6 3480.8 3491.9 4163.9 4823.4 4993.1 5023.4 5107.7 3896.0 4357.7 4472.0 4510.5 4644.0 5376.6 5429.9 5444.5 5468.3 5473.8 3223.6 3816.3 3817.9 3826.3 3829.0 5448.9 5545.6 5606.2 5632.0 5728.4 3821.2 3826.2 3837.6 3838.7 3841.8 3737.5 3914.9 3954.9 3971.4 3996.2 4139.4 4497.2 4545.4 4561.2 4610.2 4494.5 4559.8 5398.5 5404.5 5436.6 1214.3 1238.4 1294.8 1310.3 1364.5 840.9 857.8 863.8 892.7 895.8 5021.0 5026.4 5032.4 5049.3 5152.4 5502.9 5540.2 5542.3 5558.4 5572.3 4628.9 4635.4 4687.7 4998.2 4999.1 2849.0 2910.6 2928.5 2943.9 2944.7 4597.5 5062.9 5345.3 5348.5 5561.8 5089.5 5354.6 5619.7 5745.5 5830.8 5680.6 5824.3 5932.0 5941.0 5969.9 3893.8 4824.8 5285.6 5635.2 5730.5 4344.2 5594.7 5645.9 5652.2 5659.4 5896.4 5898.4 5911.6 5913.5 5943.8 3556.2 3795.8 3805.2 3811.6 3821.3 5613.9 5629.9 5630.9 5632.3 5654.3 5471.4 5479.7 5480.3 5481.7 5503.5 4612.4 5112.0 5457.8 5459.4 5552.8 5344.9 5356.9 5372.9 5374.7 5379.6 5188.9 5352.8 5412.6 5518.4 5527.3 5396.5 5396.6 5402.8 5405.4 5413.1 10:41:03 INFO - PROCESS | 1657 | 10:41:03 INFO - PROCESS | 1657 | [#5] yui.html Cycles:180 Average:25389.95 Median:3681.15 stddev:41222.22 (1119.8%) stddev-sans-first:41296.23 10:41:03 INFO - PROCESS | 1657 | Values: 714.7 727.2 773.4 815.6 815.8 14906.9 16314.3 16331.8 16355.0 16360.5 14598.6 14754.8 14768.2 14768.3 14808.5 687.5 710.7 713.0 713.9 832.7 705.3 708.6 760.8 841.3 843.7 881.1 890.7 892.4 894.6 898.6 892.3 893.4 894.0 894.1 894.3 668.1 668.6 668.6 668.8 670.4 11898.5 14501.6 14672.5 14796.1 15379.0 14029.7 14500.6 14563.8 14643.9 14760.9 11907.6 11929.9 11987.5 12245.7 12301.0 3787.8 3807.2 3818.7 3833.1 3934.1 113821.4 116407.4 116467.0 116595.0 116794.0 11154.6 11159.7 11170.3 11199.3 11620.2 23071.3 23126.7 23129.7 23148.0 23153.0 7390.4 8097.2 8125.3 8147.1 8364.9 4477.9 4509.8 4522.3 4727.9 4730.2 3558.2 3676.1 3678.4 3684.0 3692.7 629.5 662.9 669.4 672.3 691.1 643.8 678.0 691.9 694.5 713.0 8405.3 9383.0 9448.5 9474.6 9903.8 641.2 655.1 666.2 689.8 694.9 4573.9 4603.1 4619.4 4630.1 4751.7 154045.0 157836.0 160271.0 166304.0 166348.0 136727.6 138601.0 140338.0 142689.0 145150.0 77900.0 94177.0 94430.0 117944.4 118354.0 8326.4 8945.5 9082.8 9146.9 9796.7 50642.0 55821.7 56750.2 57820.2 62218.8 66137.3 83673.6 92774.0 97970.5 100935.5 13403.5 13411.9 13458.6 13594.0 14122.9 15224.8 15526.0 16073.5 16157.7 16282.7 14413.6 14567.9 14716.1 14891.0 14935.2 12803.7 13712.2 14362.4 14386.3 14692.9 12250.8 13260.5 13479.7 14108.5 14682.2 11498.1 11619.3 11646.2 11796.9 11997.9 11389.0 11452.0 11558.6 11636.9 11733.4 10:41:03 INFO - PROCESS | 1657 | -------- Summary: end -------- 10:41:03 INFO - PROCESS | 1657 | 10:41:04 INFO - TEST-INFO | 1657: exit 0 10:41:04 INFO - TEST-OK | dromaeo_css | took 1118457ms 10:41:04 INFO - TEST-START | kraken 10:41:04 INFO - operating with platform_type : mac_ 10:41:04 INFO - Initialising browser for kraken test... 10:41:04 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/tmpxp0z1p/profile http://localhost:49271/getInfo.html) 10:41:06 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 10:41:06 INFO - PROCESS | 1663 | colorDepth:24 10:41:06 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 10:41:06 INFO - PROCESS | 1663 | __metrics 10:41:06 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" 10:41:06 INFO - PROCESS | 1663 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:41:07 INFO - TEST-INFO | 1663: exit 0 10:41:07 INFO - Browser initialized. 10:41:07 INFO - Running cycle 1/1 for kraken test... 10:41:07 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/tmpxp0z1p/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:41:19 INFO - PROCESS | 1664 | RSS: Main: 243138560 10:41:19 INFO - PROCESS | 1664 | 10:41:21 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 10:41:22 INFO - PROCESS | 1664 | RSS: Main: 293957632 10:41:22 INFO - PROCESS | 1664 | 10:41:25 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 10:41:25 INFO - PROCESS | 1664 | RSS: Main: 306098176 10:41:25 INFO - PROCESS | 1664 | 10:41:29 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 10:41:29 INFO - PROCESS | 1664 | RSS: Main: 396382208 10:41:29 INFO - PROCESS | 1664 | 10:41:31 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 10:41:32 INFO - PROCESS | 1664 | RSS: Main: 300670976 10:41:32 INFO - PROCESS | 1664 | 10:41:34 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:41:35 INFO - PROCESS | 1664 | RSS: Main: 303869952 10:41:35 INFO - PROCESS | 1664 | 10:41:38 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 10:41:38 INFO - PROCESS | 1664 | RSS: Main: 341172224 10:41:38 INFO - PROCESS | 1664 | 10:41:41 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 10:41:42 INFO - PROCESS | 1664 | RSS: Main: 343674880 10:41:42 INFO - PROCESS | 1664 | 10:41:45 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 10:41:45 INFO - PROCESS | 1664 | RSS: Main: 341942272 10:41:45 INFO - PROCESS | 1664 | 10:41:47 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:41:48 INFO - PROCESS | 1664 | RSS: Main: 314396672 10:41:48 INFO - PROCESS | 1664 | 10:41:50 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:41:50 INFO - PROCESS | 1664 | RSS: Main: 318189568 10:41:50 INFO - PROCESS | 1664 | 10:41:52 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:41:53 INFO - PROCESS | 1664 | RSS: Main: 322105344 10:41:53 INFO - PROCESS | 1664 | 10:41:56 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:41:57 INFO - PROCESS | 1664 | RSS: Main: 330788864 10:41:57 INFO - PROCESS | 1664 | 10:41:59 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:42:00 INFO - PROCESS | 1664 | RSS: Main: 332234752 10:42:00 INFO - PROCESS | 1664 | 10:42:02 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 10:42:02 INFO - PROCESS | 1664 | RSS: Main: 375713792 10:42:02 INFO - PROCESS | 1664 | 10:42:02 INFO - PROCESS | 1664 | __start_tp_report 10:42:02 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 10:42:02 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 10:42:02 INFO - PROCESS | 1664 | |i|pagename|runs| 10:42:02 INFO - PROCESS | 1664 | |0;ai-astar;101;114;112;113;113;114;113;113;113;113 10:42:02 INFO - PROCESS | 1664 | |1;audio-beat-detection;144;115;108;162;103;107;130;105;141;106 10:42:02 INFO - PROCESS | 1664 | |2;audio-dft;184;185;185;185;183;182;179;183;184;184 10:42:02 INFO - PROCESS | 1664 | |3;audio-fft;78;58;57;109;58;57;63;69;84;56 10:42:02 INFO - PROCESS | 1664 | |4;audio-oscillator;86;68;95;99;96;94;97;70;68;101 10:42:02 INFO - PROCESS | 1664 | |5;imaging-gaussian-blur;89;87;91;87;89;87;91;128;117;86 10:42:02 INFO - PROCESS | 1664 | |6;imaging-darkroom;96;95;96;97;97;96;96;96;95;96 10:42:02 INFO - PROCESS | 1664 | |7;imaging-desaturate;87;86;86;86;87;85;85;93;131;91 10:42:02 INFO - PROCESS | 1664 | |8;json-parse-financial;54;53;53;51;58;61;73;75;72;55 10:42:02 INFO - PROCESS | 1664 | |9;json-stringify-tinderbox;65;74;74;73;75;71;69;98;96;98 10:42:02 INFO - PROCESS | 1664 | |10;stanford-crypto-aes;86;89;83;85;99;95;84;78;84;85 10:42:02 INFO - PROCESS | 1664 | |11;stanford-crypto-ccm;136;137;139;158;130;135;141;134;135;139 10:42:02 INFO - PROCESS | 1664 | |12;stanford-crypto-pbkdf2;217;180;165;165;163;165;162;166;163;161 10:42:02 INFO - PROCESS | 1664 | |13;stanford-crypto-sha256-iterative;67;68;66;65;68;82;83;78;60;62 10:42:02 INFO - PROCESS | 1664 | __end_tp_report 10:42:02 INFO - PROCESS | 1664 | __start_cc_report 10:42:02 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,2057 10:42:02 INFO - PROCESS | 1664 | __end_cc_report 10:42:02 INFO - PROCESS | 1664 | __startTimestamp1470418922603__endTimestamp 10:42:02 INFO - PROCESS | 1664 | 10:42:02 INFO - PROCESS | 1664 | ------- Summary: start ------- 10:42:02 INFO - PROCESS | 1664 | Number of tests: 14 10:42:02 INFO - PROCESS | 1664 | 10:42:02 INFO - PROCESS | 1664 | [#0] ai-astar Cycles:10 Average:111.90 Median:113.00 stddev:3.87 (3.4%) stddev-sans-first:0.60 10:42:02 INFO - PROCESS | 1664 | Values: 101.0 114.0 112.0 113.0 113.0 114.0 113.0 113.0 113.0 113.0 10:42:02 INFO - PROCESS | 1664 | 10:42:02 INFO - PROCESS | 1664 | [#1] audio-beat-detection Cycles:10 Average:122.10 Median:122.50 stddev:20.78 (17.0%) stddev-sans-first:20.47 10:42:02 INFO - PROCESS | 1664 | Values: 144.0 115.0 108.0 162.0 103.0 107.0 130.0 105.0 141.0 106.0 10:42:02 INFO - PROCESS | 1664 | 10:42:02 INFO - PROCESS | 1664 | [#2] audio-dft Cycles:10 Average:183.40 Median:184.00 stddev:1.84 (1.0%) stddev-sans-first:1.94 10:42:02 INFO - PROCESS | 1664 | Values: 184.0 185.0 185.0 185.0 183.0 182.0 179.0 183.0 184.0 184.0 10:42:02 INFO - PROCESS | 1664 | 10:42:02 INFO - PROCESS | 1664 | [#3] audio-fft Cycles:10 Average:68.90 Median:60.50 stddev:17.13 (28.3%) stddev-sans-first:17.85 10:42:02 INFO - PROCESS | 1664 | Values: 78.0 58.0 57.0 109.0 58.0 57.0 63.0 69.0 84.0 56.0 10:42:02 INFO - PROCESS | 1664 | 10:42:02 INFO - PROCESS | 1664 | [#4] audio-oscillator Cycles:10 Average:87.40 Median:94.50 stddev:13.52 (14.3%) stddev-sans-first:14.33 10:42:02 INFO - PROCESS | 1664 | Values: 86.0 68.0 95.0 99.0 96.0 94.0 97.0 70.0 68.0 101.0 10:42:02 INFO - PROCESS | 1664 | 10:42:02 INFO - PROCESS | 1664 | [#5] imaging-gaussian-blur Cycles:10 Average:95.20 Median:88.00 stddev:14.72 (16.7%) stddev-sans-first:15.44 10:42:02 INFO - PROCESS | 1664 | Values: 89.0 87.0 91.0 87.0 89.0 87.0 91.0 128.0 117.0 86.0 10:42:02 INFO - PROCESS | 1664 | 10:42:02 INFO - PROCESS | 1664 | [#6] imaging-darkroom Cycles:10 Average:96.00 Median:96.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 10:42:02 INFO - PROCESS | 1664 | Values: 96.0 95.0 96.0 97.0 97.0 96.0 96.0 96.0 95.0 96.0 10:42:02 INFO - PROCESS | 1664 | 10:42:02 INFO - PROCESS | 1664 | [#7] imaging-desaturate Cycles:10 Average:91.70 Median:86.50 stddev:14.06 (16.2%) stddev-sans-first:14.81 10:42:02 INFO - PROCESS | 1664 | Values: 87.0 86.0 86.0 86.0 87.0 85.0 85.0 93.0 131.0 91.0 10:42:02 INFO - PROCESS | 1664 | 10:42:02 INFO - PROCESS | 1664 | [#8] json-parse-financial Cycles:10 Average:60.50 Median:59.50 stddev:9.31 (15.7%) stddev-sans-first:9.58 10:42:02 INFO - PROCESS | 1664 | Values: 54.0 53.0 53.0 51.0 58.0 61.0 73.0 75.0 72.0 55.0 10:42:02 INFO - PROCESS | 1664 | 10:42:02 INFO - PROCESS | 1664 | [#9] json-stringify-tinderbox Cycles:10 Average:79.30 Median:74.50 stddev:12.79 (17.2%) stddev-sans-first:12.47 10:42:02 INFO - PROCESS | 1664 | Values: 65.0 74.0 74.0 73.0 75.0 71.0 69.0 98.0 96.0 98.0 10:42:02 INFO - PROCESS | 1664 | 10:42:02 INFO - PROCESS | 1664 | [#10] stanford-crypto-aes Cycles:10 Average:86.80 Median:85.50 stddev:6.11 (7.1%) stddev-sans-first:6.47 10:42:02 INFO - PROCESS | 1664 | Values: 86.0 89.0 83.0 85.0 99.0 95.0 84.0 78.0 84.0 85.0 10:42:02 INFO - PROCESS | 1664 | 10:42:02 INFO - PROCESS | 1664 | [#11] stanford-crypto-ccm Cycles:10 Average:138.40 Median:138.00 stddev:7.55 (5.5%) stddev-sans-first:7.95 10:42:02 INFO - PROCESS | 1664 | Values: 136.0 137.0 139.0 158.0 130.0 135.0 141.0 134.0 135.0 139.0 10:42:02 INFO - PROCESS | 1664 | 10:42:02 INFO - PROCESS | 1664 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.70 Median:165.00 stddev:17.12 (10.4%) stddev-sans-first:5.66 10:42:02 INFO - PROCESS | 1664 | Values: 217.0 180.0 165.0 165.0 163.0 165.0 162.0 166.0 163.0 161.0 10:42:02 INFO - PROCESS | 1664 | 10:42:02 INFO - PROCESS | 1664 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.90 Median:68.00 stddev:8.16 (12.0%) stddev-sans-first:8.58 10:42:02 INFO - PROCESS | 1664 | Values: 67.0 68.0 66.0 65.0 68.0 82.0 83.0 78.0 60.0 62.0 10:42:02 INFO - PROCESS | 1664 | -------- Summary: end -------- 10:42:02 INFO - PROCESS | 1664 | 10:42:03 INFO - TEST-INFO | 1664: exit 0 10:42:03 INFO - TEST-OK | kraken | took 59513ms 10:42:03 INFO - SUITE-END | took 1177s 10:42:03 INFO - Completed test suite (00:19:38) 10:42:03 INFO - javascript benchmark 10:42:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2883.466558686643, 3367.4443716786727, 3382.4926261060837, 3393.8472768725405, 3432.639572844851, 5563.972180139093, 5677.630953987988, 5695.259616249887, 5774.797882074128, 5780.393058728836, 5645.520130788877, 5691.402402747714, 5695.971520142404, 5710.257666503348, 5720.056190728525, 5139.306193663854, 5169.405518365398, 5196.58427325815, 5200.453952335007, 5259.289995850569, 5214.270002199695, 5219.373675158986, 5228.215767634866, 5277.102892508264, 5280.102382594949, 5241.39723931747, 5245.423003469616, 5272.209168624699, 5286.920696189561, 5288.788448462057, 4500.774961251925, 5277.7361131943635, 5280.392754833205, 5282.286891269668, 5289.86775330616, 5116.590672746172, 5127.0, 5158.174692049254, 5191.844244672666, 5207.973960130213, 5600.971995139998, 5605.943940560624, 5651.632643878176, 5653.886922261573, 5693.316801983747, 6175.62946223228, 6183.2889217740085, 6195.5663103582765, 6199.039148931923, 6203.596766210182, 5839.190930300796, 6135.312788983021, 6173.135760993465, 6233.750649973996, 6238.844028899269, 5810.448007439287, 5813.6221145625395, 5838.5621078418435, 5839.240898683144, 5845.035569131046, 5376.811811586654, 6216.129741836146, 6216.937830621636, 6224.719887605069, 6233.906491402633, 6064.3935606439, 6091.623519327842, 6112.0, 6129.1419201311855, 6138.633639632459, 6214.0, 6219.191505104308, 6226.221722284715, 6226.25284965807, 6233.906491402633, 6132.6933653317155, 6191.1641928339095, 6192.690365481708, 6193.783217587363, 6201.534884883562, 6097.25246653012, 6169.444749972435, 6232.470240029648, 6237.251529816451, 6249.78125765596, 4880.560749532559, 4916.237983112624, 4923.286123512071, 4927.926081108786, 4969.3539839821315, 4344.283193273074, 4405.273129933525, 4424.535423780521, 4425.3140763181755, 4464.129494748493, 5610.438956104357, 5677.120046392815, 5764.596478246483, 5776.393478684761, 5800.1299805029585, 5801.50687191585, 6136.427173609184, 6199.380061993855, 6205.751769929243, 6209.472194863397, 5742.109972954198, 5753.51095156908, 5760.423957604206, 5764.971175144097, 5788.797392091256, 5376.623636345418, 5400.891982160335, 5425.891482170335, 5435.619506634576, 5444.564434845203, 6180.165677633552, 6208.099825525183, 6210.72051757681, 6216.005439129717, 6227.346128656426, 6036.789629130346, 6199.194104766351, 6200.379962003944, 6211.3167551570195, 6215.378462153929, 5660.377358490645, 6229.408206220402, 6251.124842522141, 6296.779612713533, 6309.305976342507, 6035.155078289131, 6087.026075827846, 6190.628562286277, 6194.946859033885, 6199.0, 4825.372701548777, 6167.414095660905, 6209.875802483834, 6264.812055638338, 6265.8120256392385, 6082.2701275847185, 6092.045358001277, 6097.75608975636, 6098.115773212948, 6115.327313995546, 5928.140419639214, 6038.064100064497, 6041.0, 6076.635401875725, 6101.633901965896, 5896.439838215363, 5938.61399009063, 5991.490723288569, 6036.879262414639, 6122.326544080237, 5983.311919129356, 6090.147379366981, 6099.9390006099375, 6125.050617154348, 6126.0, 5854.414558544111, 5875.823725288248, 5937.168796368425, 5937.168796368598, 5961.701914904324], "value": 5666.239424386697, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [486.3312944701036, 486.3920099875156, 520.2820108250613, 530.1014779947988, 532.2654736463679, 28901.56647650284, 29586.40827183455, 30915.22711932197, 30956.0, 31009.37981240374, 33251.0, 33814.647414103405, 44195.0, 45752.627421177334, 45773.7711311443, 9772.706818795448, 9939.0, 9940.353876998008, 9942.502874856264, 9946.104850563448, 9827.705168844946, 9920.90079099207, 9925.75185620358, 9931.453770042644, 9931.602735890556, 8463.619137138843, 9847.65533206338, 9860.457674827881, 9891.90108098917, 10001.749956251078, 9808.558614862313, 9848.409095454295, 9874.605015799396, 9878.160356369717, 9887.456189909552, 9650.517474126336, 9736.685547451145, 9744.805103897954, 9746.366486178376, 9759.170470510015, 40989.590104098876, 41286.587134128575, 41293.380599291035, 41316.58683413157, 41328.0, 3331.3836940165907, 3378.6283508814013, 3393.355262500117, 3397.9830100849586, 3549.8935031948827, 31074.378512429852, 32399.676003239903, 32562.837185814155, 32570.674293257238, 32712.345753085043, 3175.841207939617, 3224.0, 3299.4555898276994, 3321.153105957965, 3376.18971446851, 481112.0, 482397.0, 483183.0, 483392.0, 483911.9999999965, 59085.70457147687, 59158.11262831061, 59548.0, 59605.403945960854, 59721.70139149277, 62156.0, 62792.372076279564, 62832.0, 62851.0, 62934.68532657399, 15767.684646307009, 15996.600084997735, 16015.919920400556, 16027.0, 16056.839431605767, 12356.62930112098, 12415.44130514129, 12450.439730212162, 12463.688407789878, 12487.93756031214, 15795.921020395055, 16362.836371636131, 16370.017798932102, 16384.918075409783, 16395.754063689055, 6620.3710647488415, 6633.2371777246235, 6856.520043597, 7222.458315626349, 7295.161056478573, 844.7888027993001, 857.4083882121316, 925.8240934222611, 1011.7723512209841, 1068.4764465412047, 29997.850010750244, 30980.0, 31005.0, 31048.22379440487, 31188.220294492818, 909.0, 966.21253678252, 1054.773223756896, 1123.7752449509967, 1134.1550544844144, 15154.939154259097, 15334.0, 15443.922780386027, 15612.687746245012, 15754.684906302038, 625135.0, 633423.0, 634220.0, 634722.0, 635014.0, 58077.709611451675, 58198.0, 58369.4163058364, 58379.70810145922, 58422.707886460295, 1268.0743057568027, 1314.9145305555107, 1387.50743486061, 1414.610981979943, 1440.819897512811, 25137.622935656345, 29693.5545966815, 30813.537796933513, 31680.683193167773, 31720.0, 50994.74502627463, 51667.483325166264, 51673.7416312916, 51706.74146629393, 51708.0, 509351.0, 510901.0, 511834.0, 512292.0, 513104.0, 23464.0, 26315.21054368372, 27059.729402706507, 27083.86458067776, 27915.581266280617, 47129.293060603435, 48010.51989480061, 48245.0, 48526.272105919146, 48667.75666121647, 31001.844990775804, 32852.014439566854, 35639.0, 35738.28523429569, 37059.62940370562, 38139.80930095332, 38259.42610860895, 38529.80735096418, 39948.40077398899, 40137.0, 38329.6167038326, 38418.231635367694, 39308.6069139305, 39542.802285989535, 41645.1670966585, 31929.61407718512, 32159.874404395734, 32464.56696830546, 35708.0, 35826.462603061496, 26232.737672623793, 26264.8686756565, 26412.471750565266, 26773.732262677902, 26813.508229991872], "value": 19669.953999999896, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [319.2195083022011, 352.8147722445717, 357.2729495476705, 375.56059410489723, 402.3200790663778, 15532.378704851806, 17389.913050434745, 20283.695744563844, 20775.584488310225, 20889.373318800423, 20392.89803550984, 20592.0, 20908.68636970443, 21118.155273789067, 21205.0, 5821.09772985188, 5833.300003999505, 6030.6080104793045, 6063.545234107438, 6070.119832624267, 5892.351841297454, 6055.788047418341, 6058.212432383784, 6060.909086363708, 6062.939370606282, 4156.521999969997, 6306.180196574439, 6561.311062338457, 6631.27056023837, 6693.832654183636, 6377.298497165308, 6566.0, 6633.86732265352, 6656.667166641672, 6673.299303573103, 5747.195392645033, 5749.080147176432, 5753.597248192628, 5757.942420575782, 5761.9135712964335, 24270.029198832024, 25261.0, 26482.47035059288, 26784.196474105807, 26997.46005079907, 1926.2391355414534, 2291.1293708390704, 2375.750546192642, 2390.5298241581477, 2462.876856157185, 10051.396916185053, 10113.342632728923, 10494.212934029978, 10512.421816800073, 12105.213161144586, 1878.1360574135913, 1879.52072221584, 2004.2383894120287, 2015.486051056971, 2053.8973051347375, 55315.72342138304, 72782.63608681975, 73575.89636155462, 88190.55904720436, 99923.99999999927, 10712.78574428507, 10913.0, 10922.945385273182, 11104.389258590694, 11210.103191744642, 13810.309484525733, 13818.792718109236, 13822.792658110136, 13835.239061851695, 13836.446542138201, 7877.527348359117, 8182.549959752152, 8189.590520473953, 8271.0, 8305.37709671777, 8081.757547273592, 8104.8379032419025, 8133.0, 8272.8345433091, 8303.792405189919, 3754.8685795997008, 4327.221100201994, 4335.653147748172, 4349.173657005159, 4420.911581768347, 1817.155022914351, 1861.8456556934516, 1862.577628151814, 1879.1731638079202, 1881.3509339277928, 700.4466471487569, 772.3743767548303, 777.8755399136111, 795.2802713544252, 858.3347905373386, 18203.27186912535, 20155.193792248145, 20934.37196884096, 21163.259285925225, 21288.25491107804, 842.5450256861349, 848.8811566380712, 850.3452341696859, 877.0484024833128, 889.6041261638638, 10814.243002989715, 10973.725656858642, 11026.0, 11053.889461105447, 11056.447177641086, 599915.0, 616527.0, 618631.0, 619108.9044554839, 620373.0, 127555.72444275438, 130750.0, 132137.0, 132318.0, 134917.0, 974.9853752193723, 977.1205914676848, 1021.6730646193296, 1039.194624166262, 1042.8696412948382, 15798.9210053949, 18738.625227495646, 18802.153903074668, 19442.0, 19640.901795491503, 57232.42767572271, 57388.71305643445, 57798.13302800377, 58036.709816450646, 58369.5407614813, 82291.17708823075, 82328.58835705783, 83816.58091709747, 84528.57735711528, 85821.0, 6958.756443524452, 7261.564306141649, 7360.815979600338, 7409.925560793762, 8037.357011439188, 8682.262007729414, 11122.443877805916, 12802.935985320013, 13002.869971300166, 13188.208707477583, 10888.346699197757, 11077.778444431227, 11343.659690209306, 11385.0, 11445.198836081394, 9552.617895284111, 10903.781924361627, 10927.235093543375, 11239.60661376848, 11424.772169426882, 11019.669409917715, 11153.051990580892, 11567.76864462723, 11675.649730508097, 11811.822822657496, 7883.369330453665, 7892.565908875067, 8130.430869839054, 8311.542865142475, 8316.043654979512, 10215.642452514126, 10930.56277748881, 10970.890291096986, 11366.0, 11534.250273732181], "value": 9403.820029202243, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1367.979480307795, 1511.05559025609, 1534.3862455017993, 1720.131333676493, 1888.1125870840708, 7732.613369331532, 8468.491890486575, 8791.604377802998, 8799.648014079437, 8926.2412694921, 8666.52334121623, 8726.607302671378, 8747.12528747125, 8747.562621868912, 8755.124487551242, 1822.2164469278239, 2070.9585808283823, 2141.41111194422, 2142.8500004999655, 2151.87088774674, 1955.6382069317222, 2396.1733202045266, 2396.4727759892794, 2398.640203969401, 2406.302172370011, 2318.93043208704, 2348.3307257431634, 2360.221127028085, 2366.2546297916188, 2372.857628542284, 2302.5625131225183, 2344.78896899279, 2346.6010778167665, 2350.9882450587806, 2394.5330660521204, 2396.916107936231, 2418.3349578865955, 2420.1771397724683, 2421.7941474974473, 2429.2105065853493, 8864.379493435463, 8917.955410222972, 8935.151160639729, 8940.552972351357, 8950.686725964624, 5186.559142472895, 5348.893022139574, 5360.3299587551555, 5363.168708850135, 5363.973180134113, 3477.6696213859896, 3503.7372197085065, 3664.615215402371, 3671.7429779915415, 3680.907977300562, 1083.685731137969, 1089.4824958144818, 1097.5554900265406, 1105.6682995101437, 1111.827666711661, 11522.827157592643, 11598.420078996101, 11608.187426880122, 11624.88375116255, 11629.651110466699, 4322.37325587788, 4378.912421751547, 4539.5460453955, 4540.818367265272, 4549.112922979987, 9083.500407477652, 9095.0, 9100.22648074921, 9102.089791020977, 9110.362274640844, 5135.435102138837, 5137.12668846297, 5141.922871156936, 5148.2020286855595, 5151.587872970228, 4094.5086589609436, 4330.35044743291, 4464.575865292792, 4809.519048095232, 4810.855674329776, 3316.7180789633153, 3721.181340105227, 3817.160224750606, 3835.175437280998, 3836.1368692044066, 1042.0, 1118.5693507999479, 1122.9606963756394, 1161.0943464098018, 1164.138537482257, 758.9772306830804, 836.7029704454952, 882.43965082172, 883.6597909804643, 898.8247291778041, 5499.890002199933, 5850.385709500526, 5980.13288073227, 5988.850278743066, 6070.332263451017, 934.0799312677008, 938.394735395666, 942.5758408716105, 962.6438217859437, 991.5438898106952, 3795.690486782056, 3796.430535419709, 3802.16352402471, 3866.7679939203736, 4006.399040144002, 9639.662611808553, 10855.185861060452, 10859.18556108295, 10898.509567069501, 10918.962698543626, 10855.620053298097, 10856.565737370574, 10857.45712714361, 10864.619738309122, 10893.019628233496, 7728.4590078693955, 8094.635741391651, 8265.669373225004, 8272.090070092285, 8280.8343833123, 5625.662460252398, 5856.795012174553, 5937.317208521075, 5937.435943585352, 5963.2844058713235, 7809.0, 9587.856182157413, 9608.567614457506, 9824.901750982684, 9898.455584942609, 8754.562271886252, 9877.345382208629, 10071.546780394754, 10119.898801011896, 10183.7454063649, 8155.755327340189, 8185.058718247477, 8358.623861926106, 8360.414770965974, 8362.498250105013, 8015.358771298399, 8134.389920755849, 8227.13615070409, 8228.341732661493, 8232.300254478436, 7328.3404493595835, 7376.262373762581, 7436.182019977907, 7460.29127232912, 7511.849763004819, 7301.671424786004, 7327.0, 7333.889991650023, 7393.667284972082, 7402.629868506446, 6776.661166941732, 6823.215330237086, 6885.965570172117, 6914.688839002157, 6924.965375173092, 4881.219004959189, 4886.804527818989, 4938.333325001079, 4940.481249468898, 5035.84892453227, 4835.540623640749, 4948.529889660476, 4969.105560999054, 5001.399832020181, 5009.998000399862], "value": 4516.786275527749, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [293.4629627781161, 298.56707773728095, 307.7922402378394, 310.0435157538992, 314.82684523512063, 3987.909370238067, 4611.838585649499, 5062.443131255559, 5204.609654275931, 5303.681779093257, 4670.392848929634, 4843.321934929113, 4919.212925931852, 5093.796248150079, 5113.974430127853, 3305.6529064448246, 3376.4766461198556, 3584.0143960410965, 3874.8956547384, 4002.959970400288, 3467.549218601578, 3968.464257325267, 4003.579624139467, 4016.196760647882, 4053.0, 3735.7772125585234, 3761.661450469457, 3815.4086116651965, 3958.44581758554, 3973.483447151866, 3937.940930886039, 3968.6825053995613, 3974.622410870977, 3984.5019372578427, 3999.820053084347, 2910.621619189492, 3455.7710276871276, 3463.5843698756057, 3480.756347055707, 3491.8952431427097, 4163.8959026024595, 4823.445303790074, 4993.101241776479, 5023.397192336906, 5107.744612769384, 3896.0, 4357.716748411343, 4471.971446567308, 4510.503844577127, 4643.9767801161115, 5376.569874410077, 5429.891402171974, 5444.482774136451, 5468.343798744137, 5473.808416705436, 3223.580934478526, 3816.313063648542, 3817.923641527154, 3826.330392181385, 3828.980855095707, 5448.863778405572, 5545.611807173499, 5606.21512988182, 5631.971840140855, 5728.42715728428, 3821.178446633987, 3826.1773718650065, 3837.597052309523, 3838.692904567628, 3841.769493830379, 3737.495438115874, 3914.8825535233987, 3954.8615798446917, 3971.384435412516, 3996.2407142643387, 4139.441175441337, 4497.190505708938, 4545.36364908913, 4561.201789686792, 4610.20202121173, 4494.4606647202545, 4559.772011399416, 5398.541124004425, 5404.513593776501, 5436.592255580847, 1214.3078445286103, 1238.4365113873166, 1294.825198598196, 1310.2531557012412, 1364.4883168811696, 840.9369297302727, 857.8241460500583, 863.7883718488885, 892.7277180460022, 895.7772640345881, 5021.0, 5026.421961474404, 5032.370953630796, 5049.26785616091, 5152.407473140637, 5502.889942201214, 5540.227127319219, 5542.334919809648, 5558.388577256498, 5572.275604171513, 4628.884277893079, 4635.420572428447, 4687.742174180453, 4998.225275082368, 4999.125677599889, 2849.017089104297, 2910.632002958606, 2928.4771918602632, 2943.867525961337, 2944.720251576097, 4597.471290801601, 5062.936923161435, 5345.289507357686, 5348.486211480598, 5561.833145005657, 5089.465606111379, 5354.571634269249, 5619.662820230799, 5745.511631511368, 5830.766769329264, 5680.630759000652, 5824.330202026652, 5932.0, 5940.970295148497, 5969.880602387928, 3893.8247778849454, 4824.782884770124, 5285.63000589955, 5635.239242702347, 5730.484256416942, 4344.196323680129, 5594.720263986703, 5645.858853528694, 5652.152177173457, 5659.405762394889, 5896.380880007538, 5898.351181370132, 5911.645301281937, 5913.526917846648, 5943.762249509971, 3556.1998550327003, 3795.823294778517, 3805.2389522095136, 3811.618838116166, 3821.2739579479808, 5613.859653508695, 5629.943700562942, 5630.943690563206, 5632.324121105494, 5654.349749778664, 5471.398146203835, 5479.671219726829, 5480.287562616834, 5481.671099734029, 5503.504684578407, 4612.400387949545, 5111.974440127776, 5457.808976685956, 5459.426760190282, 5552.833414997556, 5344.893102137858, 5356.892862142813, 5372.946270537244, 5374.704391258519, 5379.569634429158, 5188.912597004776, 5352.839414817562, 5412.621116521806, 5518.42056584069, 5527.281453411031, 5396.46035396473, 5396.625484786681, 5402.8379148624035, 5405.3513578369275, 5413.079776437936], "value": 4002.610324697258, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [714.6819665248964, 727.247299045488, 773.3967505345829, 815.5514467043125, 815.7634286057043, 14906.850931490695, 16314.347426102942, 16331.75502367464, 16355.01869887806, 16360.509184724448, 14598.635034124125, 14754.778678319833, 14768.187749673763, 14768.261586920638, 14808.481703140393, 687.4981263677519, 710.6730903784265, 712.9536580122301, 713.8679344321317, 832.7293629570398, 705.2876594639425, 708.5713143548156, 760.7831767946136, 841.2933136165619, 843.7468759372194, 881.1364862434817, 890.7149712092133, 892.4243862708549, 894.5661354243186, 898.5507246376811, 892.2906289499798, 893.3612467086065, 893.9553022348889, 894.0612357025097, 894.2667013049302, 668.0713807807152, 668.5620918298474, 668.6155460610169, 668.812732434919, 670.3598063849062, 11898.524059037629, 14501.564953051426, 14672.486462973744, 14796.112233266038, 15379.0, 14029.719405611831, 14500.564983050524, 14563.781543276858, 14643.853561464462, 14760.926195369062, 11907.642770716893, 11929.880701192964, 11987.520499180022, 12245.693857653541, 12300.986442007452, 3787.7508863575413, 3807.1814559869485, 3818.6754125899333, 3833.137544052594, 3934.1148241645687, 113821.43089284583, 116407.41796290965, 116467.0, 116595.0, 116794.0, 11154.609588664358, 11159.721006974729, 11170.329780213213, 11199.272047316897, 11620.244684095507, 23071.307860764206, 23126.65310020351, 23129.653055204184, 23148.0, 23153.0, 7390.408767298603, 8097.190280971973, 8125.3499720022255, 8147.103818579952, 8364.916350836535, 4477.888052798641, 4509.828408721919, 4522.276435770326, 4727.858164255009, 4730.195866702668, 3558.181618227822, 3676.0809797550614, 3678.3562876496508, 3683.9500742288856, 3692.7415080944616, 629.5467263570222, 662.8972509261072, 669.4343279928453, 672.2873753820966, 691.0774116554355, 643.7618081309945, 677.9966100169468, 691.9031335613018, 694.4617921111179, 712.9714811407589, 8405.285550728135, 9383.00650988449, 9448.48033358172, 9474.621015159455, 9903.752406189902, 641.2273210780997, 655.1300348874047, 666.1972323350351, 689.8196926624671, 694.9131358580178, 4573.862784116482, 4603.102395033004, 4619.353290539327, 4630.050839577879, 4751.714897106184, 154045.0, 157836.0, 160271.0, 166304.0, 166348.0, 136727.6327236715, 138601.0, 140338.0, 142689.0, 145150.0, 77900.0, 94177.0, 94430.0, 117944.41027794978, 118354.0, 8326.417150799385, 8945.46327220369, 9082.772930676785, 9146.862797058182, 9796.706098817047, 50642.0, 55821.720891395285, 56750.162491876064, 57820.241684290384, 62218.75562488634, 66137.33862661505, 83673.58163209145, 92774.0, 97970.530442042, 100935.49532252585, 13403.463861445432, 13411.865881341451, 13458.596242112751, 13594.048416610742, 14122.94077944179, 15224.771628425804, 15526.0, 16073.517794465717, 16157.67684646324, 16282.674346512766, 14413.567592972227, 14567.92716036413, 14716.117032978056, 14891.0, 14935.178565178585, 12803.71962803712, 13712.211614956166, 14362.425502979764, 14386.280685965869, 14692.853071469575, 12250.75498490043, 13260.469581216643, 13479.663008424868, 14108.506202282872, 14682.192479413734, 11498.13763967689, 11619.302841829518, 11646.184767066225, 11796.94101529487, 11997.880021199677, 11389.0, 11452.0, 11558.595449159238, 11636.941815291208, 11733.413329333329], "value": 7931.821912061706, "unit": "score"}], "name": "dromaeo_css", "value": 7291.763165683003, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 114.0, 112.0, 113.0, 113.0, 114.0, 113.0, 113.0, 113.0, 113.0], "value": 111.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 115.0, 108.0, 162.0, 103.0, 107.0, 130.0, 105.0, 141.0, 106.0], "value": 122.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 185.0, 185.0, 185.0, 183.0, 182.0, 179.0, 183.0, 184.0, 184.0], "value": 183.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 58.0, 57.0, 109.0, 58.0, 57.0, 63.0, 69.0, 84.0, 56.0], "value": 68.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 68.0, 95.0, 99.0, 96.0, 94.0, 97.0, 70.0, 68.0, 101.0], "value": 87.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 91.0, 87.0, 89.0, 87.0, 91.0, 128.0, 117.0, 86.0], "value": 95.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 96.0, 97.0, 97.0, 96.0, 96.0, 96.0, 95.0, 96.0], "value": 96.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 86.0, 86.0, 87.0, 85.0, 85.0, 93.0, 131.0, 91.0], "value": 91.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 53.0, 51.0, 58.0, 61.0, 73.0, 75.0, 72.0, 55.0], "value": 60.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 74.0, 74.0, 73.0, 75.0, 71.0, 69.0, 98.0, 96.0, 98.0], "value": 79.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 89.0, 83.0, 85.0, 99.0, 95.0, 84.0, 78.0, 84.0, 85.0], "value": 86.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 137.0, 139.0, 158.0, 130.0, 135.0, 141.0, 134.0, 135.0, 139.0], "value": 138.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [217.0, 180.0, 165.0, 165.0, 163.0, 165.0, 162.0, 166.0, 163.0, 161.0], "value": 170.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 66.0, 65.0, 68.0, 82.0, 83.0, 78.0, 60.0, 62.0], "value": 69.9, "unit": "score"}], "name": "kraken", "value": 1462.2000000000003, "alertThreshold": 2.0}]} 10:42:03 INFO - Return code: 0 10:42:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:42:03 INFO - # TBPL SUCCESS # 10:42:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:42:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:42:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:42:03 INFO - 2016-08-05 10:42:03.910 screenresolution[1668:10290] starting screenresolution argv=screenresolution get 10:42:03 INFO - 2016-08-05 10:42:03.913 screenresolution[1668:10290] Display 0: 1600x1200x32@60 10:42:03 INFO - 2016-08-05 10:42:03.919 screenresolution[1669:10293] starting screenresolution argv=screenresolution list 10:42:03 INFO - Available Modes on Display 0 10:42:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:42:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:42:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:42:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:42:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:42:04 INFO - Intel Iris: 10:42:04 INFO - Chipset Model: Intel Iris 10:42:04 INFO - Type: GPU 10:42:04 INFO - Bus: Built-In 10:42:04 INFO - VRAM (Dynamic, Max): 1536 MB 10:42:04 INFO - Vendor: Intel (0x8086) 10:42:04 INFO - Device ID: 0x0a2e 10:42:04 INFO - Revision ID: 0x0009 10:42:04 INFO - Displays: 10:42:04 INFO - Display: 10:42:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:42:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:42:04 INFO - Display Serial Number: 1600x1200 60 10:42:04 INFO - Main Display: Yes 10:42:04 INFO - Mirror: Off 10:42:04 INFO - Online: Yes 10:42:04 INFO - Rotation: Supported 10:42:04 INFO - Return code: 0 10:42:04 INFO - Running post-action listener: _resource_record_post_action 10:42:04 INFO - [mozharness: 2016-08-05 17:42:04.157954Z] Finished run-tests step (success) 10:42:04 INFO - Running post-run listener: _resource_record_post_run 10:42:04 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 31427584; Write bytes: 705761792; Read time: 194; Write time: 2016 10:42:04 INFO - TinderboxPrint: CPU usage
53.4% 10:42:04 INFO - TinderboxPrint: I/O read bytes / time
31,427,584 / 194 10:42:04 INFO - TinderboxPrint: I/O write bytes / time
705,761,792 / 2,016 10:42:04 INFO - TinderboxPrint: CPU idle
2,259.6 (46.6%) 10:42:04 INFO - TinderboxPrint: CPU system
965.2 (19.9%) 10:42:04 INFO - TinderboxPrint: CPU user
1,628.9 (33.6%) 10:42:04 INFO - TinderboxPrint: Swap in / out
431,763,456 / 0 10:42:04 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 387578368; Write bytes: 382934016; Read time: 29263; Write time: 833 10:42:04 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 30469120; Write bytes: 297559552; Read time: 182; Write time: 1123 10:42:04 INFO - Running post-run listener: _upload_blobber_files 10:42:04 INFO - Blob upload gear active. 10:42:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:42:04 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 10:42:04 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', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:42:04 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 Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:42:05 INFO - (blobuploader) - INFO - Open directory for files ... 10:42:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:42:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:42:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:42:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:42:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:42:05 INFO - (blobuploader) - INFO - Done attempting. 10:42:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:42:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:42:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:42:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:42:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:42:06 INFO - (blobuploader) - INFO - Done attempting. 10:42:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:42:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:42:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:42:09 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:42:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:42:09 INFO - (blobuploader) - INFO - Done attempting. 10:42:09 INFO - (blobuploader) - INFO - Iteration through files over. 10:42:09 INFO - Return code: 0 10:42:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:42:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:42:09 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/cc589682a7abc0739c6d45d30f9065e07dee0e22d338d03a9d75049891fc0cfa1f0934b2d45dca76b44861b7647adc1ec5629455541c66b543c7a52ebf153590", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/4af3d5a383d92c3b5da9fdc690bf171a060ce493b7f18af7af03317dce81cbfe7b6f4663847e0ca3702c8273d049a7fec88b36a841aa8ea1379d1063e02d7be2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c8c7ad1565e34cff2e59c75b2216b8242a992dec3b5042c8ec6fc5e6dd93dc7648e802b0db814a3ad58fed3686ade2b64783623c33166ec23315ef8664ececb7"} 10:42:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:42:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:42:09 INFO - Contents: 10:42:09 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/cc589682a7abc0739c6d45d30f9065e07dee0e22d338d03a9d75049891fc0cfa1f0934b2d45dca76b44861b7647adc1ec5629455541c66b543c7a52ebf153590", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/4af3d5a383d92c3b5da9fdc690bf171a060ce493b7f18af7af03317dce81cbfe7b6f4663847e0ca3702c8273d049a7fec88b36a841aa8ea1379d1063e02d7be2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c8c7ad1565e34cff2e59c75b2216b8242a992dec3b5042c8ec6fc5e6dd93dc7648e802b0db814a3ad58fed3686ade2b64783623c33166ec23315ef8664ececb7"} 10:42:09 INFO - Running post-run listener: copy_logs_to_upload_dir 10:42:09 INFO - Copying logs to upload dir... 10:42:09 INFO - mkdir: /builds/slave/test/build/upload/logs 10:42:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.682383 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-08-05 10:42:09.279309) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-05 10:42:09.282684) ========= 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.kWSnLWHwAp/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.1QLBIrZTaN/Listeners TMPDIR=/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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/Autoland/sha512/cc589682a7abc0739c6d45d30f9065e07dee0e22d338d03a9d75049891fc0cfa1f0934b2d45dca76b44861b7647adc1ec5629455541c66b543c7a52ebf153590", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/4af3d5a383d92c3b5da9fdc690bf171a060ce493b7f18af7af03317dce81cbfe7b6f4663847e0ca3702c8273d049a7fec88b36a841aa8ea1379d1063e02d7be2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c8c7ad1565e34cff2e59c75b2216b8242a992dec3b5042c8ec6fc5e6dd93dc7648e802b0db814a3ad58fed3686ade2b64783623c33166ec23315ef8664ececb7"} build_url:https://queue.taskcluster.net/v1/task/fX8evGAVS_2lxijLRJt0rA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010835 build_url: 'https://queue.taskcluster.net/v1/task/fX8evGAVS_2lxijLRJt0rA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/cc589682a7abc0739c6d45d30f9065e07dee0e22d338d03a9d75049891fc0cfa1f0934b2d45dca76b44861b7647adc1ec5629455541c66b543c7a52ebf153590", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/4af3d5a383d92c3b5da9fdc690bf171a060ce493b7f18af7af03317dce81cbfe7b6f4663847e0ca3702c8273d049a7fec88b36a841aa8ea1379d1063e02d7be2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c8c7ad1565e34cff2e59c75b2216b8242a992dec3b5042c8ec6fc5e6dd93dc7648e802b0db814a3ad58fed3686ade2b64783623c33166ec23315ef8664ececb7"}' ========= master_lag: 0.25 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-05 10:42:09.542756) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-05 10:42:09.543073) ========= 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.kWSnLWHwAp/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.1QLBIrZTaN/Listeners TMPDIR=/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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.005052 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-05 10:42:09.568202) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-05 10:42:09.568479) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-05 10:42:09.568768) ========= ========= Total master_lag: 0.38 =========