builder: autoland_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0330 starttime: 1469033927.01 results: success (0) buildid: 20160720075721 builduid: af066276d194494bb1d5ae9ae7c6d6d0 revision: c6d147ba37b52dd368bc213580e1bec9f3f71e0d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-20 09:58:47.012332) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-20 09:58:47.012753) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-20 09:58:47.013035) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-20 09:58:47.036360) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 09:58:47.036683) ========= 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.3ZRviOXtjo/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.mSW8exhuQj/Listeners TMPDIR=/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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-07-20 09:58:47-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.31M=0.001s 2016-07-20 09:58:47 (8.31 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.116902 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 09:58:47.175373) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 09:58:47.175677) ========= 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.3ZRviOXtjo/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.mSW8exhuQj/Listeners TMPDIR=/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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.019810 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 09:58:47.223477) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-20 09:58:47.223812) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev c6d147ba37b52dd368bc213580e1bec9f3f71e0d --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 c6d147ba37b52dd368bc213580e1bec9f3f71e0d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.3ZRviOXtjo/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.mSW8exhuQj/Listeners TMPDIR=/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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-07-20 09:58:47,304 truncating revision to first 12 chars 2016-07-20 09:58:47,304 Setting DEBUG logging. 2016-07-20 09:58:47,304 attempt 1/10 2016-07-20 09:58:47,304 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/c6d147ba37b5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-20 09:58:48,391 unpacking tar archive at: autoland-c6d147ba37b5/testing/mozharness/ program finished with exit code 0 elapsedTime=1.338925 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-20 09:58:48.584190) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-20 09:58:48.584529) ========= script_repo_revision: c6d147ba37b52dd368bc213580e1bec9f3f71e0d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-20 09:58:48.584917) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-20 09:58:48.585175) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-20 09:58:48.602715) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-07-20 09:58:48.603001) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.3ZRviOXtjo/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.mSW8exhuQj/Listeners TMPDIR=/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:58:48 INFO - MultiFileLogger online at 20160720 09:58:48 in /builds/slave/test 09:58:48 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 09:58:48 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:58:48 INFO - {'append_to_log': False, 09:58:48 INFO - 'base_work_dir': '/builds/slave/test', 09:58:48 INFO - 'blob_upload_branch': 'Autoland', 09:58:48 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:58:48 INFO - 'branch': 'Autoland', 09:58:48 INFO - 'buildbot_json_path': 'buildprops.json', 09:58:48 INFO - 'config_files': ('talos/mac_config.py',), 09:58:48 INFO - 'default_actions': ('clobber', 09:58:48 INFO - 'read-buildbot-config', 09:58:48 INFO - 'download-and-extract', 09:58:48 INFO - 'populate-webroot', 09:58:48 INFO - 'create-virtualenv', 09:58:48 INFO - 'install', 09:58:48 INFO - 'run-tests'), 09:58:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:58:48 INFO - 'download_minidump_stackwalk': True, 09:58:48 INFO - 'download_symbols': 'ondemand', 09:58:48 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:58:48 INFO - 'tooltool.py': '/tools/tooltool.py', 09:58:48 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:58:48 INFO - '/tools/misc-python/virtualenv.py')}, 09:58:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:58:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:58:48 INFO - 'installer_path': 'installer.exe', 09:58:48 INFO - 'log_level': 'info', 09:58:48 INFO - 'log_name': 'talos', 09:58:48 INFO - 'log_to_console': True, 09:58:48 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:58:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:58:48 INFO - 'opt_config_files': (), 09:58:48 INFO - 'pip_index': False, 09:58:48 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:58:48 INFO - 'cmd': ('bash', 09:58:48 INFO - '-c', 09:58:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:58:48 INFO - 'enabled': True, 09:58:48 INFO - 'halt_on_failure': False, 09:58:48 INFO - 'name': 'check_screen_resolution'},), 09:58:48 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:58:48 INFO - 'cmd': ('bash', 09:58:48 INFO - '-c', 09:58:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:58:48 INFO - 'enabled': True, 09:58:48 INFO - 'halt_on_failure': False, 09:58:48 INFO - 'name': 'check_screen_resolution'},), 09:58:48 INFO - 'run_cmd_checks_enabled': True, 09:58:48 INFO - 'sps_profile': False, 09:58:48 INFO - 'sps_profile_interval': 0, 09:58:48 INFO - 'suite': 'dromaeojs-e10s', 09:58:48 INFO - 'system_bits': '32', 09:58:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:58:48 INFO - 'title': 't-yosemite-r7-0330', 09:58:48 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:58:48 INFO - 'use_talos_json': True, 09:58:48 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:58:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:58:48 INFO - 'work_dir': 'build'} 09:58:48 INFO - [mozharness: 2016-07-20 16:58:48.791626Z] Running clobber step. 09:58:48 INFO - Running pre-action listener: _resource_record_pre_action 09:58:48 INFO - Running main action method: clobber 09:58:48 INFO - rmtree: /builds/slave/test/build 09:58:48 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:58:50 INFO - Running post-action listener: _resource_record_post_action 09:58:50 INFO - [mozharness: 2016-07-20 16:58:50.247588Z] Finished clobber step (success) 09:58:50 INFO - [mozharness: 2016-07-20 16:58:50.247758Z] Running read-buildbot-config step. 09:58:50 INFO - Running pre-action listener: _resource_record_pre_action 09:58:50 INFO - Running main action method: read_buildbot_config 09:58:50 INFO - Using buildbot properties: 09:58:50 INFO - { 09:58:50 INFO - "project": "", 09:58:50 INFO - "product": "firefox", 09:58:50 INFO - "script_repo_revision": "production", 09:58:50 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs-e10s", 09:58:50 INFO - "repository": "", 09:58:50 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs-e10s", 09:58:50 INFO - "buildid": "20160720075721", 09:58:50 INFO - "pgo_build": "False", 09:58:50 INFO - "basedir": "/builds/slave/test", 09:58:50 INFO - "buildnumber": 41, 09:58:50 INFO - "slavename": "t-yosemite-r7-0330", 09:58:50 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 09:58:50 INFO - "platform": "yosemite_r7", 09:58:50 INFO - "scheduler": "tests-autoland-macosx64-talos", 09:58:50 INFO - "branch": "autoland", 09:58:50 INFO - "repo_path": "integration/autoland", 09:58:50 INFO - "revision": "c6d147ba37b52dd368bc213580e1bec9f3f71e0d", 09:58:50 INFO - "stage_platform": "macosx64", 09:58:50 INFO - "builduid": "af066276d194494bb1d5ae9ae7c6d6d0", 09:58:50 INFO - "slavebuilddir": "test" 09:58:50 INFO - } 09:58:50 INFO - Found installer url https://queue.taskcluster.net/v1/task/Sqx39QfRRz6bcXB0zAAfkA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 09:58:50 INFO - Running post-action listener: _resource_record_post_action 09:58:50 INFO - [mozharness: 2016-07-20 16:58:50.258290Z] Finished read-buildbot-config step (success) 09:58:50 INFO - [mozharness: 2016-07-20 16:58:50.258474Z] Running download-and-extract step. 09:58:50 INFO - Running pre-action listener: _resource_record_pre_action 09:58:50 INFO - Running main action method: download_and_extract 09:58:50 INFO - mkdir: /builds/slave/test/build/tests 09:58:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:58:50 INFO - https://queue.taskcluster.net/v1/task/Sqx39QfRRz6bcXB0zAAfkA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 09:58:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Sqx39QfRRz6bcXB0zAAfkA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 09:58:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Sqx39QfRRz6bcXB0zAAfkA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 09:58:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Sqx39QfRRz6bcXB0zAAfkA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 09:58:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Sqx39QfRRz6bcXB0zAAfkA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 09:58:51 INFO - Downloaded 1199 bytes. 09:58:51 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 09:58:51 INFO - Using the following test package requirements: 09:58:51 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 09:58:51 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:58:51 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 09:58:51 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:58:51 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 09:58:51 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:58:51 INFO - u'jsshell-mac.zip'], 09:58:51 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:58:51 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 09:58:51 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 09:58:51 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:58:51 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 09:58:51 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:58:51 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 09:58:51 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:58:51 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 09:58:51 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:58:51 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 09:58:51 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:58:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:58:51 INFO - https://queue.taskcluster.net/v1/task/Sqx39QfRRz6bcXB0zAAfkA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:58:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Sqx39QfRRz6bcXB0zAAfkA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:58:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Sqx39QfRRz6bcXB0zAAfkA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:58:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Sqx39QfRRz6bcXB0zAAfkA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:58:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Sqx39QfRRz6bcXB0zAAfkA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:58:52 INFO - Downloaded 18800676 bytes. 09:58:52 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:58:52 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:58:55 INFO - Return code: 0 09:58:55 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:58:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:58:55 INFO - https://queue.taskcluster.net/v1/task/Sqx39QfRRz6bcXB0zAAfkA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:58:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Sqx39QfRRz6bcXB0zAAfkA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:58:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Sqx39QfRRz6bcXB0zAAfkA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:58:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Sqx39QfRRz6bcXB0zAAfkA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:58:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Sqx39QfRRz6bcXB0zAAfkA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:58:56 INFO - Downloaded 18800676 bytes. 09:58:56 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:58:56 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:59:00 INFO - Return code: 0 09:59:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:59:00 INFO - https://queue.taskcluster.net/v1/task/Sqx39QfRRz6bcXB0zAAfkA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 09:59:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Sqx39QfRRz6bcXB0zAAfkA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 09:59:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Sqx39QfRRz6bcXB0zAAfkA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 09:59:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Sqx39QfRRz6bcXB0zAAfkA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 09:59:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Sqx39QfRRz6bcXB0zAAfkA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:59:01 INFO - Downloaded 14403317 bytes. 09:59:01 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:59:01 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 09:59:01 INFO - Return code: 0 09:59:01 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:59:01 INFO - https://queue.taskcluster.net/v1/task/Sqx39QfRRz6bcXB0zAAfkA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 09:59:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Sqx39QfRRz6bcXB0zAAfkA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:59:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Sqx39QfRRz6bcXB0zAAfkA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:59:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Sqx39QfRRz6bcXB0zAAfkA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 09:59:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Sqx39QfRRz6bcXB0zAAfkA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 09:59:04 INFO - Downloaded 112854554 bytes. 09:59:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Sqx39QfRRz6bcXB0zAAfkA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:59:04 INFO - mkdir: /builds/slave/test/properties 09:59:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:59:04 INFO - Writing to file /builds/slave/test/properties/build_url 09:59:04 INFO - Contents: 09:59:04 INFO - build_url:https://queue.taskcluster.net/v1/task/Sqx39QfRRz6bcXB0zAAfkA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:59:05 INFO - Running post-action listener: _resource_record_post_action 09:59:05 INFO - Running post-action listener: set_extra_try_arguments 09:59:05 INFO - [mozharness: 2016-07-20 16:59:05.630349Z] Finished download-and-extract step (success) 09:59:05 INFO - [mozharness: 2016-07-20 16:59:05.630489Z] Running populate-webroot step. 09:59:05 INFO - Running pre-action listener: _resource_record_pre_action 09:59:05 INFO - Running main action method: populate_webroot 09:59:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:59:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:59:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:59:05 INFO - '--tppagecycles', 09:59:05 INFO - '7'], 09:59:05 INFO - 'tests': ['tsvgm']}}, 09:59:05 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:59:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:59:05 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:59:05 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:59:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:59:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:59:05 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:59:05 INFO - 'talos_options': ['--disable-e10s'], 09:59:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:59:05 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:59:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:59:05 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:59:05 INFO - 'talos_options': ['--disable-e10s'], 09:59:05 INFO - 'tests': ['damp', 'tps']}, 09:59:05 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:59:05 INFO - 'tests': ['damp', 'tps']}, 09:59:05 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:59:05 INFO - 'tests': ['dromaeo_dom']}, 09:59:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:59:05 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:59:05 INFO - 'tests': ['basic_compositor_video']}, 09:59:05 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:59:05 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:59:05 INFO - 'tests': ['a11yr', 09:59:05 INFO - 'ts_paint', 09:59:05 INFO - 'tpaint', 09:59:05 INFO - 'sessionrestore', 09:59:05 INFO - 'sessionrestore_no_auto_restore', 09:59:05 INFO - 'tabpaint']}, 09:59:05 INFO - 'other-e10s': {'tests': ['a11yr', 09:59:05 INFO - 'ts_paint', 09:59:05 INFO - 'tpaint', 09:59:05 INFO - 'sessionrestore', 09:59:05 INFO - 'sessionrestore_no_auto_restore', 09:59:05 INFO - 'tabpaint']}, 09:59:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:59:05 INFO - 'ts_paint', 09:59:05 INFO - 'tpaint', 09:59:05 INFO - 'sessionrestore', 09:59:05 INFO - 'sessionrestore_no_auto_restore', 09:59:05 INFO - 'tabpaint']}, 09:59:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:59:05 INFO - 'ts_paint', 09:59:05 INFO - 'tpaint', 09:59:05 INFO - 'sessionrestore', 09:59:05 INFO - 'sessionrestore_no_auto_restore', 09:59:05 INFO - 'tabpaint']}, 09:59:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:59:05 INFO - 'tests': ['a11yr', 09:59:05 INFO - 'ts_paint', 09:59:05 INFO - 'tpaint', 09:59:05 INFO - 'sessionrestore', 09:59:05 INFO - 'sessionrestore_no_auto_restore', 09:59:05 INFO - 'tabpaint']}, 09:59:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:59:05 INFO - 'tests': ['a11yr', 09:59:05 INFO - 'ts_paint', 09:59:05 INFO - 'tpaint', 09:59:05 INFO - 'sessionrestore', 09:59:05 INFO - 'sessionrestore_no_auto_restore', 09:59:05 INFO - 'tabpaint']}, 09:59:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:59:05 INFO - 'tests': ['tsvgx', 09:59:05 INFO - 'tsvgr_opacity', 09:59:05 INFO - 'tart', 09:59:05 INFO - 'tscrollx', 09:59:05 INFO - 'cart']}, 09:59:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:59:05 INFO - 'tsvgr_opacity', 09:59:05 INFO - 'tart', 09:59:05 INFO - 'tscrollx', 09:59:05 INFO - 'cart']}, 09:59:05 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:59:05 INFO - 'talos_options': ['--disable-e10s'], 09:59:05 INFO - 'tests': ['tp5o']}, 09:59:05 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:59:05 INFO - 'tests': ['tp5o']}, 09:59:05 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:59:05 INFO - 'talos_options': ['--disable-e10s', 09:59:05 INFO - '--xperf_path', 09:59:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:59:05 INFO - 'tests': ['tp5n']}, 09:59:05 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:59:05 INFO - 'talos_options': ['--xperf_path', 09:59:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:59:05 INFO - 'tests': ['tp5n']}}, 09:59:05 INFO - 'talos.zip': {'path': '', 09:59:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:59:05 INFO - Running post-action listener: _resource_record_post_action 09:59:05 INFO - [mozharness: 2016-07-20 16:59:05.648624Z] Finished populate-webroot step (success) 09:59:05 INFO - [mozharness: 2016-07-20 16:59:05.648729Z] Running create-virtualenv step. 09:59:05 INFO - Running pre-action listener: _resource_record_pre_action 09:59:05 INFO - Running main action method: create_virtualenv 09:59:05 INFO - Creating virtualenv /builds/slave/test/build/venv 09:59:05 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:59:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:59:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:59:05 INFO - Using real prefix '/tools/python27' 09:59:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:59:06 INFO - Installing distribute.............................................................................................................................................................................................done. 09:59:09 INFO - Installing pip.................done. 09:59:09 INFO - Return code: 0 09:59:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:59:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:59:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:59:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104065a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040fa618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbbb1c6e5c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103fc9d30>, '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 0x103d2edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103fc1b20>, '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.3ZRviOXtjo/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.mSW8exhuQj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:59:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:59:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:59:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3ZRviOXtjo/Render', 09:59:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:59:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:59:09 INFO - 'HOME': '/Users/cltbld', 09:59:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:59:09 INFO - 'LOGNAME': 'cltbld', 09:59:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:09 INFO - 'MOZ_NO_REMOTE': '1', 09:59:09 INFO - 'NO_EM_RESTART': '1', 09:59:09 INFO - 'PAGER': '/bin/cat', 09:59:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:59:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:09 INFO - 'PWD': '/builds/slave/test', 09:59:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:59:09 INFO - 'SHELL': '/bin/bash', 09:59:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mSW8exhuQj/Listeners', 09:59:09 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 09:59:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:59:09 INFO - 'USER': 'cltbld', 09:59:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:59:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:59:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:09 INFO - 'XPC_FLAGS': '0x0', 09:59:09 INFO - 'XPC_SERVICE_NAME': '0', 09:59:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:59:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:09 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:59:09 INFO - Cleaning up... 09:59:09 INFO - Return code: 0 09:59:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:59:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:59:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:59:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x104065a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040fa618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbbb1c6e5c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103fc9d30>, '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 0x103d2edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103fc1b20>, '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.3ZRviOXtjo/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.mSW8exhuQj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:59:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:59:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:59:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3ZRviOXtjo/Render', 09:59:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:59:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:59:09 INFO - 'HOME': '/Users/cltbld', 09:59:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:59:09 INFO - 'LOGNAME': 'cltbld', 09:59:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:09 INFO - 'MOZ_NO_REMOTE': '1', 09:59:09 INFO - 'NO_EM_RESTART': '1', 09:59:09 INFO - 'PAGER': '/bin/cat', 09:59:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:59:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:09 INFO - 'PWD': '/builds/slave/test', 09:59:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:59:09 INFO - 'SHELL': '/bin/bash', 09:59:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mSW8exhuQj/Listeners', 09:59:09 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 09:59:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:59:09 INFO - 'USER': 'cltbld', 09:59:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:59:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:59:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:09 INFO - 'XPC_FLAGS': '0x0', 09:59:09 INFO - 'XPC_SERVICE_NAME': '0', 09:59:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:59:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:09 INFO - Downloading/unpacking psutil>=3.1.1 09:59:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:59:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:59:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:59:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:59:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:59:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:59:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:59:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:59:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:59:12 INFO - Installing collected packages: psutil 09:59:12 INFO - Running setup.py install for psutil 09:59:12 INFO - building 'psutil._psutil_osx' extension 09:59:12 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:59:12 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:59:12 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:59:13 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:59:13 INFO - building 'psutil._psutil_posix' extension 09:59:13 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:59:13 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:59:13 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:59:13 INFO - ^ 09:59:13 INFO - 1 warning generated. 09:59:13 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:59:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:59:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:59:13 INFO - Successfully installed psutil 09:59:13 INFO - Cleaning up... 09:59:13 INFO - Return code: 0 09:59:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:59:13 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:59:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:13 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:59:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:13 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 0x104065a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040fa618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbbb1c6e5c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103fc9d30>, '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 0x103d2edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103fc1b20>, '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.3ZRviOXtjo/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.mSW8exhuQj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:59:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:59:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:59:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3ZRviOXtjo/Render', 09:59:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:59:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:59:13 INFO - 'HOME': '/Users/cltbld', 09:59:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:59:13 INFO - 'LOGNAME': 'cltbld', 09:59:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:13 INFO - 'MOZ_NO_REMOTE': '1', 09:59:13 INFO - 'NO_EM_RESTART': '1', 09:59:13 INFO - 'PAGER': '/bin/cat', 09:59:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:59:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:13 INFO - 'PWD': '/builds/slave/test', 09:59:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:59:13 INFO - 'SHELL': '/bin/bash', 09:59:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mSW8exhuQj/Listeners', 09:59:13 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 09:59:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:59:13 INFO - 'USER': 'cltbld', 09:59:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:59:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:59:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:13 INFO - 'XPC_FLAGS': '0x0', 09:59:13 INFO - 'XPC_SERVICE_NAME': '0', 09:59:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:59:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:13 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:59:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:59:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:59:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:59:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:59:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:59:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:59:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:59:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:59:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:59:16 INFO - Installing collected packages: mozsystemmonitor 09:59:16 INFO - Running setup.py install for mozsystemmonitor 09:59:16 INFO - Successfully installed mozsystemmonitor 09:59:16 INFO - Cleaning up... 09:59:16 INFO - Return code: 0 09:59:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:59:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:59:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:59:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104065a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040fa618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbbb1c6e5c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103fc9d30>, '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 0x103d2edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103fc1b20>, '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.3ZRviOXtjo/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.mSW8exhuQj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:59:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:59:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:59:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3ZRviOXtjo/Render', 09:59:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:59:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:59:16 INFO - 'HOME': '/Users/cltbld', 09:59:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:59:16 INFO - 'LOGNAME': 'cltbld', 09:59:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:16 INFO - 'MOZ_NO_REMOTE': '1', 09:59:16 INFO - 'NO_EM_RESTART': '1', 09:59:16 INFO - 'PAGER': '/bin/cat', 09:59:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:59:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:16 INFO - 'PWD': '/builds/slave/test', 09:59:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:59:16 INFO - 'SHELL': '/bin/bash', 09:59:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mSW8exhuQj/Listeners', 09:59:16 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 09:59:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:59:16 INFO - 'USER': 'cltbld', 09:59:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:59:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:59:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:16 INFO - 'XPC_FLAGS': '0x0', 09:59:16 INFO - 'XPC_SERVICE_NAME': '0', 09:59:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:59:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:16 INFO - Downloading/unpacking jsonschema==2.5.1 09:59:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:59:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:59:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:59:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:59:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:59:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:59:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:59:19 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:59:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:59:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:59:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:59:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:59:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:59:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:59:19 INFO - Downloading functools32-3.2.3-2.tar.gz 09:59:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:59:19 INFO - warning: no files found matching '*.txt' 09:59:19 INFO - no previously-included directories found matching 'build' 09:59:19 INFO - no previously-included directories found matching 'dist' 09:59:19 INFO - no previously-included directories found matching '.git*' 09:59:19 INFO - Installing collected packages: jsonschema, functools32 09:59:19 INFO - Running setup.py install for functools32 09:59:19 INFO - warning: no files found matching '*.txt' 09:59:19 INFO - no previously-included directories found matching 'build' 09:59:19 INFO - no previously-included directories found matching 'dist' 09:59:19 INFO - no previously-included directories found matching '.git*' 09:59:19 INFO - Successfully installed jsonschema functools32 09:59:19 INFO - Cleaning up... 09:59:19 INFO - Return code: 0 09:59:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:59:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:59:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:59:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104065a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040fa618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbbb1c6e5c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103fc9d30>, '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 0x103d2edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103fc1b20>, '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.3ZRviOXtjo/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.mSW8exhuQj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:59:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:59:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:59:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3ZRviOXtjo/Render', 09:59:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:59:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:59:19 INFO - 'HOME': '/Users/cltbld', 09:59:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:59:19 INFO - 'LOGNAME': 'cltbld', 09:59:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:19 INFO - 'MOZ_NO_REMOTE': '1', 09:59:19 INFO - 'NO_EM_RESTART': '1', 09:59:19 INFO - 'PAGER': '/bin/cat', 09:59:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:59:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:19 INFO - 'PWD': '/builds/slave/test', 09:59:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:59:19 INFO - 'SHELL': '/bin/bash', 09:59:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mSW8exhuQj/Listeners', 09:59:19 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 09:59:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:59:19 INFO - 'USER': 'cltbld', 09:59:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:59:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:59:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:19 INFO - 'XPC_FLAGS': '0x0', 09:59:19 INFO - 'XPC_SERVICE_NAME': '0', 09:59:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:59:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:20 INFO - Downloading/unpacking blobuploader==1.2.4 09:59:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:59:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:59:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:59:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:59:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:59:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:59:22 INFO - Downloading blobuploader-1.2.4.tar.gz 09:59:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:59:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:59:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:59:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:59:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:59:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:59:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:59:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:59:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:59:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:59: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 09:59: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 09:59: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 09:59: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 09:59: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 09:59: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 09:59:23 INFO - Downloading docopt-0.6.1.tar.gz 09:59:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:59:23 INFO - Installing collected packages: blobuploader, requests, docopt 09:59:23 INFO - Running setup.py install for blobuploader 09:59:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:59:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:59:24 INFO - Running setup.py install for requests 09:59:24 INFO - Running setup.py install for docopt 09:59:24 INFO - Successfully installed blobuploader requests docopt 09:59:24 INFO - Cleaning up... 09:59:24 INFO - Return code: 0 09:59:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:59:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:59:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:59:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104065a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040fa618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbbb1c6e5c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103fc9d30>, '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 0x103d2edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103fc1b20>, '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.3ZRviOXtjo/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.mSW8exhuQj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:59:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:59:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:59:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3ZRviOXtjo/Render', 09:59:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:59:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:59:24 INFO - 'HOME': '/Users/cltbld', 09:59:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:59:24 INFO - 'LOGNAME': 'cltbld', 09:59:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:24 INFO - 'MOZ_NO_REMOTE': '1', 09:59:24 INFO - 'NO_EM_RESTART': '1', 09:59:24 INFO - 'PAGER': '/bin/cat', 09:59:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:59:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:24 INFO - 'PWD': '/builds/slave/test', 09:59:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:59:24 INFO - 'SHELL': '/bin/bash', 09:59:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mSW8exhuQj/Listeners', 09:59:24 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 09:59:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:59:24 INFO - 'USER': 'cltbld', 09:59:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:59:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:59:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:24 INFO - 'XPC_FLAGS': '0x0', 09:59:24 INFO - 'XPC_SERVICE_NAME': '0', 09:59:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:59:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:59:25 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-FjM8vh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:59:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:59:25 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-SyLdaY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:59:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:59:25 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-9oQ4Ee-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:59:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:59:25 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-w7Xix7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:59:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:59:25 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-d5ZFRQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:59:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:59:25 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-SKJETq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:59:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:59:25 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-dxiBaS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:59:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:59:25 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-NOJlm7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:59:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:59:25 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-gAjg6Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:59:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:59:25 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-mFKkMg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:59:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:59:26 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-vlgseJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:59:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:59:26 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-4t9VdG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:59:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:59:26 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-aiqr0Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:59:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:59:26 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-WQzOcV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:59:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:59:26 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-xp4B9c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:59:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:59:26 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-IDlxjE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:59:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:59:26 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-P7HSyr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:59:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:59:26 INFO - Running setup.py install for manifestparser 09:59:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:59:26 INFO - Running setup.py install for mozcrash 09:59:27 INFO - Running setup.py install for mozdebug 09:59:27 INFO - Running setup.py install for mozdevice 09:59:27 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:59:27 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:59:27 INFO - Running setup.py install for mozfile 09:59:27 INFO - Running setup.py install for mozhttpd 09:59:27 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:59:27 INFO - Running setup.py install for mozinfo 09:59:27 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:59:27 INFO - Running setup.py install for mozInstall 09:59:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:59:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:59:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:59:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:59:27 INFO - Running setup.py install for mozleak 09:59:28 INFO - Running setup.py install for mozlog 09:59:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:59:28 INFO - Running setup.py install for moznetwork 09:59:28 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:59:28 INFO - Running setup.py install for mozprocess 09:59:28 INFO - Running setup.py install for mozprofile 09:59:28 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:59:28 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:59:28 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:59:28 INFO - Running setup.py install for mozrunner 09:59:28 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:59:28 INFO - Running setup.py install for mozscreenshot 09:59:29 INFO - Running setup.py install for moztest 09:59:29 INFO - Running setup.py install for mozversion 09:59:29 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:59:29 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:59:29 INFO - Cleaning up... 09:59:29 INFO - Return code: 0 09:59:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:59:29 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:59:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:29 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:59:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:29 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 0x104065a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040fa618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbbb1c6e5c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103fc9d30>, '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 0x103d2edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103fc1b20>, '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.3ZRviOXtjo/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.mSW8exhuQj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:59:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:59:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:59:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3ZRviOXtjo/Render', 09:59:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:59:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:59:29 INFO - 'HOME': '/Users/cltbld', 09:59:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:59:29 INFO - 'LOGNAME': 'cltbld', 09:59:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:29 INFO - 'MOZ_NO_REMOTE': '1', 09:59:29 INFO - 'NO_EM_RESTART': '1', 09:59:29 INFO - 'PAGER': '/bin/cat', 09:59:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:59:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:29 INFO - 'PWD': '/builds/slave/test', 09:59:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:59:29 INFO - 'SHELL': '/bin/bash', 09:59:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mSW8exhuQj/Listeners', 09:59:29 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 09:59:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:59:29 INFO - 'USER': 'cltbld', 09:59:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:59:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:59:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:29 INFO - 'XPC_FLAGS': '0x0', 09:59:29 INFO - 'XPC_SERVICE_NAME': '0', 09:59:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:59:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:59:29 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-ni8L_4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:59:29 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:59:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:59:29 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-1cFRCU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:59:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:59:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:59:29 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-MAViOT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:59:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:59:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:59:29 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-GS3T6j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:59:30 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)) 09:59:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:59:30 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-naMa91-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:59:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:59:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:59:30 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-9YyRf1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:59:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:59:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:59:30 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-T2cvW0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:59:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:59:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:59:30 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-tjTNuZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:59:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:59:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:59:30 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-plCoTn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:59:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:59:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:59:30 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-1WDixK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:59:30 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)) 09:59:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:59:30 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-ymP88S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:59:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:59:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:59:30 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-wdsTuy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:59:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:59:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:59:30 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-MHChrZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:59:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:59:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:59:31 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-_bburN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:59:31 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)) 09:59:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:59:31 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-JxW8G_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:59:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:59:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:59:31 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-x8W2P7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:59:31 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)) 09:59:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:59:31 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-p1BiaK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:59:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:59:31 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)) 09:59:31 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)) 09:59:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:59:31 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)) 09:59:31 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)) 09:59:31 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:59: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 09:59: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 09:59: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 09:59: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 09:59: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 09:59: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 09:59:33 INFO - Downloading blessings-1.6.tar.gz 09:59:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:59:33 INFO - Installing collected packages: blessings 09:59:33 INFO - Running setup.py install for blessings 09:59:34 INFO - Successfully installed blessings 09:59:34 INFO - Cleaning up... 09:59:34 INFO - Return code: 0 09:59:34 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:59:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:59:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:59:34 INFO - Reading from file tmpfile_stdout 09:59:34 INFO - Current package versions: 09:59:34 INFO - blessings == 1.6 09:59:34 INFO - blobuploader == 1.2.4 09:59:34 INFO - docopt == 0.6.1 09:59:34 INFO - functools32 == 3.2.3-2 09:59:34 INFO - jsonschema == 2.5.1 09:59:34 INFO - manifestparser == 1.1 09:59:34 INFO - mozInstall == 1.12 09:59:34 INFO - mozcrash == 0.17 09:59:34 INFO - mozdebug == 0.1 09:59:34 INFO - mozdevice == 0.48 09:59:34 INFO - mozfile == 1.2 09:59:34 INFO - mozhttpd == 0.7 09:59:34 INFO - mozinfo == 0.9 09:59:34 INFO - mozleak == 0.1 09:59:34 INFO - mozlog == 3.3 09:59:34 INFO - moznetwork == 0.27 09:59:34 INFO - mozprocess == 0.23 09:59:34 INFO - mozprofile == 0.28 09:59:34 INFO - mozrunner == 6.12 09:59:34 INFO - mozscreenshot == 0.1 09:59:34 INFO - mozsystemmonitor == 0.3 09:59:34 INFO - moztest == 0.7 09:59:34 INFO - mozversion == 1.4 09:59:34 INFO - psutil == 3.1.1 09:59:34 INFO - requests == 1.2.3 09:59:34 INFO - wsgiref == 0.1.2 09:59:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:59: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:59:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:59:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:34 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 0x104065a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040fa618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbbb1c6e5c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103fc9d30>, '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 0x103d2edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103fc1b20>, '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.3ZRviOXtjo/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.mSW8exhuQj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:59:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:59:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:59:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3ZRviOXtjo/Render', 09:59:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:59:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:59:34 INFO - 'HOME': '/Users/cltbld', 09:59:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:59:34 INFO - 'LOGNAME': 'cltbld', 09:59:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:34 INFO - 'MOZ_NO_REMOTE': '1', 09:59:34 INFO - 'NO_EM_RESTART': '1', 09:59:34 INFO - 'PAGER': '/bin/cat', 09:59:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:59:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:34 INFO - 'PWD': '/builds/slave/test', 09:59:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:59:34 INFO - 'SHELL': '/bin/bash', 09:59:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mSW8exhuQj/Listeners', 09:59:34 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 09:59:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:59:34 INFO - 'USER': 'cltbld', 09:59:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:59:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:59:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:34 INFO - 'XPC_FLAGS': '0x0', 09:59:34 INFO - 'XPC_SERVICE_NAME': '0', 09:59:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:59:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:59:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:59:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:59:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:59:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:59:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:59:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:59:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:59:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:59:34 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:59:34 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:59:34 INFO - Cleaning up... 09:59:34 INFO - Return code: 0 09:59:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:59: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:59:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:59:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104065a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040fa618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbbb1c6e5c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103fc9d30>, '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 0x103d2edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103fc1b20>, '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.3ZRviOXtjo/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.mSW8exhuQj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:59:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:59:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:59:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3ZRviOXtjo/Render', 09:59:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:59:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:59:34 INFO - 'HOME': '/Users/cltbld', 09:59:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:59:34 INFO - 'LOGNAME': 'cltbld', 09:59:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:34 INFO - 'MOZ_NO_REMOTE': '1', 09:59:34 INFO - 'NO_EM_RESTART': '1', 09:59:34 INFO - 'PAGER': '/bin/cat', 09:59:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:59:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:34 INFO - 'PWD': '/builds/slave/test', 09:59:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:59:34 INFO - 'SHELL': '/bin/bash', 09:59:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mSW8exhuQj/Listeners', 09:59:34 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 09:59:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:59:34 INFO - 'USER': 'cltbld', 09:59:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:59:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:59:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:34 INFO - 'XPC_FLAGS': '0x0', 09:59:34 INFO - 'XPC_SERVICE_NAME': '0', 09:59:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:59:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:34 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:59:34 INFO - Cleaning up... 09:59:34 INFO - Return code: 0 09:59:34 INFO - Running post-action listener: _resource_record_post_action 09:59:34 INFO - Running post-action listener: _start_resource_monitoring 09:59:35 INFO - Starting resource monitoring. 09:59:35 INFO - [mozharness: 2016-07-20 16:59:35.017142Z] Finished create-virtualenv step (success) 09:59:35 INFO - [mozharness: 2016-07-20 16:59:35.018079Z] Running install step. 09:59:35 INFO - Running pre-action listener: _resource_record_pre_action 09:59:35 INFO - Running main action method: install 09:59:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:59:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:59:35 INFO - Reading from file tmpfile_stdout 09:59:35 INFO - Detecting whether we're running mozinstall >=1.0... 09:59:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:59:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:59:35 INFO - Reading from file tmpfile_stdout 09:59:35 INFO - Output received: 09:59:35 INFO - Usage: mozinstall [options] installer 09:59:35 INFO - Options: 09:59:35 INFO - -h, --help show this help message and exit 09:59:35 INFO - -d DEST, --destination=DEST 09:59:35 INFO - Directory to install application into. [default: 09:59:35 INFO - "/builds/slave/test"] 09:59:35 INFO - --app=APP Application being installed. [default: firefox] 09:59:35 INFO - mkdir: /builds/slave/test/build/application 09:59:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:59:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 10:00:10 INFO - Reading from file tmpfile_stdout 10:00:10 INFO - Output received: 10:00:10 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:00:10 INFO - Running post-action listener: _resource_record_post_action 10:00:10 INFO - [mozharness: 2016-07-20 17:00:10.013018Z] Finished install step (success) 10:00:10 INFO - [mozharness: 2016-07-20 17:00:10.013164Z] Running run-tests step. 10:00:10 INFO - Running pre-action listener: _resource_record_pre_action 10:00:10 INFO - Running main action method: run_tests 10:00:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:00:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:00:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:00:10 INFO - 2016-07-20 10:00:10.027 screenresolution[2226:17300] starting screenresolution argv=screenresolution get 10:00:10 INFO - 2016-07-20 10:00:10.030 screenresolution[2226:17300] Display 0: 1600x1200x32@60 10:00:10 INFO - 2016-07-20 10:00:10.036 screenresolution[2227:17302] starting screenresolution argv=screenresolution list 10:00:10 INFO - Available Modes on Display 0 10:00:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:00:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:00:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:00:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:00:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:00:10 INFO - Intel Iris: 10:00:10 INFO - Chipset Model: Intel Iris 10:00:10 INFO - Type: GPU 10:00:10 INFO - Bus: Built-In 10:00:10 INFO - VRAM (Dynamic, Max): 1536 MB 10:00:10 INFO - Vendor: Intel (0x8086) 10:00:10 INFO - Device ID: 0x0a2e 10:00:10 INFO - Revision ID: 0x0009 10:00:10 INFO - Displays: 10:00:10 INFO - Display: 10:00:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:00:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:00:10 INFO - Display Serial Number: 1600x1200 60 10:00:10 INFO - Main Display: Yes 10:00:10 INFO - Mirror: Off 10:00:10 INFO - Online: Yes 10:00:10 INFO - Rotation: Supported 10:00:10 INFO - Return code: 0 10:00:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:00:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:00:10 INFO - Python 2.7.3 10:00:10 INFO - Return code: 0 10:00:10 INFO - grabbing minidump binary from tooltool 10:00: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:00:10 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 0x103fc9d30>, '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 0x103d2edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103fc1b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:00:10 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:00:10 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:00:10 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:00:10 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:00:10 INFO - Return code: 0 10:00:10 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:00:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:00:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:00:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:00:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:00:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Sqx39QfRRz6bcXB0zAAfkA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0330', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 10:00:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Sqx39QfRRz6bcXB0zAAfkA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0330 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 10:00:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3ZRviOXtjo/Render', 10:00:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:00:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:00:10 INFO - 'HOME': '/Users/cltbld', 10:00:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:00:10 INFO - 'LOGNAME': 'cltbld', 10:00:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:00:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:00:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:00:10 INFO - 'MOZ_NO_REMOTE': '1', 10:00:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:00:10 INFO - 'NO_EM_RESTART': '1', 10:00:10 INFO - 'PAGER': '/bin/cat', 10:00:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:00:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:00:10 INFO - 'PWD': '/builds/slave/test', 10:00:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:00:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:00:10 INFO - 'SHELL': '/bin/bash', 10:00:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mSW8exhuQj/Listeners', 10:00:10 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 10:00:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:00:10 INFO - 'USER': 'cltbld', 10:00:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:00:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:00:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:00:10 INFO - 'XPC_FLAGS': '0x0', 10:00:10 INFO - 'XPC_SERVICE_NAME': '0', 10:00:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:00:10 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Sqx39QfRRz6bcXB0zAAfkA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0330', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 10:00:10 INFO - mozversion application_buildid: 20160720075721 10:00:10 INFO - mozversion application_changeset: c6d147ba37b52dd368bc213580e1bec9f3f71e0d 10:00:10 INFO - mozversion application_display_name: Nightly 10:00:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:00:10 INFO - mozversion application_name: Firefox 10:00:10 INFO - mozversion application_remotingname: firefox 10:00:10 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 10:00:10 INFO - mozversion application_vendor: Mozilla 10:00:10 INFO - mozversion application_version: 50.0a1 10:00:10 INFO - mozversion platform_buildid: 20160720075721 10:00:10 INFO - mozversion platform_changeset: c6d147ba37b52dd368bc213580e1bec9f3f71e0d 10:00:10 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 10:00:10 INFO - mozversion platform_version: 50.0a1 10:00:10 INFO - using testdate: 1469034010 10:00:10 INFO - actual date: 1469034010 10:00:10 INFO - starting webserver on 'localhost:54930' 10:00:10 INFO - SUITE-START | Running 2 tests 10:00:10 INFO - TEST-START | dromaeo_css 10:00:10 INFO - operating with platform_type : mac_ 10:00:10 INFO - Initialising browser for dromaeo_css test... 10:00:10 INFO - TEST-INFO | started process 2237 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/tmphmmSPp/profile http://localhost:54930/getInfo.html) 10:00:13 INFO - PROCESS | 2237 | __metrics Screen width/height:1600/1200 10:00:13 INFO - PROCESS | 2237 | colorDepth:24 10:00:13 INFO - PROCESS | 2237 | Browser inner width/height: 1024/689 10:00:13 INFO - PROCESS | 2237 | __metrics 10:00:13 ERROR - PROCESS | 2237 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 10:00:14 INFO - TEST-INFO | 2237: exit 0 10:00:14 INFO - Browser initialized. 10:00:14 INFO - Running cycle 1/1 for dromaeo_css test... 10:00:14 INFO - TEST-INFO | started process 2240 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/tmphmmSPp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:00:26 INFO - PROCESS | 2240 | RSS: Main: 255021056 10:00:26 INFO - PROCESS | 2240 | 10:01:15 INFO - PROCESS | 2240 | 1469034075745 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:03:27 INFO - PROCESS | 2240 | Cycle 1(1): loaded http://localhost:54930/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54930/tests/dromaeo/cssquery-ext.html) 10:03:28 INFO - PROCESS | 2240 | RSS: Main: 267681792 10:03:28 INFO - PROCESS | 2240 | 10:06:31 INFO - PROCESS | 2240 | Cycle 1(1): loaded http://localhost:54930/tests/dromaeo/cssquery-ext.html (next: http://localhost:54930/tests/dromaeo/cssquery-jquery.html) 10:06:31 INFO - PROCESS | 2240 | RSS: Main: 276414464 10:06:31 INFO - PROCESS | 2240 | 10:09:33 INFO - PROCESS | 2240 | Cycle 1(1): loaded http://localhost:54930/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54930/tests/dromaeo/cssquery-mootools.html) 10:09:34 INFO - PROCESS | 2240 | RSS: Main: 280989696 10:09:34 INFO - PROCESS | 2240 | 10:12:36 INFO - PROCESS | 2240 | Cycle 1(1): loaded http://localhost:54930/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54930/tests/dromaeo/cssquery-prototype.html) 10:12:36 INFO - PROCESS | 2240 | RSS: Main: 280371200 10:12:36 INFO - PROCESS | 2240 | 10:15:39 INFO - PROCESS | 2240 | Cycle 1(1): loaded http://localhost:54930/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54930/tests/dromaeo/cssquery-yui.html) 10:15:39 INFO - PROCESS | 2240 | RSS: Main: 283758592 10:15:39 INFO - PROCESS | 2240 | 10:18:42 INFO - PROCESS | 2240 | Cycle 1(1): loaded http://localhost:54930/tests/dromaeo/cssquery-yui.html (next: http://localhost:54930/tests/dromaeo/cssquery-dojo.html) 10:18:42 INFO - PROCESS | 2240 | RSS: Main: 283774976 10:18:42 INFO - PROCESS | 2240 | 10:18:42 INFO - PROCESS | 2240 | __start_tp_report 10:18:42 INFO - PROCESS | 2240 | _x_x_mozilla_page_load 10:18:42 INFO - PROCESS | 2240 | _x_x_mozilla_page_load_details 10:18:42 INFO - PROCESS | 2240 | |i|pagename|runs| 10:18:42 INFO - PROCESS | 2240 | |0;dojo.html;3335.08285221564;3348.9162770930725;3377.9324413511713;3388.711959483444;3396.473546600277;5726.3414707308675;5760.913586296204;5824.563157763164;5832.825015249544;5845.8830823383505;5643.774249030044;5651.604387692863;5801.854953626162;5825.155352473888;5847.181394604748;4486.349479325496;5272.9981303552395;5333.813316533922;5335.839924802262;5363.436839131893;5301.5228629423345;5382.2464854920345;5387;5455.1544510600925;5459.3994660587305;5220.843374698765;5337.893242135136;5361.651492652965;5379.677219366831;5413.431589683086;5233.398159211702;5304.708241046741;5341.225522299244;5348.625596208267;5366.812161574365;4540.432445944257;5090.032893750175;5235.528802407764;5242.370915490127;5276.182191760283;5655.575831812589;5701.2018317435595;5734.225879506255;5782.884342313172;5817.970910145422;6230.906536401958;6237.6257424554215;6255.6872156392;6307.936920630781;6317.305096439388;6248.875202463555;6261.342559031282;6275.592086514362;6277;6299.8740025199695;5758.424157584208;5864.501517370986;5913.586048976574;5914.852128696774;5920.141579470996;4744;6267.592606480564;6306.558540902093;6351.333110023474;6351.396617321347;5153.6907785532985;6192.380761923771;6234.438900498977;6236.781712640036;6243.406876346739;4905.877353066202;5276.36683597963;5598.768270980459;6152.969235153885;6215.12988181655;6255.812325630239;6265.843353916189;6276.246850377893;6281.7173227203975;6288.52836037299;6088.756449742049;6287.245530536365;6295.779647712308;6297.086922396227;6321.65230912295;4374.912501749947;4937.506249375106;4946.4558898521855;4992.8751781205765;4998.250262460588;3751.692708983955;4438.533953934855;4459;4459.019015816515;4539.931901021488;5926.377730338252;5979.431953964367;5986.191864098378;6058.1518587397795;6059.48494377974;6130.459285710702;6136.570440069152;6139.921477737008;6222.906656400158;6232.75068997235;5939.07944268639;6004.0693692477735;6023.307319658208;6036.396360363929;6040.214772079602;5482.835514934479;5485.314335708036;5498.945010549924;5501.504864562128;5521.475459831309;5977.760889564455;6234.251889773256;6253.968095264412;6415.230172379251;6454.935450645527;6112.564273424362;6209.348018457997;6210.161628180138;6224.968875155776;6231.75072997075;6328.746850126127;6362.395572420613;6366.554341196072;6377.713002914973;6385.489160867212;6105.8843398869;6147.508199343953;6157.32269450369;6187.659678717714;6217.098520714561;5219.373675159005;6196.81409557714;6225.657588832658;6244.625322480666;6245.219347581552;6051.364606716407;6151.323354431099;6172.722227499863;6192.876142477216;6213.1788949796455;6047;6117.908231376444;6157.384261573807;6163.106349579196;6222.222222222223;5181.730476033396;5667.4332566743;6052.182955300978;6087.69561521931;6137.1101190328045;5522.751476183501;5860.912086318794;6243.750249990131;6256.311805701278;6278.780242691484;5886.470217680429;6019.849503762441;6038.184845045863;6052.394760523913;6069.9089513656445 10:18:42 INFO - PROCESS | 2240 | |1;ext.html;470.8869871230905;528.1391332128629;546.6200990311733;550.5925615044865;566.9007923613367;39017.80491097544;41827.581724182746;41879.99999999996;41918.95202619936;41938.790306048424;41682.37476437848;41748.16503669925;42349;42420;42423.57576424235;9650.131488166064;9786.706398808012;9818.50907454628;9849.458279794608;9854.50727463624;8164.550949697764;8304.584770761468;9758.170555502791;9789.265805064613;9838.45888476137;9810.166135878459;9813.362131461432;9816.803663926717;9874.654387096452;9902.267103256938;9781.706548803511;9812.607495700164;9887;9894.653687120988;9900.851487227697;8344.41589088764;9744.853827192597;9758.365706229073;9841.212702983745;9849.45827979468;40784.79607601943;41128.79435602833;41249.1750164995;41261.1747765046;41309.793451032856;3022.637283525969;3179.71382575567;3324.5345651608795;3490.1449144959647;3546.989108104178;29521.261968450744;29821;29950.95171669003;31043.379132417453;31234.843825780954;3198.4882418812876;3202.551642770014;3204.279037216631;3205.5191721241767;3310.519974603669;471615;473240;475320.99999999657;476283;477715;33808.830955845064;34049.65950340465;34099.65900341015;34219.828900855835;34229.828850855585;34963.4755478668;35021.124471888405;35323.4701479478;35489.64510354863;36597.63402365996;17093;17207.96752194872;17212.31150753981;17243.482695519153;17289.13554322279;13084.542041028519;13106;13191.934040329737;13207.471701132039;13213.009024323022;17952.281908723504;18778.81221187798;19829.30597429083;20117.994100294927;20153;8058.758237252891;8193.296212249943;8201.343892488587;8212.219839115274;8232.830360311204;897.9775505612412;947.2469386837481;983.4296108257194;995.8854731705947;1075.3118004477142;29709.554356684668;30764.69235307663;31055.84472077625;31261.687383126333;31481.898133565217;1151.2344291046527;1164.5109054197258;1196.252342286071;1208.8972437342925;1217.7381862899342;16012.67974640524;16207.946483478772;16217.675646487005;16247.431339903047;16454.670906581803;698753;753042;757129;757149;758661;60154.398456015755;60662.39337606656;60677;60680;60971.780564388464;1413.6041908265702;1416.3484796993293;1462.093502028749;1472.85271472854;1473.8452462491498;27824.860875695493;30250.90249107485;30384;30510;30706.701061850912;48109.75945120252;52314.738426307624;52431.73784131055;52440.7377963123;52558;530402;531988.3602327855;534974;538196;538991;26497.94008239649;26960.460790784466;27404.040858569853;27465.588016179372;27552.86223568869;48949.26576101433;49988.75005625094;50014.74992625014;50278.96160410443;51188.48811511837;40402.1919561613;40850.59149408468;41810;42450.51423200173;42580.78709606432;39671;40583.7970810144;40951.59048409478;41064.58935410608;42382.5761742391;34280.82859585686;40110.39834402428;40251.99370015773;40468.392974106;40826.9793255171;30588.235294116934;32885;33128.33743325168;35901.11887626998;36283;22688.319350419515;26348.077817276302;26561.468770624866;26605.86697066502;26680.46639067169 10:18:42 INFO - PROCESS | 2240 | |2;jquery.html;378.4361301660527;385.751190482139;386.079838214163;389.9025243689078;413.2850169207271;14738.778918316233;19826.405207843753;20065.398038058844;21468.89265553672;21470.248541301058;20296.69554956673;20376.898115509437;20688.793112068874;21163.47091322714;21276.57446851066;5420.159875219338;5494.80768173114;5548.611597188198;5603.915941260884;5608.747606357703;4808.471068182497;5410.972945135269;5601.775928962837;5606.271184745978;5624.887502249952;5488.9725551372385;5496.31296087989;5516.420775818541;5580.330360356763;5581.609287349887;5448.891022179574;5456.427075157111;5563.638363506359;5601.327840659126;5610.69141197234;5235.816746413857;5329.8934021319355;5402;5406;5420.945790542083;13673.521426750116;13894.374753136133;21654.783452165593;26219.475610487683;26238.73761262382;2086.1968142265337;2699.716529764366;3015.653199881998;3028.8939887103847;3156.163616641592;10149.035841595036;10185.59257629694;10208.387496750218;10325.89674103257;10441;1871.2234422714557;1914.15777058094;1944.3583617406364;1946.1145178943548;1960.2061165227963;113971.72056558823;121252.39373803163;127985;130196.34901825525;131911;9059.411138275991;11623.709407264885;11649.650510484698;11808.232464889754;11915.582954596548;14918.477853275082;14925.925370373296;14926.955113142192;14928;14936.477223297132;8446.489104116223;8826.735197944072;8832.955835220911;8949.552522373855;8977.551122443852;8431.536265505456;8738.519381434082;8761.912380876238;8938.50838203905;8974.506402147814;3859.9614003860165;4048.716589838742;4632.397788287569;4661.650376221729;4723.031778485402;1666.5916850371736;1940.1657287366286;1979.4259664697365;1987.7813440521531;1989.7512810898638;912.4570880326195;924.2605915267759;980.593053882645;982.7690492734201;984.2273815055147;16785.496435106965;16973.49079527616;21159.25942592002;22268.888655556617;22637.88681056584;903.8430808565047;941.3833938770116;964.8022155458115;988.367444835306;1001.5092604623827;10856.62001829948;11261.66215013551;11266.718332041764;11275.492602832892;11303.773924521463;517617;523806;636108.8194558998;643425;648360;102615;129582;130123;141511;156372.2181389086;957.7222605444343;1009.2783659683362;1030.258214085842;1031.159604921986;1043.1654676258963;18694.906525467286;19747.703784443533;20013.09941052627;20116.59766804685;20319.59360812746;56389;58156.12765808432;58603.4139658615;59597.40402596092;60651.69674151601;86223.56888215519;86638.13361866552;88635;89466;90285.64571531564;7891.092524359772;7963.482373645695;8134;8198.262156405952;8396.202360775716;8764.693235736719;10932.234743567873;11872.703182420162;12117.15179937396;12194.75610487803;9079;9670.806583868423;9775.315727898978;9970.252231082553;10633.20250981195;8396.874046889423;8480.448770829877;10177.643782467578;10253.718285214349;10319.690409287434;10983.340999540053;11523.654290371302;11605.593804216813;11678.474468648761;11953;7181.425485961006;7602.429817763579;7991.041075071029;8195.221453961864;8288.751337459886;11048.116150707763;11184.608538701106;11192.944035279772;11415.143864210384;11574.942125289319 10:18:42 INFO - PROCESS | 2240 | |3;mootools.html;1508.2006536535637;1565.982111627442;1715.6654452381783;1782.8662850286216;1799.8380145786878;7980.800479988004;8377.623006964686;8542.615582298797;9015.737796708467;9027.864582031274;8638.308935285178;8788.560571971408;8795.25240354569;8800.77998050049;8812.118788121184;2347.213683416058;2371.9407014824596;2371.988140059306;2372.466195106105;2382.273406610983;2301.8503797253125;2353.235198560466;2370.691810064689;2377.5007248477755;2388.259639511757;2346.4603141277516;2352.5765362234793;2357.9292621221307;2358.1157066100213;2370.20598099636;2075.823554997827;2232.526532488566;2335.7313908900437;2344.460774021976;2350.0364850411333;1925.2491528303972;2276.112316196685;2290.7171983689022;2291.232437133562;2299.6435552489393;7675.7313494028;8822.558872056436;8847.42491738035;8903.154200350957;8907;5213.504717051874;5324.7071411072375;5360.544353729938;5366.543843773284;5374;3481.791092534431;3566.696830769388;3621.3481573316794;3660.6705396514176;3666.4866918631415;1047.9633212837514;1088.5917780832187;1099.439285964156;1113.9443027848574;1115.949782259792;11466.312021278667;11572.76854462906;11604.94197529007;11605.593804216896;11624.593139240085;4231.449911511484;4292.334688123346;4620.1221767864;4729.62163026957;4786.210275304606;9812.411255324703;9861.457619830835;9861.802763944825;9868.802623947624;9871.506424678737;5282.603804714585;5309.707966061904;5310.2831117798605;5311.123664595376;5338.572914166857;3771.867984620525;4393.648508119343;4778.546038126373;4818.903621927542;4890.706557606555;3873.1479074603544;3955.841766329372;3978.4629075074495;3999.6600288975765;4034.23349563582;1204.512172570093;1251.205484517338;1294.501616877509;1301.1802564384377;1317.3347459532874;917.6100157433065;946.1531928923713;951.743029382063;959.29971121082;1008.8890222075565;5871.618344807659;5959.374265702126;5988.071848863433;5995.850103747442;6101.7559297627595;437.28067329243214;440.5990548600758;444.85542198785527;448.89002194462563;452.5316297631935;3430.142464383904;4023.0746928206677;4146.4133524501085;4149.481314835645;4149.792510374469;9777.951110244403;10307.742306442398;10425.687229383131;10434.530446129942;10445.634402795866;10119.747006324753;10408.947955260326;10411.5835366586;10422.635207767691;10445.84331235032;7936.4841285317425;8073.555954422446;8136.796580085427;8136.796580085545;8140.674373025131;5360.946390536201;5687.260656114844;5847.356790753099;5936.525077993664;5972.8805423892145;9675.322727409013;9755.658551950648;9793.265505087289;9806.21550275962;9813;9668.419894806335;9836.458994755358;9837.803243935225;9904.900950990397;9912.702618921443;6813.625250611264;7242.456815738736;7364.77905662831;7469.88795168062;7543.7736867894055;8267.917320826715;8444.704435344733;8446.915530844859;8459.915400845914;8464.576771161293;6189.195404597373;7358.595277259802;7505.437092218214;7551.622418878924;7612.505187162817;6079.392060793885;7362.631868406451;7386.704531818667;7404.925950740424;7417.92582074194;7029.718811247493;7033.648317583998;7035.33164349386;7100.112485939258;7115;4648.395708557866;5045.924311135409;5084.771185296597;5089.6691715038405;5192.480751924928;4457.219986502414;4497.392851964943;4942.55517003457;5077.593792496666;5168.896622067613 10:18:42 INFO - PROCESS | 2240 | |4;prototype.html;309.2901790391052;312.6701330096747;314.7324773942151;318.53175831527653;321.0737023686666;4993.575546078588;5563.610547261694;5721.25623668923;5738.913916291253;5822.301323841134;4220.873373798783;4879.9024019519675;5103.081445339837;5310.283111779909;5736.368999410061;3620.2216523447455;4076.653484453825;4179.519355274137;4240.936385954213;4277.401163837066;3710.2764960832515;4220.451341325639;4227.091175397289;4258.148370325932;4277.700560960734;4246.150769846027;4252.617264446199;4256.637256567308;4260.2532961485795;4264.402983582301;3655.6892664123316;4063.573324800898;4246.256905041636;4260.488741351027;4290.871273861789;3053.2366908272934;3665.6356649960626;3675.558932928039;3679.392900171495;3696.890932720173;5242.397124330674;5366.6779993200535;5446.945530544723;5455.318085239345;5462.317210348707;4319.481662200525;4660.277656963176;4910.7299098549565;4957.157283261854;4965.875853103666;4934.383202099738;5174.560162386202;5932.406759324076;5961.791337303217;5997.850053748648;4121.443605113332;4123.443335149746;4123.60825721556;4126.319157339036;4135.152293779768;4934.40787105546;5931.792387266468;6268.623882567061;6297.937020629781;6319.115323854664;4133.297339452301;4140.3168477201525;4161.897097269226;4170.874873753732;4177.916441671149;4186.665066794649;4220.303649897794;4224.324108142682;4233.640140588023;4243.6605071594195;4407.889802754893;4486.977565112154;4701.48283688794;4944.431390390079;4958.016793282643;5981.342052374235;5993.250843644544;6001.789937352171;6004.127337867051;6020.8795824084145;1430.4421275702484;1443.9819926951684;1455.1519570145856;1457.205822826562;1460.9116208424791;820.5774026376421;886.264400547544;888.4979986307716;896.171041786345;896.5472436419566;4686.945437276654;5224.320838291073;5290.444503327172;5508.531774799107;5661.4806411147465;6114.816555503342;6229.813105606839;6273.58466990212;6277.246730392382;6287.685615719196;5407.378151512547;5562.471565201299;5626.621477737898;5732.710140218417;5809.651420914674;2446.2783478873775;2990.745786608119;3270.296886169485;3275.017494751565;3287.671232876741;4650.512115913065;5626.1842032905;5901.704914754245;6473.708683109271;6541.28045914949;6329.810105696836;6331.96834015827;6397.528568429194;6401.9039714404325;6417.679116044273;5397.514223719884;6476.740930362733;6486.967565162144;6510;6513.427196257547;5892.941070589411;6171.642238707477;6172.839506172803;6191.84520386994;6263.937360626335;6040.879182416239;6152.753889844356;6196.907046394217;6198.442140207403;6200.56596038273;5363.115086010921;6235.46998505114;6387.904181437189;6508.940797825015;6510.381513756186;3563.3051554946537;3707.7404581680103;3941.4876066111224;4018.4173294871493;4089.5910408958866;6113.388661133851;6200.3799620037635;6202.875942481215;6224.968875155595;6225;5686.516646085129;5976.581639285208;6000.819975400745;6026.939730602638;6048.90926636092;5706.429357064427;5986.2217911671205;6014.518838492998;6015.796840631803;6056.152138700673;4776.450708168605;5197.818076367309;5386.7575959082715;5427.810026649048;5883;5407.864348486704;5418.701971391453;5681.778417640227;5931.051031834886;5992.67040312787;5077.416097148875;5140.691558506502;5860.589758716849;5868.092869817241;5933.139694744152 10:18:42 INFO - PROCESS | 2240 | |5;yui.html;749.490346564336;769.1731388757086;773.9187385324541;842.755600875746;868.374536310902;15753.369865205392;15908.125053122074;16114.274857631406;16117.435889743847;16165.91917040416;14420.783688244685;14468.710625787504;14691;14691.77962330566;14704.117752934804;731.3089130771423;746.4327111395352;757.4243574883101;910.0035461170024;940.8306504829128;756.4440136499668;931.9301052421062;939.9895112753783;940.0787228516058;949.5015117063527;957.0381766324833;985.6796541124128;998.3859926145441;1002.2332915319786;1002.4887307473205;976.1946394224758;983.7688143286322;985.8274801909708;996;999.7150812018613;735.6726256815666;737.9262073792631;739.3124394313287;741.0699572037113;741.3328004795673;14973.326200321011;15215.923920380437;15248.923755381264;15533.922330388388;15537.368576299552;11980.34108124053;14067.648308792259;14279.643008924757;14374.496892608708;14453.638659033502;13116.27860467674;13246.40391182399;13442.79835802454;13554;13930.164190148624;4761.119192949315;4807.447143578498;4892.168331383673;4967.826126085606;4973.403191616993;97052.51473742657;113995.290070649;116348.99999999916;116588;118333;11491.712707182387;11510.251833630788;11632.418379081013;11657.417129143509;11935;22730.43173920665;23039.42401439944;23121.768782311963;23171;23390.298291051295;8153.633086511121;8166.714165004197;8248.505089694638;8276.65809282625;8384.329253659693;4497.752623605667;4593.54064593538;4597.862064138081;4647.047355292157;4752.192127338291;3518.894433167009;3615.078155070493;3615.2769446110356;3619.76471529355;3629.3467175908063;649.9512536559777;659.4361820643377;661.83784972682;672.5829985409077;676.3946268090032;619.7242227208849;640.4236187431256;642.9807105786834;666.2737615998487;670.4569298867932;8223.465474744122;9100.135487128713;9133.497657628894;9173.128552787475;9192.126747958933;599.9190109335184;629.553017357672;630.5586089737202;652.5366989437458;653.6794379951154;4454.198244316054;4490.3040028795585;4498.797554110073;4505.07645932583;4555.1117532080925;88836.11163888407;89021.66467502993;89217;132184;145346;78332;105545.94454055515;125461.11808322882;126384.1042384365;131018;102995;103564.48217759014;112839.43580282046;114379.42810286062;115170;9991.800163996824;10049;10177.389356638625;10321.690349289533;10426.582936682447;58734;60278;62178.68910655418;62700.68649656723;63560.36439635544;93362.53318733363;94160.05839941512;95623.04376956141;96857.51571242098;98972;13502.257375844423;13563.86436135626;13706.314684265548;13868.375923083688;14164.575062748134;15283.694326113638;15476.535703928901;15523;15594.986325888782;16083.75874361862;14164.716705666035;14195.219262940638;14312.785308220593;14344.282785860874;14661.853381466475;14238.857611423753;14341.856581434053;14384.784228236795;14766.704665906836;14801.777973330623;11820.70448238801;12610.432530535965;14340.784888226477;14516.854831451972;14633.34149963233;9597.616095356108;10496.212784041074;11279.887201127884;11425.771484570096;12009.339486327986;8734.82530349402;10376.118029967236;11667.804413794396;12017.399130043637;12170.513179473077 10:18:42 INFO - PROCESS | 2240 | __end_tp_report 10:18:42 INFO - PROCESS | 2240 | __start_cc_report 10:18:42 INFO - PROCESS | 2240 | _x_x_mozilla_cycle_collect,453 10:18:42 INFO - PROCESS | 2240 | __end_cc_report 10:18:42 INFO - PROCESS | 2240 | __startTimestamp1469035122744__endTimestamp 10:18:42 INFO - PROCESS | 2240 | 10:18:42 INFO - PROCESS | 2240 | ------- Summary: start ------- 10:18:42 INFO - PROCESS | 2240 | Number of tests: 6 10:18:42 INFO - PROCESS | 2240 | 10:18:42 INFO - PROCESS | 2240 | [#0] dojo.html Cycles:165 Average:5745.36 Median:6019.85 stddev:666.91 (11.1%) stddev-sans-first:641.60 10:18:42 INFO - PROCESS | 2240 | Values: 3335.1 3348.9 3377.9 3388.7 3396.5 5726.3 5760.9 5824.6 5832.8 5845.9 5643.8 5651.6 5801.9 5825.2 5847.2 4486.3 5273.0 5333.8 5335.8 5363.4 5301.5 5382.2 5387.0 5455.2 5459.4 5220.8 5337.9 5361.7 5379.7 5413.4 5233.4 5304.7 5341.2 5348.6 5366.8 4540.4 5090.0 5235.5 5242.4 5276.2 5655.6 5701.2 5734.2 5782.9 5818.0 6230.9 6237.6 6255.7 6307.9 6317.3 6248.9 6261.3 6275.6 6277.0 6299.9 5758.4 5864.5 5913.6 5914.9 5920.1 4744.0 6267.6 6306.6 6351.3 6351.4 5153.7 6192.4 6234.4 6236.8 6243.4 4905.9 5276.4 5598.8 6153.0 6215.1 6255.8 6265.8 6276.2 6281.7 6288.5 6088.8 6287.2 6295.8 6297.1 6321.7 4374.9 4937.5 4946.5 4992.9 4998.3 3751.7 4438.5 4459.0 4459.0 4539.9 5926.4 5979.4 5986.2 6058.2 6059.5 6130.5 6136.6 6139.9 6222.9 6232.8 5939.1 6004.1 6023.3 6036.4 6040.2 5482.8 5485.3 5498.9 5501.5 5521.5 5977.8 6234.3 6254.0 6415.2 6454.9 6112.6 6209.3 6210.2 6225.0 6231.8 6328.7 6362.4 6366.6 6377.7 6385.5 6105.9 6147.5 6157.3 6187.7 6217.1 5219.4 6196.8 6225.7 6244.6 6245.2 6051.4 6151.3 6172.7 6192.9 6213.2 6047.0 6117.9 6157.4 6163.1 6222.2 5181.7 5667.4 6052.2 6087.7 6137.1 5522.8 5860.9 6243.8 6256.3 6278.8 5886.5 6019.8 6038.2 6052.4 6069.9 10:18:42 INFO - PROCESS | 2240 | 10:18:42 INFO - PROCESS | 2240 | [#1] ext.html Cycles:180 Average:70657.63 Median:40838.79 stddev:159935.94 (391.6%) stddev-sans-first:160297.78 10:18:42 INFO - PROCESS | 2240 | Values: 470.9 528.1 546.6 550.6 566.9 39017.8 41827.6 41880.0 41919.0 41938.8 41682.4 41748.2 42349.0 42420.0 42423.6 9650.1 9786.7 9818.5 9849.5 9854.5 8164.6 8304.6 9758.2 9789.3 9838.5 9810.2 9813.4 9816.8 9874.7 9902.3 9781.7 9812.6 9887.0 9894.7 9900.9 8344.4 9744.9 9758.4 9841.2 9849.5 40784.8 41128.8 41249.2 41261.2 41309.8 3022.6 3179.7 3324.5 3490.1 3547.0 29521.3 29821.0 29951.0 31043.4 31234.8 3198.5 3202.6 3204.3 3205.5 3310.5 471615.0 473240.0 475321.0 476283.0 477715.0 33808.8 34049.7 34099.7 34219.8 34229.8 34963.5 35021.1 35323.5 35489.6 36597.6 17093.0 17208.0 17212.3 17243.5 17289.1 13084.5 13106.0 13191.9 13207.5 13213.0 17952.3 18778.8 19829.3 20118.0 20153.0 8058.8 8193.3 8201.3 8212.2 8232.8 898.0 947.2 983.4 995.9 1075.3 29709.6 30764.7 31055.8 31261.7 31481.9 1151.2 1164.5 1196.3 1208.9 1217.7 16012.7 16207.9 16217.7 16247.4 16454.7 698753.0 753042.0 757129.0 757149.0 758661.0 60154.4 60662.4 60677.0 60680.0 60971.8 1413.6 1416.3 1462.1 1472.9 1473.8 27824.9 30250.9 30384.0 30510.0 30706.7 48109.8 52314.7 52431.7 52440.7 52558.0 530402.0 531988.4 534974.0 538196.0 538991.0 26497.9 26960.5 27404.0 27465.6 27552.9 48949.3 49988.8 50014.7 50279.0 51188.5 40402.2 40850.6 41810.0 42450.5 42580.8 39671.0 40583.8 40951.6 41064.6 42382.6 34280.8 40110.4 40252.0 40468.4 40827.0 30588.2 32885.0 33128.3 35901.1 36283.0 22688.3 26348.1 26561.5 26605.9 26680.5 10:18:42 INFO - PROCESS | 2240 | 10:18:42 INFO - PROCESS | 2240 | [#2] jquery.html Cycles:180 Average:35401.82 Median:3092.53 stddev:100208.49 (3240.3%) stddev-sans-first:100455.09 10:18:42 INFO - PROCESS | 2240 | Values: 378.4 385.8 386.1 389.9 413.3 14738.8 19826.4 20065.4 21468.9 21470.2 20296.7 20376.9 20688.8 21163.5 21276.6 5420.2 5494.8 5548.6 5603.9 5608.7 4808.5 5411.0 5601.8 5606.3 5624.9 5489.0 5496.3 5516.4 5580.3 5581.6 5448.9 5456.4 5563.6 5601.3 5610.7 5235.8 5329.9 5402.0 5406.0 5420.9 13673.5 13894.4 21654.8 26219.5 26238.7 2086.2 2699.7 3015.7 3028.9 3156.2 10149.0 10185.6 10208.4 10325.9 10441.0 1871.2 1914.2 1944.4 1946.1 1960.2 113971.7 121252.4 127985.0 130196.3 131911.0 9059.4 11623.7 11649.7 11808.2 11915.6 14918.5 14925.9 14927.0 14928.0 14936.5 8446.5 8826.7 8833.0 8949.6 8977.6 8431.5 8738.5 8761.9 8938.5 8974.5 3860.0 4048.7 4632.4 4661.7 4723.0 1666.6 1940.2 1979.4 1987.8 1989.8 912.5 924.3 980.6 982.8 984.2 16785.5 16973.5 21159.3 22268.9 22637.9 903.8 941.4 964.8 988.4 1001.5 10856.6 11261.7 11266.7 11275.5 11303.8 517617.0 523806.0 636108.8 643425.0 648360.0 102615.0 129582.0 130123.0 141511.0 156372.2 957.7 1009.3 1030.3 1031.2 1043.2 18694.9 19747.7 20013.1 20116.6 20319.6 56389.0 58156.1 58603.4 59597.4 60651.7 86223.6 86638.1 88635.0 89466.0 90285.6 7891.1 7963.5 8134.0 8198.3 8396.2 8764.7 10932.2 11872.7 12117.2 12194.8 9079.0 9670.8 9775.3 9970.3 10633.2 8396.9 8480.4 10177.6 10253.7 10319.7 10983.3 11523.7 11605.6 11678.5 11953.0 7181.4 7602.4 7991.0 8195.2 8288.8 11048.1 11184.6 11192.9 11415.1 11574.9 10:18:42 INFO - PROCESS | 2240 | 10:18:42 INFO - PROCESS | 2240 | [#3] mootools.html Cycles:180 Average:5631.35 Median:5081.18 stddev:3157.91 (62.1%) stddev-sans-first:3151.57 10:18:42 INFO - PROCESS | 2240 | Values: 1508.2 1566.0 1715.7 1782.9 1799.8 7980.8 8377.6 8542.6 9015.7 9027.9 8638.3 8788.6 8795.3 8800.8 8812.1 2347.2 2371.9 2372.0 2372.5 2382.3 2301.9 2353.2 2370.7 2377.5 2388.3 2346.5 2352.6 2357.9 2358.1 2370.2 2075.8 2232.5 2335.7 2344.5 2350.0 1925.2 2276.1 2290.7 2291.2 2299.6 7675.7 8822.6 8847.4 8903.2 8907.0 5213.5 5324.7 5360.5 5366.5 5374.0 3481.8 3566.7 3621.3 3660.7 3666.5 1048.0 1088.6 1099.4 1113.9 1115.9 11466.3 11572.8 11604.9 11605.6 11624.6 4231.4 4292.3 4620.1 4729.6 4786.2 9812.4 9861.5 9861.8 9868.8 9871.5 5282.6 5309.7 5310.3 5311.1 5338.6 3771.9 4393.6 4778.5 4818.9 4890.7 3873.1 3955.8 3978.5 3999.7 4034.2 1204.5 1251.2 1294.5 1301.2 1317.3 917.6 946.2 951.7 959.3 1008.9 5871.6 5959.4 5988.1 5995.9 6101.8 437.3 440.6 444.9 448.9 452.5 3430.1 4023.1 4146.4 4149.5 4149.8 9778.0 10307.7 10425.7 10434.5 10445.6 10119.7 10408.9 10411.6 10422.6 10445.8 7936.5 8073.6 8136.8 8136.8 8140.7 5360.9 5687.3 5847.4 5936.5 5972.9 9675.3 9755.7 9793.3 9806.2 9813.0 9668.4 9836.5 9837.8 9904.9 9912.7 6813.6 7242.5 7364.8 7469.9 7543.8 8267.9 8444.7 8446.9 8459.9 8464.6 6189.2 7358.6 7505.4 7551.6 7612.5 6079.4 7362.6 7386.7 7404.9 7417.9 7029.7 7033.6 7035.3 7100.1 7115.0 4648.4 5045.9 5084.8 5089.7 5192.5 4457.2 4497.4 4942.6 5077.6 5168.9 10:18:42 INFO - PROCESS | 2240 | 10:18:42 INFO - PROCESS | 2240 | [#4] prototype.html Cycles:180 Average:4834.56 Median:5402.45 stddev:1520.75 (28.1%) stddev-sans-first:1486.60 10:18:42 INFO - PROCESS | 2240 | Values: 309.3 312.7 314.7 318.5 321.1 4993.6 5563.6 5721.3 5738.9 5822.3 4220.9 4879.9 5103.1 5310.3 5736.4 3620.2 4076.7 4179.5 4240.9 4277.4 3710.3 4220.5 4227.1 4258.1 4277.7 4246.2 4252.6 4256.6 4260.3 4264.4 3655.7 4063.6 4246.3 4260.5 4290.9 3053.2 3665.6 3675.6 3679.4 3696.9 5242.4 5366.7 5446.9 5455.3 5462.3 4319.5 4660.3 4910.7 4957.2 4965.9 4934.4 5174.6 5932.4 5961.8 5997.9 4121.4 4123.4 4123.6 4126.3 4135.2 4934.4 5931.8 6268.6 6297.9 6319.1 4133.3 4140.3 4161.9 4170.9 4177.9 4186.7 4220.3 4224.3 4233.6 4243.7 4407.9 4487.0 4701.5 4944.4 4958.0 5981.3 5993.3 6001.8 6004.1 6020.9 1430.4 1444.0 1455.2 1457.2 1460.9 820.6 886.3 888.5 896.2 896.5 4686.9 5224.3 5290.4 5508.5 5661.5 6114.8 6229.8 6273.6 6277.2 6287.7 5407.4 5562.5 5626.6 5732.7 5809.7 2446.3 2990.7 3270.3 3275.0 3287.7 4650.5 5626.2 5901.7 6473.7 6541.3 6329.8 6332.0 6397.5 6401.9 6417.7 5397.5 6476.7 6487.0 6510.0 6513.4 5892.9 6171.6 6172.8 6191.8 6263.9 6040.9 6152.8 6196.9 6198.4 6200.6 5363.1 6235.5 6387.9 6508.9 6510.4 3563.3 3707.7 3941.5 4018.4 4089.6 6113.4 6200.4 6202.9 6225.0 6225.0 5686.5 5976.6 6000.8 6026.9 6048.9 5706.4 5986.2 6014.5 6015.8 6056.2 4776.5 5197.8 5386.8 5427.8 5883.0 5407.9 5418.7 5681.8 5931.1 5992.7 5077.4 5140.7 5860.6 5868.1 5933.1 10:18:42 INFO - PROCESS | 2240 | 10:18:42 INFO - PROCESS | 2240 | [#5] yui.html Cycles:180 Average:23768.41 Median:3615.18 stddev:36034.34 (996.8%) stddev-sans-first:36093.97 10:18:42 INFO - PROCESS | 2240 | Values: 749.5 769.2 773.9 842.8 868.4 15753.4 15908.1 16114.3 16117.4 16165.9 14420.8 14468.7 14691.0 14691.8 14704.1 731.3 746.4 757.4 910.0 940.8 756.4 931.9 940.0 940.1 949.5 957.0 985.7 998.4 1002.2 1002.5 976.2 983.8 985.8 996.0 999.7 735.7 737.9 739.3 741.1 741.3 14973.3 15215.9 15248.9 15533.9 15537.4 11980.3 14067.6 14279.6 14374.5 14453.6 13116.3 13246.4 13442.8 13554.0 13930.2 4761.1 4807.4 4892.2 4967.8 4973.4 97052.5 113995.3 116349.0 116588.0 118333.0 11491.7 11510.3 11632.4 11657.4 11935.0 22730.4 23039.4 23121.8 23171.0 23390.3 8153.6 8166.7 8248.5 8276.7 8384.3 4497.8 4593.5 4597.9 4647.0 4752.2 3518.9 3615.1 3615.3 3619.8 3629.3 650.0 659.4 661.8 672.6 676.4 619.7 640.4 643.0 666.3 670.5 8223.5 9100.1 9133.5 9173.1 9192.1 599.9 629.6 630.6 652.5 653.7 4454.2 4490.3 4498.8 4505.1 4555.1 88836.1 89021.7 89217.0 132184.0 145346.0 78332.0 105545.9 125461.1 126384.1 131018.0 102995.0 103564.5 112839.4 114379.4 115170.0 9991.8 10049.0 10177.4 10321.7 10426.6 58734.0 60278.0 62178.7 62700.7 63560.4 93362.5 94160.1 95623.0 96857.5 98972.0 13502.3 13563.9 13706.3 13868.4 14164.6 15283.7 15476.5 15523.0 15595.0 16083.8 14164.7 14195.2 14312.8 14344.3 14661.9 14238.9 14341.9 14384.8 14766.7 14801.8 11820.7 12610.4 14340.8 14516.9 14633.3 9597.6 10496.2 11279.9 11425.8 12009.3 8734.8 10376.1 11667.8 12017.4 12170.5 10:18:42 INFO - PROCESS | 2240 | -------- Summary: end -------- 10:18:42 INFO - PROCESS | 2240 | 10:18:43 INFO - TEST-INFO | 2240: exit 0 10:18:43 INFO - TEST-OK | dromaeo_css | took 1112716ms 10:18:43 INFO - TEST-START | kraken 10:18:43 INFO - operating with platform_type : mac_ 10:18:43 INFO - Initialising browser for kraken test... 10:18:43 INFO - TEST-INFO | started process 2247 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/tmpDaWLy4/profile http://localhost:54930/getInfo.html) 10:18:46 INFO - PROCESS | 2247 | __metrics Screen width/height:1600/1200 10:18:46 INFO - PROCESS | 2247 | colorDepth:24 10:18:46 INFO - PROCESS | 2247 | Browser inner width/height: 1024/689 10:18:46 INFO - PROCESS | 2247 | __metrics 10:18:46 ERROR - PROCESS | 2247 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 10:18:47 INFO - TEST-INFO | 2247: exit 0 10:18:47 INFO - Browser initialized. 10:18:47 INFO - Running cycle 1/1 for kraken test... 10:18:47 INFO - TEST-INFO | started process 2249 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/tmpDaWLy4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:18:59 INFO - PROCESS | 2249 | RSS: Main: 255336448 10:18:59 INFO - PROCESS | 2249 | 10:19:01 INFO - PROCESS | 2249 | Cycle 1(1): loaded http://localhost:54930/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54930/tests/kraken/driver.html?testName=audio-beat-detection) 10:19:02 INFO - PROCESS | 2249 | RSS: Main: 268263424 10:19:02 INFO - PROCESS | 2249 | 10:19:05 INFO - PROCESS | 2249 | Cycle 1(1): loaded http://localhost:54930/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54930/tests/kraken/driver.html?testName=audio-dft) 10:19:06 INFO - PROCESS | 2249 | RSS: Main: 272388096 10:19:06 INFO - PROCESS | 2249 | 10:19:09 INFO - PROCESS | 2249 | Cycle 1(1): loaded http://localhost:54930/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54930/tests/kraken/driver.html?testName=audio-fft) 10:19:10 INFO - PROCESS | 2249 | RSS: Main: 272601088 10:19:10 INFO - PROCESS | 2249 | 10:19:12 INFO - PROCESS | 2249 | Cycle 1(1): loaded http://localhost:54930/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54930/tests/kraken/driver.html?testName=audio-oscillator) 10:19:12 INFO - PROCESS | 2249 | RSS: Main: 273502208 10:19:12 INFO - PROCESS | 2249 | 10:19:14 INFO - PROCESS | 2249 | Cycle 1(1): loaded http://localhost:54930/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54930/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:19:15 INFO - PROCESS | 2249 | RSS: Main: 274149376 10:19:15 INFO - PROCESS | 2249 | 10:19:18 INFO - PROCESS | 2249 | Cycle 1(1): loaded http://localhost:54930/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54930/tests/kraken/driver.html?testName=imaging-darkroom) 10:19:18 INFO - PROCESS | 2249 | RSS: Main: 274677760 10:19:18 INFO - PROCESS | 2249 | 10:19:21 INFO - PROCESS | 2249 | Cycle 1(1): loaded http://localhost:54930/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54930/tests/kraken/driver.html?testName=imaging-desaturate) 10:19:22 INFO - PROCESS | 2249 | RSS: Main: 274894848 10:19:22 INFO - PROCESS | 2249 | 10:19:25 INFO - PROCESS | 2249 | Cycle 1(1): loaded http://localhost:54930/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54930/tests/kraken/driver.html?testName=json-parse-financial) 10:19:26 INFO - PROCESS | 2249 | RSS: Main: 275046400 10:19:26 INFO - PROCESS | 2249 | 10:19:27 INFO - PROCESS | 2249 | Cycle 1(1): loaded http://localhost:54930/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54930/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:19:28 INFO - PROCESS | 2249 | RSS: Main: 274739200 10:19:28 INFO - PROCESS | 2249 | 10:19:30 INFO - PROCESS | 2249 | Cycle 1(1): loaded http://localhost:54930/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54930/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:19:31 INFO - PROCESS | 2249 | RSS: Main: 274477056 10:19:31 INFO - PROCESS | 2249 | 10:19:33 INFO - PROCESS | 2249 | Cycle 1(1): loaded http://localhost:54930/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54930/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:19:34 INFO - PROCESS | 2249 | RSS: Main: 274763776 10:19:34 INFO - PROCESS | 2249 | 10:19:36 INFO - PROCESS | 2249 | Cycle 1(1): loaded http://localhost:54930/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54930/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:19:37 INFO - PROCESS | 2249 | RSS: Main: 274911232 10:19:37 INFO - PROCESS | 2249 | 10:19:40 INFO - PROCESS | 2249 | Cycle 1(1): loaded http://localhost:54930/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54930/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:19:41 INFO - PROCESS | 2249 | RSS: Main: 275087360 10:19:41 INFO - PROCESS | 2249 | 10:19:42 INFO - PROCESS | 2249 | Cycle 1(1): loaded http://localhost:54930/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54930/tests/kraken/driver.html?testName=ai-astar) 10:19:43 INFO - PROCESS | 2249 | RSS: Main: 275861504 10:19:43 INFO - PROCESS | 2249 | 10:19:43 INFO - PROCESS | 2249 | __start_tp_report 10:19:43 INFO - PROCESS | 2249 | _x_x_mozilla_page_load 10:19:43 INFO - PROCESS | 2249 | _x_x_mozilla_page_load_details 10:19:43 INFO - PROCESS | 2249 | |i|pagename|runs| 10:19:43 INFO - PROCESS | 2249 | |0;ai-astar;118;118;109;117;118;116;101;97;146;166 10:19:43 INFO - PROCESS | 2249 | |1;audio-beat-detection;162;112;135;178;138;140;161;143;176;107 10:19:43 INFO - PROCESS | 2249 | |2;audio-dft;195;201;205;230;229;271;206;203;201;195 10:19:43 INFO - PROCESS | 2249 | |3;audio-fft;70;84;75;147;58;58;57;59;136;58 10:19:43 INFO - PROCESS | 2249 | |4;audio-oscillator;69;70;125;98;71;88;116;65;74;124 10:19:43 INFO - PROCESS | 2249 | |5;imaging-gaussian-blur;91;86;135;130;90;85;92;88;89;87 10:19:43 INFO - PROCESS | 2249 | |6;imaging-darkroom;96;96;95;104;94;150;139;99;126;120 10:19:43 INFO - PROCESS | 2249 | |7;imaging-desaturate;104;147;123;92;118;149;90;92;93;91 10:19:43 INFO - PROCESS | 2249 | |8;json-parse-financial;56;56;55;56;53;57;56;55;55;54 10:19:43 INFO - PROCESS | 2249 | |9;json-stringify-tinderbox;71;98;67;100;106;114;112;72;73;73 10:19:43 INFO - PROCESS | 2249 | |10;stanford-crypto-aes;87;86;83;85;82;83;89;83;86;87 10:19:43 INFO - PROCESS | 2249 | |11;stanford-crypto-ccm;133;135;139;138;145;151;150;158;185;151 10:19:43 INFO - PROCESS | 2249 | |12;stanford-crypto-pbkdf2;164;176;224;165;159;160;163;167;162;173 10:19:43 INFO - PROCESS | 2249 | |13;stanford-crypto-sha256-iterative;68;64;65;67;67;67;65;75;81;77 10:19:43 INFO - PROCESS | 2249 | __end_tp_report 10:19:43 INFO - PROCESS | 2249 | __start_cc_report 10:19:43 INFO - PROCESS | 2249 | _x_x_mozilla_cycle_collect,1137 10:19:43 INFO - PROCESS | 2249 | __end_cc_report 10:19:43 INFO - PROCESS | 2249 | __startTimestamp1469035183084__endTimestamp 10:19:43 INFO - PROCESS | 2249 | 10:19:43 INFO - PROCESS | 2249 | ------- Summary: start ------- 10:19:43 INFO - PROCESS | 2249 | Number of tests: 14 10:19:43 INFO - PROCESS | 2249 | 10:19:43 INFO - PROCESS | 2249 | [#0] ai-astar Cycles:10 Average:120.60 Median:118.00 stddev:20.65 (17.5%) stddev-sans-first:21.88 10:19:43 INFO - PROCESS | 2249 | Values: 118.0 118.0 109.0 117.0 118.0 116.0 101.0 97.0 146.0 166.0 10:19:43 INFO - PROCESS | 2249 | 10:19:43 INFO - PROCESS | 2249 | [#1] audio-beat-detection Cycles:10 Average:145.20 Median:152.00 stddev:24.28 (16.0%) stddev-sans-first:24.98 10:19:43 INFO - PROCESS | 2249 | Values: 162.0 112.0 135.0 178.0 138.0 140.0 161.0 143.0 176.0 107.0 10:19:43 INFO - PROCESS | 2249 | 10:19:43 INFO - PROCESS | 2249 | [#2] audio-dft Cycles:10 Average:213.60 Median:205.50 stddev:23.70 (11.5%) stddev-sans-first:24.16 10:19:43 INFO - PROCESS | 2249 | Values: 195.0 201.0 205.0 230.0 229.0 271.0 206.0 203.0 201.0 195.0 10:19:43 INFO - PROCESS | 2249 | 10:19:43 INFO - PROCESS | 2249 | [#3] audio-fft Cycles:10 Average:80.20 Median:58.50 stddev:33.64 (57.5%) stddev-sans-first:35.48 10:19:43 INFO - PROCESS | 2249 | Values: 70.0 84.0 75.0 147.0 58.0 58.0 57.0 59.0 136.0 58.0 10:19:43 INFO - PROCESS | 2249 | 10:19:43 INFO - PROCESS | 2249 | [#4] audio-oscillator Cycles:10 Average:90.00 Median:70.50 stddev:24.06 (34.1%) stddev-sans-first:24.28 10:19:43 INFO - PROCESS | 2249 | Values: 69.0 70.0 125.0 98.0 71.0 88.0 116.0 65.0 74.0 124.0 10:19:43 INFO - PROCESS | 2249 | 10:19:43 INFO - PROCESS | 2249 | [#5] imaging-gaussian-blur Cycles:10 Average:97.30 Median:88.50 stddev:18.71 (21.1%) stddev-sans-first:19.71 10:19:43 INFO - PROCESS | 2249 | Values: 91.0 86.0 135.0 130.0 90.0 85.0 92.0 88.0 89.0 87.0 10:19:43 INFO - PROCESS | 2249 | 10:19:43 INFO - PROCESS | 2249 | [#6] imaging-darkroom Cycles:10 Average:111.90 Median:94.50 stddev:20.52 (21.7%) stddev-sans-first:20.95 10:19:43 INFO - PROCESS | 2249 | Values: 96.0 96.0 95.0 104.0 94.0 150.0 139.0 99.0 126.0 120.0 10:19:43 INFO - PROCESS | 2249 | 10:19:43 INFO - PROCESS | 2249 | [#7] imaging-desaturate Cycles:10 Average:109.90 Median:90.50 stddev:23.23 (25.7%) stddev-sans-first:24.54 10:19:43 INFO - PROCESS | 2249 | Values: 104.0 147.0 123.0 92.0 118.0 149.0 90.0 92.0 93.0 91.0 10:19:43 INFO - PROCESS | 2249 | 10:19:43 INFO - PROCESS | 2249 | [#8] json-parse-financial Cycles:10 Average:55.30 Median:56.00 stddev:1.16 (2.1%) stddev-sans-first:1.20 10:19:43 INFO - PROCESS | 2249 | Values: 56.0 56.0 55.0 56.0 53.0 57.0 56.0 55.0 55.0 54.0 10:19:43 INFO - PROCESS | 2249 | 10:19:43 INFO - PROCESS | 2249 | [#9] json-stringify-tinderbox Cycles:10 Average:88.60 Median:71.50 stddev:19.01 (26.6%) stddev-sans-first:19.07 10:19:43 INFO - PROCESS | 2249 | Values: 71.0 98.0 67.0 100.0 106.0 114.0 112.0 72.0 73.0 73.0 10:19:43 INFO - PROCESS | 2249 | 10:19:43 INFO - PROCESS | 2249 | [#10] stanford-crypto-aes Cycles:10 Average:85.10 Median:86.00 stddev:2.28 (2.7%) stddev-sans-first:2.32 10:19:43 INFO - PROCESS | 2249 | Values: 87.0 86.0 83.0 85.0 82.0 83.0 89.0 83.0 86.0 87.0 10:19:43 INFO - PROCESS | 2249 | 10:19:43 INFO - PROCESS | 2249 | [#11] stanford-crypto-ccm Cycles:10 Average:148.50 Median:150.50 stddev:15.17 (10.1%) stddev-sans-first:15.02 10:19:43 INFO - PROCESS | 2249 | Values: 133.0 135.0 139.0 138.0 145.0 151.0 150.0 158.0 185.0 151.0 10:19:43 INFO - PROCESS | 2249 | 10:19:43 INFO - PROCESS | 2249 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.30 Median:166.00 stddev:19.29 (11.6%) stddev-sans-first:20.28 10:19:43 INFO - PROCESS | 2249 | Values: 164.0 176.0 224.0 165.0 159.0 160.0 163.0 167.0 162.0 173.0 10:19:43 INFO - PROCESS | 2249 | 10:19:43 INFO - PROCESS | 2249 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.60 Median:67.50 stddev:5.87 (8.7%) stddev-sans-first:6.20 10:19:43 INFO - PROCESS | 2249 | Values: 68.0 64.0 65.0 67.0 67.0 67.0 65.0 75.0 81.0 77.0 10:19:43 INFO - PROCESS | 2249 | -------- Summary: end -------- 10:19:43 INFO - PROCESS | 2249 | 10:19:43 INFO - TEST-INFO | 2249: exit 0 10:19:43 INFO - TEST-OK | kraken | took 60549ms 10:19:43 INFO - SUITE-END | took 1173s 10:19:44 INFO - Completed test suite (00:19:33) 10:19:44 INFO - javascript benchmark 10:19:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7429.319619356203, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3335.08285221564, 3348.9162770930725, 3377.9324413511713, 3388.711959483444, 3396.473546600277, 5726.3414707308675, 5760.913586296204, 5824.563157763164, 5832.825015249544, 5845.8830823383505, 5643.774249030044, 5651.604387692863, 5801.854953626162, 5825.155352473888, 5847.181394604748, 4486.349479325496, 5272.9981303552395, 5333.813316533922, 5335.839924802262, 5363.436839131893, 5301.5228629423345, 5382.2464854920345, 5387.0, 5455.1544510600925, 5459.3994660587305, 5220.843374698765, 5337.893242135136, 5361.651492652965, 5379.677219366831, 5413.431589683086, 5233.398159211702, 5304.708241046741, 5341.225522299244, 5348.625596208267, 5366.812161574365, 4540.432445944257, 5090.032893750175, 5235.528802407764, 5242.370915490127, 5276.182191760283, 5655.575831812589, 5701.2018317435595, 5734.225879506255, 5782.884342313172, 5817.970910145422, 6230.906536401958, 6237.6257424554215, 6255.6872156392, 6307.936920630781, 6317.305096439388, 6248.875202463555, 6261.342559031282, 6275.592086514362, 6277.0, 6299.8740025199695, 5758.424157584208, 5864.501517370986, 5913.586048976574, 5914.852128696774, 5920.141579470996, 4744.0, 6267.592606480564, 6306.558540902093, 6351.333110023474, 6351.396617321347, 5153.6907785532985, 6192.380761923771, 6234.438900498977, 6236.781712640036, 6243.406876346739, 4905.877353066202, 5276.36683597963, 5598.768270980459, 6152.969235153885, 6215.12988181655, 6255.812325630239, 6265.843353916189, 6276.246850377893, 6281.7173227203975, 6288.52836037299, 6088.756449742049, 6287.245530536365, 6295.779647712308, 6297.086922396227, 6321.65230912295, 4374.912501749947, 4937.506249375106, 4946.4558898521855, 4992.8751781205765, 4998.250262460588, 3751.692708983955, 4438.533953934855, 4459.0, 4459.019015816515, 4539.931901021488, 5926.377730338252, 5979.431953964367, 5986.191864098378, 6058.1518587397795, 6059.48494377974, 6130.459285710702, 6136.570440069152, 6139.921477737008, 6222.906656400158, 6232.75068997235, 5939.07944268639, 6004.0693692477735, 6023.307319658208, 6036.396360363929, 6040.214772079602, 5482.835514934479, 5485.314335708036, 5498.945010549924, 5501.504864562128, 5521.475459831309, 5977.760889564455, 6234.251889773256, 6253.968095264412, 6415.230172379251, 6454.935450645527, 6112.564273424362, 6209.348018457997, 6210.161628180138, 6224.968875155776, 6231.75072997075, 6328.746850126127, 6362.395572420613, 6366.554341196072, 6377.713002914973, 6385.489160867212, 6105.8843398869, 6147.508199343953, 6157.32269450369, 6187.659678717714, 6217.098520714561, 5219.373675159005, 6196.81409557714, 6225.657588832658, 6244.625322480666, 6245.219347581552, 6051.364606716407, 6151.323354431099, 6172.722227499863, 6192.876142477216, 6213.1788949796455, 6047.0, 6117.908231376444, 6157.384261573807, 6163.106349579196, 6222.222222222223, 5181.730476033396, 5667.4332566743, 6052.182955300978, 6087.69561521931, 6137.1101190328045, 5522.751476183501, 5860.912086318794, 6243.750249990131, 6256.311805701278, 6278.780242691484, 5886.470217680429, 6019.849503762441, 6038.184845045863, 6052.394760523913, 6069.9089513656445], "value": 5704.203689833638, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [470.8869871230905, 528.1391332128629, 546.6200990311733, 550.5925615044865, 566.9007923613367, 39017.80491097544, 41827.581724182746, 41879.99999999996, 41918.95202619936, 41938.790306048424, 41682.37476437848, 41748.16503669925, 42349.0, 42420.0, 42423.57576424235, 9650.131488166064, 9786.706398808012, 9818.50907454628, 9849.458279794608, 9854.50727463624, 8164.550949697764, 8304.584770761468, 9758.170555502791, 9789.265805064613, 9838.45888476137, 9810.166135878459, 9813.362131461432, 9816.803663926717, 9874.654387096452, 9902.267103256938, 9781.706548803511, 9812.607495700164, 9887.0, 9894.653687120988, 9900.851487227697, 8344.41589088764, 9744.853827192597, 9758.365706229073, 9841.212702983745, 9849.45827979468, 40784.79607601943, 41128.79435602833, 41249.1750164995, 41261.1747765046, 41309.793451032856, 3022.637283525969, 3179.71382575567, 3324.5345651608795, 3490.1449144959647, 3546.989108104178, 29521.261968450744, 29821.0, 29950.95171669003, 31043.379132417453, 31234.843825780954, 3198.4882418812876, 3202.551642770014, 3204.279037216631, 3205.5191721241767, 3310.519974603669, 471615.0, 473240.0, 475320.99999999657, 476283.0, 477715.0, 33808.830955845064, 34049.65950340465, 34099.65900341015, 34219.828900855835, 34229.828850855585, 34963.4755478668, 35021.124471888405, 35323.4701479478, 35489.64510354863, 36597.63402365996, 17093.0, 17207.96752194872, 17212.31150753981, 17243.482695519153, 17289.13554322279, 13084.542041028519, 13106.0, 13191.934040329737, 13207.471701132039, 13213.009024323022, 17952.281908723504, 18778.81221187798, 19829.30597429083, 20117.994100294927, 20153.0, 8058.758237252891, 8193.296212249943, 8201.343892488587, 8212.219839115274, 8232.830360311204, 897.9775505612412, 947.2469386837481, 983.4296108257194, 995.8854731705947, 1075.3118004477142, 29709.554356684668, 30764.69235307663, 31055.84472077625, 31261.687383126333, 31481.898133565217, 1151.2344291046527, 1164.5109054197258, 1196.252342286071, 1208.8972437342925, 1217.7381862899342, 16012.67974640524, 16207.946483478772, 16217.675646487005, 16247.431339903047, 16454.670906581803, 698753.0, 753042.0, 757129.0, 757149.0, 758661.0, 60154.398456015755, 60662.39337606656, 60677.0, 60680.0, 60971.780564388464, 1413.6041908265702, 1416.3484796993293, 1462.093502028749, 1472.85271472854, 1473.8452462491498, 27824.860875695493, 30250.90249107485, 30384.0, 30510.0, 30706.701061850912, 48109.75945120252, 52314.738426307624, 52431.73784131055, 52440.7377963123, 52558.0, 530402.0, 531988.3602327855, 534974.0, 538196.0, 538991.0, 26497.94008239649, 26960.460790784466, 27404.040858569853, 27465.588016179372, 27552.86223568869, 48949.26576101433, 49988.75005625094, 50014.74992625014, 50278.96160410443, 51188.48811511837, 40402.1919561613, 40850.59149408468, 41810.0, 42450.51423200173, 42580.78709606432, 39671.0, 40583.7970810144, 40951.59048409478, 41064.58935410608, 42382.5761742391, 34280.82859585686, 40110.39834402428, 40251.99370015773, 40468.392974106, 40826.9793255171, 30588.235294116934, 32885.0, 33128.33743325168, 35901.11887626998, 36283.0, 22688.319350419515, 26348.077817276302, 26561.468770624866, 26605.86697066502, 26680.46639067169], "value": 19852.7888910663, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [378.4361301660527, 385.751190482139, 386.079838214163, 389.9025243689078, 413.2850169207271, 14738.778918316233, 19826.405207843753, 20065.398038058844, 21468.89265553672, 21470.248541301058, 20296.69554956673, 20376.898115509437, 20688.793112068874, 21163.47091322714, 21276.57446851066, 5420.159875219338, 5494.80768173114, 5548.611597188198, 5603.915941260884, 5608.747606357703, 4808.471068182497, 5410.972945135269, 5601.775928962837, 5606.271184745978, 5624.887502249952, 5488.9725551372385, 5496.31296087989, 5516.420775818541, 5580.330360356763, 5581.609287349887, 5448.891022179574, 5456.427075157111, 5563.638363506359, 5601.327840659126, 5610.69141197234, 5235.816746413857, 5329.8934021319355, 5402.0, 5406.0, 5420.945790542083, 13673.521426750116, 13894.374753136133, 21654.783452165593, 26219.475610487683, 26238.73761262382, 2086.1968142265337, 2699.716529764366, 3015.653199881998, 3028.8939887103847, 3156.163616641592, 10149.035841595036, 10185.59257629694, 10208.387496750218, 10325.89674103257, 10441.0, 1871.2234422714557, 1914.15777058094, 1944.3583617406364, 1946.1145178943548, 1960.2061165227963, 113971.72056558823, 121252.39373803163, 127985.0, 130196.34901825525, 131911.0, 9059.411138275991, 11623.709407264885, 11649.650510484698, 11808.232464889754, 11915.582954596548, 14918.477853275082, 14925.925370373296, 14926.955113142192, 14928.0, 14936.477223297132, 8446.489104116223, 8826.735197944072, 8832.955835220911, 8949.552522373855, 8977.551122443852, 8431.536265505456, 8738.519381434082, 8761.912380876238, 8938.50838203905, 8974.506402147814, 3859.9614003860165, 4048.716589838742, 4632.397788287569, 4661.650376221729, 4723.031778485402, 1666.5916850371736, 1940.1657287366286, 1979.4259664697365, 1987.7813440521531, 1989.7512810898638, 912.4570880326195, 924.2605915267759, 980.593053882645, 982.7690492734201, 984.2273815055147, 16785.496435106965, 16973.49079527616, 21159.25942592002, 22268.888655556617, 22637.88681056584, 903.8430808565047, 941.3833938770116, 964.8022155458115, 988.367444835306, 1001.5092604623827, 10856.62001829948, 11261.66215013551, 11266.718332041764, 11275.492602832892, 11303.773924521463, 517617.0, 523806.0, 636108.8194558998, 643425.0, 648360.0, 102615.0, 129582.0, 130123.0, 141511.0, 156372.2181389086, 957.7222605444343, 1009.2783659683362, 1030.258214085842, 1031.159604921986, 1043.1654676258963, 18694.906525467286, 19747.703784443533, 20013.09941052627, 20116.59766804685, 20319.59360812746, 56389.0, 58156.12765808432, 58603.4139658615, 59597.40402596092, 60651.69674151601, 86223.56888215519, 86638.13361866552, 88635.0, 89466.0, 90285.64571531564, 7891.092524359772, 7963.482373645695, 8134.0, 8198.262156405952, 8396.202360775716, 8764.693235736719, 10932.234743567873, 11872.703182420162, 12117.15179937396, 12194.75610487803, 9079.0, 9670.806583868423, 9775.315727898978, 9970.252231082553, 10633.20250981195, 8396.874046889423, 8480.448770829877, 10177.643782467578, 10253.718285214349, 10319.690409287434, 10983.340999540053, 11523.654290371302, 11605.593804216813, 11678.474468648761, 11953.0, 7181.425485961006, 7602.429817763579, 7991.041075071029, 8195.221453961864, 8288.751337459886, 11048.116150707763, 11184.608538701106, 11192.944035279772, 11415.143864210384, 11574.942125289319], "value": 9524.97620863877, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1508.2006536535637, 1565.982111627442, 1715.6654452381783, 1782.8662850286216, 1799.8380145786878, 7980.800479988004, 8377.623006964686, 8542.615582298797, 9015.737796708467, 9027.864582031274, 8638.308935285178, 8788.560571971408, 8795.25240354569, 8800.77998050049, 8812.118788121184, 2347.213683416058, 2371.9407014824596, 2371.988140059306, 2372.466195106105, 2382.273406610983, 2301.8503797253125, 2353.235198560466, 2370.691810064689, 2377.5007248477755, 2388.259639511757, 2346.4603141277516, 2352.5765362234793, 2357.9292621221307, 2358.1157066100213, 2370.20598099636, 2075.823554997827, 2232.526532488566, 2335.7313908900437, 2344.460774021976, 2350.0364850411333, 1925.2491528303972, 2276.112316196685, 2290.7171983689022, 2291.232437133562, 2299.6435552489393, 7675.7313494028, 8822.558872056436, 8847.42491738035, 8903.154200350957, 8907.0, 5213.504717051874, 5324.7071411072375, 5360.544353729938, 5366.543843773284, 5374.0, 3481.791092534431, 3566.696830769388, 3621.3481573316794, 3660.6705396514176, 3666.4866918631415, 1047.9633212837514, 1088.5917780832187, 1099.439285964156, 1113.9443027848574, 1115.949782259792, 11466.312021278667, 11572.76854462906, 11604.94197529007, 11605.593804216896, 11624.593139240085, 4231.449911511484, 4292.334688123346, 4620.1221767864, 4729.62163026957, 4786.210275304606, 9812.411255324703, 9861.457619830835, 9861.802763944825, 9868.802623947624, 9871.506424678737, 5282.603804714585, 5309.707966061904, 5310.2831117798605, 5311.123664595376, 5338.572914166857, 3771.867984620525, 4393.648508119343, 4778.546038126373, 4818.903621927542, 4890.706557606555, 3873.1479074603544, 3955.841766329372, 3978.4629075074495, 3999.6600288975765, 4034.23349563582, 1204.512172570093, 1251.205484517338, 1294.501616877509, 1301.1802564384377, 1317.3347459532874, 917.6100157433065, 946.1531928923713, 951.743029382063, 959.29971121082, 1008.8890222075565, 5871.618344807659, 5959.374265702126, 5988.071848863433, 5995.850103747442, 6101.7559297627595, 437.28067329243214, 440.5990548600758, 444.85542198785527, 448.89002194462563, 452.5316297631935, 3430.142464383904, 4023.0746928206677, 4146.4133524501085, 4149.481314835645, 4149.792510374469, 9777.951110244403, 10307.742306442398, 10425.687229383131, 10434.530446129942, 10445.634402795866, 10119.747006324753, 10408.947955260326, 10411.5835366586, 10422.635207767691, 10445.84331235032, 7936.4841285317425, 8073.555954422446, 8136.796580085427, 8136.796580085545, 8140.674373025131, 5360.946390536201, 5687.260656114844, 5847.356790753099, 5936.525077993664, 5972.8805423892145, 9675.322727409013, 9755.658551950648, 9793.265505087289, 9806.21550275962, 9813.0, 9668.419894806335, 9836.458994755358, 9837.803243935225, 9904.900950990397, 9912.702618921443, 6813.625250611264, 7242.456815738736, 7364.77905662831, 7469.88795168062, 7543.7736867894055, 8267.917320826715, 8444.704435344733, 8446.915530844859, 8459.915400845914, 8464.576771161293, 6189.195404597373, 7358.595277259802, 7505.437092218214, 7551.622418878924, 7612.505187162817, 6079.392060793885, 7362.631868406451, 7386.704531818667, 7404.925950740424, 7417.92582074194, 7029.718811247493, 7033.648317583998, 7035.33164349386, 7100.112485939258, 7115.0, 4648.395708557866, 5045.924311135409, 5084.771185296597, 5089.6691715038405, 5192.480751924928, 4457.219986502414, 4497.392851964943, 4942.55517003457, 5077.593792496666, 5168.896622067613], "value": 4465.845232813239, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [309.2901790391052, 312.6701330096747, 314.7324773942151, 318.53175831527653, 321.0737023686666, 4993.575546078588, 5563.610547261694, 5721.25623668923, 5738.913916291253, 5822.301323841134, 4220.873373798783, 4879.9024019519675, 5103.081445339837, 5310.283111779909, 5736.368999410061, 3620.2216523447455, 4076.653484453825, 4179.519355274137, 4240.936385954213, 4277.401163837066, 3710.2764960832515, 4220.451341325639, 4227.091175397289, 4258.148370325932, 4277.700560960734, 4246.150769846027, 4252.617264446199, 4256.637256567308, 4260.2532961485795, 4264.402983582301, 3655.6892664123316, 4063.573324800898, 4246.256905041636, 4260.488741351027, 4290.871273861789, 3053.2366908272934, 3665.6356649960626, 3675.558932928039, 3679.392900171495, 3696.890932720173, 5242.397124330674, 5366.6779993200535, 5446.945530544723, 5455.318085239345, 5462.317210348707, 4319.481662200525, 4660.277656963176, 4910.7299098549565, 4957.157283261854, 4965.875853103666, 4934.383202099738, 5174.560162386202, 5932.406759324076, 5961.791337303217, 5997.850053748648, 4121.443605113332, 4123.443335149746, 4123.60825721556, 4126.319157339036, 4135.152293779768, 4934.40787105546, 5931.792387266468, 6268.623882567061, 6297.937020629781, 6319.115323854664, 4133.297339452301, 4140.3168477201525, 4161.897097269226, 4170.874873753732, 4177.916441671149, 4186.665066794649, 4220.303649897794, 4224.324108142682, 4233.640140588023, 4243.6605071594195, 4407.889802754893, 4486.977565112154, 4701.48283688794, 4944.431390390079, 4958.016793282643, 5981.342052374235, 5993.250843644544, 6001.789937352171, 6004.127337867051, 6020.8795824084145, 1430.4421275702484, 1443.9819926951684, 1455.1519570145856, 1457.205822826562, 1460.9116208424791, 820.5774026376421, 886.264400547544, 888.4979986307716, 896.171041786345, 896.5472436419566, 4686.945437276654, 5224.320838291073, 5290.444503327172, 5508.531774799107, 5661.4806411147465, 6114.816555503342, 6229.813105606839, 6273.58466990212, 6277.246730392382, 6287.685615719196, 5407.378151512547, 5562.471565201299, 5626.621477737898, 5732.710140218417, 5809.651420914674, 2446.2783478873775, 2990.745786608119, 3270.296886169485, 3275.017494751565, 3287.671232876741, 4650.512115913065, 5626.1842032905, 5901.704914754245, 6473.708683109271, 6541.28045914949, 6329.810105696836, 6331.96834015827, 6397.528568429194, 6401.9039714404325, 6417.679116044273, 5397.514223719884, 6476.740930362733, 6486.967565162144, 6510.0, 6513.427196257547, 5892.941070589411, 6171.642238707477, 6172.839506172803, 6191.84520386994, 6263.937360626335, 6040.879182416239, 6152.753889844356, 6196.907046394217, 6198.442140207403, 6200.56596038273, 5363.115086010921, 6235.46998505114, 6387.904181437189, 6508.940797825015, 6510.381513756186, 3563.3051554946537, 3707.7404581680103, 3941.4876066111224, 4018.4173294871493, 4089.5910408958866, 6113.388661133851, 6200.3799620037635, 6202.875942481215, 6224.968875155595, 6225.0, 5686.516646085129, 5976.581639285208, 6000.819975400745, 6026.939730602638, 6048.90926636092, 5706.429357064427, 5986.2217911671205, 6014.518838492998, 6015.796840631803, 6056.152138700673, 4776.450708168605, 5197.818076367309, 5386.7575959082715, 5427.810026649048, 5883.0, 5407.864348486704, 5418.701971391453, 5681.778417640227, 5931.051031834886, 5992.67040312787, 5077.416097148875, 5140.691558506502, 5860.589758716849, 5868.092869817241, 5933.139694744152], "value": 4343.976145015694, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [749.490346564336, 769.1731388757086, 773.9187385324541, 842.755600875746, 868.374536310902, 15753.369865205392, 15908.125053122074, 16114.274857631406, 16117.435889743847, 16165.91917040416, 14420.783688244685, 14468.710625787504, 14691.0, 14691.77962330566, 14704.117752934804, 731.3089130771423, 746.4327111395352, 757.4243574883101, 910.0035461170024, 940.8306504829128, 756.4440136499668, 931.9301052421062, 939.9895112753783, 940.0787228516058, 949.5015117063527, 957.0381766324833, 985.6796541124128, 998.3859926145441, 1002.2332915319786, 1002.4887307473205, 976.1946394224758, 983.7688143286322, 985.8274801909708, 996.0, 999.7150812018613, 735.6726256815666, 737.9262073792631, 739.3124394313287, 741.0699572037113, 741.3328004795673, 14973.326200321011, 15215.923920380437, 15248.923755381264, 15533.922330388388, 15537.368576299552, 11980.34108124053, 14067.648308792259, 14279.643008924757, 14374.496892608708, 14453.638659033502, 13116.27860467674, 13246.40391182399, 13442.79835802454, 13554.0, 13930.164190148624, 4761.119192949315, 4807.447143578498, 4892.168331383673, 4967.826126085606, 4973.403191616993, 97052.51473742657, 113995.290070649, 116348.99999999916, 116588.0, 118333.0, 11491.712707182387, 11510.251833630788, 11632.418379081013, 11657.417129143509, 11935.0, 22730.43173920665, 23039.42401439944, 23121.768782311963, 23171.0, 23390.298291051295, 8153.633086511121, 8166.714165004197, 8248.505089694638, 8276.65809282625, 8384.329253659693, 4497.752623605667, 4593.54064593538, 4597.862064138081, 4647.047355292157, 4752.192127338291, 3518.894433167009, 3615.078155070493, 3615.2769446110356, 3619.76471529355, 3629.3467175908063, 649.9512536559777, 659.4361820643377, 661.83784972682, 672.5829985409077, 676.3946268090032, 619.7242227208849, 640.4236187431256, 642.9807105786834, 666.2737615998487, 670.4569298867932, 8223.465474744122, 9100.135487128713, 9133.497657628894, 9173.128552787475, 9192.126747958933, 599.9190109335184, 629.553017357672, 630.5586089737202, 652.5366989437458, 653.6794379951154, 4454.198244316054, 4490.3040028795585, 4498.797554110073, 4505.07645932583, 4555.1117532080925, 88836.11163888407, 89021.66467502993, 89217.0, 132184.0, 145346.0, 78332.0, 105545.94454055515, 125461.11808322882, 126384.1042384365, 131018.0, 102995.0, 103564.48217759014, 112839.43580282046, 114379.42810286062, 115170.0, 9991.800163996824, 10049.0, 10177.389356638625, 10321.690349289533, 10426.582936682447, 58734.0, 60278.0, 62178.68910655418, 62700.68649656723, 63560.36439635544, 93362.53318733363, 94160.05839941512, 95623.04376956141, 96857.51571242098, 98972.0, 13502.257375844423, 13563.86436135626, 13706.314684265548, 13868.375923083688, 14164.575062748134, 15283.694326113638, 15476.535703928901, 15523.0, 15594.986325888782, 16083.75874361862, 14164.716705666035, 14195.219262940638, 14312.785308220593, 14344.282785860874, 14661.853381466475, 14238.857611423753, 14341.856581434053, 14384.784228236795, 14766.704665906836, 14801.777973330623, 11820.70448238801, 12610.432530535965, 14340.784888226477, 14516.854831451972, 14633.34149963233, 9597.616095356108, 10496.212784041074, 11279.887201127884, 11425.771484570096, 12009.339486327986, 8734.82530349402, 10376.118029967236, 11667.804413794396, 12017.399130043637, 12170.513179473077], "value": 8034.864872142665, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1587.0999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 109.0, 117.0, 118.0, 116.0, 101.0, 97.0, 146.0, 166.0], "value": 120.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 112.0, 135.0, 178.0, 138.0, 140.0, 161.0, 143.0, 176.0, 107.0], "value": 145.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 201.0, 205.0, 230.0, 229.0, 271.0, 206.0, 203.0, 201.0, 195.0], "value": 213.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 84.0, 75.0, 147.0, 58.0, 58.0, 57.0, 59.0, 136.0, 58.0], "value": 80.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 125.0, 98.0, 71.0, 88.0, 116.0, 65.0, 74.0, 124.0], "value": 90.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 86.0, 135.0, 130.0, 90.0, 85.0, 92.0, 88.0, 89.0, 87.0], "value": 97.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 95.0, 104.0, 94.0, 150.0, 139.0, 99.0, 126.0, 120.0], "value": 111.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 147.0, 123.0, 92.0, 118.0, 149.0, 90.0, 92.0, 93.0, 91.0], "value": 109.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 55.0, 56.0, 53.0, 57.0, 56.0, 55.0, 55.0, 54.0], "value": 55.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 98.0, 67.0, 100.0, 106.0, 114.0, 112.0, 72.0, 73.0, 73.0], "value": 88.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 83.0, 85.0, 82.0, 83.0, 89.0, 83.0, 86.0, 87.0], "value": 85.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 135.0, 139.0, 138.0, 145.0, 151.0, 150.0, 158.0, 185.0, 151.0], "value": 148.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 176.0, 224.0, 165.0, 159.0, 160.0, 163.0, 167.0, 162.0, 173.0], "value": 171.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 64.0, 65.0, 67.0, 67.0, 67.0, 65.0, 75.0, 81.0, 77.0], "value": 69.6, "unit": "score"}]}]} 10:19:44 INFO - Return code: 0 10:19:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:19:44 ERROR - # TBPL SUCCESS # 10:19:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:19:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:19:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:19:44 INFO - 2016-07-20 10:19:44.385 screenresolution[2254:19890] starting screenresolution argv=screenresolution get 10:19:44 INFO - 2016-07-20 10:19:44.388 screenresolution[2254:19890] Display 0: 1600x1200x32@60 10:19:44 INFO - 2016-07-20 10:19:44.394 screenresolution[2255:19893] starting screenresolution argv=screenresolution list 10:19:44 INFO - Available Modes on Display 0 10:19:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:19:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:19:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:19:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:19:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:19:44 INFO - Intel Iris: 10:19:44 INFO - Chipset Model: Intel Iris 10:19:44 INFO - Type: GPU 10:19:44 INFO - Bus: Built-In 10:19:44 INFO - VRAM (Dynamic, Max): 1536 MB 10:19:44 INFO - Vendor: Intel (0x8086) 10:19:44 INFO - Device ID: 0x0a2e 10:19:44 INFO - Revision ID: 0x0009 10:19:44 INFO - Displays: 10:19:44 INFO - Display: 10:19:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:19:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:19:44 INFO - Display Serial Number: 1600x1200 60 10:19:44 INFO - Main Display: Yes 10:19:44 INFO - Mirror: Off 10:19:44 INFO - Online: Yes 10:19:44 INFO - Rotation: Supported 10:19:44 INFO - Return code: 0 10:19:44 INFO - Running post-action listener: _resource_record_post_action 10:19:44 INFO - [mozharness: 2016-07-20 17:19:44.628855Z] Finished run-tests step (success) 10:19:44 INFO - Running post-run listener: _resource_record_post_run 10:19:44 INFO - Total resource usage - Wall time: 1209s; CPU: 55.0%; Read bytes: 647168; Write bytes: 711226880; Read time: 8; Write time: 2024 10:19:44 INFO - TinderboxPrint: CPU usage
54.6% 10:19:44 INFO - TinderboxPrint: I/O read bytes / time
647,168 / 8 10:19:44 INFO - TinderboxPrint: I/O write bytes / time
711,226,880 / 2,024 10:19:44 INFO - TinderboxPrint: CPU idle
2,196.4 (45.4%) 10:19:44 INFO - TinderboxPrint: CPU system
984.9 (20.4%) 10:19:44 INFO - TinderboxPrint: CPU user
1,656.3 (34.2%) 10:19:44 INFO - TinderboxPrint: Swap in / out
408,739,840 / 0 10:19:44 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 386763264; Write bytes: 383808512; Read time: 29344; Write time: 833 10:19:44 INFO - run-tests - Wall time: 1175s; CPU: 55.0%; Read bytes: 647168; Write bytes: 298095104; Read time: 8; Write time: 1124 10:19:45 INFO - Running post-run listener: _upload_blobber_files 10:19:45 INFO - Blob upload gear active. 10:19:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:19:45 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:19:45 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:19:45 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:19:45 INFO - (blobuploader) - INFO - Open directory for files ... 10:19:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:19:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:19:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:19:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:19:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:19:46 INFO - (blobuploader) - INFO - Done attempting. 10:19:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:19:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:19:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:19:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:19:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:19:47 INFO - (blobuploader) - INFO - Done attempting. 10:19:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:19:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:19:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:19:51 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:19:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:19:51 INFO - (blobuploader) - INFO - Done attempting. 10:19:51 INFO - (blobuploader) - INFO - Iteration through files over. 10:19:51 INFO - Return code: 0 10:19:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:19:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:19:51 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/6d014a6b4c31565dc9040cc5bed8461a09158b214dc2ff78c85dfc90ca8634fdda2d001645dd07e49515a9df7eef1db616c6e6573932ea2c0ddd69c49ead2e88", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/636f7126b317b99f14bc9563578e0954a905f1a1d2ccc237722e3bc12b6d482563152f07a815ba82585ac1050327f052f3f89cc1777b1a971b84069f83155d21", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9414a56062dd9dd3328cb69d2f0511206abc89d0ca2ff8d6d1b9e1d42a03621e5edf859917bea886af2af79496b5ef6b8b813bf5f54ce2da2e60b438acf09c0e"} 10:19:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:19:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:19:51 INFO - Contents: 10:19:51 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/6d014a6b4c31565dc9040cc5bed8461a09158b214dc2ff78c85dfc90ca8634fdda2d001645dd07e49515a9df7eef1db616c6e6573932ea2c0ddd69c49ead2e88", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/636f7126b317b99f14bc9563578e0954a905f1a1d2ccc237722e3bc12b6d482563152f07a815ba82585ac1050327f052f3f89cc1777b1a971b84069f83155d21", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9414a56062dd9dd3328cb69d2f0511206abc89d0ca2ff8d6d1b9e1d42a03621e5edf859917bea886af2af79496b5ef6b8b813bf5f54ce2da2e60b438acf09c0e"} 10:19:51 INFO - Running post-run listener: copy_logs_to_upload_dir 10:19:51 INFO - Copying logs to upload dir... 10:19:51 INFO - mkdir: /builds/slave/test/build/upload/logs 10:19:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1263.089426 ========= master_lag: 0.15 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-07-20 10:19:51.844291) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-20 10:19:51.848221) ========= 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.3ZRviOXtjo/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.mSW8exhuQj/Listeners TMPDIR=/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/6d014a6b4c31565dc9040cc5bed8461a09158b214dc2ff78c85dfc90ca8634fdda2d001645dd07e49515a9df7eef1db616c6e6573932ea2c0ddd69c49ead2e88", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/636f7126b317b99f14bc9563578e0954a905f1a1d2ccc237722e3bc12b6d482563152f07a815ba82585ac1050327f052f3f89cc1777b1a971b84069f83155d21", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9414a56062dd9dd3328cb69d2f0511206abc89d0ca2ff8d6d1b9e1d42a03621e5edf859917bea886af2af79496b5ef6b8b813bf5f54ce2da2e60b438acf09c0e"} build_url:https://queue.taskcluster.net/v1/task/Sqx39QfRRz6bcXB0zAAfkA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010970 build_url: 'https://queue.taskcluster.net/v1/task/Sqx39QfRRz6bcXB0zAAfkA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/6d014a6b4c31565dc9040cc5bed8461a09158b214dc2ff78c85dfc90ca8634fdda2d001645dd07e49515a9df7eef1db616c6e6573932ea2c0ddd69c49ead2e88", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/636f7126b317b99f14bc9563578e0954a905f1a1d2ccc237722e3bc12b6d482563152f07a815ba82585ac1050327f052f3f89cc1777b1a971b84069f83155d21", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9414a56062dd9dd3328cb69d2f0511206abc89d0ca2ff8d6d1b9e1d42a03621e5edf859917bea886af2af79496b5ef6b8b813bf5f54ce2da2e60b438acf09c0e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-20 10:19:51.881047) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 10:19:51.881364) ========= 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.3ZRviOXtjo/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.mSW8exhuQj/Listeners TMPDIR=/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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.004997 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 10:19:51.929732) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-20 10:19:51.930035) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-20 10:19:51.930333) ========= ========= Total master_lag: 0.29 =========