builder: autoland_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0289 starttime: 1472790565.28 results: success (0) buildid: 20160901175542 builduid: 2e92dedc2ebc4343863f17c27a033c44 revision: e12415c1560179174bd3bdaca11dd20e6b97d35a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 21:29:25.277418) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 21:29:25.277868) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 21:29:25.278140) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 21:29:25.303244) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 21:29:25.303623) ========= 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.7gbodLJKqD/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.ouobj3VpVY/Listeners TMPDIR=/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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-09-01 21:29:25-- 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% 19.5M=0.001s 2016-09-01 21:29:25 (19.5 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.159483 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 21:29:25.486015) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 21:29:25.486325) ========= 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.7gbodLJKqD/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.ouobj3VpVY/Listeners TMPDIR=/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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.026672 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 21:29:25.534259) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-01 21:29:25.534602) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev e12415c1560179174bd3bdaca11dd20e6b97d35a --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 e12415c1560179174bd3bdaca11dd20e6b97d35a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.7gbodLJKqD/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.ouobj3VpVY/Listeners TMPDIR=/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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-09-01 21:29:25,620 truncating revision to first 12 chars 2016-09-01 21:29:25,620 Setting DEBUG logging. 2016-09-01 21:29:25,620 attempt 1/10 2016-09-01 21:29:25,620 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/e12415c15601?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-01 21:29:26,772 unpacking tar archive at: autoland-e12415c15601/testing/mozharness/ program finished with exit code 0 elapsedTime=1.423716 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-01 21:29:26.980465) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 21:29:26.980797) ========= script_repo_revision: e12415c1560179174bd3bdaca11dd20e6b97d35a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 21:29:26.981245) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 21:29:26.981700) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 21:29:27.000312) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-09-01 21:29:27.000702) ========= /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.7gbodLJKqD/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.ouobj3VpVY/Listeners TMPDIR=/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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 21:29:27 INFO - MultiFileLogger online at 20160901 21:29:27 in /builds/slave/test 21:29:27 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 21:29:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:29:27 INFO - {'append_to_log': False, 21:29:27 INFO - 'base_work_dir': '/builds/slave/test', 21:29:27 INFO - 'blob_upload_branch': 'Autoland', 21:29:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:29:27 INFO - 'branch': 'Autoland', 21:29:27 INFO - 'buildbot_json_path': 'buildprops.json', 21:29:27 INFO - 'config_files': ('talos/mac_config.py',), 21:29:27 INFO - 'default_actions': ('clobber', 21:29:27 INFO - 'read-buildbot-config', 21:29:27 INFO - 'download-and-extract', 21:29:27 INFO - 'populate-webroot', 21:29:27 INFO - 'create-virtualenv', 21:29:27 INFO - 'install', 21:29:27 INFO - 'run-tests'), 21:29:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:29:27 INFO - 'download_minidump_stackwalk': True, 21:29:27 INFO - 'download_symbols': 'ondemand', 21:29:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:29:27 INFO - 'tooltool.py': '/tools/tooltool.py', 21:29:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:29:27 INFO - '/tools/misc-python/virtualenv.py')}, 21:29:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:29:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:29:27 INFO - 'installer_path': 'installer.exe', 21:29:27 INFO - 'log_level': 'info', 21:29:27 INFO - 'log_name': 'talos', 21:29:27 INFO - 'log_to_console': True, 21:29:27 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 21:29:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 21:29:27 INFO - 'opt_config_files': (), 21:29:27 INFO - 'pip_index': False, 21:29:27 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:29:27 INFO - 'cmd': ('bash', 21:29:27 INFO - '-c', 21:29:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:29:27 INFO - 'enabled': True, 21:29:27 INFO - 'halt_on_failure': False, 21:29:27 INFO - 'name': 'check_screen_resolution'},), 21:29:27 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:29:27 INFO - 'cmd': ('bash', 21:29:27 INFO - '-c', 21:29:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:29:27 INFO - 'enabled': True, 21:29:27 INFO - 'halt_on_failure': False, 21:29:27 INFO - 'name': 'check_screen_resolution'},), 21:29:27 INFO - 'run_cmd_checks_enabled': True, 21:29:27 INFO - 'sps_profile': False, 21:29:27 INFO - 'sps_profile_interval': 0, 21:29:27 INFO - 'suite': 'dromaeojs-e10s', 21:29:27 INFO - 'system_bits': '32', 21:29:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:29:27 INFO - 'title': 't-yosemite-r7-0289', 21:29:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:29:27 INFO - 'use_talos_json': True, 21:29:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:29:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:29:27 INFO - 'work_dir': 'build'} 21:29:27 INFO - [mozharness: 2016-09-02 04:29:27.197165Z] Running clobber step. 21:29:27 INFO - Running pre-action listener: _resource_record_pre_action 21:29:27 INFO - Running main action method: clobber 21:29:27 INFO - rmtree: /builds/slave/test/build 21:29:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:29:29 INFO - Running post-action listener: _resource_record_post_action 21:29:29 INFO - [mozharness: 2016-09-02 04:29:29.227639Z] Finished clobber step (success) 21:29:29 INFO - [mozharness: 2016-09-02 04:29:29.227866Z] Running read-buildbot-config step. 21:29:29 INFO - Running pre-action listener: _resource_record_pre_action 21:29:29 INFO - Running main action method: read_buildbot_config 21:29:29 INFO - Using buildbot properties: 21:29:29 INFO - { 21:29:29 INFO - "project": "", 21:29:29 INFO - "product": "firefox", 21:29:29 INFO - "script_repo_revision": "production", 21:29:29 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs-e10s", 21:29:29 INFO - "repository": "", 21:29:29 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs-e10s", 21:29:29 INFO - "buildid": "20160901175542", 21:29:29 INFO - "pgo_build": "False", 21:29:29 INFO - "basedir": "/builds/slave/test", 21:29:29 INFO - "buildnumber": 165, 21:29:29 INFO - "slavename": "t-yosemite-r7-0289", 21:29:29 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 21:29:29 INFO - "platform": "yosemite_r7", 21:29:29 INFO - "scheduler": "tests-autoland-macosx64-talos-7-3600", 21:29:29 INFO - "branch": "autoland", 21:29:29 INFO - "repo_path": "integration/autoland", 21:29:29 INFO - "revision": "e12415c1560179174bd3bdaca11dd20e6b97d35a", 21:29:29 INFO - "stage_platform": "macosx64", 21:29:29 INFO - "builduid": "2e92dedc2ebc4343863f17c27a033c44", 21:29:29 INFO - "slavebuilddir": "test" 21:29:29 INFO - } 21:29:29 INFO - Found installer url https://queue.taskcluster.net/v1/task/d-fNWJ9XRVSurVLPiYbNuw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 21:29:29 INFO - Running post-action listener: _resource_record_post_action 21:29:29 INFO - [mozharness: 2016-09-02 04:29:29.233740Z] Finished read-buildbot-config step (success) 21:29:29 INFO - [mozharness: 2016-09-02 04:29:29.233998Z] Running download-and-extract step. 21:29:29 INFO - Running pre-action listener: _resource_record_pre_action 21:29:29 INFO - Running main action method: download_and_extract 21:29:29 INFO - mkdir: /builds/slave/test/build/tests 21:29: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:29:29 INFO - trying https://queue.taskcluster.net/v1/task/d-fNWJ9XRVSurVLPiYbNuw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 21:29:29 INFO - Downloading https://queue.taskcluster.net/v1/task/d-fNWJ9XRVSurVLPiYbNuw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 21:29:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/d-fNWJ9XRVSurVLPiYbNuw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 21:29:30 INFO - Downloaded 1199 bytes. 21:29:30 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 21:29:30 INFO - Using the following test package requirements: 21:29:30 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 21:29:30 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:29:30 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 21:29:30 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:29:30 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 21:29:30 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:29:30 INFO - u'jsshell-mac.zip'], 21:29:30 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:29:30 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 21:29:30 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 21:29:30 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:29:30 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 21:29:30 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:29:30 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 21:29:30 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:29:30 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 21:29:30 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:29:30 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 21:29:30 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 21:29:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:29:30 INFO - trying https://queue.taskcluster.net/v1/task/d-fNWJ9XRVSurVLPiYbNuw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 21:29:30 INFO - Downloading https://queue.taskcluster.net/v1/task/d-fNWJ9XRVSurVLPiYbNuw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 21:29:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/d-fNWJ9XRVSurVLPiYbNuw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 21:29:31 INFO - Downloaded 16399471 bytes. 21:29:31 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 21:29:38 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 21:29:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:29:38 INFO - trying https://queue.taskcluster.net/v1/task/d-fNWJ9XRVSurVLPiYbNuw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 21:29:38 INFO - Downloading https://queue.taskcluster.net/v1/task/d-fNWJ9XRVSurVLPiYbNuw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 21:29:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/d-fNWJ9XRVSurVLPiYbNuw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 21:29:39 INFO - Downloaded 16399471 bytes. 21:29:39 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 21:29:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:29:45 INFO - trying https://queue.taskcluster.net/v1/task/d-fNWJ9XRVSurVLPiYbNuw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 21:29:45 INFO - Downloading https://queue.taskcluster.net/v1/task/d-fNWJ9XRVSurVLPiYbNuw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip 21:29:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/d-fNWJ9XRVSurVLPiYbNuw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip'}, attempt #1 21:29:46 INFO - Downloaded 14413998 bytes. 21:29:46 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 21:29:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:29:46 INFO - trying https://queue.taskcluster.net/v1/task/d-fNWJ9XRVSurVLPiYbNuw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 21:29:46 INFO - Downloading https://queue.taskcluster.net/v1/task/d-fNWJ9XRVSurVLPiYbNuw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 21:29:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/d-fNWJ9XRVSurVLPiYbNuw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 21:29:49 INFO - Downloaded 111601513 bytes. 21:29:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/d-fNWJ9XRVSurVLPiYbNuw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 21:29:49 INFO - mkdir: /builds/slave/test/properties 21:29:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:29:49 INFO - Writing to file /builds/slave/test/properties/build_url 21:29:49 INFO - Contents: 21:29:49 INFO - build_url:https://queue.taskcluster.net/v1/task/d-fNWJ9XRVSurVLPiYbNuw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 21:29:50 INFO - Running post-action listener: _resource_record_post_action 21:29:50 INFO - Running post-action listener: set_extra_try_arguments 21:29:50 INFO - [mozharness: 2016-09-02 04:29:50.242074Z] Finished download-and-extract step (success) 21:29:50 INFO - [mozharness: 2016-09-02 04:29:50.242245Z] Running populate-webroot step. 21:29:50 INFO - Running pre-action listener: _resource_record_pre_action 21:29:50 INFO - Running main action method: populate_webroot 21:29:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:29:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:29:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:29:50 INFO - '--tppagecycles', 21:29:50 INFO - '7'], 21:29:50 INFO - 'tests': ['tsvgm']}}, 21:29:50 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:29:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:29:50 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:29:50 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:29:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:29:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:29:50 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:29:50 INFO - 'talos_options': ['--disable-e10s'], 21:29:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:29:50 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:29:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:29:50 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:29:50 INFO - 'talos_options': ['--disable-e10s'], 21:29:50 INFO - 'tests': ['damp', 'tps']}, 21:29:50 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:29:50 INFO - 'tests': ['damp', 'tps']}, 21:29:50 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:29:50 INFO - 'tests': ['dromaeo_dom']}, 21:29:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:29:50 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:29:50 INFO - 'tests': ['basic_compositor_video']}, 21:29:50 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:29:50 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:29:50 INFO - 'tests': ['a11yr', 21:29:50 INFO - 'ts_paint', 21:29:50 INFO - 'tpaint', 21:29:50 INFO - 'sessionrestore', 21:29:50 INFO - 'sessionrestore_no_auto_restore', 21:29:50 INFO - 'tabpaint']}, 21:29:50 INFO - 'other-e10s': {'tests': ['a11yr', 21:29:50 INFO - 'ts_paint', 21:29:50 INFO - 'tpaint', 21:29:50 INFO - 'sessionrestore', 21:29:50 INFO - 'sessionrestore_no_auto_restore', 21:29:50 INFO - 'tabpaint']}, 21:29:50 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:29:50 INFO - 'ts_paint', 21:29:50 INFO - 'tpaint', 21:29:50 INFO - 'sessionrestore', 21:29:50 INFO - 'sessionrestore_no_auto_restore', 21:29:50 INFO - 'tabpaint']}, 21:29:50 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:29:50 INFO - 'ts_paint', 21:29:50 INFO - 'tpaint', 21:29:50 INFO - 'sessionrestore', 21:29:50 INFO - 'sessionrestore_no_auto_restore', 21:29:50 INFO - 'tabpaint']}, 21:29:50 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:29:50 INFO - 'tests': ['a11yr', 21:29:50 INFO - 'ts_paint', 21:29:50 INFO - 'tpaint', 21:29:50 INFO - 'sessionrestore', 21:29:50 INFO - 'sessionrestore_no_auto_restore', 21:29:50 INFO - 'tabpaint']}, 21:29:50 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:29:50 INFO - 'tests': ['a11yr', 21:29:50 INFO - 'ts_paint', 21:29:50 INFO - 'tpaint', 21:29:50 INFO - 'sessionrestore', 21:29:50 INFO - 'sessionrestore_no_auto_restore', 21:29:50 INFO - 'tabpaint']}, 21:29:50 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:29:50 INFO - 'tests': ['tsvgx', 21:29:50 INFO - 'tsvgr_opacity', 21:29:50 INFO - 'tart', 21:29:50 INFO - 'tscrollx', 21:29:50 INFO - 'cart']}, 21:29:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:29:50 INFO - 'tsvgr_opacity', 21:29:50 INFO - 'tart', 21:29:50 INFO - 'tscrollx', 21:29:50 INFO - 'cart']}, 21:29:50 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:29:50 INFO - 'talos_options': ['--disable-e10s'], 21:29:50 INFO - 'tests': ['tp5o']}, 21:29:50 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:29:50 INFO - 'tests': ['tp5o']}, 21:29:50 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:29:50 INFO - 'talos_options': ['--disable-e10s', 21:29:50 INFO - '--xperf_path', 21:29:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:29:50 INFO - 'tests': ['tp5n']}, 21:29:50 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:29:50 INFO - 'talos_options': ['--xperf_path', 21:29:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:29:50 INFO - 'tests': ['tp5n']}}, 21:29:50 INFO - 'talos.zip': {'path': '', 21:29:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:29:50 INFO - Running post-action listener: _resource_record_post_action 21:29:50 INFO - [mozharness: 2016-09-02 04:29:50.260569Z] Finished populate-webroot step (success) 21:29:50 INFO - [mozharness: 2016-09-02 04:29:50.260674Z] Running create-virtualenv step. 21:29:50 INFO - Running pre-action listener: _resource_record_pre_action 21:29:50 INFO - Running main action method: create_virtualenv 21:29:50 INFO - Creating virtualenv /builds/slave/test/build/venv 21:29:50 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 21:29:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:29:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:29:50 INFO - Using real prefix '/tools/python27' 21:29:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:29:51 INFO - Installing distribute.............................................................................................................................................................................................done. 21:29:53 INFO - Installing pip.................done. 21:29:53 INFO - Return code: 0 21:29:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:29:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:29:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:29:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:29:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:29:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:53 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 0x10c36da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c402618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc491d861b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2d1d30>, '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 0x10c036db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c2c9b20>, '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.7gbodLJKqD/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.ouobj3VpVY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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 21:29:53 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 21:29:53 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 21:29:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7gbodLJKqD/Render', 21:29:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:29:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:29:53 INFO - 'HOME': '/Users/cltbld', 21:29:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:29:53 INFO - 'LOGNAME': 'cltbld', 21:29:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:29:53 INFO - 'MOZ_NO_REMOTE': '1', 21:29:53 INFO - 'NO_EM_RESTART': '1', 21:29:53 INFO - 'PAGER': '/bin/cat', 21:29:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:29:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:29:53 INFO - 'PWD': '/builds/slave/test', 21:29:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:29:53 INFO - 'SHELL': '/bin/bash', 21:29:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ouobj3VpVY/Listeners', 21:29:53 INFO - 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', 21:29:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:29:53 INFO - 'USER': 'cltbld', 21:29:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:29:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:29:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:29:53 INFO - 'XPC_FLAGS': '0x0', 21:29:53 INFO - 'XPC_SERVICE_NAME': '0', 21:29:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:29:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:29:54 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 21:29:54 INFO - Cleaning up... 21:29:54 INFO - Return code: 0 21:29:54 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:29:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:29:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:29:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:29:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:29:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:54 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 0x10c36da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c402618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc491d861b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2d1d30>, '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 0x10c036db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c2c9b20>, '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.7gbodLJKqD/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.ouobj3VpVY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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 21:29:54 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 21:29:54 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 21:29:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7gbodLJKqD/Render', 21:29:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:29:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:29:54 INFO - 'HOME': '/Users/cltbld', 21:29:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:29:54 INFO - 'LOGNAME': 'cltbld', 21:29:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:29:54 INFO - 'MOZ_NO_REMOTE': '1', 21:29:54 INFO - 'NO_EM_RESTART': '1', 21:29:54 INFO - 'PAGER': '/bin/cat', 21:29:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:29:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:29:54 INFO - 'PWD': '/builds/slave/test', 21:29:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:29:54 INFO - 'SHELL': '/bin/bash', 21:29:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ouobj3VpVY/Listeners', 21:29:54 INFO - 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', 21:29:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:29:54 INFO - 'USER': 'cltbld', 21:29:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:29:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:29:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:29:54 INFO - 'XPC_FLAGS': '0x0', 21:29:54 INFO - 'XPC_SERVICE_NAME': '0', 21:29:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:29:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:29:54 INFO - Downloading/unpacking psutil>=3.1.1 21:29:54 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 21:29:54 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 21:29:54 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 21:29:54 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 21:29:54 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 21:29:54 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 21:29:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:29:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:29:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:29:57 INFO - Installing collected packages: psutil 21:29:57 INFO - Running setup.py install for psutil 21:29:57 INFO - building 'psutil._psutil_osx' extension 21:29:57 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 21:29:58 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 21:29:58 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 21:29:58 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 21:29:58 INFO - building 'psutil._psutil_posix' extension 21:29:58 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 21:29:58 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 21:29:58 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 21:29:58 INFO - ^ 21:29:58 INFO - 1 warning generated. 21:29:58 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 21:29:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:29:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:29:58 INFO - Successfully installed psutil 21:29:58 INFO - Cleaning up... 21:29:59 INFO - Return code: 0 21:29:59 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:29:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:29:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:29:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:29:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:29:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c36da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c402618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc491d861b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2d1d30>, '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 0x10c036db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c2c9b20>, '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.7gbodLJKqD/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.ouobj3VpVY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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 21:29:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 21:29:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 21:29:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7gbodLJKqD/Render', 21:29:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:29:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:29:59 INFO - 'HOME': '/Users/cltbld', 21:29:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:29:59 INFO - 'LOGNAME': 'cltbld', 21:29:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:29:59 INFO - 'MOZ_NO_REMOTE': '1', 21:29:59 INFO - 'NO_EM_RESTART': '1', 21:29:59 INFO - 'PAGER': '/bin/cat', 21:29:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:29:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:29:59 INFO - 'PWD': '/builds/slave/test', 21:29:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:29:59 INFO - 'SHELL': '/bin/bash', 21:29:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ouobj3VpVY/Listeners', 21:29:59 INFO - 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', 21:29:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:29:59 INFO - 'USER': 'cltbld', 21:29:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:29:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:29:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:29:59 INFO - 'XPC_FLAGS': '0x0', 21:29:59 INFO - 'XPC_SERVICE_NAME': '0', 21:29:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:29:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:29:59 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:29:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:29:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:29:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:29:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:29:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:29:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:30:01 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:30:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:30:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:30:01 INFO - Installing collected packages: mozsystemmonitor 21:30:01 INFO - Running setup.py install for mozsystemmonitor 21:30:02 INFO - Successfully installed mozsystemmonitor 21:30:02 INFO - Cleaning up... 21:30:02 INFO - Return code: 0 21:30:02 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:30:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:30:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:30:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:30:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:30:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:30:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:30:02 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 0x10c36da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c402618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc491d861b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2d1d30>, '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 0x10c036db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c2c9b20>, '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.7gbodLJKqD/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.ouobj3VpVY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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 21:30:02 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 21:30:02 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 21:30:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7gbodLJKqD/Render', 21:30:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:30:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:30:02 INFO - 'HOME': '/Users/cltbld', 21:30:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:30:02 INFO - 'LOGNAME': 'cltbld', 21:30:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:30:02 INFO - 'MOZ_NO_REMOTE': '1', 21:30:02 INFO - 'NO_EM_RESTART': '1', 21:30:02 INFO - 'PAGER': '/bin/cat', 21:30:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:30:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:30:02 INFO - 'PWD': '/builds/slave/test', 21:30:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:30:02 INFO - 'SHELL': '/bin/bash', 21:30:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ouobj3VpVY/Listeners', 21:30:02 INFO - 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', 21:30:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:30:02 INFO - 'USER': 'cltbld', 21:30:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:30:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:30:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:30:02 INFO - 'XPC_FLAGS': '0x0', 21:30:02 INFO - 'XPC_SERVICE_NAME': '0', 21:30:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:30:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:30:02 INFO - Downloading/unpacking jsonschema==2.5.1 21:30:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:30:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:30:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:30:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:30:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:30:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:30:04 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:30:04 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:30:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:30:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:30:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:30:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:30:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:30:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:30:05 INFO - Downloading functools32-3.2.3-2.tar.gz 21:30:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:30:05 INFO - warning: no files found matching '*.txt' 21:30:05 INFO - no previously-included directories found matching 'build' 21:30:05 INFO - no previously-included directories found matching 'dist' 21:30:05 INFO - no previously-included directories found matching '.git*' 21:30:05 INFO - Installing collected packages: jsonschema, functools32 21:30:05 INFO - Running setup.py install for functools32 21:30:05 INFO - warning: no files found matching '*.txt' 21:30:05 INFO - no previously-included directories found matching 'build' 21:30:05 INFO - no previously-included directories found matching 'dist' 21:30:05 INFO - no previously-included directories found matching '.git*' 21:30:05 INFO - Successfully installed jsonschema functools32 21:30:05 INFO - Cleaning up... 21:30:05 INFO - Return code: 0 21:30:05 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:30:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:30:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:30:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:30:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:30:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:30:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:30:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c36da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c402618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc491d861b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2d1d30>, '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 0x10c036db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c2c9b20>, '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.7gbodLJKqD/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.ouobj3VpVY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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 21:30:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 21:30:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 21:30:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7gbodLJKqD/Render', 21:30:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:30:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:30:05 INFO - 'HOME': '/Users/cltbld', 21:30:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:30:05 INFO - 'LOGNAME': 'cltbld', 21:30:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:30:05 INFO - 'MOZ_NO_REMOTE': '1', 21:30:05 INFO - 'NO_EM_RESTART': '1', 21:30:05 INFO - 'PAGER': '/bin/cat', 21:30:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:30:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:30:05 INFO - 'PWD': '/builds/slave/test', 21:30:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:30:05 INFO - 'SHELL': '/bin/bash', 21:30:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ouobj3VpVY/Listeners', 21:30:05 INFO - 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', 21:30:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:30:05 INFO - 'USER': 'cltbld', 21:30:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:30:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:30:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:30:05 INFO - 'XPC_FLAGS': '0x0', 21:30:05 INFO - 'XPC_SERVICE_NAME': '0', 21:30:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:30:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:30:05 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:30:05 INFO - Cleaning up... 21:30:05 INFO - Return code: 0 21:30:05 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:30:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:30:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:30:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:30:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:30:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:30:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:30:05 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 0x10c36da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c402618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc491d861b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2d1d30>, '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 0x10c036db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c2c9b20>, '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.7gbodLJKqD/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.ouobj3VpVY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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 21:30:05 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 21:30:05 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 21:30:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7gbodLJKqD/Render', 21:30:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:30:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:30:05 INFO - 'HOME': '/Users/cltbld', 21:30:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:30:05 INFO - 'LOGNAME': 'cltbld', 21:30:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:30:05 INFO - 'MOZ_NO_REMOTE': '1', 21:30:05 INFO - 'NO_EM_RESTART': '1', 21:30:05 INFO - 'PAGER': '/bin/cat', 21:30:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:30:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:30:05 INFO - 'PWD': '/builds/slave/test', 21:30:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:30:05 INFO - 'SHELL': '/bin/bash', 21:30:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ouobj3VpVY/Listeners', 21:30:05 INFO - 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', 21:30:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:30:05 INFO - 'USER': 'cltbld', 21:30:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:30:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:30:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:30:05 INFO - 'XPC_FLAGS': '0x0', 21:30:05 INFO - 'XPC_SERVICE_NAME': '0', 21:30:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:30:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:30:06 INFO - Downloading/unpacking blobuploader==1.2.4 21:30:06 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 21:30:06 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 21:30:06 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 21:30:06 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 21:30:06 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 21:30:06 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 21:30:08 INFO - Downloading blobuploader-1.2.4.tar.gz 21:30:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:30:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:30:08 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 21:30:08 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 21:30:08 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 21:30:08 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 21:30:08 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 21:30:08 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 21:30:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:30:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:30: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 21:30: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 21:30: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 21:30: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 21:30: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 21:30: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 21:30:09 INFO - Downloading docopt-0.6.1.tar.gz 21:30:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:30:09 INFO - Installing collected packages: blobuploader, requests, docopt 21:30:09 INFO - Running setup.py install for blobuploader 21:30:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:30:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:30:09 INFO - Running setup.py install for requests 21:30:10 INFO - Running setup.py install for docopt 21:30:10 INFO - Successfully installed blobuploader requests docopt 21:30:10 INFO - Cleaning up... 21:30:10 INFO - Return code: 0 21:30:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:30:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:30:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:30:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:30:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:30:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:30:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:30:10 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 0x10c36da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c402618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc491d861b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2d1d30>, '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 0x10c036db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c2c9b20>, '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.7gbodLJKqD/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.ouobj3VpVY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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 21:30:10 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 21:30:10 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 21:30:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7gbodLJKqD/Render', 21:30:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:30:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:30:10 INFO - 'HOME': '/Users/cltbld', 21:30:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:30:10 INFO - 'LOGNAME': 'cltbld', 21:30:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:30:10 INFO - 'MOZ_NO_REMOTE': '1', 21:30:10 INFO - 'NO_EM_RESTART': '1', 21:30:10 INFO - 'PAGER': '/bin/cat', 21:30:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:30:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:30:10 INFO - 'PWD': '/builds/slave/test', 21:30:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:30:10 INFO - 'SHELL': '/bin/bash', 21:30:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ouobj3VpVY/Listeners', 21:30:10 INFO - 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', 21:30:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:30:10 INFO - 'USER': 'cltbld', 21:30:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:30:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:30:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:30:10 INFO - 'XPC_FLAGS': '0x0', 21:30:10 INFO - 'XPC_SERVICE_NAME': '0', 21:30:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:30:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:30:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:30:10 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-RV6_em-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:30:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:30:11 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-5gh03u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:30:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:30:11 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-n0ZDWq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:30:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:30:11 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-OQNFfb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:30:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:30:11 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-XSmzb0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:30:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:30:11 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-qtVDf2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:30:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:30:11 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-8PMMbA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:30:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:30:11 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-ECTmYc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:30:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:30:11 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-DRy7Ql-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:30:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:30:11 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-nH0szX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:30:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:30:11 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-tgoSoW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:30:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:30:12 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-oR8PGi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:30:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:30:12 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-JiSkCY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:30:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:30:12 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-aRY0T6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:30:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:30:12 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-_QM2ys-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:30:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:30:12 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-OEWqdT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:30:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:30:12 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-yZxrsv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:30:12 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:30:12 INFO - Running setup.py install for manifestparser 21:30:12 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:30:12 INFO - Running setup.py install for mozcrash 21:30:12 INFO - Running setup.py install for mozdebug 21:30:13 INFO - Running setup.py install for mozdevice 21:30:13 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:30:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:30:13 INFO - Running setup.py install for mozfile 21:30:13 INFO - Running setup.py install for mozhttpd 21:30:13 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:30:13 INFO - Running setup.py install for mozinfo 21:30:13 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:30:13 INFO - Running setup.py install for mozInstall 21:30:13 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:30:13 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:30:13 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:30:13 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:30:13 INFO - Running setup.py install for mozleak 21:30:13 INFO - Running setup.py install for mozlog 21:30:14 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:30:14 INFO - Running setup.py install for moznetwork 21:30:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:30:14 INFO - Running setup.py install for mozprocess 21:30:14 INFO - Running setup.py install for mozprofile 21:30:14 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:30:14 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:30:14 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:30:14 INFO - Running setup.py install for mozrunner 21:30:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:30:14 INFO - Running setup.py install for mozscreenshot 21:30:14 INFO - Running setup.py install for moztest 21:30:15 INFO - Running setup.py install for mozversion 21:30:15 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:30:15 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:30:15 INFO - Cleaning up... 21:30:15 INFO - Return code: 0 21:30:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:30:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:30:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:30:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:30:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:30:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:30:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:30:15 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 0x10c36da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c402618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc491d861b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2d1d30>, '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 0x10c036db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c2c9b20>, '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.7gbodLJKqD/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.ouobj3VpVY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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 21:30:15 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 21:30:15 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 21:30:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7gbodLJKqD/Render', 21:30:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:30:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:30:15 INFO - 'HOME': '/Users/cltbld', 21:30:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:30:15 INFO - 'LOGNAME': 'cltbld', 21:30:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:30:15 INFO - 'MOZ_NO_REMOTE': '1', 21:30:15 INFO - 'NO_EM_RESTART': '1', 21:30:15 INFO - 'PAGER': '/bin/cat', 21:30:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:30:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:30:15 INFO - 'PWD': '/builds/slave/test', 21:30:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:30:15 INFO - 'SHELL': '/bin/bash', 21:30:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ouobj3VpVY/Listeners', 21:30:15 INFO - 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', 21:30:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:30:15 INFO - 'USER': 'cltbld', 21:30:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:30:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:30:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:30:15 INFO - 'XPC_FLAGS': '0x0', 21:30:15 INFO - 'XPC_SERVICE_NAME': '0', 21:30:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:30:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:30:15 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-3SKyvC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:30:15 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)) 21:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:30:15 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-Gk_NTY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:30:15 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)) 21:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:30:15 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-iDnMLL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:30:15 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)) 21:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:30:15 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-gkOql1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:30:15 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)) 21:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:30:15 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-eMUQlN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:30:16 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)) 21:30:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:30:16 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-Kg7wmY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:30:16 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)) 21:30:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:30:16 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-16imbF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:30:16 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)) 21:30:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:30:16 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-ooZoNO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:30:16 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)) 21:30:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:30:16 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-APtul0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:30:16 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)) 21:30:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:30:16 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-pJxCyL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:30:16 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)) 21:30:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:30:16 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-8yq1Wf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:30:16 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)) 21:30:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:30:16 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-OT1Zxp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:30:16 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)) 21:30:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:30:16 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-tABfMy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:30:16 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)) 21:30:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:30:16 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-9acas3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:30:17 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)) 21:30:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:30:17 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-gwhWQ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:30:17 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)) 21:30:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:30:17 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-i34DT7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:30:17 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)) 21:30:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:30:17 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-0uMEii-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:30:17 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)) 21:30:17 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)) 21:30:17 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)) 21:30:17 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)) 21:30:17 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)) 21:30:17 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)) 21:30:17 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:30:17 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 21:30:17 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 21:30:17 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 21:30:17 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 21:30:17 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 21:30:17 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 21:30:19 INFO - Downloading blessings-1.6.tar.gz 21:30:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:30:19 INFO - Installing collected packages: blessings 21:30:19 INFO - Running setup.py install for blessings 21:30:20 INFO - Successfully installed blessings 21:30:20 INFO - Cleaning up... 21:30:20 INFO - Return code: 0 21:30:20 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:30:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:30:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:30:20 INFO - Reading from file tmpfile_stdout 21:30:20 INFO - Current package versions: 21:30:20 INFO - blessings == 1.6 21:30:20 INFO - blobuploader == 1.2.4 21:30:20 INFO - docopt == 0.6.1 21:30:20 INFO - functools32 == 3.2.3-2 21:30:20 INFO - jsonschema == 2.5.1 21:30:20 INFO - manifestparser == 1.1 21:30:20 INFO - mozInstall == 1.12 21:30:20 INFO - mozcrash == 0.17 21:30:20 INFO - mozdebug == 0.1 21:30:20 INFO - mozdevice == 0.48 21:30:20 INFO - mozfile == 1.2 21:30:20 INFO - mozhttpd == 0.7 21:30:20 INFO - mozinfo == 0.9 21:30:20 INFO - mozleak == 0.1 21:30:20 INFO - mozlog == 3.3 21:30:20 INFO - moznetwork == 0.27 21:30:20 INFO - mozprocess == 0.23 21:30:20 INFO - mozprofile == 0.28 21:30:20 INFO - mozrunner == 6.12 21:30:20 INFO - mozscreenshot == 0.1 21:30:20 INFO - mozsystemmonitor == 0.3 21:30:20 INFO - moztest == 0.7 21:30:20 INFO - mozversion == 1.4 21:30:20 INFO - psutil == 3.1.1 21:30:20 INFO - requests == 1.2.3 21:30:20 INFO - wsgiref == 0.1.2 21:30:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:30:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:30:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:30:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:30:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:30:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:30:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:30:20 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 0x10c36da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c402618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc491d861b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2d1d30>, '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 0x10c036db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c2c9b20>, '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.7gbodLJKqD/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.ouobj3VpVY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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 21:30:20 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 21:30:20 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 21:30:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7gbodLJKqD/Render', 21:30:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:30:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:30:20 INFO - 'HOME': '/Users/cltbld', 21:30:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:30:20 INFO - 'LOGNAME': 'cltbld', 21:30:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:30:20 INFO - 'MOZ_NO_REMOTE': '1', 21:30:20 INFO - 'NO_EM_RESTART': '1', 21:30:20 INFO - 'PAGER': '/bin/cat', 21:30:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:30:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:30:20 INFO - 'PWD': '/builds/slave/test', 21:30:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:30:20 INFO - 'SHELL': '/bin/bash', 21:30:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ouobj3VpVY/Listeners', 21:30:20 INFO - 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', 21:30:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:30:20 INFO - 'USER': 'cltbld', 21:30:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:30:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:30:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:30:20 INFO - 'XPC_FLAGS': '0x0', 21:30:20 INFO - 'XPC_SERVICE_NAME': '0', 21:30:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:30:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:30:20 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)) 21:30:20 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)) 21:30:20 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)) 21:30:20 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)) 21:30:20 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)) 21:30:20 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)) 21:30:20 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)) 21:30:20 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)) 21:30:20 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)) 21:30:20 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)) 21:30:20 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)) 21:30:20 INFO - Cleaning up... 21:30:20 INFO - Return code: 0 21:30:20 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:30:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:30:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:30:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:30:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:30:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:30:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:30:20 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 0x10c36da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c402618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc491d861b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2d1d30>, '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 0x10c036db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c2c9b20>, '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.7gbodLJKqD/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.ouobj3VpVY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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 21:30:20 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 21:30:20 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 21:30:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7gbodLJKqD/Render', 21:30:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:30:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:30:20 INFO - 'HOME': '/Users/cltbld', 21:30:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:30:20 INFO - 'LOGNAME': 'cltbld', 21:30:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:30:20 INFO - 'MOZ_NO_REMOTE': '1', 21:30:20 INFO - 'NO_EM_RESTART': '1', 21:30:20 INFO - 'PAGER': '/bin/cat', 21:30:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:30:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:30:20 INFO - 'PWD': '/builds/slave/test', 21:30:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:30:20 INFO - 'SHELL': '/bin/bash', 21:30:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ouobj3VpVY/Listeners', 21:30:20 INFO - 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', 21:30:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:30:20 INFO - 'USER': 'cltbld', 21:30:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:30:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:30:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:30:20 INFO - 'XPC_FLAGS': '0x0', 21:30:20 INFO - 'XPC_SERVICE_NAME': '0', 21:30:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:30:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:30:21 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:30:21 INFO - Cleaning up... 21:30:21 INFO - Return code: 0 21:30:21 INFO - Running post-action listener: _resource_record_post_action 21:30:21 INFO - Running post-action listener: _start_resource_monitoring 21:30:21 INFO - Starting resource monitoring. 21:30:21 INFO - [mozharness: 2016-09-02 04:30:21.056708Z] Finished create-virtualenv step (success) 21:30:21 INFO - [mozharness: 2016-09-02 04:30:21.057668Z] Running install step. 21:30:21 INFO - Running pre-action listener: _resource_record_pre_action 21:30:21 INFO - Running main action method: install 21:30:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:30:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:30:21 INFO - Reading from file tmpfile_stdout 21:30:21 INFO - Detecting whether we're running mozinstall >=1.0... 21:30:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:30:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:30:21 INFO - Reading from file tmpfile_stdout 21:30:21 INFO - Output received: 21:30:21 INFO - Usage: mozinstall [options] installer 21:30:21 INFO - Options: 21:30:21 INFO - -h, --help show this help message and exit 21:30:21 INFO - -d DEST, --destination=DEST 21:30:21 INFO - Directory to install application into. [default: 21:30:21 INFO - "/builds/slave/test"] 21:30:21 INFO - --app=APP Application being installed. [default: firefox] 21:30:21 INFO - mkdir: /builds/slave/test/build/application 21:30:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 21:30:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 21:30:55 INFO - Reading from file tmpfile_stdout 21:30:55 INFO - Output received: 21:30:55 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 21:30:55 INFO - Running post-action listener: _resource_record_post_action 21:30:55 INFO - [mozharness: 2016-09-02 04:30:55.543654Z] Finished install step (success) 21:30:55 INFO - [mozharness: 2016-09-02 04:30:55.543778Z] Running run-tests step. 21:30:55 INFO - Running pre-action listener: _resource_record_pre_action 21:30:55 INFO - Running main action method: run_tests 21:30:55 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:30:55 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:30:55 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:30:55 INFO - 2016-09-01 21:30:55.558 screenresolution[1640:7581] starting screenresolution argv=screenresolution get 21:30:55 INFO - 2016-09-01 21:30:55.560 screenresolution[1640:7581] Display 0: 1600x1200x32@60 21:30:55 INFO - 2016-09-01 21:30:55.566 screenresolution[1641:7583] starting screenresolution argv=screenresolution list 21:30:55 INFO - Available Modes on Display 0 21:30:55 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:30:55 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:30:55 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:30:55 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:30:55 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:30:55 INFO - Intel Iris: 21:30:55 INFO - Chipset Model: Intel Iris 21:30:55 INFO - Type: GPU 21:30:55 INFO - Bus: Built-In 21:30:55 INFO - VRAM (Dynamic, Max): 1536 MB 21:30:55 INFO - Vendor: Intel (0x8086) 21:30:55 INFO - Device ID: 0x0a2e 21:30:55 INFO - Revision ID: 0x0009 21:30:55 INFO - Displays: 21:30:55 INFO - Display: 21:30:55 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:30:55 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:30:55 INFO - Display Serial Number: 1600x1200 60 21:30:55 INFO - Main Display: Yes 21:30:55 INFO - Mirror: Off 21:30:55 INFO - Online: Yes 21:30:55 INFO - Rotation: Supported 21:30:55 INFO - Return code: 0 21:30:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:30:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:30:55 INFO - Python 2.7.3 21:30:55 INFO - Return code: 0 21:30:55 INFO - grabbing minidump binary from tooltool 21:30:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:30:55 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 0x10c2d1d30>, '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 0x10c036db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c2c9b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:30:55 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 21:30:55 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 21:30:55 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 21:30:55 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:30:55 INFO - Return code: 0 21:30:55 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 21:30:55 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:30:55 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:30:55 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 21:30:55 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:30:55 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/d-fNWJ9XRVSurVLPiYbNuw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0289', '--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 21:30:55 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/d-fNWJ9XRVSurVLPiYbNuw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0289 --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 21:30:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7gbodLJKqD/Render', 21:30:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:30:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:30:55 INFO - 'HOME': '/Users/cltbld', 21:30:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:30:55 INFO - 'LOGNAME': 'cltbld', 21:30:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:30:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 21:30:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:30:55 INFO - 'MOZ_NO_REMOTE': '1', 21:30:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:30:55 INFO - 'NO_EM_RESTART': '1', 21:30:55 INFO - 'PAGER': '/bin/cat', 21:30:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:30:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:30:55 INFO - 'PWD': '/builds/slave/test', 21:30:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:30:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:30:55 INFO - 'SHELL': '/bin/bash', 21:30:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ouobj3VpVY/Listeners', 21:30:55 INFO - 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', 21:30:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:30:55 INFO - 'USER': 'cltbld', 21:30:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:30:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:30:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:30:55 INFO - 'XPC_FLAGS': '0x0', 21:30:55 INFO - 'XPC_SERVICE_NAME': '0', 21:30:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:30:55 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/d-fNWJ9XRVSurVLPiYbNuw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0289', '--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 21:30:56 INFO - mozversion application_buildid: 20160901175542 21:30:56 INFO - mozversion application_changeset: e12415c1560179174bd3bdaca11dd20e6b97d35a 21:30:56 INFO - mozversion application_display_name: Nightly 21:30:56 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:30:56 INFO - mozversion application_name: Firefox 21:30:56 INFO - mozversion application_remotingname: firefox 21:30:56 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 21:30:56 INFO - mozversion application_vendor: Mozilla 21:30:56 INFO - mozversion application_version: 51.0a1 21:30:56 INFO - mozversion platform_buildid: 20160901175542 21:30:56 INFO - mozversion platform_changeset: e12415c1560179174bd3bdaca11dd20e6b97d35a 21:30:56 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 21:30:56 INFO - mozversion platform_version: 51.0a1 21:30:56 INFO - using testdate: 1472790656 21:30:56 INFO - actual date: 1472790656 21:30:56 INFO - starting webserver on 'localhost:49280' 21:30:56 INFO - SUITE-START | Running 2 tests 21:30:56 INFO - TEST-START | dromaeo_css 21:30:56 INFO - operating with platform_type : mac_ 21:30:56 INFO - Initialising browser for dromaeo_css test... 21:30:56 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/tmpCmz2oa/profile http://localhost:49280/getInfo.html) 21:30:59 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 21:30:59 INFO - PROCESS | 1651 | colorDepth:24 21:30:59 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 21:30:59 INFO - PROCESS | 1651 | __metrics 21:30:59 ERROR - PROCESS | 1651 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2998: TypeError: this.tabs is undefined 21:30:59 INFO - TEST-INFO | 1651: exit 0 21:30:59 INFO - Browser initialized. 21:30:59 INFO - Running cycle 1/1 for dromaeo_css test... 21:30:59 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/tmpCmz2oa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:31:11 INFO - PROCESS | 1654 | RSS: Main: 245309440 21:31:11 INFO - PROCESS | 1654 | 21:32:01 INFO - PROCESS | 1654 | 1472790721607 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] 21:34:14 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49280/tests/dromaeo/cssquery-ext.html) 21:34:15 INFO - PROCESS | 1654 | RSS: Main: 268365824 21:34:15 INFO - PROCESS | 1654 | 21:37:17 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-ext.html (next: http://localhost:49280/tests/dromaeo/cssquery-jquery.html) 21:37:18 INFO - PROCESS | 1654 | RSS: Main: 271978496 21:37:18 INFO - PROCESS | 1654 | 21:40:20 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49280/tests/dromaeo/cssquery-mootools.html) 21:40:20 INFO - PROCESS | 1654 | RSS: Main: 274579456 21:40:20 INFO - PROCESS | 1654 | 21:43:22 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49280/tests/dromaeo/cssquery-prototype.html) 21:43:23 INFO - PROCESS | 1654 | RSS: Main: 269926400 21:43:23 INFO - PROCESS | 1654 | 21:46:26 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49280/tests/dromaeo/cssquery-yui.html) 21:46:26 INFO - PROCESS | 1654 | RSS: Main: 275279872 21:46:26 INFO - PROCESS | 1654 | 21:49:29 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-yui.html (next: http://localhost:49280/tests/dromaeo/cssquery-dojo.html) 21:49:29 INFO - PROCESS | 1654 | RSS: Main: 278028288 21:49:29 INFO - PROCESS | 1654 | 21:49:29 INFO - PROCESS | 1654 | __start_tp_report 21:49:29 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 21:49:29 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 21:49:29 INFO - PROCESS | 1654 | |i|pagename|runs| 21:49:29 INFO - PROCESS | 1654 | |0;dojo.html;3348.799072055678;3355.312161006994;3373.4771110477877;3393.9321213575713;3411.09605954422;5599.216109744639;5614.185943038257;5622.7188640567965;5691.174779656947;5736.397678243787;5138.691678499284;5609.467100625434;5664.886702265952;5689.061304884689;5769.798057068004;5103.872403189931;5137.897242055156;5171.120909445403;5172.715500647463;5251.894962100756;3824.273388056274;4080.8165631966694;5176.275321454999;5322.467753224685;5329.653572517794;5104.285400043997;5136.9469258801855;5204.42751297357;5222.582193424536;5273.6572122811895;5129.410117836459;5199.922001169985;5222.32109825724;5244.475552444763;5266.8683282917855;4236.555161708007;5147.433782283946;5163.922541161885;5193.870153246161;5216;4697.906041879143;5398.541124004464;5515.145152501369;5579.995600791855;5702.714864256771;6122.245686735438;6124.808163753419;6150.04674275488;6189.721462534197;6218.973869311558;6212.844678883019;6215.782447614357;6235.438810507031;6239.625622462621;6276.843078923018;5649.717514124277;5739.512141467939;5774.047282198474;5775.942240577582;5811.6803575803315;6205.317415084337;6215.782447614311;6246.781362652286;6253.437190652863;6254.718537665816;6050.183225264621;6054.54590905675;6066.4540191382985;6070.525070979092;6103.84740381494;6075.210222671025;6095.969520152371;6140.478059365004;6141.201643786279;6173.228346456693;6103.549811064039;6164.907526387108;6194.4115309045565;6198.81403557894;6203.4416902479;6163.691815409212;6192.349803270592;6217.067439884054;6230.563860529719;6249.6562689051625;4796.016816552598;4891.070696567641;4933.260010998307;4974.751262436864;4985.152524070917;4207.179599977975;4411.911761764747;4418.182636212311;4477.238869392211;4504.842330518416;5066.214736715815;5585.888282234332;5736.483716465538;5753.769849205984;5759.876824019276;6029.879402411927;6153.29237137726;6210.689465526705;6241.875162496724;6289.842753931097;5719;5754.108113242454;5781.942180578225;5793.826185214451;5841.853953651193;5455.727213639381;5475.561955043587;5479.123340265538;5484.341878974469;5502.0371434999515;6126.30393285589;6142.1304578115505;6324.747010119636;6328.968355158195;6362.4273815357765;6106.2179897963915;6205.844853878689;6215.720292586754;6220.308417797664;6231;6226.034964580497;6236;6236.469900058546;6238.594491358047;6244.2819075806865;4984.1526940420945;5491.780328786804;6081.969590152021;6099.451049405575;6100.786472473442;6139.201903752483;6140.105498721277;6169.784057557964;6220.595661282002;6241.375862413723;6054.061620448838;6056.394360563909;6057.969710151421;6078.635281882925;6111.663858487826;5868.295804503487;5936.821895343147;6055.091736239599;6068.029115341524;6078.54410919174;5744.856378590569;5830.82507524775;5910.143029260647;5913.763449461973;5916.201312822886;5257.5005374489365;6017.939820601738;6037.909431358621;6046.455818976137;6052.636841789506;5706.6005379624685;5790.826275211751;5872.412758724094;5929.940700592939;5963.791267305797 21:49:29 INFO - PROCESS | 1654 | |1;ext.html;468.3162582629361;495.39561734683673;506.97465126743674;510.8161062017675;516.3158814570694;39004.02489937753;39838.00404989873;39840.60159398405;39847.402288965655;40035.799821000925;40119.19761604766;40859.77420677377;40883;40959.180816383654;41002;9537.427754334762;9763.36538125023;9783.804323913517;9796.461194634328;9837.409755414663;9737.56180971858;9794.510274486283;9806.215502759762;9826.705198844045;9855.507224638775;9788.070133337322;9841.901580984206;9848.55681494331;9873.506324683773;9889.456079915602;8103.797405064862;9761.755956101084;9784.657536986242;9801.950990245074;9832.901670983341;9629.855552166724;9706.951465242628;9731.464769437678;9743.512824358753;9754.658586949423;39204.60795392038;39401.21197576061;39459.60540394588;39561.20877582432;39758.40362394538;3351.1957130288556;3377.814220217887;3395.7283417326553;3403.40440422925;3443.104792753902;30171;30284.394312113855;30406.54390184149;30561.54157687614;31556.684433155606;3214.0612686663903;3222.532732753761;3233.1917020744813;3238.6599407062154;3333.516640087198;473836.78489645367;488525;494430.52784736204;495754;499001.5049924727;57323.56691082756;57805.13292300619;57858.13212801727;57875;57903.42096579065;33450.330993380485;34495.13762155916;59775.103373449434;60244;60304.698476508216;15969.600759981093;16194.676106477804;16370.263338149813;16371.754423683655;16439.424620138478;12469.127161098817;12540.122191446511;12541.122121451594;12576.434060467302;12606.747865042647;18720.906395467937;18816.529586760167;18828.246870125316;18853.62292754137;18885.339013134475;6771.559848609824;6922.653867306715;7920.841583168304;7963.522188668699;7984.600769961478;845.4758050008911;873.1094283830457;909.2907532124954;1079.4818487126224;1126.7577470843644;25299.62050569243;29327.706722932926;29971.100866974026;30356.696433035828;30458.69541304603;939.3002213351098;1034.7620047389144;1048.124815778818;1062.3625824505234;1139.920205585601;12137.089718271165;13401.128926619738;15102.697946041018;15132.470363537444;15380;622529;630550;640780;641435;642579;45840.08319833651;54432.727836360566;54885.1767223492;55234;55487.44512554823;1167.2529581068122;1171.5782318365552;1344.8991325650616;1351.4053816320788;1370.5477192526441;27915.16254512368;28733;29104.7089529102;29401.264968375963;29559.408811824072;47488.52511474841;47745;47926.28110578414;48145.5185448155;48710.5128948706;568403;574399;576077.1196143993;576144;577697;25877.741222588287;25956;26186.214413567624;26246.34384140412;26313.473730525664;44959.55040449685;45498.772506137255;45553.54446455625;46489;46869.29696054494;32649.836750816095;38435;38840.6115938837;39201.2159756809;39331.410028850165;37619.435708464945;37846.053848654;37999.620003800715;38985;39048.6095139045;30102;37193.88418347454;38087.61912380951;38101.04747381338;38323.616763832004;32582;34195.14512137217;34546.65453345434;34592.65407345894;34901.65098348984;24135;25181.87409062943;25295;25439.74560254374;26339.473210536064 21:49:29 INFO - PROCESS | 1654 | |2;jquery.html;373.97382183247174;377.420659287993;380.9028697682091;389.77003567894934;404.9433079368887;18787.43637690868;19784;20852.374428767143;21068.367948961517;21176.25883094092;11670.708232294197;11711;12056;12082.033437325015;18908.20372064655;5246.7638956247065;5511.724413779295;5515.09001014832;5520.089185284423;5543.584231182657;5236.528712415882;5373.543248823855;5425.186222066702;5438.564914806826;5455.236266922634;4868.537488938563;5216.191490318987;5340.599455040868;5414.160805075219;5414.945850541503;5189.481051894818;5375.596830237729;5402.594805389612;5444;5457.536109430703;5223.582113430916;5280.2343660169445;5293.941211757626;5305.946940530584;5320.255164277004;12682.873171268262;12874.29191394473;12984.545540906118;12991.610251692464;13119.278439685813;2076.854620176573;2112.6619740841547;2280.6122959096992;2938.7355138037465;2960.6891276416095;9649.276304277208;10238.948805256001;10313.329633573869;13256.337183140804;13787.655308617264;1369.2811274081087;1450.5068276785944;1575.3912337743172;1773.2286455391816;1892.9872518202696;61049;98245.0175498243;116282;123264;123449;9881.752956176153;10158.542865571068;11506.252093613888;11523.884761152449;11669.183157178915;14255.572332830032;14305.499307524187;14374.640633984025;14388.928055359656;14391.85608143926;8281.95859020713;8560.571971401405;8611.870821937677;8667.739967800977;8720.389572729975;7527.172011078777;8068.8386232275025;8531.53076580794;8565.229129378386;8731.912680873236;3515.736319776027;4402.207602631557;4465.843695470708;4487.977560112178;4576.061907308994;1896.4121122451809;1928.2961718972474;1936.8741031832885;1940.185122248659;1943.4169749075504;815;842.5787106446777;871.2028493927985;874.1215078845719;888.3337496877342;20007.699884501744;20645.89677051605;20709.896450517652;20774.272900448414;20799.06404211814;919.3610440743619;932.5498849289285;952.6094301336419;961.1301771896445;1012.5190534496055;9798.351047978573;10096.949515252523;10903.945480272707;10941.945290273497;10950.452477376099;546293;562001;566051;568162;569724;91126;114611;116833;121847;125807;438.7082590077563;451.3342819341445;455.0035422424945;455.99908201498056;458.159277725371;8953.641854326015;10535.841962370418;10781.730456738644;10985.285956412808;11228.887711122785;47330;49613.75193124011;49880;51343.74328128336;54187;51605;71434.64282678554;73493.7951861445;73693.26306736864;74892;7736.7678969631;7768.300852923264;8004.679812807655;8082.87875681877;8196.385271104571;11858;12185.634430966731;12386.442610082748;12569.49722011146;12685.175463594836;10345.327553708985;10490.737731556772;10722.678319650424;10723.463826808784;11248.606298779503;10726.249162558546;10743.46282685847;10968.945155274172;11228.719282018015;11233.775324493628;9772.609095636379;11058;11163.944180279046;11267.492962816528;11344.773104538028;7945.324647405036;8147.470414423044;8211.71259005932;8266.628001739817;8293.875591866006;10374.948125259627;10812.297200681931;10997.395143267197;11050.723731906766;11178.888211117785 21:49:29 INFO - PROCESS | 1654 | |3;mootools.html;1459.5475402625186;1520.2854658310594;1705.3349193814422;1718.1237568839895;1870.8587761465494;7989.280964713174;8492.235698787108;8691;9119.179273865351;9137.497437640935;8817.382783205177;8899.68851090212;8918.152775486335;8931.50876701781;8944.686935957243;2370.419247284418;2383.7616238376154;2386.4630458146953;2425.3451568076607;2428.9878550607223;2312.537492501498;2349.447879748266;2391.3304274802995;2397.4485868250313;2412.7104747430335;2295.8507696999727;2409.8795060246916;2413.81896357773;2417.1660777031984;2427.9028838846425;2366.928992130239;2372.525494901018;2381.3808409813487;2383.9046438142454;2385.224801939368;2379.3694670912223;2393.6888204533298;2394.856308621488;2395.413123784684;2396.8322217444784;8924.88438945132;8951.910480895238;8972.41679290844;9068.41055331408;9068.546572671406;5258.3689957205;5400.702961337125;5429.782808687648;5440.863978400532;5447.918281225785;3608.1340478285283;3652.543432070991;3669.6697297243377;3703.6296370363016;3712;1072.5066469424075;1078.9406582637953;1090.536521978164;1103.4593049405814;1105.336797921249;11662.183647144704;11718.23831450953;11788.941055294668;11800.645980620595;11848.170628055954;4702.388689470347;4727.120638842144;4781.211100168433;4874.926876096862;4916.237983112552;9898.653547125816;10039.397636141855;10053.447060411747;10064;10073.69778906634;5389;5392.379876314274;5405.891882162335;5420.214068959979;5439.48324909133;4448.221561226837;4757.809687612456;4883.975580122076;4886.877828054256;4933.851984440473;3936.7834769087976;4013.3979903014783;4066.877993660136;4106.322456794655;4128.669706423479;1316.4799904037852;1320.5473978623438;1326.608650448101;1331.3809078778415;1335.8263425754783;852.4927668037508;854.3080105114877;907.8955920069144;913.3332667153015;940.9529523523796;5724.4561766632105;6028.156058151862;6041.879162416727;6084.817455476343;6182.938170618237;806.9556174410463;845.780097174739;878.6265837019369;883.1918794303269;1017.6845177994845;3507.6141624421293;3917.8432862685745;3978.6036429744713;4100.138970816102;4171.749695018309;10714.517846696917;10966.616168434066;10991.780164396827;11067.833982490269;11078.5568577256;11013.614523491638;11021.889781102247;11022.669319920415;11025.062869656169;11037.889621103686;8013.559254241072;8217.013958325038;8271.13153118926;8353.289970352587;8439.071702112762;5683.090705487268;5895.882082358415;5986.07215881539;6011.819645410645;6124.326324104434;8056.637451314587;10269.075783179549;10328.535215915152;10354.534045968101;10358.896411035794;9933.552990115606;10022;10495.580176792842;10508.474576271308;10557.419341936267;6619.569727967667;6967.338102880219;7070.858582828418;7096.148462184571;7233.674484648309;8586.656533738831;8651.610677519653;8659.960804703476;8681.609327580149;8691.391602587759;7507.66215520292;7628.771136865903;7654.617269136632;7757.72847950319;7763.883541746765;7470.017702645293;7517.849643007218;7559.244075592397;7575.621218939141;7702.114256860309;6088.665123418077;6986.720531178696;7080.504364694422;7127.7148914042855;7131.429485641032;4928.630352723631;5064.834478450968;5114.718690471911;5138.203578445347;5186.533212010937;4200.537940826568;4989.800407983786;5062.443131255485;5242.842714718565;5300.443453437488 21:49:29 INFO - PROCESS | 1654 | |4;prototype.html;272.24995138393723;296.9242843075016;298.20082179758253;304.55078758830723;313.62365161805826;4182.853600123996;5171.79312827487;5238.84283471496;5243.475652434764;5469.589780766443;4537.387452693885;4790.281572650936;4874.512548745124;5108.770105345259;5348.518633323;3404.3872103021386;3636.9816451393654;3931.567527571979;4175.765965208614;4178.8328466861285;3632.7648599476306;3740.9812950935207;4175.582441755815;4182.435371224891;4193.379138038726;3753.418220175877;3955.564887862333;3974.205158968218;3989.321815291393;3991.560928297885;3367.966320336814;3973.920521589581;3994.460747799068;4009.659178969791;4018.4775979122674;3126.296583268769;3252.9349413011846;3470.7396945228957;3487.9536139158404;3505.772124811879;4879.779345631901;5164.741762911877;5208.765605547759;5249.792547714009;5271.446498117719;3864.1692036212066;4531.207038768202;4541.568550987651;4551.135284296006;4557.156925968707;5577.581681373873;5607.663540187602;5608.46719561641;5609.80365687199;5629.3526244482;3532.205253817896;3754.3429899767702;3764.548254209485;3772.13240954582;3848.960780589225;4574.496805351442;5177.870553236162;5314.92027619586;5848.444397782204;5960.374160713106;3840.3663395539425;3855.8072096395067;3861.189150278417;3862.9034274143373;3879.476270703475;3292.3086151907887;3631.7457777955287;3905.6484916357663;3923.1761330120426;3936.252112098692;4554.586069383232;4584.6561507887045;4599.356090147382;4600.88497787558;4612.95387046132;5077.847664570068;5414.88994756082;5465.535429488538;5640.379558248589;5742;1263.128441375448;1288.826008488842;1308.7578797922415;1319.7162610038695;1323.8146659467682;814.7433558429076;824.8658095119212;860.7116615933733;898.3980732908967;902.8465160922659;4838.927416088762;4902.896848209124;5217.060929032735;5260.237265596467;5279.47205279469;4618.468876079227;4685.976570117127;4750.714957102585;5744.770209191669;5769.596128271065;4816.301636262722;4860.586850117709;4948.292838970549;5017.422996355393;5248.606354523426;2834.1861208826367;2989.1630343503853;3035.6964303569466;3053.679363666783;3092.597962264891;4963.691883274295;6102.359252278447;6106.381808820666;6115.6330620162935;6187.226596675416;4968.279599458058;4972.129877271535;5601.181919026666;5930.449906540143;5953.285605727254;6051.727672254759;6058.334332893601;6075.149479072845;6084.30030546484;6098.878022439526;5217.373915130209;5374.946250537445;5810.68041257718;5830.47525722687;5861.443162899518;4809.855704328876;4831.647138801141;5786.81370319092;5821.592488525932;5828.970855145697;4861.951380486292;5074.5686616637995;5421.457854214705;6080.695965201634;6157.84605384869;3182.379436009956;3459.740519461;3701.5373078365205;3922.764634121962;3994.920101597894;5373.247745315737;5424.942136283387;5551.583631227755;5717.198572699431;5794.681292528951;4897.338859253955;5685.857853553529;5703.515201207943;5711.600187986801;5721.256236689203;4973.950260497349;5051.621128415457;5652.2086907833755;5683.744231509675;5716.28546431696;4468.955310446854;5378.596605254544;5509.917351239815;5566.749496272759;5586.692731899784;5451.890962180813;5453.64551304164;5696.601237913306;5722.971385143048;5738.311402631711;5582.916256256241;5588.608797384307;5599.860003499945;5603.9545565868975;5636.5208957237455 21:49:29 INFO - PROCESS | 1654 | |5;yui.html;703.7114782938996;723.5441671746802;727.2836256287555;732.7362149626134;768.277818850281;12034.398280085994;15976.520704378887;16046;16080.678386432264;16145.192740362994;14392.568222953329;14461.710765784705;14486.275686215673;14496.420143194286;14507.637309067251;672.8654269146166;851.1828644501286;856.6659002988839;873.1879352202449;873.5981448533682;870.5516658920661;875.1948207648978;881.2553392383426;881.7046289492995;884.9601767920427;933.785229397239;935.289180223032;937.8077494113691;938.0478814003792;938.760616042908;856.4903882190087;912.867634193045;924.9907500925005;925.7130289610243;930.1628534319133;675.8614484030762;688.8140202145443;694.4687314204638;697.5605368617789;699.1260923845193;14456.638584035378;14547.199904005276;14547.927260363736;14552.781708274384;14591.92704036473;14078.788818167735;14113.294335283195;14139.610065654333;14154.363053662508;14270.64323391913;11761.059115270758;11772.470238839187;11850.288982661068;11957.760844783055;11993.340366279852;3484.5470088888283;3728.720345974036;3781.2626537825136;3872.477215575889;3907.492026036597;103910.48044759728;104574;105927.8814423715;106973;107019.99999999921;10853.823506007791;10950.945245273722;11040.779184416428;11050.642554484024;11164.49279347294;19662.803371966384;22187.77812221857;22322.330330090124;22326.33021009372;22368.44078898008;6676.6327851967635;8028.157043510348;8107.108218096005;8220.958895205606;8335.208155225244;4437.33439984005;4491.550844915548;4547.613452856478;4549.385832912581;4557.339185818038;3550.183457804719;3681.208540163878;3688.686461650736;3695.2240029594086;3704.8518059277867;669.7509145443712;672.5695554844904;676.8172593399754;684.3567046975827;691.6472598974459;505.88111793728444;589.4665327878276;615.286267929209;636.1157990393265;696.004713260043;9470.289728270407;9535.666251681158;9547.331686781998;9621.567029483691;9732.90267097334;581.3372755059236;635.9141515895295;636.7803107927757;694.979150625482;707.9575225486487;4448.310511870665;4595.264757638762;4658.7204767713365;4805.663603547788;4837.661363704507;89364.5531772337;91140;139002;146067.26966365313;148013.51986480213;84374.15625843663;125487;125694.3715281436;126104;126712;86775.99999999873;106748;109448;112816.43591781988;112889.43555282171;8061.596920154087;9994.550245239127;10037.44794036335;10098.49507524606;10098.495075246356;48929;63785;63898;64673;65161;58708.706456467444;76082.09794755175;79016.81474777768;90933.63599546145;97680.51159744155;13140.27728474943;13217.735645287234;13297.202167869958;13522.729545409233;13919.93040034834;14735.3583621159;14812.855574408279;14842.109473431628;15067.547973560811;15270.541883743506;13730.45078196861;13746.793798092836;13804.654883627989;13829.79255311151;13857.72284554283;13658.726825463635;13735.725285494435;13828.308584570932;13897.583072507841;13917.721645567235;13341.266230357423;13468.663283417602;13820.378082986088;13924.524721474889;14358.210298433687;10600.864768152798;11057.447127643747;11087.55649774;11206.663800086011;11441.771164576829;9633.438976260195;11360.772784544097;11425.428728563706;11590.826137608112;11652.766944661229 21:49:29 INFO - PROCESS | 1654 | __end_tp_report 21:49:29 INFO - PROCESS | 1654 | __start_cc_report 21:49:29 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,381 21:49:29 INFO - PROCESS | 1654 | __end_cc_report 21:49:29 INFO - PROCESS | 1654 | __startTimestamp1472791769629__endTimestamp 21:49:29 INFO - PROCESS | 1654 | 21:49:29 INFO - PROCESS | 1654 | ------- Summary: start ------- 21:49:29 INFO - PROCESS | 1654 | Number of tests: 6 21:49:29 INFO - PROCESS | 1654 | 21:49:29 INFO - PROCESS | 1654 | [#0] dojo.html Cycles:165 Average:5663.25 Median:5868.30 stddev:663.72 (11.3%) stddev-sans-first:640.44 21:49:29 INFO - PROCESS | 1654 | Values: 3348.8 3355.3 3373.5 3393.9 3411.1 5599.2 5614.2 5622.7 5691.2 5736.4 5138.7 5609.5 5664.9 5689.1 5769.8 5103.9 5137.9 5171.1 5172.7 5251.9 3824.3 4080.8 5176.3 5322.5 5329.7 5104.3 5136.9 5204.4 5222.6 5273.7 5129.4 5199.9 5222.3 5244.5 5266.9 4236.6 5147.4 5163.9 5193.9 5216.0 4697.9 5398.5 5515.1 5580.0 5702.7 6122.2 6124.8 6150.0 6189.7 6219.0 6212.8 6215.8 6235.4 6239.6 6276.8 5649.7 5739.5 5774.0 5775.9 5811.7 6205.3 6215.8 6246.8 6253.4 6254.7 6050.2 6054.5 6066.5 6070.5 6103.8 6075.2 6096.0 6140.5 6141.2 6173.2 6103.5 6164.9 6194.4 6198.8 6203.4 6163.7 6192.3 6217.1 6230.6 6249.7 4796.0 4891.1 4933.3 4974.8 4985.2 4207.2 4411.9 4418.2 4477.2 4504.8 5066.2 5585.9 5736.5 5753.8 5759.9 6029.9 6153.3 6210.7 6241.9 6289.8 5719.0 5754.1 5781.9 5793.8 5841.9 5455.7 5475.6 5479.1 5484.3 5502.0 6126.3 6142.1 6324.7 6329.0 6362.4 6106.2 6205.8 6215.7 6220.3 6231.0 6226.0 6236.0 6236.5 6238.6 6244.3 4984.2 5491.8 6082.0 6099.5 6100.8 6139.2 6140.1 6169.8 6220.6 6241.4 6054.1 6056.4 6058.0 6078.6 6111.7 5868.3 5936.8 6055.1 6068.0 6078.5 5744.9 5830.8 5910.1 5913.8 5916.2 5257.5 6017.9 6037.9 6046.5 6052.6 5706.6 5790.8 5872.4 5929.9 5963.8 21:49:29 INFO - PROCESS | 1654 | 21:49:29 INFO - PROCESS | 1654 | [#1] ext.html Cycles:180 Average:69159.91 Median:39430.41 stddev:152384.00 (386.5%) stddev-sans-first:152724.20 21:49:29 INFO - PROCESS | 1654 | Values: 468.3 495.4 507.0 510.8 516.3 39004.0 39838.0 39840.6 39847.4 40035.8 40119.2 40859.8 40883.0 40959.2 41002.0 9537.4 9763.4 9783.8 9796.5 9837.4 9737.6 9794.5 9806.2 9826.7 9855.5 9788.1 9841.9 9848.6 9873.5 9889.5 8103.8 9761.8 9784.7 9802.0 9832.9 9629.9 9707.0 9731.5 9743.5 9754.7 39204.6 39401.2 39459.6 39561.2 39758.4 3351.2 3377.8 3395.7 3403.4 3443.1 30171.0 30284.4 30406.5 30561.5 31556.7 3214.1 3222.5 3233.2 3238.7 3333.5 473836.8 488525.0 494430.5 495754.0 499001.5 57323.6 57805.1 57858.1 57875.0 57903.4 33450.3 34495.1 59775.1 60244.0 60304.7 15969.6 16194.7 16370.3 16371.8 16439.4 12469.1 12540.1 12541.1 12576.4 12606.7 18720.9 18816.5 18828.2 18853.6 18885.3 6771.6 6922.7 7920.8 7963.5 7984.6 845.5 873.1 909.3 1079.5 1126.8 25299.6 29327.7 29971.1 30356.7 30458.7 939.3 1034.8 1048.1 1062.4 1139.9 12137.1 13401.1 15102.7 15132.5 15380.0 622529.0 630550.0 640780.0 641435.0 642579.0 45840.1 54432.7 54885.2 55234.0 55487.4 1167.3 1171.6 1344.9 1351.4 1370.5 27915.2 28733.0 29104.7 29401.3 29559.4 47488.5 47745.0 47926.3 48145.5 48710.5 568403.0 574399.0 576077.1 576144.0 577697.0 25877.7 25956.0 26186.2 26246.3 26313.5 44959.6 45498.8 45553.5 46489.0 46869.3 32649.8 38435.0 38840.6 39201.2 39331.4 37619.4 37846.1 37999.6 38985.0 39048.6 30102.0 37193.9 38087.6 38101.0 38323.6 32582.0 34195.1 34546.7 34592.7 34901.7 24135.0 25181.9 25295.0 25439.7 26339.5 21:49:29 INFO - PROCESS | 1654 | 21:49:29 INFO - PROCESS | 1654 | [#2] jquery.html Cycles:180 Average:32052.41 Median:379.16 stddev:93711.96 (24715.6%) stddev-sans-first:93944.66 21:49:29 INFO - PROCESS | 1654 | Values: 374.0 377.4 380.9 389.8 404.9 18787.4 19784.0 20852.4 21068.4 21176.3 11670.7 11711.0 12056.0 12082.0 18908.2 5246.8 5511.7 5515.1 5520.1 5543.6 5236.5 5373.5 5425.2 5438.6 5455.2 4868.5 5216.2 5340.6 5414.2 5414.9 5189.5 5375.6 5402.6 5444.0 5457.5 5223.6 5280.2 5293.9 5305.9 5320.3 12682.9 12874.3 12984.5 12991.6 13119.3 2076.9 2112.7 2280.6 2938.7 2960.7 9649.3 10238.9 10313.3 13256.3 13787.7 1369.3 1450.5 1575.4 1773.2 1893.0 61049.0 98245.0 116282.0 123264.0 123449.0 9881.8 10158.5 11506.3 11523.9 11669.2 14255.6 14305.5 14374.6 14388.9 14391.9 8282.0 8560.6 8611.9 8667.7 8720.4 7527.2 8068.8 8531.5 8565.2 8731.9 3515.7 4402.2 4465.8 4488.0 4576.1 1896.4 1928.3 1936.9 1940.2 1943.4 815.0 842.6 871.2 874.1 888.3 20007.7 20645.9 20709.9 20774.3 20799.1 919.4 932.5 952.6 961.1 1012.5 9798.4 10096.9 10903.9 10941.9 10950.5 546293.0 562001.0 566051.0 568162.0 569724.0 91126.0 114611.0 116833.0 121847.0 125807.0 438.7 451.3 455.0 456.0 458.2 8953.6 10535.8 10781.7 10985.3 11228.9 47330.0 49613.8 49880.0 51343.7 54187.0 51605.0 71434.6 73493.8 73693.3 74892.0 7736.8 7768.3 8004.7 8082.9 8196.4 11858.0 12185.6 12386.4 12569.5 12685.2 10345.3 10490.7 10722.7 10723.5 11248.6 10726.2 10743.5 10968.9 11228.7 11233.8 9772.6 11058.0 11163.9 11267.5 11344.8 7945.3 8147.5 8211.7 8266.6 8293.9 10374.9 10812.3 10997.4 11050.7 11178.9 21:49:29 INFO - PROCESS | 1654 | 21:49:29 INFO - PROCESS | 1654 | [#3] mootools.html Cycles:180 Average:5788.55 Median:4885.43 stddev:3249.66 (66.5%) stddev-sans-first:3242.49 21:49:29 INFO - PROCESS | 1654 | Values: 1459.5 1520.3 1705.3 1718.1 1870.9 7989.3 8492.2 8691.0 9119.2 9137.5 8817.4 8899.7 8918.2 8931.5 8944.7 2370.4 2383.8 2386.5 2425.3 2429.0 2312.5 2349.4 2391.3 2397.4 2412.7 2295.9 2409.9 2413.8 2417.2 2427.9 2366.9 2372.5 2381.4 2383.9 2385.2 2379.4 2393.7 2394.9 2395.4 2396.8 8924.9 8951.9 8972.4 9068.4 9068.5 5258.4 5400.7 5429.8 5440.9 5447.9 3608.1 3652.5 3669.7 3703.6 3712.0 1072.5 1078.9 1090.5 1103.5 1105.3 11662.2 11718.2 11788.9 11800.6 11848.2 4702.4 4727.1 4781.2 4874.9 4916.2 9898.7 10039.4 10053.4 10064.0 10073.7 5389.0 5392.4 5405.9 5420.2 5439.5 4448.2 4757.8 4884.0 4886.9 4933.9 3936.8 4013.4 4066.9 4106.3 4128.7 1316.5 1320.5 1326.6 1331.4 1335.8 852.5 854.3 907.9 913.3 941.0 5724.5 6028.2 6041.9 6084.8 6182.9 807.0 845.8 878.6 883.2 1017.7 3507.6 3917.8 3978.6 4100.1 4171.7 10714.5 10966.6 10991.8 11067.8 11078.6 11013.6 11021.9 11022.7 11025.1 11037.9 8013.6 8217.0 8271.1 8353.3 8439.1 5683.1 5895.9 5986.1 6011.8 6124.3 8056.6 10269.1 10328.5 10354.5 10358.9 9933.6 10022.0 10495.6 10508.5 10557.4 6619.6 6967.3 7070.9 7096.1 7233.7 8586.7 8651.6 8660.0 8681.6 8691.4 7507.7 7628.8 7654.6 7757.7 7763.9 7470.0 7517.8 7559.2 7575.6 7702.1 6088.7 6986.7 7080.5 7127.7 7131.4 4928.6 5064.8 5114.7 5138.2 5186.5 4200.5 4989.8 5062.4 5242.8 5300.4 21:49:29 INFO - PROCESS | 1654 | 21:49:29 INFO - PROCESS | 1654 | [#4] prototype.html Cycles:180 Average:4508.75 Median:4970.20 stddev:1405.79 (28.3%) stddev-sans-first:1373.30 21:49:29 INFO - PROCESS | 1654 | Values: 272.2 296.9 298.2 304.6 313.6 4182.9 5171.8 5238.8 5243.5 5469.6 4537.4 4790.3 4874.5 5108.8 5348.5 3404.4 3637.0 3931.6 4175.8 4178.8 3632.8 3741.0 4175.6 4182.4 4193.4 3753.4 3955.6 3974.2 3989.3 3991.6 3368.0 3973.9 3994.5 4009.7 4018.5 3126.3 3252.9 3470.7 3488.0 3505.8 4879.8 5164.7 5208.8 5249.8 5271.4 3864.2 4531.2 4541.6 4551.1 4557.2 5577.6 5607.7 5608.5 5609.8 5629.4 3532.2 3754.3 3764.5 3772.1 3849.0 4574.5 5177.9 5314.9 5848.4 5960.4 3840.4 3855.8 3861.2 3862.9 3879.5 3292.3 3631.7 3905.6 3923.2 3936.3 4554.6 4584.7 4599.4 4600.9 4613.0 5077.8 5414.9 5465.5 5640.4 5742.0 1263.1 1288.8 1308.8 1319.7 1323.8 814.7 824.9 860.7 898.4 902.8 4838.9 4902.9 5217.1 5260.2 5279.5 4618.5 4686.0 4750.7 5744.8 5769.6 4816.3 4860.6 4948.3 5017.4 5248.6 2834.2 2989.2 3035.7 3053.7 3092.6 4963.7 6102.4 6106.4 6115.6 6187.2 4968.3 4972.1 5601.2 5930.4 5953.3 6051.7 6058.3 6075.1 6084.3 6098.9 5217.4 5374.9 5810.7 5830.5 5861.4 4809.9 4831.6 5786.8 5821.6 5829.0 4862.0 5074.6 5421.5 6080.7 6157.8 3182.4 3459.7 3701.5 3922.8 3994.9 5373.2 5424.9 5551.6 5717.2 5794.7 4897.3 5685.9 5703.5 5711.6 5721.3 4974.0 5051.6 5652.2 5683.7 5716.3 4469.0 5378.6 5509.9 5566.7 5586.7 5451.9 5453.6 5696.6 5723.0 5738.3 5582.9 5588.6 5599.9 5604.0 5636.5 21:49:29 INFO - PROCESS | 1654 | 21:49:29 INFO - PROCESS | 1654 | [#5] yui.html Cycles:180 Average:23312.27 Median:3684.95 stddev:35988.96 (976.6%) stddev-sans-first:36049.88 21:49:29 INFO - PROCESS | 1654 | Values: 703.7 723.5 727.3 732.7 768.3 12034.4 15976.5 16046.0 16080.7 16145.2 14392.6 14461.7 14486.3 14496.4 14507.6 672.9 851.2 856.7 873.2 873.6 870.6 875.2 881.3 881.7 885.0 933.8 935.3 937.8 938.0 938.8 856.5 912.9 925.0 925.7 930.2 675.9 688.8 694.5 697.6 699.1 14456.6 14547.2 14547.9 14552.8 14591.9 14078.8 14113.3 14139.6 14154.4 14270.6 11761.1 11772.5 11850.3 11957.8 11993.3 3484.5 3728.7 3781.3 3872.5 3907.5 103910.5 104574.0 105927.9 106973.0 107020.0 10853.8 10950.9 11040.8 11050.6 11164.5 19662.8 22187.8 22322.3 22326.3 22368.4 6676.6 8028.2 8107.1 8221.0 8335.2 4437.3 4491.6 4547.6 4549.4 4557.3 3550.2 3681.2 3688.7 3695.2 3704.9 669.8 672.6 676.8 684.4 691.6 505.9 589.5 615.3 636.1 696.0 9470.3 9535.7 9547.3 9621.6 9732.9 581.3 635.9 636.8 695.0 708.0 4448.3 4595.3 4658.7 4805.7 4837.7 89364.6 91140.0 139002.0 146067.3 148013.5 84374.2 125487.0 125694.4 126104.0 126712.0 86776.0 106748.0 109448.0 112816.4 112889.4 8061.6 9994.6 10037.4 10098.5 10098.5 48929.0 63785.0 63898.0 64673.0 65161.0 58708.7 76082.1 79016.8 90933.6 97680.5 13140.3 13217.7 13297.2 13522.7 13919.9 14735.4 14812.9 14842.1 15067.5 15270.5 13730.5 13746.8 13804.7 13829.8 13857.7 13658.7 13735.7 13828.3 13897.6 13917.7 13341.3 13468.7 13820.4 13924.5 14358.2 10600.9 11057.4 11087.6 11206.7 11441.8 9633.4 11360.8 11425.4 11590.8 11652.8 21:49:29 INFO - PROCESS | 1654 | -------- Summary: end -------- 21:49:29 INFO - PROCESS | 1654 | 21:49:30 INFO - TEST-INFO | 1654: exit 0 21:49:30 INFO - TEST-OK | dromaeo_css | took 1114208ms 21:49:30 INFO - TEST-START | kraken 21:49:30 INFO - operating with platform_type : mac_ 21:49:30 INFO - Initialising browser for kraken test... 21:49:30 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/tmppMiyIf/profile http://localhost:49280/getInfo.html) 21:49:33 INFO - PROCESS | 1666 | __metrics Screen width/height:1600/1200 21:49:33 INFO - PROCESS | 1666 | colorDepth:24 21:49:33 INFO - PROCESS | 1666 | Browser inner width/height: 1024/689 21:49:33 INFO - PROCESS | 1666 | __metrics 21:49:33 ERROR - PROCESS | 1666 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2998: TypeError: this.tabs is undefined 21:49:34 INFO - TEST-INFO | 1666: exit 0 21:49:34 INFO - Browser initialized. 21:49:34 INFO - Running cycle 1/1 for kraken test... 21:49:34 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/tmppMiyIf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:49:45 INFO - PROCESS | 1668 | RSS: Main: 245297152 21:49:45 INFO - PROCESS | 1668 | 21:49:48 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-beat-detection) 21:49:49 INFO - PROCESS | 1668 | RSS: Main: 258064384 21:49:49 INFO - PROCESS | 1668 | 21:49:52 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-dft) 21:49:52 INFO - PROCESS | 1668 | RSS: Main: 266797056 21:49:52 INFO - PROCESS | 1668 | 21:49:56 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-fft) 21:49:56 INFO - PROCESS | 1668 | RSS: Main: 264069120 21:49:56 INFO - PROCESS | 1668 | 21:49:58 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-oscillator) 21:49:59 INFO - PROCESS | 1668 | RSS: Main: 266608640 21:49:59 INFO - PROCESS | 1668 | 21:50:01 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:50:02 INFO - PROCESS | 1668 | RSS: Main: 266903552 21:50:02 INFO - PROCESS | 1668 | 21:50:05 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-darkroom) 21:50:05 INFO - PROCESS | 1668 | RSS: Main: 267280384 21:50:05 INFO - PROCESS | 1668 | 21:50:09 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-desaturate) 21:50:09 INFO - PROCESS | 1668 | RSS: Main: 267702272 21:50:09 INFO - PROCESS | 1668 | 21:50:12 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49280/tests/kraken/driver.html?testName=json-parse-financial) 21:50:13 INFO - PROCESS | 1668 | RSS: Main: 267943936 21:50:13 INFO - PROCESS | 1668 | 21:50:15 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49280/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:50:15 INFO - PROCESS | 1668 | RSS: Main: 267579392 21:50:15 INFO - PROCESS | 1668 | 21:50:17 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:50:18 INFO - PROCESS | 1668 | RSS: Main: 267624448 21:50:18 INFO - PROCESS | 1668 | 21:50:20 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:50:21 INFO - PROCESS | 1668 | RSS: Main: 267870208 21:50:21 INFO - PROCESS | 1668 | 21:50:24 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:50:24 INFO - PROCESS | 1668 | RSS: Main: 268148736 21:50:24 INFO - PROCESS | 1668 | 21:50:27 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:50:28 INFO - PROCESS | 1668 | RSS: Main: 268132352 21:50:28 INFO - PROCESS | 1668 | 21:50:30 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49280/tests/kraken/driver.html?testName=ai-astar) 21:50:30 INFO - PROCESS | 1668 | RSS: Main: 270065664 21:50:30 INFO - PROCESS | 1668 | 21:50:30 INFO - PROCESS | 1668 | __start_tp_report 21:50:30 INFO - PROCESS | 1668 | _x_x_mozilla_page_load 21:50:30 INFO - PROCESS | 1668 | _x_x_mozilla_page_load_details 21:50:30 INFO - PROCESS | 1668 | |i|pagename|runs| 21:50:30 INFO - PROCESS | 1668 | |0;ai-astar;106;117;117;115;118;117;118;118;116;132 21:50:30 INFO - PROCESS | 1668 | |1;audio-beat-detection;141;111;140;152;108;130;111;142;147;110 21:50:30 INFO - PROCESS | 1668 | |2;audio-dft;195;195;203;225;243;244;194;200;197;198 21:50:30 INFO - PROCESS | 1668 | |3;audio-fft;66;109;107;172;57;59;57;59;130;57 21:50:30 INFO - PROCESS | 1668 | |4;audio-oscillator;61;71;120;71;71;93;111;68;78;109 21:50:30 INFO - PROCESS | 1668 | |5;imaging-gaussian-blur;89;87;90;88;137;85;104;73;113;131 21:50:30 INFO - PROCESS | 1668 | |6;imaging-darkroom;140;128;104;147;140;105;147;151;152;92 21:50:30 INFO - PROCESS | 1668 | |7;imaging-desaturate;97;79;147;146;100;143;143;91;93;90 21:50:30 INFO - PROCESS | 1668 | |8;json-parse-financial;56;57;56;56;55;54;56;57;56;54 21:50:30 INFO - PROCESS | 1668 | |9;json-stringify-tinderbox;65;69;68;70;86;114;81;76;70;71 21:50:30 INFO - PROCESS | 1668 | |10;stanford-crypto-aes;85;93;77;91;113;114;110;88;87;89 21:50:30 INFO - PROCESS | 1668 | |11;stanford-crypto-ccm;140;183;161;141;162;160;159;161;194;152 21:50:30 INFO - PROCESS | 1668 | |12;stanford-crypto-pbkdf2;175;238;185;170;168;172;167;169;171;173 21:50:30 INFO - PROCESS | 1668 | |13;stanford-crypto-sha256-iterative;68;71;67;61;97;92;87;92;91;97 21:50:30 INFO - PROCESS | 1668 | __end_tp_report 21:50:30 INFO - PROCESS | 1668 | __start_cc_report 21:50:30 INFO - PROCESS | 1668 | _x_x_mozilla_cycle_collect,961 21:50:30 INFO - PROCESS | 1668 | __end_cc_report 21:50:30 INFO - PROCESS | 1668 | __startTimestamp1472791830633__endTimestamp 21:50:30 INFO - PROCESS | 1668 | 21:50:30 INFO - PROCESS | 1668 | ------- Summary: start ------- 21:50:30 INFO - PROCESS | 1668 | Number of tests: 14 21:50:30 INFO - PROCESS | 1668 | 21:50:30 INFO - PROCESS | 1668 | [#0] ai-astar Cycles:10 Average:117.40 Median:117.50 stddev:6.26 (5.3%) stddev-sans-first:5.10 21:50:30 INFO - PROCESS | 1668 | Values: 106.0 117.0 117.0 115.0 118.0 117.0 118.0 118.0 116.0 132.0 21:50:30 INFO - PROCESS | 1668 | 21:50:30 INFO - PROCESS | 1668 | [#1] audio-beat-detection Cycles:10 Average:129.20 Median:140.50 stddev:17.44 (12.4%) stddev-sans-first:17.97 21:50:30 INFO - PROCESS | 1668 | Values: 141.0 111.0 140.0 152.0 108.0 130.0 111.0 142.0 147.0 110.0 21:50:30 INFO - PROCESS | 1668 | 21:50:30 INFO - PROCESS | 1668 | [#2] audio-dft Cycles:10 Average:209.40 Median:201.50 stddev:20.10 (10.0%) stddev-sans-first:20.63 21:50:30 INFO - PROCESS | 1668 | Values: 195.0 195.0 203.0 225.0 243.0 244.0 194.0 200.0 197.0 198.0 21:50:30 INFO - PROCESS | 1668 | 21:50:30 INFO - PROCESS | 1668 | [#3] audio-fft Cycles:10 Average:87.30 Median:57.00 stddev:40.37 (70.8%) stddev-sans-first:42.07 21:50:30 INFO - PROCESS | 1668 | Values: 66.0 109.0 107.0 172.0 57.0 59.0 57.0 59.0 130.0 57.0 21:50:30 INFO - PROCESS | 1668 | 21:50:30 INFO - PROCESS | 1668 | [#4] audio-oscillator Cycles:10 Average:85.30 Median:71.00 stddev:21.19 (29.8%) stddev-sans-first:20.57 21:50:30 INFO - PROCESS | 1668 | Values: 61.0 71.0 120.0 71.0 71.0 93.0 111.0 68.0 78.0 109.0 21:50:30 INFO - PROCESS | 1668 | 21:50:30 INFO - PROCESS | 1668 | [#5] imaging-gaussian-blur Cycles:10 Average:99.70 Median:86.00 stddev:21.09 (24.5%) stddev-sans-first:22.01 21:50:30 INFO - PROCESS | 1668 | Values: 89.0 87.0 90.0 88.0 137.0 85.0 104.0 73.0 113.0 131.0 21:50:30 INFO - PROCESS | 1668 | 21:50:30 INFO - PROCESS | 1668 | [#6] imaging-darkroom Cycles:10 Average:130.60 Median:147.00 stddev:22.23 (15.1%) stddev-sans-first:23.32 21:50:30 INFO - PROCESS | 1668 | Values: 140.0 128.0 104.0 147.0 140.0 105.0 147.0 151.0 152.0 92.0 21:50:30 INFO - PROCESS | 1668 | 21:50:30 INFO - PROCESS | 1668 | [#7] imaging-desaturate Cycles:10 Average:112.90 Median:84.50 stddev:27.97 (33.1%) stddev-sans-first:29.06 21:50:30 INFO - PROCESS | 1668 | Values: 97.0 79.0 147.0 146.0 100.0 143.0 143.0 91.0 93.0 90.0 21:50:30 INFO - PROCESS | 1668 | 21:50:30 INFO - PROCESS | 1668 | [#8] json-parse-financial Cycles:10 Average:55.70 Median:56.00 stddev:1.06 (1.9%) stddev-sans-first:1.12 21:50:30 INFO - PROCESS | 1668 | Values: 56.0 57.0 56.0 56.0 55.0 54.0 56.0 57.0 56.0 54.0 21:50:30 INFO - PROCESS | 1668 | 21:50:30 INFO - PROCESS | 1668 | [#9] json-stringify-tinderbox Cycles:10 Average:77.00 Median:70.50 stddev:14.49 (20.6%) stddev-sans-first:14.71 21:50:30 INFO - PROCESS | 1668 | Values: 65.0 69.0 68.0 70.0 86.0 114.0 81.0 76.0 70.0 71.0 21:50:30 INFO - PROCESS | 1668 | 21:50:30 INFO - PROCESS | 1668 | [#10] stanford-crypto-aes Cycles:10 Average:94.70 Median:87.50 stddev:12.92 (14.8%) stddev-sans-first:13.22 21:50:30 INFO - PROCESS | 1668 | Values: 85.0 93.0 77.0 91.0 113.0 114.0 110.0 88.0 87.0 89.0 21:50:30 INFO - PROCESS | 1668 | 21:50:30 INFO - PROCESS | 1668 | [#11] stanford-crypto-ccm Cycles:10 Average:161.30 Median:161.00 stddev:16.67 (10.4%) stddev-sans-first:15.80 21:50:30 INFO - PROCESS | 1668 | Values: 140.0 183.0 161.0 141.0 162.0 160.0 159.0 161.0 194.0 152.0 21:50:30 INFO - PROCESS | 1668 | 21:50:30 INFO - PROCESS | 1668 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.80 Median:172.50 stddev:21.42 (12.4%) stddev-sans-first:22.67 21:50:30 INFO - PROCESS | 1668 | Values: 175.0 238.0 185.0 170.0 168.0 172.0 167.0 169.0 171.0 173.0 21:50:30 INFO - PROCESS | 1668 | 21:50:30 INFO - PROCESS | 1668 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:82.30 Median:91.50 stddev:13.90 (15.2%) stddev-sans-first:13.74 21:50:30 INFO - PROCESS | 1668 | Values: 68.0 71.0 67.0 61.0 97.0 92.0 87.0 92.0 91.0 97.0 21:50:30 INFO - PROCESS | 1668 | -------- Summary: end -------- 21:50:30 INFO - PROCESS | 1668 | 21:50:31 INFO - TEST-INFO | 1668: exit 0 21:50:31 INFO - TEST-OK | kraken | took 61072ms 21:50:31 INFO - SUITE-END | took 1175s 21:50:31 INFO - Completed test suite (00:19:35) 21:50:31 INFO - javascript benchmark 21:50:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7202.419435716933, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3348.799072055678, 3355.312161006994, 3373.4771110477877, 3393.9321213575713, 3411.09605954422, 5599.216109744639, 5614.185943038257, 5622.7188640567965, 5691.174779656947, 5736.397678243787, 5138.691678499284, 5609.467100625434, 5664.886702265952, 5689.061304884689, 5769.798057068004, 5103.872403189931, 5137.897242055156, 5171.120909445403, 5172.715500647463, 5251.894962100756, 3824.273388056274, 4080.8165631966694, 5176.275321454999, 5322.467753224685, 5329.653572517794, 5104.285400043997, 5136.9469258801855, 5204.42751297357, 5222.582193424536, 5273.6572122811895, 5129.410117836459, 5199.922001169985, 5222.32109825724, 5244.475552444763, 5266.8683282917855, 4236.555161708007, 5147.433782283946, 5163.922541161885, 5193.870153246161, 5216.0, 4697.906041879143, 5398.541124004464, 5515.145152501369, 5579.995600791855, 5702.714864256771, 6122.245686735438, 6124.808163753419, 6150.04674275488, 6189.721462534197, 6218.973869311558, 6212.844678883019, 6215.782447614357, 6235.438810507031, 6239.625622462621, 6276.843078923018, 5649.717514124277, 5739.512141467939, 5774.047282198474, 5775.942240577582, 5811.6803575803315, 6205.317415084337, 6215.782447614311, 6246.781362652286, 6253.437190652863, 6254.718537665816, 6050.183225264621, 6054.54590905675, 6066.4540191382985, 6070.525070979092, 6103.84740381494, 6075.210222671025, 6095.969520152371, 6140.478059365004, 6141.201643786279, 6173.228346456693, 6103.549811064039, 6164.907526387108, 6194.4115309045565, 6198.81403557894, 6203.4416902479, 6163.691815409212, 6192.349803270592, 6217.067439884054, 6230.563860529719, 6249.6562689051625, 4796.016816552598, 4891.070696567641, 4933.260010998307, 4974.751262436864, 4985.152524070917, 4207.179599977975, 4411.911761764747, 4418.182636212311, 4477.238869392211, 4504.842330518416, 5066.214736715815, 5585.888282234332, 5736.483716465538, 5753.769849205984, 5759.876824019276, 6029.879402411927, 6153.29237137726, 6210.689465526705, 6241.875162496724, 6289.842753931097, 5719.0, 5754.108113242454, 5781.942180578225, 5793.826185214451, 5841.853953651193, 5455.727213639381, 5475.561955043587, 5479.123340265538, 5484.341878974469, 5502.0371434999515, 6126.30393285589, 6142.1304578115505, 6324.747010119636, 6328.968355158195, 6362.4273815357765, 6106.2179897963915, 6205.844853878689, 6215.720292586754, 6220.308417797664, 6231.0, 6226.034964580497, 6236.0, 6236.469900058546, 6238.594491358047, 6244.2819075806865, 4984.1526940420945, 5491.780328786804, 6081.969590152021, 6099.451049405575, 6100.786472473442, 6139.201903752483, 6140.105498721277, 6169.784057557964, 6220.595661282002, 6241.375862413723, 6054.061620448838, 6056.394360563909, 6057.969710151421, 6078.635281882925, 6111.663858487826, 5868.295804503487, 5936.821895343147, 6055.091736239599, 6068.029115341524, 6078.54410919174, 5744.856378590569, 5830.82507524775, 5910.143029260647, 5913.763449461973, 5916.201312822886, 5257.5005374489365, 6017.939820601738, 6037.909431358621, 6046.455818976137, 6052.636841789506, 5706.6005379624685, 5790.826275211751, 5872.412758724094, 5929.940700592939, 5963.791267305797], "value": 5621.664081347506, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [468.3162582629361, 495.39561734683673, 506.97465126743674, 510.8161062017675, 516.3158814570694, 39004.02489937753, 39838.00404989873, 39840.60159398405, 39847.402288965655, 40035.799821000925, 40119.19761604766, 40859.77420677377, 40883.0, 40959.180816383654, 41002.0, 9537.427754334762, 9763.36538125023, 9783.804323913517, 9796.461194634328, 9837.409755414663, 9737.56180971858, 9794.510274486283, 9806.215502759762, 9826.705198844045, 9855.507224638775, 9788.070133337322, 9841.901580984206, 9848.55681494331, 9873.506324683773, 9889.456079915602, 8103.797405064862, 9761.755956101084, 9784.657536986242, 9801.950990245074, 9832.901670983341, 9629.855552166724, 9706.951465242628, 9731.464769437678, 9743.512824358753, 9754.658586949423, 39204.60795392038, 39401.21197576061, 39459.60540394588, 39561.20877582432, 39758.40362394538, 3351.1957130288556, 3377.814220217887, 3395.7283417326553, 3403.40440422925, 3443.104792753902, 30171.0, 30284.394312113855, 30406.54390184149, 30561.54157687614, 31556.684433155606, 3214.0612686663903, 3222.532732753761, 3233.1917020744813, 3238.6599407062154, 3333.516640087198, 473836.78489645367, 488525.0, 494430.52784736204, 495754.0, 499001.5049924727, 57323.56691082756, 57805.13292300619, 57858.13212801727, 57875.0, 57903.42096579065, 33450.330993380485, 34495.13762155916, 59775.103373449434, 60244.0, 60304.698476508216, 15969.600759981093, 16194.676106477804, 16370.263338149813, 16371.754423683655, 16439.424620138478, 12469.127161098817, 12540.122191446511, 12541.122121451594, 12576.434060467302, 12606.747865042647, 18720.906395467937, 18816.529586760167, 18828.246870125316, 18853.62292754137, 18885.339013134475, 6771.559848609824, 6922.653867306715, 7920.841583168304, 7963.522188668699, 7984.600769961478, 845.4758050008911, 873.1094283830457, 909.2907532124954, 1079.4818487126224, 1126.7577470843644, 25299.62050569243, 29327.706722932926, 29971.100866974026, 30356.696433035828, 30458.69541304603, 939.3002213351098, 1034.7620047389144, 1048.124815778818, 1062.3625824505234, 1139.920205585601, 12137.089718271165, 13401.128926619738, 15102.697946041018, 15132.470363537444, 15380.0, 622529.0, 630550.0, 640780.0, 641435.0, 642579.0, 45840.08319833651, 54432.727836360566, 54885.1767223492, 55234.0, 55487.44512554823, 1167.2529581068122, 1171.5782318365552, 1344.8991325650616, 1351.4053816320788, 1370.5477192526441, 27915.16254512368, 28733.0, 29104.7089529102, 29401.264968375963, 29559.408811824072, 47488.52511474841, 47745.0, 47926.28110578414, 48145.5185448155, 48710.5128948706, 568403.0, 574399.0, 576077.1196143993, 576144.0, 577697.0, 25877.741222588287, 25956.0, 26186.214413567624, 26246.34384140412, 26313.473730525664, 44959.55040449685, 45498.772506137255, 45553.54446455625, 46489.0, 46869.29696054494, 32649.836750816095, 38435.0, 38840.6115938837, 39201.2159756809, 39331.410028850165, 37619.435708464945, 37846.053848654, 37999.620003800715, 38985.0, 39048.6095139045, 30102.0, 37193.88418347454, 38087.61912380951, 38101.04747381338, 38323.616763832004, 32582.0, 34195.14512137217, 34546.65453345434, 34592.65407345894, 34901.65098348984, 24135.0, 25181.87409062943, 25295.0, 25439.74560254374, 26339.473210536064], "value": 19540.968731172696, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [373.97382183247174, 377.420659287993, 380.9028697682091, 389.77003567894934, 404.9433079368887, 18787.43637690868, 19784.0, 20852.374428767143, 21068.367948961517, 21176.25883094092, 11670.708232294197, 11711.0, 12056.0, 12082.033437325015, 18908.20372064655, 5246.7638956247065, 5511.724413779295, 5515.09001014832, 5520.089185284423, 5543.584231182657, 5236.528712415882, 5373.543248823855, 5425.186222066702, 5438.564914806826, 5455.236266922634, 4868.537488938563, 5216.191490318987, 5340.599455040868, 5414.160805075219, 5414.945850541503, 5189.481051894818, 5375.596830237729, 5402.594805389612, 5444.0, 5457.536109430703, 5223.582113430916, 5280.2343660169445, 5293.941211757626, 5305.946940530584, 5320.255164277004, 12682.873171268262, 12874.29191394473, 12984.545540906118, 12991.610251692464, 13119.278439685813, 2076.854620176573, 2112.6619740841547, 2280.6122959096992, 2938.7355138037465, 2960.6891276416095, 9649.276304277208, 10238.948805256001, 10313.329633573869, 13256.337183140804, 13787.655308617264, 1369.2811274081087, 1450.5068276785944, 1575.3912337743172, 1773.2286455391816, 1892.9872518202696, 61049.0, 98245.0175498243, 116282.0, 123264.0, 123449.0, 9881.752956176153, 10158.542865571068, 11506.252093613888, 11523.884761152449, 11669.183157178915, 14255.572332830032, 14305.499307524187, 14374.640633984025, 14388.928055359656, 14391.85608143926, 8281.95859020713, 8560.571971401405, 8611.870821937677, 8667.739967800977, 8720.389572729975, 7527.172011078777, 8068.8386232275025, 8531.53076580794, 8565.229129378386, 8731.912680873236, 3515.736319776027, 4402.207602631557, 4465.843695470708, 4487.977560112178, 4576.061907308994, 1896.4121122451809, 1928.2961718972474, 1936.8741031832885, 1940.185122248659, 1943.4169749075504, 815.0, 842.5787106446777, 871.2028493927985, 874.1215078845719, 888.3337496877342, 20007.699884501744, 20645.89677051605, 20709.896450517652, 20774.272900448414, 20799.06404211814, 919.3610440743619, 932.5498849289285, 952.6094301336419, 961.1301771896445, 1012.5190534496055, 9798.351047978573, 10096.949515252523, 10903.945480272707, 10941.945290273497, 10950.452477376099, 546293.0, 562001.0, 566051.0, 568162.0, 569724.0, 91126.0, 114611.0, 116833.0, 121847.0, 125807.0, 438.7082590077563, 451.3342819341445, 455.0035422424945, 455.99908201498056, 458.159277725371, 8953.641854326015, 10535.841962370418, 10781.730456738644, 10985.285956412808, 11228.887711122785, 47330.0, 49613.75193124011, 49880.0, 51343.74328128336, 54187.0, 51605.0, 71434.64282678554, 73493.7951861445, 73693.26306736864, 74892.0, 7736.7678969631, 7768.300852923264, 8004.679812807655, 8082.87875681877, 8196.385271104571, 11858.0, 12185.634430966731, 12386.442610082748, 12569.49722011146, 12685.175463594836, 10345.327553708985, 10490.737731556772, 10722.678319650424, 10723.463826808784, 11248.606298779503, 10726.249162558546, 10743.46282685847, 10968.945155274172, 11228.719282018015, 11233.775324493628, 9772.609095636379, 11058.0, 11163.944180279046, 11267.492962816528, 11344.773104538028, 7945.324647405036, 8147.470414423044, 8211.71259005932, 8266.628001739817, 8293.875591866006, 10374.948125259627, 10812.297200681931, 10997.395143267197, 11050.723731906766, 11178.888211117785], "value": 8656.03990964717, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1459.5475402625186, 1520.2854658310594, 1705.3349193814422, 1718.1237568839895, 1870.8587761465494, 7989.280964713174, 8492.235698787108, 8691.0, 9119.179273865351, 9137.497437640935, 8817.382783205177, 8899.68851090212, 8918.152775486335, 8931.50876701781, 8944.686935957243, 2370.419247284418, 2383.7616238376154, 2386.4630458146953, 2425.3451568076607, 2428.9878550607223, 2312.537492501498, 2349.447879748266, 2391.3304274802995, 2397.4485868250313, 2412.7104747430335, 2295.8507696999727, 2409.8795060246916, 2413.81896357773, 2417.1660777031984, 2427.9028838846425, 2366.928992130239, 2372.525494901018, 2381.3808409813487, 2383.9046438142454, 2385.224801939368, 2379.3694670912223, 2393.6888204533298, 2394.856308621488, 2395.413123784684, 2396.8322217444784, 8924.88438945132, 8951.910480895238, 8972.41679290844, 9068.41055331408, 9068.546572671406, 5258.3689957205, 5400.702961337125, 5429.782808687648, 5440.863978400532, 5447.918281225785, 3608.1340478285283, 3652.543432070991, 3669.6697297243377, 3703.6296370363016, 3712.0, 1072.5066469424075, 1078.9406582637953, 1090.536521978164, 1103.4593049405814, 1105.336797921249, 11662.183647144704, 11718.23831450953, 11788.941055294668, 11800.645980620595, 11848.170628055954, 4702.388689470347, 4727.120638842144, 4781.211100168433, 4874.926876096862, 4916.237983112552, 9898.653547125816, 10039.397636141855, 10053.447060411747, 10064.0, 10073.69778906634, 5389.0, 5392.379876314274, 5405.891882162335, 5420.214068959979, 5439.48324909133, 4448.221561226837, 4757.809687612456, 4883.975580122076, 4886.877828054256, 4933.851984440473, 3936.7834769087976, 4013.3979903014783, 4066.877993660136, 4106.322456794655, 4128.669706423479, 1316.4799904037852, 1320.5473978623438, 1326.608650448101, 1331.3809078778415, 1335.8263425754783, 852.4927668037508, 854.3080105114877, 907.8955920069144, 913.3332667153015, 940.9529523523796, 5724.4561766632105, 6028.156058151862, 6041.879162416727, 6084.817455476343, 6182.938170618237, 806.9556174410463, 845.780097174739, 878.6265837019369, 883.1918794303269, 1017.6845177994845, 3507.6141624421293, 3917.8432862685745, 3978.6036429744713, 4100.138970816102, 4171.749695018309, 10714.517846696917, 10966.616168434066, 10991.780164396827, 11067.833982490269, 11078.5568577256, 11013.614523491638, 11021.889781102247, 11022.669319920415, 11025.062869656169, 11037.889621103686, 8013.559254241072, 8217.013958325038, 8271.13153118926, 8353.289970352587, 8439.071702112762, 5683.090705487268, 5895.882082358415, 5986.07215881539, 6011.819645410645, 6124.326324104434, 8056.637451314587, 10269.075783179549, 10328.535215915152, 10354.534045968101, 10358.896411035794, 9933.552990115606, 10022.0, 10495.580176792842, 10508.474576271308, 10557.419341936267, 6619.569727967667, 6967.338102880219, 7070.858582828418, 7096.148462184571, 7233.674484648309, 8586.656533738831, 8651.610677519653, 8659.960804703476, 8681.609327580149, 8691.391602587759, 7507.66215520292, 7628.771136865903, 7654.617269136632, 7757.72847950319, 7763.883541746765, 7470.017702645293, 7517.849643007218, 7559.244075592397, 7575.621218939141, 7702.114256860309, 6088.665123418077, 6986.720531178696, 7080.504364694422, 7127.7148914042855, 7131.429485641032, 4928.630352723631, 5064.834478450968, 5114.718690471911, 5138.203578445347, 5186.533212010937, 4200.537940826568, 4989.800407983786, 5062.443131255485, 5242.842714718565, 5300.443453437488], "value": 4647.488256828755, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [272.24995138393723, 296.9242843075016, 298.20082179758253, 304.55078758830723, 313.62365161805826, 4182.853600123996, 5171.79312827487, 5238.84283471496, 5243.475652434764, 5469.589780766443, 4537.387452693885, 4790.281572650936, 4874.512548745124, 5108.770105345259, 5348.518633323, 3404.3872103021386, 3636.9816451393654, 3931.567527571979, 4175.765965208614, 4178.8328466861285, 3632.7648599476306, 3740.9812950935207, 4175.582441755815, 4182.435371224891, 4193.379138038726, 3753.418220175877, 3955.564887862333, 3974.205158968218, 3989.321815291393, 3991.560928297885, 3367.966320336814, 3973.920521589581, 3994.460747799068, 4009.659178969791, 4018.4775979122674, 3126.296583268769, 3252.9349413011846, 3470.7396945228957, 3487.9536139158404, 3505.772124811879, 4879.779345631901, 5164.741762911877, 5208.765605547759, 5249.792547714009, 5271.446498117719, 3864.1692036212066, 4531.207038768202, 4541.568550987651, 4551.135284296006, 4557.156925968707, 5577.581681373873, 5607.663540187602, 5608.46719561641, 5609.80365687199, 5629.3526244482, 3532.205253817896, 3754.3429899767702, 3764.548254209485, 3772.13240954582, 3848.960780589225, 4574.496805351442, 5177.870553236162, 5314.92027619586, 5848.444397782204, 5960.374160713106, 3840.3663395539425, 3855.8072096395067, 3861.189150278417, 3862.9034274143373, 3879.476270703475, 3292.3086151907887, 3631.7457777955287, 3905.6484916357663, 3923.1761330120426, 3936.252112098692, 4554.586069383232, 4584.6561507887045, 4599.356090147382, 4600.88497787558, 4612.95387046132, 5077.847664570068, 5414.88994756082, 5465.535429488538, 5640.379558248589, 5742.0, 1263.128441375448, 1288.826008488842, 1308.7578797922415, 1319.7162610038695, 1323.8146659467682, 814.7433558429076, 824.8658095119212, 860.7116615933733, 898.3980732908967, 902.8465160922659, 4838.927416088762, 4902.896848209124, 5217.060929032735, 5260.237265596467, 5279.47205279469, 4618.468876079227, 4685.976570117127, 4750.714957102585, 5744.770209191669, 5769.596128271065, 4816.301636262722, 4860.586850117709, 4948.292838970549, 5017.422996355393, 5248.606354523426, 2834.1861208826367, 2989.1630343503853, 3035.6964303569466, 3053.679363666783, 3092.597962264891, 4963.691883274295, 6102.359252278447, 6106.381808820666, 6115.6330620162935, 6187.226596675416, 4968.279599458058, 4972.129877271535, 5601.181919026666, 5930.449906540143, 5953.285605727254, 6051.727672254759, 6058.334332893601, 6075.149479072845, 6084.30030546484, 6098.878022439526, 5217.373915130209, 5374.946250537445, 5810.68041257718, 5830.47525722687, 5861.443162899518, 4809.855704328876, 4831.647138801141, 5786.81370319092, 5821.592488525932, 5828.970855145697, 4861.951380486292, 5074.5686616637995, 5421.457854214705, 6080.695965201634, 6157.84605384869, 3182.379436009956, 3459.740519461, 3701.5373078365205, 3922.764634121962, 3994.920101597894, 5373.247745315737, 5424.942136283387, 5551.583631227755, 5717.198572699431, 5794.681292528951, 4897.338859253955, 5685.857853553529, 5703.515201207943, 5711.600187986801, 5721.256236689203, 4973.950260497349, 5051.621128415457, 5652.2086907833755, 5683.744231509675, 5716.28546431696, 4468.955310446854, 5378.596605254544, 5509.917351239815, 5566.749496272759, 5586.692731899784, 5451.890962180813, 5453.64551304164, 5696.601237913306, 5722.971385143048, 5738.311402631711, 5582.916256256241, 5588.608797384307, 5599.860003499945, 5603.9545565868975, 5636.5208957237455], "value": 4058.78127335201, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [703.7114782938996, 723.5441671746802, 727.2836256287555, 732.7362149626134, 768.277818850281, 12034.398280085994, 15976.520704378887, 16046.0, 16080.678386432264, 16145.192740362994, 14392.568222953329, 14461.710765784705, 14486.275686215673, 14496.420143194286, 14507.637309067251, 672.8654269146166, 851.1828644501286, 856.6659002988839, 873.1879352202449, 873.5981448533682, 870.5516658920661, 875.1948207648978, 881.2553392383426, 881.7046289492995, 884.9601767920427, 933.785229397239, 935.289180223032, 937.8077494113691, 938.0478814003792, 938.760616042908, 856.4903882190087, 912.867634193045, 924.9907500925005, 925.7130289610243, 930.1628534319133, 675.8614484030762, 688.8140202145443, 694.4687314204638, 697.5605368617789, 699.1260923845193, 14456.638584035378, 14547.199904005276, 14547.927260363736, 14552.781708274384, 14591.92704036473, 14078.788818167735, 14113.294335283195, 14139.610065654333, 14154.363053662508, 14270.64323391913, 11761.059115270758, 11772.470238839187, 11850.288982661068, 11957.760844783055, 11993.340366279852, 3484.5470088888283, 3728.720345974036, 3781.2626537825136, 3872.477215575889, 3907.492026036597, 103910.48044759728, 104574.0, 105927.8814423715, 106973.0, 107019.99999999921, 10853.823506007791, 10950.945245273722, 11040.779184416428, 11050.642554484024, 11164.49279347294, 19662.803371966384, 22187.77812221857, 22322.330330090124, 22326.33021009372, 22368.44078898008, 6676.6327851967635, 8028.157043510348, 8107.108218096005, 8220.958895205606, 8335.208155225244, 4437.33439984005, 4491.550844915548, 4547.613452856478, 4549.385832912581, 4557.339185818038, 3550.183457804719, 3681.208540163878, 3688.686461650736, 3695.2240029594086, 3704.8518059277867, 669.7509145443712, 672.5695554844904, 676.8172593399754, 684.3567046975827, 691.6472598974459, 505.88111793728444, 589.4665327878276, 615.286267929209, 636.1157990393265, 696.004713260043, 9470.289728270407, 9535.666251681158, 9547.331686781998, 9621.567029483691, 9732.90267097334, 581.3372755059236, 635.9141515895295, 636.7803107927757, 694.979150625482, 707.9575225486487, 4448.310511870665, 4595.264757638762, 4658.7204767713365, 4805.663603547788, 4837.661363704507, 89364.5531772337, 91140.0, 139002.0, 146067.26966365313, 148013.51986480213, 84374.15625843663, 125487.0, 125694.3715281436, 126104.0, 126712.0, 86775.99999999873, 106748.0, 109448.0, 112816.43591781988, 112889.43555282171, 8061.596920154087, 9994.550245239127, 10037.44794036335, 10098.49507524606, 10098.495075246356, 48929.0, 63785.0, 63898.0, 64673.0, 65161.0, 58708.706456467444, 76082.09794755175, 79016.81474777768, 90933.63599546145, 97680.51159744155, 13140.27728474943, 13217.735645287234, 13297.202167869958, 13522.729545409233, 13919.93040034834, 14735.3583621159, 14812.855574408279, 14842.109473431628, 15067.547973560811, 15270.541883743506, 13730.45078196861, 13746.793798092836, 13804.654883627989, 13829.79255311151, 13857.72284554283, 13658.726825463635, 13735.725285494435, 13828.308584570932, 13897.583072507841, 13917.721645567235, 13341.266230357423, 13468.663283417602, 13820.378082986088, 13924.524721474889, 14358.210298433687, 10600.864768152798, 11057.447127643747, 11087.55649774, 11206.663800086011, 11441.771164576829, 9633.438976260195, 11360.772784544097, 11425.428728563706, 11590.826137608112, 11652.766944661229], "value": 7781.837702949865, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1621.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 117.0, 117.0, 115.0, 118.0, 117.0, 118.0, 118.0, 116.0, 132.0], "value": 117.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 111.0, 140.0, 152.0, 108.0, 130.0, 111.0, 142.0, 147.0, 110.0], "value": 129.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 195.0, 203.0, 225.0, 243.0, 244.0, 194.0, 200.0, 197.0, 198.0], "value": 209.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 109.0, 107.0, 172.0, 57.0, 59.0, 57.0, 59.0, 130.0, 57.0], "value": 87.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 71.0, 120.0, 71.0, 71.0, 93.0, 111.0, 68.0, 78.0, 109.0], "value": 85.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 90.0, 88.0, 137.0, 85.0, 104.0, 73.0, 113.0, 131.0], "value": 99.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 128.0, 104.0, 147.0, 140.0, 105.0, 147.0, 151.0, 152.0, 92.0], "value": 130.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 79.0, 147.0, 146.0, 100.0, 143.0, 143.0, 91.0, 93.0, 90.0], "value": 112.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 57.0, 56.0, 56.0, 55.0, 54.0, 56.0, 57.0, 56.0, 54.0], "value": 55.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 69.0, 68.0, 70.0, 86.0, 114.0, 81.0, 76.0, 70.0, 71.0], "value": 77.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 93.0, 77.0, 91.0, 113.0, 114.0, 110.0, 88.0, 87.0, 89.0], "value": 94.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 183.0, 161.0, 141.0, 162.0, 160.0, 159.0, 161.0, 194.0, 152.0], "value": 161.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 238.0, 185.0, 170.0, 168.0, 172.0, 167.0, 169.0, 171.0, 173.0], "value": 178.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 71.0, 67.0, 61.0, 97.0, 92.0, 87.0, 92.0, 91.0, 97.0], "value": 82.3, "unit": "score"}]}]} 21:50:31 INFO - Return code: 0 21:50:31 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:50:31 ERROR - # TBPL SUCCESS # 21:50:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:50:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:50:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:50:31 INFO - 2016-09-01 21:50:31.911 screenresolution[1673:10711] starting screenresolution argv=screenresolution get 21:50:31 INFO - 2016-09-01 21:50:31.914 screenresolution[1673:10711] Display 0: 1600x1200x32@60 21:50:31 INFO - 2016-09-01 21:50:31.920 screenresolution[1674:10714] starting screenresolution argv=screenresolution list 21:50:31 INFO - Available Modes on Display 0 21:50:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:50:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:50:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:50:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:50:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:50:32 INFO - Intel Iris: 21:50:32 INFO - Chipset Model: Intel Iris 21:50:32 INFO - Type: GPU 21:50:32 INFO - Bus: Built-In 21:50:32 INFO - VRAM (Dynamic, Max): 1536 MB 21:50:32 INFO - Vendor: Intel (0x8086) 21:50:32 INFO - Device ID: 0x0a2e 21:50:32 INFO - Revision ID: 0x0009 21:50:32 INFO - Displays: 21:50:32 INFO - Display: 21:50:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:50:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:50:32 INFO - Display Serial Number: 1600x1200 60 21:50:32 INFO - Main Display: Yes 21:50:32 INFO - Mirror: Off 21:50:32 INFO - Online: Yes 21:50:32 INFO - Rotation: Supported 21:50:32 INFO - Return code: 0 21:50:32 INFO - Running post-action listener: _resource_record_post_action 21:50:32 INFO - [mozharness: 2016-09-02 04:50:32.160680Z] Finished run-tests step (success) 21:50:32 INFO - Running post-run listener: _resource_record_post_run 21:50:32 INFO - Total resource usage - Wall time: 1210s; CPU: 55.0%; Read bytes: 37221376; Write bytes: 706474496; Read time: 241; Write time: 2048 21:50:32 INFO - TinderboxPrint: CPU usage
54.5% 21:50:32 INFO - TinderboxPrint: I/O read bytes / time
37,221,376 / 241 21:50:32 INFO - TinderboxPrint: I/O write bytes / time
706,474,496 / 2,048 21:50:32 INFO - TinderboxPrint: CPU idle
2,200.9 (45.5%) 21:50:32 INFO - TinderboxPrint: CPU system
980.3 (20.2%) 21:50:32 INFO - TinderboxPrint: CPU user
1,660.6 (34.3%) 21:50:32 INFO - TinderboxPrint: Swap in / out
443,568,128 / 0 21:50:32 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 396818944; Write bytes: 390354432; Read time: 29476; Write time: 844 21:50:32 INFO - run-tests - Wall time: 1177s; CPU: 55.0%; Read bytes: 35189760; Write bytes: 297376768; Read time: 222; Write time: 1155 21:50:32 INFO - Running post-run listener: _upload_blobber_files 21:50:32 INFO - Blob upload gear active. 21:50:32 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:50:32 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 21:50:32 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'] 21:50:32 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 21:50:33 INFO - (blobuploader) - INFO - Open directory for files ... 21:50:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 21:50:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:50:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:50:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 21:50:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:50:34 INFO - (blobuploader) - INFO - Done attempting. 21:50:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 21:50:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:50:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:50:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 21:50:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:50:35 INFO - (blobuploader) - INFO - Done attempting. 21:50:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:50:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:50:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:50:37 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:50:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:50:37 INFO - (blobuploader) - INFO - Done attempting. 21:50:37 INFO - (blobuploader) - INFO - Iteration through files over. 21:50:37 INFO - Return code: 0 21:50:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:50:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:50:37 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/26f2b2ed1bbca8c982a12f171d99b3a3d19f99b1b5b88ae5d3e26da5930d5ae5e189b62cb9c3900ae3cca5646a2e235edce2633a5dfc3876dc46d9a16851ed41", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1b8bcc3556a379bce9d6d25f399189e40e1ae340096b9d6a80fbc9c8682d74289305647327214ac043cc94c0fdb434ba617394f2bd4851d6d74a15be200a0bfa", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/660d66e51a466d9553db4956bdfaba058b05e3a04bebc69d873f5081477505c886e5b3aee99baf3ad29af80057011cadc2e71d4f2b85883e59ff7731a2ff85a5"} 21:50:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:50:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:50:37 INFO - Contents: 21:50:37 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/26f2b2ed1bbca8c982a12f171d99b3a3d19f99b1b5b88ae5d3e26da5930d5ae5e189b62cb9c3900ae3cca5646a2e235edce2633a5dfc3876dc46d9a16851ed41", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1b8bcc3556a379bce9d6d25f399189e40e1ae340096b9d6a80fbc9c8682d74289305647327214ac043cc94c0fdb434ba617394f2bd4851d6d74a15be200a0bfa", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/660d66e51a466d9553db4956bdfaba058b05e3a04bebc69d873f5081477505c886e5b3aee99baf3ad29af80057011cadc2e71d4f2b85883e59ff7731a2ff85a5"} 21:50:37 INFO - Running post-run listener: copy_logs_to_upload_dir 21:50:37 INFO - Copying logs to upload dir... 21:50:37 INFO - mkdir: /builds/slave/test/build/upload/logs 21:50:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.981370 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-09-01 21:50:38.019168) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-01 21:50:38.023093) ========= 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.7gbodLJKqD/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.ouobj3VpVY/Listeners TMPDIR=/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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/26f2b2ed1bbca8c982a12f171d99b3a3d19f99b1b5b88ae5d3e26da5930d5ae5e189b62cb9c3900ae3cca5646a2e235edce2633a5dfc3876dc46d9a16851ed41", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1b8bcc3556a379bce9d6d25f399189e40e1ae340096b9d6a80fbc9c8682d74289305647327214ac043cc94c0fdb434ba617394f2bd4851d6d74a15be200a0bfa", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/660d66e51a466d9553db4956bdfaba058b05e3a04bebc69d873f5081477505c886e5b3aee99baf3ad29af80057011cadc2e71d4f2b85883e59ff7731a2ff85a5"} build_url:https://queue.taskcluster.net/v1/task/d-fNWJ9XRVSurVLPiYbNuw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011050 build_url: 'https://queue.taskcluster.net/v1/task/d-fNWJ9XRVSurVLPiYbNuw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/26f2b2ed1bbca8c982a12f171d99b3a3d19f99b1b5b88ae5d3e26da5930d5ae5e189b62cb9c3900ae3cca5646a2e235edce2633a5dfc3876dc46d9a16851ed41", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1b8bcc3556a379bce9d6d25f399189e40e1ae340096b9d6a80fbc9c8682d74289305647327214ac043cc94c0fdb434ba617394f2bd4851d6d74a15be200a0bfa", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/660d66e51a466d9553db4956bdfaba058b05e3a04bebc69d873f5081477505c886e5b3aee99baf3ad29af80057011cadc2e71d4f2b85883e59ff7731a2ff85a5"}' ========= master_lag: 0.72 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-01 21:50:38.750954) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 21:50:38.751285) ========= 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.7gbodLJKqD/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.ouobj3VpVY/Listeners TMPDIR=/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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.005143 ========= master_lag: 0.11 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 21:50:38.863291) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 21:50:38.863617) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 21:50:38.863949) ========= ========= Total master_lag: 0.93 =========