builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0180 starttime: 1468461086.6 results: success (0) buildid: 20160713154542 builduid: df9637022caf4d3383bef82a269a1255 revision: 31cab0ba8bbf1d88b08abeae45bcdf317645355a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-13 18:51:26.599284) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-13 18:51:26.600073) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-13 18:51:26.600622) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-13 18:51:26.633135) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 18:51:26.633565) ========= 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.LqGhbrBono/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.JgLK95z5xc/Listeners TMPDIR=/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-07-13 18:51:26-- 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% 15.3M=0.001s 2016-07-13 18:51:27 (15.3 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.505158 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 18:51:27.162519) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 18:51:27.162869) ========= 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.LqGhbrBono/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.JgLK95z5xc/Listeners TMPDIR=/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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.033128 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 18:51:27.217284) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-13 18:51:27.217670) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 31cab0ba8bbf1d88b08abeae45bcdf317645355a --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 31cab0ba8bbf1d88b08abeae45bcdf317645355a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.LqGhbrBono/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.JgLK95z5xc/Listeners TMPDIR=/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-07-13 18:51:27,290 truncating revision to first 12 chars 2016-07-13 18:51:27,290 Setting DEBUG logging. 2016-07-13 18:51:27,290 attempt 1/10 2016-07-13 18:51:27,291 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/31cab0ba8bbf?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-13 18:51:28,486 unpacking tar archive at: fx-team-31cab0ba8bbf/testing/mozharness/ program finished with exit code 0 elapsedTime=1.468565 ========= master_lag: 0.40 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-13 18:51:29.083116) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-13 18:51:29.083696) ========= script_repo_revision: 31cab0ba8bbf1d88b08abeae45bcdf317645355a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-13 18:51:29.084159) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-13 18:51:29.084488) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-13 18:51:29.115585) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-07-13 18:51:29.115903) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.LqGhbrBono/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.JgLK95z5xc/Listeners TMPDIR=/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 18:51:29 INFO - MultiFileLogger online at 20160713 18:51:29 in /builds/slave/test 18:51:29 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 18:51:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:51:29 INFO - {'append_to_log': False, 18:51:29 INFO - 'base_work_dir': '/builds/slave/test', 18:51:29 INFO - 'blob_upload_branch': 'Fx-Team', 18:51:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:51:29 INFO - 'branch': 'Fx-Team', 18:51:29 INFO - 'buildbot_json_path': 'buildprops.json', 18:51:29 INFO - 'config_files': ('talos/mac_config.py',), 18:51:29 INFO - 'default_actions': ('clobber', 18:51:29 INFO - 'read-buildbot-config', 18:51:29 INFO - 'download-and-extract', 18:51:29 INFO - 'populate-webroot', 18:51:29 INFO - 'create-virtualenv', 18:51:29 INFO - 'install', 18:51:29 INFO - 'run-tests'), 18:51:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:51:29 INFO - 'download_minidump_stackwalk': True, 18:51:29 INFO - 'download_symbols': 'ondemand', 18:51:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:51:29 INFO - 'tooltool.py': '/tools/tooltool.py', 18:51:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:51:29 INFO - '/tools/misc-python/virtualenv.py')}, 18:51:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:51:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:51:29 INFO - 'installer_path': 'installer.exe', 18:51:29 INFO - 'log_level': 'info', 18:51:29 INFO - 'log_name': 'talos', 18:51:29 INFO - 'log_to_console': True, 18:51:29 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:51:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:51:29 INFO - 'opt_config_files': (), 18:51:29 INFO - 'pip_index': False, 18:51:29 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:51:29 INFO - 'cmd': ('bash', 18:51:29 INFO - '-c', 18:51:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:51:29 INFO - 'enabled': True, 18:51:29 INFO - 'halt_on_failure': False, 18:51:29 INFO - 'name': 'check_screen_resolution'},), 18:51:29 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:51:29 INFO - 'cmd': ('bash', 18:51:29 INFO - '-c', 18:51:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:51:29 INFO - 'enabled': True, 18:51:29 INFO - 'halt_on_failure': False, 18:51:29 INFO - 'name': 'check_screen_resolution'},), 18:51:29 INFO - 'run_cmd_checks_enabled': True, 18:51:29 INFO - 'sps_profile': False, 18:51:29 INFO - 'sps_profile_interval': 0, 18:51:29 INFO - 'suite': 'dromaeojs', 18:51:29 INFO - 'system_bits': '32', 18:51:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:51:29 INFO - 'title': 't-yosemite-r7-0180', 18:51:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:51:29 INFO - 'use_talos_json': True, 18:51:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:51:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:51:29 INFO - 'work_dir': 'build'} 18:51:29 INFO - ##### 18:51:29 INFO - ##### Running clobber step. 18:51:29 INFO - ##### 18:51:29 INFO - Running pre-action listener: _resource_record_pre_action 18:51:29 INFO - Running main action method: clobber 18:51:29 INFO - rmtree: /builds/slave/test/build 18:51:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:51:30 INFO - Running post-action listener: _resource_record_post_action 18:51:30 INFO - ##### 18:51:30 INFO - ##### Finished clobber step (success) 18:51:30 INFO - ##### 18:51:30 INFO - ##### 18:51:30 INFO - ##### Running read-buildbot-config step. 18:51:30 INFO - ##### 18:51:30 INFO - Running pre-action listener: _resource_record_pre_action 18:51:30 INFO - Running main action method: read_buildbot_config 18:51:30 INFO - Using buildbot properties: 18:51:30 INFO - { 18:51:30 INFO - "project": "", 18:51:30 INFO - "product": "firefox", 18:51:30 INFO - "script_repo_revision": "production", 18:51:30 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 18:51:30 INFO - "repository": "", 18:51:30 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 18:51:30 INFO - "buildid": "20160713154542", 18:51:30 INFO - "pgo_build": "False", 18:51:30 INFO - "basedir": "/builds/slave/test", 18:51:30 INFO - "buildnumber": 889, 18:51:30 INFO - "slavename": "t-yosemite-r7-0180", 18:51:30 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 18:51:30 INFO - "platform": "yosemite_r7", 18:51:30 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 18:51:30 INFO - "branch": "fx-team", 18:51:30 INFO - "repo_path": "integration/fx-team", 18:51:30 INFO - "revision": "31cab0ba8bbf1d88b08abeae45bcdf317645355a", 18:51:30 INFO - "stage_platform": "macosx64", 18:51:30 INFO - "builduid": "df9637022caf4d3383bef82a269a1255", 18:51:30 INFO - "slavebuilddir": "test" 18:51:30 INFO - } 18:51:30 INFO - Found installer url https://queue.taskcluster.net/v1/task/R_8FiZH1RjuZQ02dNXyHVQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 18:51:30 INFO - Running post-action listener: _resource_record_post_action 18:51:30 INFO - ##### 18:51:30 INFO - ##### Finished read-buildbot-config step (success) 18:51:30 INFO - ##### 18:51:30 INFO - ##### 18:51:30 INFO - ##### Running download-and-extract step. 18:51:30 INFO - ##### 18:51:30 INFO - Running pre-action listener: _resource_record_pre_action 18:51:30 INFO - Running main action method: download_and_extract 18:51:30 INFO - mkdir: /builds/slave/test/build/tests 18:51: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:51:30 INFO - https://queue.taskcluster.net/v1/task/R_8FiZH1RjuZQ02dNXyHVQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 18:51:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_8FiZH1RjuZQ02dNXyHVQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 18:51:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_8FiZH1RjuZQ02dNXyHVQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 18:51:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_8FiZH1RjuZQ02dNXyHVQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 18:51:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_8FiZH1RjuZQ02dNXyHVQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 18:51:31 INFO - Downloaded 1199 bytes. 18:51:31 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 18:51:31 INFO - Using the following test package requirements: 18:51:31 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 18:51:31 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 18:51:31 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 18:51:31 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 18:51:31 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 18:51:31 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 18:51:31 INFO - u'jsshell-mac.zip'], 18:51:31 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 18:51:31 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 18:51:31 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 18:51:31 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 18:51:31 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 18:51:31 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 18:51:31 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 18:51:31 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 18:51:31 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 18:51:31 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 18:51:31 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 18:51:31 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:51:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:51:31 INFO - https://queue.taskcluster.net/v1/task/R_8FiZH1RjuZQ02dNXyHVQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:51:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_8FiZH1RjuZQ02dNXyHVQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 18:51:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_8FiZH1RjuZQ02dNXyHVQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 18:51:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_8FiZH1RjuZQ02dNXyHVQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 18:51:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_8FiZH1RjuZQ02dNXyHVQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:51:33 INFO - Downloaded 18827387 bytes. 18:51:33 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:51:33 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 18:51:36 INFO - Return code: 0 18:51:36 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 18:51:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:51:36 INFO - https://queue.taskcluster.net/v1/task/R_8FiZH1RjuZQ02dNXyHVQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:51:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_8FiZH1RjuZQ02dNXyHVQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 18:51:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_8FiZH1RjuZQ02dNXyHVQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 18:51:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_8FiZH1RjuZQ02dNXyHVQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 18:51:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_8FiZH1RjuZQ02dNXyHVQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:51:38 INFO - Downloaded 18827387 bytes. 18:51:38 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:51:38 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 18:51:41 INFO - Return code: 0 18:51:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:51:41 INFO - https://queue.taskcluster.net/v1/task/R_8FiZH1RjuZQ02dNXyHVQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 18:51:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_8FiZH1RjuZQ02dNXyHVQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 18:51:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_8FiZH1RjuZQ02dNXyHVQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 18:51:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_8FiZH1RjuZQ02dNXyHVQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 18:51:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_8FiZH1RjuZQ02dNXyHVQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 18:51:43 INFO - Downloaded 14403317 bytes. 18:51:43 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:51:43 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 18:51:43 INFO - Return code: 0 18:51:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:51:43 INFO - https://queue.taskcluster.net/v1/task/R_8FiZH1RjuZQ02dNXyHVQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 18:51:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_8FiZH1RjuZQ02dNXyHVQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 18:51:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_8FiZH1RjuZQ02dNXyHVQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 18:51:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_8FiZH1RjuZQ02dNXyHVQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 18:51:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_8FiZH1RjuZQ02dNXyHVQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 18:51:46 INFO - Downloaded 112755730 bytes. 18:51:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/R_8FiZH1RjuZQ02dNXyHVQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 18:51:46 INFO - mkdir: /builds/slave/test/properties 18:51:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:51:46 INFO - Writing to file /builds/slave/test/properties/build_url 18:51:46 INFO - Contents: 18:51:46 INFO - build_url:https://queue.taskcluster.net/v1/task/R_8FiZH1RjuZQ02dNXyHVQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 18:51:47 INFO - Running post-action listener: _resource_record_post_action 18:51:47 INFO - Running post-action listener: set_extra_try_arguments 18:51:47 INFO - ##### 18:51:47 INFO - ##### Finished download-and-extract step (success) 18:51:47 INFO - ##### 18:51:47 INFO - ##### 18:51:47 INFO - ##### Running populate-webroot step. 18:51:47 INFO - ##### 18:51:47 INFO - Running pre-action listener: _resource_record_pre_action 18:51:47 INFO - Running main action method: populate_webroot 18:51:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:51:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:51:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:51:47 INFO - '--tppagecycles', 18:51:47 INFO - '7'], 18:51:47 INFO - 'tests': ['tsvgm']}}, 18:51:47 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:51:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:51:47 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:51:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:51:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:51:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:51:47 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:51:47 INFO - 'talos_options': ['--disable-e10s'], 18:51:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:51:47 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:51:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:51:47 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:51:47 INFO - 'talos_options': ['--disable-e10s'], 18:51:47 INFO - 'tests': ['damp', 'tps']}, 18:51:47 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:51:47 INFO - 'tests': ['damp', 'tps']}, 18:51:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:51:47 INFO - 'tests': ['dromaeo_dom']}, 18:51:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:51:47 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:51:47 INFO - 'tests': ['basic_compositor_video']}, 18:51:47 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:51:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:51:47 INFO - 'tests': ['a11yr', 18:51:47 INFO - 'ts_paint', 18:51:47 INFO - 'tpaint', 18:51:47 INFO - 'sessionrestore', 18:51:47 INFO - 'sessionrestore_no_auto_restore', 18:51:47 INFO - 'tabpaint']}, 18:51:47 INFO - 'other-e10s': {'tests': ['a11yr', 18:51:47 INFO - 'ts_paint', 18:51:47 INFO - 'tpaint', 18:51:47 INFO - 'sessionrestore', 18:51:47 INFO - 'sessionrestore_no_auto_restore', 18:51:47 INFO - 'tabpaint']}, 18:51:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:51:47 INFO - 'ts_paint', 18:51:47 INFO - 'tpaint', 18:51:47 INFO - 'sessionrestore', 18:51:47 INFO - 'sessionrestore_no_auto_restore', 18:51:47 INFO - 'tabpaint']}, 18:51:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:51:47 INFO - 'ts_paint', 18:51:47 INFO - 'tpaint', 18:51:47 INFO - 'sessionrestore', 18:51:47 INFO - 'sessionrestore_no_auto_restore', 18:51:47 INFO - 'tabpaint']}, 18:51:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:51:47 INFO - 'tests': ['a11yr', 18:51:47 INFO - 'ts_paint', 18:51:47 INFO - 'tpaint', 18:51:47 INFO - 'sessionrestore', 18:51:47 INFO - 'sessionrestore_no_auto_restore', 18:51:47 INFO - 'tabpaint']}, 18:51:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:51:47 INFO - 'tests': ['a11yr', 18:51:47 INFO - 'ts_paint', 18:51:47 INFO - 'tpaint', 18:51:47 INFO - 'sessionrestore', 18:51:47 INFO - 'sessionrestore_no_auto_restore', 18:51:47 INFO - 'tabpaint']}, 18:51:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:51:47 INFO - 'tests': ['tsvgx', 18:51:47 INFO - 'tsvgr_opacity', 18:51:47 INFO - 'tart', 18:51:47 INFO - 'tscrollx', 18:51:47 INFO - 'cart']}, 18:51:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:51:47 INFO - 'tsvgr_opacity', 18:51:47 INFO - 'tart', 18:51:47 INFO - 'tscrollx', 18:51:47 INFO - 'cart']}, 18:51:47 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:51:47 INFO - 'talos_options': ['--disable-e10s'], 18:51:47 INFO - 'tests': ['tp5o']}, 18:51:47 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:51:47 INFO - 'tests': ['tp5o']}, 18:51:47 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:51:47 INFO - 'talos_options': ['--disable-e10s', 18:51:47 INFO - '--xperf_path', 18:51:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:51:47 INFO - 'tests': ['tp5n']}, 18:51:47 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:51:47 INFO - 'talos_options': ['--xperf_path', 18:51:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:51:47 INFO - 'tests': ['tp5n']}}, 18:51:47 INFO - 'talos.zip': {'path': '', 18:51:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:51:47 INFO - Running post-action listener: _resource_record_post_action 18:51:47 INFO - ##### 18:51:47 INFO - ##### Finished populate-webroot step (success) 18:51:47 INFO - ##### 18:51:47 INFO - ##### 18:51:47 INFO - ##### Running create-virtualenv step. 18:51:47 INFO - ##### 18:51:47 INFO - Running pre-action listener: _resource_record_pre_action 18:51:47 INFO - Running main action method: create_virtualenv 18:51:47 INFO - Creating virtualenv /builds/slave/test/build/venv 18:51:47 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 18:51:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:51:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:51:47 INFO - Using real prefix '/tools/python27' 18:51:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:51:48 INFO - Installing distribute.............................................................................................................................................................................................done. 18:51:51 INFO - Installing pip.................done. 18:51:51 INFO - Return code: 0 18:51:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:51:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:51:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:51:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:51:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:51:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51:51 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 0x109a01a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a96618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad2ac953f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109965d30>, '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 0x1096cadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10995db20>, '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.LqGhbrBono/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.JgLK95z5xc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 18:51:51 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 18:51:51 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 18:51:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LqGhbrBono/Render', 18:51:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:51:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:51:51 INFO - 'HOME': '/Users/cltbld', 18:51:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:51:51 INFO - 'LOGNAME': 'cltbld', 18:51:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:51:51 INFO - 'MOZ_NO_REMOTE': '1', 18:51:51 INFO - 'NO_EM_RESTART': '1', 18:51:51 INFO - 'PAGER': '/bin/cat', 18:51:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:51:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:51:51 INFO - 'PWD': '/builds/slave/test', 18:51:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:51:51 INFO - 'SHELL': '/bin/bash', 18:51:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JgLK95z5xc/Listeners', 18:51:51 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 18:51:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:51:51 INFO - 'USER': 'cltbld', 18:51:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:51:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:51:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:51:51 INFO - 'XPC_FLAGS': '0x0', 18:51:51 INFO - 'XPC_SERVICE_NAME': '0', 18:51:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:51:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:51:51 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 18:51:51 INFO - Cleaning up... 18:51:51 INFO - Return code: 0 18:51:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:51:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:51:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:51:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:51:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:51:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51:51 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 0x109a01a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a96618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad2ac953f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109965d30>, '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 0x1096cadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10995db20>, '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.LqGhbrBono/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.JgLK95z5xc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 18:51:51 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 18:51:51 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 18:51:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LqGhbrBono/Render', 18:51:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:51:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:51:51 INFO - 'HOME': '/Users/cltbld', 18:51:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:51:51 INFO - 'LOGNAME': 'cltbld', 18:51:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:51:51 INFO - 'MOZ_NO_REMOTE': '1', 18:51:51 INFO - 'NO_EM_RESTART': '1', 18:51:51 INFO - 'PAGER': '/bin/cat', 18:51:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:51:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:51:51 INFO - 'PWD': '/builds/slave/test', 18:51:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:51:51 INFO - 'SHELL': '/bin/bash', 18:51:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JgLK95z5xc/Listeners', 18:51:51 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 18:51:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:51:51 INFO - 'USER': 'cltbld', 18:51:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:51:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:51:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:51:51 INFO - 'XPC_FLAGS': '0x0', 18:51:51 INFO - 'XPC_SERVICE_NAME': '0', 18:51:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:51:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:51:51 INFO - Downloading/unpacking psutil>=3.1.1 18:51:51 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 18:51:51 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 18:51:51 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 18:51:51 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 18:51:51 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 18:51:51 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 18:51:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:51:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:51:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:51:54 INFO - Installing collected packages: psutil 18:51:54 INFO - Running setup.py install for psutil 18:51:55 INFO - building 'psutil._psutil_osx' extension 18:51:55 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 18:51:55 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 18:51:55 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 18:51:55 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 18:51:55 INFO - building 'psutil._psutil_posix' extension 18:51:55 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 18:51:55 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:51:55 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:51:55 INFO - ^ 18:51:55 INFO - 1 warning generated. 18:51:55 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 18:51:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:51:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:51:56 INFO - Successfully installed psutil 18:51:56 INFO - Cleaning up... 18:51:56 INFO - Return code: 0 18:51:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:51:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:51:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:51:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:51:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:51:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51:56 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 0x109a01a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a96618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad2ac953f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109965d30>, '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 0x1096cadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10995db20>, '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.LqGhbrBono/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.JgLK95z5xc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 18:51:56 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 18:51:56 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 18:51:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LqGhbrBono/Render', 18:51:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:51:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:51:56 INFO - 'HOME': '/Users/cltbld', 18:51:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:51:56 INFO - 'LOGNAME': 'cltbld', 18:51:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:51:56 INFO - 'MOZ_NO_REMOTE': '1', 18:51:56 INFO - 'NO_EM_RESTART': '1', 18:51:56 INFO - 'PAGER': '/bin/cat', 18:51:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:51:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:51:56 INFO - 'PWD': '/builds/slave/test', 18:51:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:51:56 INFO - 'SHELL': '/bin/bash', 18:51:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JgLK95z5xc/Listeners', 18:51:56 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 18:51:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:51:56 INFO - 'USER': 'cltbld', 18:51:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:51:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:51:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:51:56 INFO - 'XPC_FLAGS': '0x0', 18:51:56 INFO - 'XPC_SERVICE_NAME': '0', 18:51:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:51:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:51:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:51:56 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 18:51:56 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 18:51:56 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 18:51:56 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 18:51:56 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 18:51:56 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 18:51:58 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:51:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:51:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:51:58 INFO - Installing collected packages: mozsystemmonitor 18:51:58 INFO - Running setup.py install for mozsystemmonitor 18:51:59 INFO - Successfully installed mozsystemmonitor 18:51:59 INFO - Cleaning up... 18:51:59 INFO - Return code: 0 18:51:59 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:51: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:51:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:51:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:51:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:51:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51: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', '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 0x109a01a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a96618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad2ac953f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109965d30>, '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 0x1096cadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10995db20>, '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.LqGhbrBono/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.JgLK95z5xc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 18:51: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:51: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 jsonschema==2.5.1 18:51:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LqGhbrBono/Render', 18:51:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:51:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:51:59 INFO - 'HOME': '/Users/cltbld', 18:51:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:51:59 INFO - 'LOGNAME': 'cltbld', 18:51:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:51:59 INFO - 'MOZ_NO_REMOTE': '1', 18:51:59 INFO - 'NO_EM_RESTART': '1', 18:51:59 INFO - 'PAGER': '/bin/cat', 18:51:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:51:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:51:59 INFO - 'PWD': '/builds/slave/test', 18:51:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:51:59 INFO - 'SHELL': '/bin/bash', 18:51:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JgLK95z5xc/Listeners', 18:51:59 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 18:51:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:51:59 INFO - 'USER': 'cltbld', 18:51:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:51:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:51:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:51:59 INFO - 'XPC_FLAGS': '0x0', 18:51:59 INFO - 'XPC_SERVICE_NAME': '0', 18:51:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:51:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:51:59 INFO - Downloading/unpacking jsonschema==2.5.1 18:51: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 18:51: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 18:51: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 18:51: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 18:51: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 18:51: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 18:52:01 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:52:01 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:52:01 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 18:52:01 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 18:52:01 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 18:52:01 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 18:52:01 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 18:52:01 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 18:52:02 INFO - Downloading functools32-3.2.3-2.tar.gz 18:52:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:52:02 INFO - warning: no files found matching '*.txt' 18:52:02 INFO - no previously-included directories found matching 'build' 18:52:02 INFO - no previously-included directories found matching 'dist' 18:52:02 INFO - no previously-included directories found matching '.git*' 18:52:02 INFO - Installing collected packages: jsonschema, functools32 18:52:02 INFO - Running setup.py install for functools32 18:52:02 INFO - warning: no files found matching '*.txt' 18:52:02 INFO - no previously-included directories found matching 'build' 18:52:02 INFO - no previously-included directories found matching 'dist' 18:52:02 INFO - no previously-included directories found matching '.git*' 18:52:02 INFO - Successfully installed jsonschema functools32 18:52:02 INFO - Cleaning up... 18:52:02 INFO - Return code: 0 18:52:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:52: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:52:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:52:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:52: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:52:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:52:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:52: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', '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 0x109a01a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a96618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad2ac953f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109965d30>, '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 0x1096cadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10995db20>, '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.LqGhbrBono/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.JgLK95z5xc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 18:52: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:52: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 blobuploader==1.2.4 18:52:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LqGhbrBono/Render', 18:52:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:52:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:52:02 INFO - 'HOME': '/Users/cltbld', 18:52:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:52:02 INFO - 'LOGNAME': 'cltbld', 18:52:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:52:02 INFO - 'MOZ_NO_REMOTE': '1', 18:52:02 INFO - 'NO_EM_RESTART': '1', 18:52:02 INFO - 'PAGER': '/bin/cat', 18:52:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:52:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:52:02 INFO - 'PWD': '/builds/slave/test', 18:52:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:52:02 INFO - 'SHELL': '/bin/bash', 18:52:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JgLK95z5xc/Listeners', 18:52:02 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 18:52:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:52:02 INFO - 'USER': 'cltbld', 18:52:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:52:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:52:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:52:02 INFO - 'XPC_FLAGS': '0x0', 18:52:02 INFO - 'XPC_SERVICE_NAME': '0', 18:52:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:52:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:52:02 INFO - Downloading/unpacking blobuploader==1.2.4 18:52: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 18:52: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 18:52: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 18:52: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 18:52: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 18:52: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 18:52:05 INFO - Downloading blobuploader-1.2.4.tar.gz 18:52:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:52:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:52:05 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 18:52:05 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 18:52:05 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 18:52:05 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 18:52:05 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 18:52:05 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 18:52:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:52:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:52: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 18:52: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 18:52: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 18:52: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 18:52: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 18:52: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 18:52:06 INFO - Downloading docopt-0.6.1.tar.gz 18:52:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:52:06 INFO - Installing collected packages: blobuploader, requests, docopt 18:52:06 INFO - Running setup.py install for blobuploader 18:52:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:52:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:52:06 INFO - Running setup.py install for requests 18:52:07 INFO - Running setup.py install for docopt 18:52:07 INFO - Successfully installed blobuploader requests docopt 18:52:07 INFO - Cleaning up... 18:52:07 INFO - Return code: 0 18:52:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:52:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:52:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:52:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:52:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:52:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:52:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:52:07 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 0x109a01a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a96618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad2ac953f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109965d30>, '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 0x1096cadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10995db20>, '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.LqGhbrBono/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.JgLK95z5xc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 18:52:07 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 18:52:07 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 18:52:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LqGhbrBono/Render', 18:52:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:52:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:52:07 INFO - 'HOME': '/Users/cltbld', 18:52:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:52:07 INFO - 'LOGNAME': 'cltbld', 18:52:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:52:07 INFO - 'MOZ_NO_REMOTE': '1', 18:52:07 INFO - 'NO_EM_RESTART': '1', 18:52:07 INFO - 'PAGER': '/bin/cat', 18:52:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:52:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:52:07 INFO - 'PWD': '/builds/slave/test', 18:52:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:52:07 INFO - 'SHELL': '/bin/bash', 18:52:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JgLK95z5xc/Listeners', 18:52:07 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 18:52:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:52:07 INFO - 'USER': 'cltbld', 18:52:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:52:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:52:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:52:07 INFO - 'XPC_FLAGS': '0x0', 18:52:07 INFO - 'XPC_SERVICE_NAME': '0', 18:52:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:52:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:52:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:52:07 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-Mdt2jz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:52:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:52:07 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-o74vQi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:52:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:52:08 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-_1W04r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:52:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:52:08 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-dbzNJT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:52:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:52:08 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-J7LFyD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:52:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:52:08 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-_DwBYO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:52:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:52:08 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-e1VECs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:52:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:52:08 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-dchumF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:52:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:52:08 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-18L08B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:52:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:52:08 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-JFNKMy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:52:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:52:08 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-ZjJz2U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:52:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:52:08 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-Tq9CqH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:52:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:52:09 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-K1vMk0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:52:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:52:09 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-BDbb2E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:52:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:52:09 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-t1imn7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:52:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:52:09 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-Bg8DyG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:52:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:52:09 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-YK_eB7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:52:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:52:09 INFO - Running setup.py install for manifestparser 18:52:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:52:09 INFO - Running setup.py install for mozcrash 18:52:09 INFO - Running setup.py install for mozdebug 18:52:10 INFO - Running setup.py install for mozdevice 18:52:10 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:52:10 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:52:10 INFO - Running setup.py install for mozfile 18:52:10 INFO - Running setup.py install for mozhttpd 18:52:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:52:10 INFO - Running setup.py install for mozinfo 18:52:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:52:10 INFO - Running setup.py install for mozInstall 18:52:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:52:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:52:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:52:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:52:10 INFO - Running setup.py install for mozleak 18:52:10 INFO - Running setup.py install for mozlog 18:52:11 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:52:11 INFO - Running setup.py install for moznetwork 18:52:11 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:52:11 INFO - Running setup.py install for mozprocess 18:52:11 INFO - Running setup.py install for mozprofile 18:52:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:52:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:52:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:52:11 INFO - Running setup.py install for mozrunner 18:52:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:52:11 INFO - Running setup.py install for mozscreenshot 18:52:11 INFO - Running setup.py install for moztest 18:52:12 INFO - Running setup.py install for mozversion 18:52:12 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:52:12 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:52:12 INFO - Cleaning up... 18:52:12 INFO - Return code: 0 18:52:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:52:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:52:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:52:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:52:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:52:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:52:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:52:12 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 0x109a01a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a96618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad2ac953f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109965d30>, '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 0x1096cadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10995db20>, '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.LqGhbrBono/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.JgLK95z5xc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 18:52:12 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 18:52:12 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 18:52:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LqGhbrBono/Render', 18:52:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:52:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:52:12 INFO - 'HOME': '/Users/cltbld', 18:52:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:52:12 INFO - 'LOGNAME': 'cltbld', 18:52:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:52:12 INFO - 'MOZ_NO_REMOTE': '1', 18:52:12 INFO - 'NO_EM_RESTART': '1', 18:52:12 INFO - 'PAGER': '/bin/cat', 18:52:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:52:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:52:12 INFO - 'PWD': '/builds/slave/test', 18:52:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:52:12 INFO - 'SHELL': '/bin/bash', 18:52:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JgLK95z5xc/Listeners', 18:52:12 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 18:52:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:52:12 INFO - 'USER': 'cltbld', 18:52:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:52:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:52:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:52:12 INFO - 'XPC_FLAGS': '0x0', 18:52:12 INFO - 'XPC_SERVICE_NAME': '0', 18:52:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:52:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:52:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:52:12 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-Z0T2EG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:52:12 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)) 18:52:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:52:12 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-nLubXy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:52:12 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)) 18:52:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:52:12 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-Uv5CKk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:52:12 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)) 18:52:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:52:12 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-pNw4ET-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:52:12 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)) 18:52:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:52:12 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-E385Y_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:52:12 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)) 18:52:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:52:12 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-pBzzJq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:52:13 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)) 18:52:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:52:13 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-DD7sjL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:52:13 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)) 18:52:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:52:13 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-4ASeBE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:52:13 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)) 18:52:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:52:13 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-7A7i0k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:52:13 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)) 18:52:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:52:13 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-9dssIo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:52:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 18:52:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:52:13 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-NPC7db-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:52:13 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)) 18:52:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:52:13 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-nwoMIH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:52:13 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)) 18:52:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:52:13 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-oIynER-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:52:13 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)) 18:52:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:52:13 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-6sVcdK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:52:13 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)) 18:52:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:52:13 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-hSZOP1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:52:14 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)) 18:52:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:52:14 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-EXCKii-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:52:14 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)) 18:52:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:52:14 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-Nn164b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:52:14 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)) 18:52:14 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)) 18:52:14 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)) 18:52:14 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)) 18:52:14 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)) 18:52:14 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)) 18:52:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:52:14 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 18:52:14 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 18:52:14 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 18:52:14 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 18:52:14 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 18:52:14 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 18:52:16 INFO - Downloading blessings-1.6.tar.gz 18:52:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:52:16 INFO - Installing collected packages: blessings 18:52:16 INFO - Running setup.py install for blessings 18:52:16 INFO - Successfully installed blessings 18:52:16 INFO - Cleaning up... 18:52:17 INFO - Return code: 0 18:52:17 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:52:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:52:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:52:17 INFO - Reading from file tmpfile_stdout 18:52:17 INFO - Current package versions: 18:52:17 INFO - blessings == 1.6 18:52:17 INFO - blobuploader == 1.2.4 18:52:17 INFO - docopt == 0.6.1 18:52:17 INFO - functools32 == 3.2.3-2 18:52:17 INFO - jsonschema == 2.5.1 18:52:17 INFO - manifestparser == 1.1 18:52:17 INFO - mozInstall == 1.12 18:52:17 INFO - mozcrash == 0.17 18:52:17 INFO - mozdebug == 0.1 18:52:17 INFO - mozdevice == 0.48 18:52:17 INFO - mozfile == 1.2 18:52:17 INFO - mozhttpd == 0.7 18:52:17 INFO - mozinfo == 0.9 18:52:17 INFO - mozleak == 0.1 18:52:17 INFO - mozlog == 3.2 18:52:17 INFO - moznetwork == 0.27 18:52:17 INFO - mozprocess == 0.23 18:52:17 INFO - mozprofile == 0.28 18:52:17 INFO - mozrunner == 6.12 18:52:17 INFO - mozscreenshot == 0.1 18:52:17 INFO - mozsystemmonitor == 0.3 18:52:17 INFO - moztest == 0.7 18:52:17 INFO - mozversion == 1.4 18:52:17 INFO - psutil == 3.1.1 18:52:17 INFO - requests == 1.2.3 18:52:17 INFO - wsgiref == 0.1.2 18:52:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:52:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:52:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:52:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:52:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:52:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:52:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:52:17 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 0x109a01a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a96618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad2ac953f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109965d30>, '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 0x1096cadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10995db20>, '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.LqGhbrBono/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.JgLK95z5xc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 18:52:17 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 18:52:17 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 18:52:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LqGhbrBono/Render', 18:52:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:52:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:52:17 INFO - 'HOME': '/Users/cltbld', 18:52:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:52:17 INFO - 'LOGNAME': 'cltbld', 18:52:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:52:17 INFO - 'MOZ_NO_REMOTE': '1', 18:52:17 INFO - 'NO_EM_RESTART': '1', 18:52:17 INFO - 'PAGER': '/bin/cat', 18:52:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:52:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:52:17 INFO - 'PWD': '/builds/slave/test', 18:52:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:52:17 INFO - 'SHELL': '/bin/bash', 18:52:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JgLK95z5xc/Listeners', 18:52:17 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 18:52:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:52:17 INFO - 'USER': 'cltbld', 18:52:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:52:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:52:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:52:17 INFO - 'XPC_FLAGS': '0x0', 18:52:17 INFO - 'XPC_SERVICE_NAME': '0', 18:52:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:52:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:52:17 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)) 18:52:17 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)) 18:52:17 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)) 18:52:17 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)) 18:52:17 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)) 18:52:17 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)) 18:52:17 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)) 18:52:17 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)) 18:52:17 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)) 18:52:17 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)) 18:52:17 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)) 18:52:17 INFO - Cleaning up... 18:52:17 INFO - Return code: 0 18:52:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:52:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:52:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:52:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:52:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:52:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:52:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:52:17 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 0x109a01a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a96618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad2ac953f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109965d30>, '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 0x1096cadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10995db20>, '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.LqGhbrBono/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.JgLK95z5xc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 18:52:17 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 18:52:17 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 18:52:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LqGhbrBono/Render', 18:52:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:52:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:52:17 INFO - 'HOME': '/Users/cltbld', 18:52:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:52:17 INFO - 'LOGNAME': 'cltbld', 18:52:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:52:17 INFO - 'MOZ_NO_REMOTE': '1', 18:52:17 INFO - 'NO_EM_RESTART': '1', 18:52:17 INFO - 'PAGER': '/bin/cat', 18:52:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:52:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:52:17 INFO - 'PWD': '/builds/slave/test', 18:52:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:52:17 INFO - 'SHELL': '/bin/bash', 18:52:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JgLK95z5xc/Listeners', 18:52:17 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 18:52:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:52:17 INFO - 'USER': 'cltbld', 18:52:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:52:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:52:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:52:17 INFO - 'XPC_FLAGS': '0x0', 18:52:17 INFO - 'XPC_SERVICE_NAME': '0', 18:52:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:52:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:52:17 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:52:17 INFO - Cleaning up... 18:52:17 INFO - Return code: 0 18:52:17 INFO - Running post-action listener: _resource_record_post_action 18:52:17 INFO - Running post-action listener: _start_resource_monitoring 18:52:17 INFO - Starting resource monitoring. 18:52:17 INFO - ##### 18:52:17 INFO - ##### Finished create-virtualenv step (success) 18:52:17 INFO - ##### 18:52:17 INFO - ##### 18:52:17 INFO - ##### Running install step. 18:52:17 INFO - ##### 18:52:17 INFO - Running pre-action listener: _resource_record_pre_action 18:52:17 INFO - Running main action method: install 18:52:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:52:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:52:18 INFO - Reading from file tmpfile_stdout 18:52:18 INFO - Detecting whether we're running mozinstall >=1.0... 18:52:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:52:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:52:18 INFO - Reading from file tmpfile_stdout 18:52:18 INFO - Output received: 18:52:18 INFO - Usage: mozinstall [options] installer 18:52:18 INFO - Options: 18:52:18 INFO - -h, --help show this help message and exit 18:52:18 INFO - -d DEST, --destination=DEST 18:52:18 INFO - Directory to install application into. [default: 18:52:18 INFO - "/builds/slave/test"] 18:52:18 INFO - --app=APP Application being installed. [default: firefox] 18:52:18 INFO - mkdir: /builds/slave/test/build/application 18:52:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 18:52:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 18:52:53 INFO - Reading from file tmpfile_stdout 18:52:53 INFO - Output received: 18:52:53 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:52:53 INFO - Running post-action listener: _resource_record_post_action 18:52:53 INFO - ##### 18:52:53 INFO - ##### Finished install step (success) 18:52:53 INFO - ##### 18:52:53 INFO - ##### 18:52:53 INFO - ##### Running run-tests step. 18:52:53 INFO - ##### 18:52:53 INFO - Running pre-action listener: _resource_record_pre_action 18:52:53 INFO - Running main action method: run_tests 18:52:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:52:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:52:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:52:53 INFO - 2016-07-13 18:52:53.106 screenresolution[1658:8025] starting screenresolution argv=screenresolution get 18:52:53 INFO - 2016-07-13 18:52:53.108 screenresolution[1658:8025] Display 0: 1600x1200x32@60 18:52:53 INFO - 2016-07-13 18:52:53.114 screenresolution[1659:8027] starting screenresolution argv=screenresolution list 18:52:53 INFO - Available Modes on Display 0 18:52:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:52:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:52:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:52:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:52:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:52:53 INFO - Intel Iris: 18:52:53 INFO - Chipset Model: Intel Iris 18:52:53 INFO - Type: GPU 18:52:53 INFO - Bus: Built-In 18:52:53 INFO - VRAM (Dynamic, Max): 1536 MB 18:52:53 INFO - Vendor: Intel (0x8086) 18:52:53 INFO - Device ID: 0x0a2e 18:52:53 INFO - Revision ID: 0x0009 18:52:53 INFO - Displays: 18:52:53 INFO - Display: 18:52:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:52:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:52:53 INFO - Display Serial Number: 1600x1200 60 18:52:53 INFO - Main Display: Yes 18:52:53 INFO - Mirror: Off 18:52:53 INFO - Online: Yes 18:52:53 INFO - Rotation: Supported 18:52:53 INFO - Return code: 0 18:52:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:52:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:52:53 INFO - Python 2.7.3 18:52:53 INFO - Return code: 0 18:52:53 INFO - grabbing minidump binary from tooltool 18:52: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:52:53 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 0x109965d30>, '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 0x1096cadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10995db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:52:53 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 18:52:53 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 18:52:53 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 18:52:53 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:52:53 INFO - Return code: 0 18:52:53 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:52:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:52:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:52:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:52:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:52:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/R_8FiZH1RjuZQ02dNXyHVQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0180', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 18:52:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/R_8FiZH1RjuZQ02dNXyHVQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0180 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 18:52:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LqGhbrBono/Render', 18:52:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:52:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:52:53 INFO - 'HOME': '/Users/cltbld', 18:52:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:52:53 INFO - 'LOGNAME': 'cltbld', 18:52:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:52:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:52:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:52:53 INFO - 'MOZ_NO_REMOTE': '1', 18:52:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:52:53 INFO - 'NO_EM_RESTART': '1', 18:52:53 INFO - 'PAGER': '/bin/cat', 18:52:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:52:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:52:53 INFO - 'PWD': '/builds/slave/test', 18:52:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:52:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:52:53 INFO - 'SHELL': '/bin/bash', 18:52:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JgLK95z5xc/Listeners', 18:52:53 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 18:52:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:52:53 INFO - 'USER': 'cltbld', 18:52:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:52:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:52:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:52:53 INFO - 'XPC_FLAGS': '0x0', 18:52:53 INFO - 'XPC_SERVICE_NAME': '0', 18:52:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:52:53 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/R_8FiZH1RjuZQ02dNXyHVQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0180', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 18:52:53 INFO - mozversion application_buildid: 20160713154542 18:52:53 INFO - mozversion application_changeset: 31cab0ba8bbf1d88b08abeae45bcdf317645355a 18:52:53 INFO - mozversion application_display_name: Nightly 18:52:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:52:53 INFO - mozversion application_name: Firefox 18:52:53 INFO - mozversion application_remotingname: firefox 18:52:53 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 18:52:53 INFO - mozversion application_vendor: Mozilla 18:52:53 INFO - mozversion application_version: 50.0a1 18:52:53 INFO - mozversion platform_buildid: 20160713154542 18:52:53 INFO - mozversion platform_changeset: 31cab0ba8bbf1d88b08abeae45bcdf317645355a 18:52:53 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 18:52:53 INFO - mozversion platform_version: 50.0a1 18:52:53 INFO - using testdate: 1468461173 18:52:53 INFO - actual date: 1468461173 18:52:53 INFO - starting webserver on 'localhost:49266' 18:52:53 INFO - SUITE-START | Running 2 tests 18:52:53 INFO - TEST-START | dromaeo_css 18:52:53 INFO - operating with platform_type : mac_ 18:52:53 INFO - Initialising browser for dromaeo_css test... 18:52:53 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/tmpctKpFi/profile http://localhost:49266/getInfo.html) 18:52:56 INFO - PROCESS | 1669 | __metrics Screen width/height:1600/1200 18:52:56 INFO - PROCESS | 1669 | colorDepth:24 18:52:56 INFO - PROCESS | 1669 | Browser inner width/height: 1024/689 18:52:56 INFO - PROCESS | 1669 | __metrics 18:52:56 INFO - PROCESS | 1669 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:52:56 INFO - PROCESS | 1669 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:52:57 INFO - TEST-INFO | 1669: exit 0 18:52:57 INFO - Browser initialized. 18:52:57 INFO - Running cycle 1/1 for dromaeo_css test... 18:52:57 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/tmpctKpFi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:53:08 INFO - PROCESS | 1671 | RSS: Main: 243834880 18:53:08 INFO - PROCESS | 1671 | 18:54:00 INFO - PROCESS | 1671 | 1468461240040 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] 18:56:11 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49266/tests/dromaeo/cssquery-ext.html) 18:56:12 INFO - PROCESS | 1671 | RSS: Main: 383754240 18:56:12 INFO - PROCESS | 1671 | 18:59:16 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-ext.html (next: http://localhost:49266/tests/dromaeo/cssquery-jquery.html) 18:59:16 INFO - PROCESS | 1671 | RSS: Main: 381222912 18:59:16 INFO - PROCESS | 1671 | 19:02:18 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49266/tests/dromaeo/cssquery-mootools.html) 19:02:19 INFO - PROCESS | 1671 | RSS: Main: 371552256 19:02:19 INFO - PROCESS | 1671 | 19:05:21 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49266/tests/dromaeo/cssquery-prototype.html) 19:05:22 INFO - PROCESS | 1671 | RSS: Main: 371220480 19:05:22 INFO - PROCESS | 1671 | 19:08:25 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49266/tests/dromaeo/cssquery-yui.html) 19:08:26 INFO - PROCESS | 1671 | RSS: Main: 345628672 19:08:26 INFO - PROCESS | 1671 | 19:11:29 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-yui.html (next: http://localhost:49266/tests/dromaeo/cssquery-dojo.html) 19:11:29 INFO - PROCESS | 1671 | RSS: Main: 386019328 19:11:29 INFO - PROCESS | 1671 | 19:11:29 INFO - PROCESS | 1671 | __start_tp_report 19:11:29 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 19:11:29 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 19:11:29 INFO - PROCESS | 1671 | |i|pagename|runs| 19:11:29 INFO - PROCESS | 1671 | |0;dojo.html;3368.360011597797;3388.62725100239;3397.830108494576;3415.316936612678;3468.497067925151;5535.806246781363;5605.775768969236;5678.091505359143;5755.424457554248;5788.913166302509;5663.688497132656;5682.545396368291;5714.857128571788;5790.971045144768;5804.709764511768;4410.25025745624;4987.800487980477;5103.183490641498;5118.513741194599;5292.947070529303;5295.920561191585;5296.443873393296;5307.416184219752;5346.492083252104;5391.379991300992;5121.462246464105;5176.171812510018;5178.378594568639;5232.659877107976;5279.868003299891;5207.3490813648295;5220.947790522122;5229.424763276036;5229.660072095302;5229.895402091975;4378.562143785628;4431.7340959542535;5159.458256883011;5161.922571161435;5180.9740951295;5586.720663966786;5587.525060369873;5702.201691763156;5784.797532086399;5813.157092221646;6192.225971753531;6200.534959878028;6203.596766210182;6210.254769427653;6213.068039794022;6030.879382412371;6240;6296.087067375251;6300.810975670737;6333.081703153063;5732.799352022701;5780.855478613068;5789.160571717078;5844.59087863854;5873.471387575139;6231.22109736283;6240.843978900473;6245.156903817927;6246.562740608114;6252.218472690914;5921.467067963904;5974.551908606872;6139.4781443577795;6149.0776383542825;6160.815175544741;6174.351693072253;6207.193064901534;6210.285817130997;6278.434940855345;6283.214598175228;6203.937960620427;6225.782097626652;6235.594686345373;6255.937440625536;6257.937420625826;5954.404559544011;6091.0558863376245;6256.249250090018;6266.404691554295;6273.0904018917;4901.43633482147;4918.213085906238;4940.7035577865445;4945.134601444733;4947.010597880439;4372.322290045048;4411.20598292304;4414.073044660658;4424.3142312941545;4446.4886538047895;5424.376196737426;5516.14499752539;5696.6582005079;5722.570807189478;5739.8565035874435;6042.305134909454;6101.816945491642;6110.755569777248;6116.541259405473;6130.111133885562;5551.472610102114;5551.528120109836;5558.527525160326;5559.833205003856;5564.415736347706;5317.388500322435;5328.893422131536;5331;5334.839954801362;5339.706316152571;6164.815055548341;6214;6215.627062376272;6233.96883015582;6272.435480806749;6258.280297765815;6282.591631543935;6289.308176100717;6296.496280297657;6306.527010474141;6158.969205153946;6167.814965551041;6283.403076707706;6291.056341548804;6297.464715499051;6097.706167156046;6193.163922870535;6211.223597050369;6215.06773983906;6217.4715149212825;6231.53263505248;6254;6258.812235632939;6262.185915830913;6278.497720182283;5953.493953014043;6031.125486804301;6101.29835068955;6103.084537319438;6108.419700128481;5249.291345668286;5792.189093526826;5946.881062378815;6030.396960303934;6038.094285857156;5146.665466744649;5901.734421951109;5906.320773111147;5923.437273459014;5928.4960778334325;5824.912626310524;5877.647341159544;6054.878902422015;6061.909071363845;6104.725287362168;5739.368669446274;5782.190493330853;5787;5797.043487824631;5801.767929282782 19:11:29 INFO - PROCESS | 1671 | |1;ext.html;507.5888530290463;514.7837908078607;524.45456725006;540.9883517821675;559.2450192240476;30908.845455772716;43054;43938.78030609842;44072.779636101855;44151.11697766043;45012.77493612535;45174;45352.77323613386;45360.09279814402;45386;8671.913280867206;9671.274654400913;9847.162991145726;9853.901460985406;9862;8789.824203515958;11676.1826672133;11774.646760597196;11982.70043248917;11988.340641264727;11286.98417142457;12147.696307592292;12155.817662735022;12175.208611440272;12194.146409751322;8702.390832641655;8884.555772211428;11981.161318707695;12050.578229761917;12068.879311206863;8844.11558844117;8893.866592001124;11703.7074073148;11930.642080737593;11939.641810745692;42857.57142428567;43042;43070;43113.35329970053;43129.78435107836;3104.6895310469;3419.7264218862433;3481.7736847104857;3503.8773642922374;3515.1212196950764;25650.615240771403;26006;32216.678545972805;33036.834815826005;33465.33069338599;2701.8649067546544;3242.189452636841;3372.37611041958;3402.914927126817;3402.9829850850833;514498;514708;517738;518592;519515;65898.67050664716;67222.66388668025;67294;67298.99051514232;67441.66279168573;70430;70492;70549;70585;70673.64663176652;16589.585260368345;16884.831151688573;16895.493135205965;16906.830931690773;16928.40750573724;12754.426050827735;12849.036322275866;12851.550195743292;12866.035047371484;12877.227366358049;17153.1016032847;18975.33586324472;18988.335408260646;19010.904945475184;19382;7210.242924492957;7310.780676579711;7325.304096110861;7370.8894366584545;7391.408687305003;913.1735779119774;979.2949076664933;980.8038392321422;1008.8436292374694;1069.198101423932;30972.380552389277;31750.682493174772;31756;31851.68148318487;32051.03846884597;1087.2063393722626;1092.9890701092888;1166.5450474315023;1180.86420061694;1250.3810613746255;16107.677846443006;16126.75809862853;16235.675286494205;16348.836511634969;16570;672753;677875;678992;679261;679423;54046;54363.56364363532;60649.69675151596;61853.38146618566;62398.37601623926;1424.886009119268;1448.377197804954;1460.6561962994072;1462.4077248714289;1462.5100591302032;27331.31671708223;31831.363372732878;33351.83324083364;33371.66398352092;33513.16217094592;58990;59619.1057134152;59828.205153845454;59843.40156598378;59996;628838;655388;655975;656428;657060;26887.327816804736;27234.455310894067;27518.862405687843;27905.720942791126;27951.860240698665;49821;50132.74933625309;51409.228861567855;52785.680357991354;53268.73365633147;39854;40484;40999.59000409958;41187;41716.539921102616;41467;41667.7916610415;41789.37315940203;42699.359509608;42708.78645606752;39460.60539394569;40042.39936400898;40176.79469615916;40238.79880600578;40338.39492407675;33724;35397;35673.82163089168;35857.90415629297;36026.63973360233;23765.405864853517;25474.981000759763;26352;26534.734652654;26808.329791754582 19:11:29 INFO - PROCESS | 1671 | |2;jquery.html;363.4403019350201;383.9193769308445;390.7245391998641;391.256612436371;412.2394182732859;15286.414075777264;16542.586435339108;20418;20585.38243852683;20587.38237852867;21320;21564.89217553914;21662.675059874113;21664.89167554164;21673.458163545918;5953.345132035483;5970.6119102258435;6028.3067447243475;6113.388661133873;6176.505879529627;6131.601445906024;6154.938450615481;6228.096925945735;6229.6573688447115;6232.594881332721;5871.29544454666;6146.508279337642;6149.262088549381;6178.38216178383;6181.443670069692;5843.298804143487;6089.1170780236835;6113.23584551931;6158;6163.969180154116;5285;5318.73406329682;5320.654157479751;5321.467853214647;5324.54741346986;26339.868300658567;26812.86593567039;26846.181150979257;27191.193232947404;27329;2231.018351925164;2274.727032756063;2605.908793192229;2788.9820215621153;3188.872445102193;13522.729545409036;16029.679406411806;16363.672726545403;16406.1796910154;16532.83467165325;1912.6844070728314;1940.4178746376172;1942.2910637617167;1968.7342208801915;1987.0760096555027;123196.38401807933;123860.76139238583;125972.37013814964;126143.36928315391;126946.36526817399;10593.205509586813;10688.679339619825;11102.111831053497;11263.887361126448;11444.771104578029;12426.192297500633;13715.519956801663;13897.79153312701;13905.096168749;13924.51264205748;8576.099509551532;8580.099089595502;8592.269657079218;8629.61166747498;8724.651013959498;7235.38499227561;8743.868841967376;8768.807798050488;8885.822283554293;8887.466751994902;6643.949757492849;6733.293004234485;6736.764213252611;6741.460683145337;6832.555883867532;1614.273576890404;1849.9630007400046;1885.5286178455385;1888.5373083594607;1897.6679081161017;876.8027193881453;990.3760630802549;1010.8584798128268;1016.8220561401725;1034.6016783538391;21065.157393704387;21418.250361237282;22624.321270361914;22704.432389190402;22740.4314892129;947.346331031586;948.3029972969862;966.5602151021268;980.0934135924241;984.0995489127512;11086.501107450184;11511.251768635011;11676.47455864488;11955.16313858039;12109.515619375303;605869;606596;607316;607790;621851.7814821794;106067;119305.40347298382;121605;122092;128683;1013.9239557033252;1034.849946757716;1058.2063452410691;1058.3491152940885;1058.4866339825178;19245.80754192496;19701.40895773129;20125.993700314633;20148.496287592927;20240.684355516845;40394;54670.453295466534;54898;55277.44722552723;55961.44038559725;77029.45941081035;77363.61318193373;77699.61150194213;77977;79419.60290198513;7568.5458872467825;7879.975603171551;8026.799330016796;8212.63043163071;8407.621657025571;11581.7104572383;13571.253581053137;13676.452941882213;13902.37439315213;13905.721885562434;10965.683077520474;11468.770624587294;11527.88472115268;11779.411029448322;12029.097817663886;11252.831207532057;11420.371879546705;11528;11677.065834733032;11777.941110294394;9986.751656042994;11622.244554103956;11840.82238766402;11925.4037298133;11948.76102477963;7244.130704315516;8281.047679516692;8294.87557686623;8568.143185681382;8632.309415246891;9695.369800962915;11295.322280663186;11453.885461145283;11587.246828956091;11737.237079589799 19:11:29 INFO - PROCESS | 1671 | |3;mootools.html;1342.5768685193702;1544.3668096080605;1580.3599542185416;1810.176369751763;1965.007671126081;7927.326177274931;8776.69281575145;8986.415882967602;9014.684486042997;9110.271178305737;8727.607257673404;8870.37907346486;8924.955375223131;8938.553072346389;8947.150020748035;1776.9022703751289;2175.3039027511204;2363.550925324183;2402.3513651314133;2406.4585468269674;2056.7634722006937;2403.3631087761755;2414.360194548446;2419.697537807774;2422.0553983946274;2345.7771511706446;2353.5410594933996;2366.5740166769974;2393.2102406205995;2407.6268178432283;2073.2950796729033;2302.1482051640833;2316.6177580699164;2334.1480359668767;2385.1294277588554;1955.589326241491;2254.109626697462;2264;2267.5238199978025;2269.727632684072;8947.955260223722;9027.954860225656;9039.45763254207;9068.909310906873;9084.727458176265;5201.687898726089;5377.0321342158395;5378.623496355218;5392.649477783932;5394.757235924393;3059.4340047091137;3443.2424866529323;3526.964730352715;3623.691986181178;3649.4160934250394;1052.2003277509148;1052.69998050556;1058.2645061682133;1072.5388083124253;1073.683263437288;11904;11916.285022898566;11920.582779602759;11926.642200733992;11931.522739090426;4617.03042361108;4635.605973492291;4853.708777473293;4857.878553036202;4862.003289325679;10198.898011019943;10200.591976321013;10202.591896324064;10218.386896786216;10237.385756854612;5400.162974738922;5413.756380962866;5415.321250412385;5423.3491980962535;5424.484673955968;4296.37702533131;4474.39595654589;4630.53694630541;4796.472388037313;4954.355933728664;3981.5222173339384;4003.6797056236014;4029.9395509067385;4111.342185250345;4142.937855932103;1290.0389210038581;1293.8318018657703;1299.5971248912867;1311.5016293808292;1311.7048664050512;672.5460314287836;836.6235194162651;938.5401153434768;960.415314730686;977.4965892565334;5652.406497317805;5718.599698021182;6078.057901025348;6083.69581520931;6273.686315684197;955.1260596554075;977.2914636888341;983.5524836199512;983.5770618634056;984.9852252216223;3920.4511368408444;4057.5536690963972;4066.4916885389325;4113.979430102891;4170.47869016373;9548.18840398574;11083.390413527333;11088.722781930355;11099.389533575732;11108.833367499494;10911.945440272748;10958.890411095947;11054.778904422028;11065.391403472726;11074.280171788809;8343.249107580346;8423.452475589067;8437.282830959315;8446.197611226924;8446.197611226924;5205.505476979681;6112.205413296421;6234.3453937337745;6322.178116844781;6336.6533369762665;9825.213982881496;9924.702258932244;10223.28437009432;10223.642172523925;10318.948405257926;10223;10366.637167699095;10411.791764164826;10443.895561044292;10598.470076496298;7607.657655405409;7741.651625676745;7756.457048006811;7761.573113478813;7773.727919522789;8016.238457346542;8032.397570182143;8106.8783968241705;8168;8235.13531079228;7417.777466676009;7678.8080297993;7684.500507466997;7807.375409967075;7842.137364889972;7420.666070026754;7536.698532058876;7592.962035189788;7656.464047516621;7794.376449883882;7113.217546070032;7175.174854891546;7178.892316615359;7182.317679820618;7191.137063552407;4898.828541001048;4902.852914412573;5084.720340381166;5165.896682066413;5198.766055527435;5177.016366890279;5201.1678131498775;5234.450382709761;5262.97368513155;5327.760250788646 19:11:29 INFO - PROCESS | 1671 | |4;prototype.html;306.9585605943197;309.3009797856844;318.4490830862607;324.98700051997923;326.508604550152;4623.907521849561;5129.6409251352325;5375;5409.350877894653;5443.176535860482;4513.3455648930885;5367.141257398817;5373.032854086263;5405.729713514328;5418.674879507223;3685.613010633885;4119.732217405874;4154.896127596804;4163.791810409475;4184.7907604619795;3485.9477107843404;3770.7926064066464;4166.791660416982;4180.372944058385;4196.3705444183315;3777.3578491656485;3939.5272567291963;4100.85647002355;4114.506259248894;4152.501699796028;3650.6166852480374;3999.8600048998146;4043.575424580406;4107.732997355162;4132.359484279941;3065.862036208376;3155.5739975103525;3593.460980852893;3611.1694310308512;3632.691221246197;4969.975150124263;5243.187305967581;5285.550728188108;5298.814541491068;5361.828459473881;3747.756395834262;4465.307877279027;4672.883177920545;4755.595774359149;4787.904241915142;4767.523247675204;5473.589480788957;5707.657540547539;5740.282464691913;5768.971155144198;3342.649021852719;3702.740808143431;3801.2967600993907;3975.60243975603;3978.428520734323;4861.003494283698;5820.708964551798;5964.493018093423;5968.67172305523;5982.521398288127;3860.3630400983525;4026.134381108076;4040.2727509048655;4047.494063242095;4073;3503.404421248394;3655.744097913174;3833.9808300958316;3949.6050394960275;4091.959080409217;4408.713433626804;4559.540946896961;4569.794359253841;4575.382323386367;4608.792604332813;5008.847964968078;5607.52336051432;5652.0391533439415;5685.260916080883;5717.914231286534;1326.7346530693899;1359.9252041137634;1370.869767372098;1381.1022835157228;1382.7925811128412;713.4791602130371;733.5891900535611;745.5750222373196;860.264473874828;867.6659486097898;4340.784580317516;4415.2935530315;4437.934895625027;4439.4228750262255;4485.842995495142;4306.74159550428;4389;4651.62786977041;4787.39622226558;4811.789684026477;3786.1258676955085;4081.816228317836;4370.164530896967;4399.73601583906;4488.3940668010055;2286.622707253318;2298.8735619540753;2350.7296660884217;2517.1315896015844;2546.630738542901;4906.552566992746;4934.67924584908;4950.118954797154;4972.950270497321;4983.903541220926;4418.911621767546;4641.396618439581;4642.237868468256;4749.126778299148;5008.372279009263;4236.6187043166265;4244.469441319835;4664.860054198311;4992.950070499393;5089.302669585126;4556.749378784066;4689.514557284645;4704.741239231738;4732.455767586634;4765.928511072406;4139.523954745243;4675.779004972611;4700.226866148989;4832.468428472936;4843.46721860588;3922.490076290064;4388.51726310112;5029.164355010468;5088.59291256706;5092.796288148581;3048.8018278811805;3488.755787094879;3496.7202623789526;3505.789652620851;3525.735569832324;4463.843765468193;4525.660575456788;4733.650909490779;4779.402574678165;4808.6153107752;4345.739255644671;4362.498312693954;4518.480374756945;4553.271476563734;4782.976085119552;4039.4748682671066;4572.588467037983;4671.626269898332;4727.565303868441;4774.665773395968;4075.4090656853996;4262.7016108872085;4323.702889133185;4451.175018242505;4606.401167848158;4130.876073717794;4192.4759405074365;4245.9363109554;4488.932666009947;4722.669413141184;3970.80145992705;4189.4344263524035;4549.4278250358175;4604.907901841877;4675.812967481263 19:11:29 INFO - PROCESS | 1671 | |5;yui.html;736.7568702328232;748.9625518724063;769.5267410542518;852.5737131434283;863.0980625246617;17706.645867082665;18249.908750456263;18275.634487310246;18299.54251143722;18376.816231837693;17214.655706885856;17259.913700431513;17406;17406.39077632283;17445.651086978283;718.5221827484717;886.5965985476593;904.0913881549067;905.316486053029;908.7364664247393;731.6159016516318;879.3492815316683;912.7170577121113;915.1351972386097;916.3173435790345;933.7898972731149;945.0927109974433;947.554649314821;948.6347756113877;949.2453499467939;749.1459735901077;773.6634563964693;937.9812403751887;940.2431043010374;941.8869735631733;570.5179123640518;571.9656820590778;686.128616656848;707.3598393453881;712.1596516110988;15615.921920390325;15622.609434764108;15658;15661.608459788482;15666.373345066184;13843.30783460833;13904.235267060307;13958.720825583432;14084.22536760488;14125.929370353082;12384.75230495385;12469.189502682388;13081.803772943318;13402.664933376645;13874.861251387458;4558.544145585415;4619.838305659286;4633.1660301145785;4637.559431853969;4791.209450440673;107755;108942.28461723123;110354.44822775915;110513;111754.44122779416;10840.030235518752;10895.83656245157;11026.889731102747;11078.612248571262;11255.943720281346;18280.542986425447;21532.354029379145;21547.784522154892;21570.35288941334;21573.784262157493;7953.12515623281;7964.681412743425;8019.037715474064;8026.83946321082;8143.83712325762;4501.819927202875;4519.932201016987;4553.5218802025975;4614.469336026333;4708.929366059512;3362.815045172539;3460.602030766494;3466.8959931202103;3469.6703813138215;3472.461768425898;624.709510077816;630.1870586942792;634.9936500634934;643.7521554201572;655.0501772429996;574.9880210828971;605.6305653551331;638.814743724324;642.8489305013318;653.7940548727231;8780.121987801169;8976.68581599654;9006.189442950166;9066.138716821892;9226.953865230631;557.4202829057815;615.7813976038442;633.1922361657494;644.1239913717341;650.4893658478072;4392.846250381222;4442.133783912106;4449.3326001100095;4467.975206611565;4562.224421848294;131639.34180329036;132657.33671331775;136282.3185884084;137386;140809;109853;112302.43848780867;112627.43686281516;113373.4331328338;113730.43134784438;97470.58852940255;97561.65849219778;97898.510507447;98288.01711982931;102202.48898755459;8398.096511268333;8481.194286542768;8589.44168629037;8718.782030449289;8899.777505562413;55721.164182536486;56169;57398;57469;60093.39906601053;85680.13030289426;89513.55243223743;89676;90496.54751726199;91780.54109729409;11225.71935701614;11407.714807129889;11427.60003399877;11590.826137608112;11815.468303926516;12486.616171630336;12590.622281331574;12702.872971270539;12723.427445764777;12963.15739476931;11678.24091434054;11802.232854864405;11874.940625297164;12002.459889304828;12012.879871201525;10865.239433239914;11518.136139789716;11902.285862848257;11990.460429280529;12218.877811221773;11448.885511145116;11555.357231394915;11645.243059201124;11788.823167652663;12186.817197741864;10028;10110.494475276353;10156.39061656303;10160.33957792741;10513.737156570842;9787.265955053543;9912.058354456316;10096.242781791248;11200.439978000904;11785.646430607096 19:11:29 INFO - PROCESS | 1671 | __end_tp_report 19:11:29 INFO - PROCESS | 1671 | __start_cc_report 19:11:29 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,1527 19:11:29 INFO - PROCESS | 1671 | __end_cc_report 19:11:29 INFO - PROCESS | 1671 | __startTimestamp1468462289495__endTimestamp 19:11:29 INFO - PROCESS | 1671 | 19:11:29 INFO - PROCESS | 1671 | ------- Summary: start ------- 19:11:29 INFO - PROCESS | 1671 | Number of tests: 6 19:11:29 INFO - PROCESS | 1671 | 19:11:29 INFO - PROCESS | 1671 | [#0] dojo.html Cycles:165 Average:5692.02 Median:5844.59 stddev:644.99 (11.0%) stddev-sans-first:620.68 19:11:29 INFO - PROCESS | 1671 | Values: 3368.4 3388.6 3397.8 3415.3 3468.5 5535.8 5605.8 5678.1 5755.4 5788.9 5663.7 5682.5 5714.9 5791.0 5804.7 4410.3 4987.8 5103.2 5118.5 5292.9 5295.9 5296.4 5307.4 5346.5 5391.4 5121.5 5176.2 5178.4 5232.7 5279.9 5207.3 5220.9 5229.4 5229.7 5229.9 4378.6 4431.7 5159.5 5161.9 5181.0 5586.7 5587.5 5702.2 5784.8 5813.2 6192.2 6200.5 6203.6 6210.3 6213.1 6030.9 6240.0 6296.1 6300.8 6333.1 5732.8 5780.9 5789.2 5844.6 5873.5 6231.2 6240.8 6245.2 6246.6 6252.2 5921.5 5974.6 6139.5 6149.1 6160.8 6174.4 6207.2 6210.3 6278.4 6283.2 6203.9 6225.8 6235.6 6255.9 6257.9 5954.4 6091.1 6256.2 6266.4 6273.1 4901.4 4918.2 4940.7 4945.1 4947.0 4372.3 4411.2 4414.1 4424.3 4446.5 5424.4 5516.1 5696.7 5722.6 5739.9 6042.3 6101.8 6110.8 6116.5 6130.1 5551.5 5551.5 5558.5 5559.8 5564.4 5317.4 5328.9 5331.0 5334.8 5339.7 6164.8 6214.0 6215.6 6234.0 6272.4 6258.3 6282.6 6289.3 6296.5 6306.5 6159.0 6167.8 6283.4 6291.1 6297.5 6097.7 6193.2 6211.2 6215.1 6217.5 6231.5 6254.0 6258.8 6262.2 6278.5 5953.5 6031.1 6101.3 6103.1 6108.4 5249.3 5792.2 5946.9 6030.4 6038.1 5146.7 5901.7 5906.3 5923.4 5928.5 5824.9 5877.6 6054.9 6061.9 6104.7 5739.4 5782.2 5787.0 5797.0 5801.8 19:11:29 INFO - PROCESS | 1671 | 19:11:29 INFO - PROCESS | 1671 | [#1] ext.html Cycles:180 Average:75680.16 Median:40109.60 stddev:165543.54 (412.7%) stddev-sans-first:165911.72 19:11:29 INFO - PROCESS | 1671 | Values: 507.6 514.8 524.5 541.0 559.2 30908.8 43054.0 43938.8 44072.8 44151.1 45012.8 45174.0 45352.8 45360.1 45386.0 8671.9 9671.3 9847.2 9853.9 9862.0 8789.8 11676.2 11774.6 11982.7 11988.3 11287.0 12147.7 12155.8 12175.2 12194.1 8702.4 8884.6 11981.2 12050.6 12068.9 8844.1 8893.9 11703.7 11930.6 11939.6 42857.6 43042.0 43070.0 43113.4 43129.8 3104.7 3419.7 3481.8 3503.9 3515.1 25650.6 26006.0 32216.7 33036.8 33465.3 2701.9 3242.2 3372.4 3402.9 3403.0 514498.0 514708.0 517738.0 518592.0 519515.0 65898.7 67222.7 67294.0 67299.0 67441.7 70430.0 70492.0 70549.0 70585.0 70673.6 16589.6 16884.8 16895.5 16906.8 16928.4 12754.4 12849.0 12851.6 12866.0 12877.2 17153.1 18975.3 18988.3 19010.9 19382.0 7210.2 7310.8 7325.3 7370.9 7391.4 913.2 979.3 980.8 1008.8 1069.2 30972.4 31750.7 31756.0 31851.7 32051.0 1087.2 1093.0 1166.5 1180.9 1250.4 16107.7 16126.8 16235.7 16348.8 16570.0 672753.0 677875.0 678992.0 679261.0 679423.0 54046.0 54363.6 60649.7 61853.4 62398.4 1424.9 1448.4 1460.7 1462.4 1462.5 27331.3 31831.4 33351.8 33371.7 33513.2 58990.0 59619.1 59828.2 59843.4 59996.0 628838.0 655388.0 655975.0 656428.0 657060.0 26887.3 27234.5 27518.9 27905.7 27951.9 49821.0 50132.7 51409.2 52785.7 53268.7 39854.0 40484.0 40999.6 41187.0 41716.5 41467.0 41667.8 41789.4 42699.4 42708.8 39460.6 40042.4 40176.8 40238.8 40338.4 33724.0 35397.0 35673.8 35857.9 36026.6 23765.4 25475.0 26352.0 26534.7 26808.3 19:11:29 INFO - PROCESS | 1671 | 19:11:29 INFO - PROCESS | 1671 | [#2] jquery.html Cycles:180 Average:35684.16 Median:12489.58 stddev:101574.30 (813.3%) stddev-sans-first:101824.62 19:11:29 INFO - PROCESS | 1671 | Values: 363.4 383.9 390.7 391.3 412.2 15286.4 16542.6 20418.0 20585.4 20587.4 21320.0 21564.9 21662.7 21664.9 21673.5 5953.3 5970.6 6028.3 6113.4 6176.5 6131.6 6154.9 6228.1 6229.7 6232.6 5871.3 6146.5 6149.3 6178.4 6181.4 5843.3 6089.1 6113.2 6158.0 6164.0 5285.0 5318.7 5320.7 5321.5 5324.5 26339.9 26812.9 26846.2 27191.2 27329.0 2231.0 2274.7 2605.9 2789.0 3188.9 13522.7 16029.7 16363.7 16406.2 16532.8 1912.7 1940.4 1942.3 1968.7 1987.1 123196.4 123860.8 125972.4 126143.4 126946.4 10593.2 10688.7 11102.1 11263.9 11444.8 12426.2 13715.5 13897.8 13905.1 13924.5 8576.1 8580.1 8592.3 8629.6 8724.7 7235.4 8743.9 8768.8 8885.8 8887.5 6643.9 6733.3 6736.8 6741.5 6832.6 1614.3 1850.0 1885.5 1888.5 1897.7 876.8 990.4 1010.9 1016.8 1034.6 21065.2 21418.3 22624.3 22704.4 22740.4 947.3 948.3 966.6 980.1 984.1 11086.5 11511.3 11676.5 11955.2 12109.5 605869.0 606596.0 607316.0 607790.0 621851.8 106067.0 119305.4 121605.0 122092.0 128683.0 1013.9 1034.8 1058.2 1058.3 1058.5 19245.8 19701.4 20126.0 20148.5 20240.7 40394.0 54670.5 54898.0 55277.4 55961.4 77029.5 77363.6 77699.6 77977.0 79419.6 7568.5 7880.0 8026.8 8212.6 8407.6 11581.7 13571.3 13676.5 13902.4 13905.7 10965.7 11468.8 11527.9 11779.4 12029.1 11252.8 11420.4 11528.0 11677.1 11777.9 9986.8 11622.2 11840.8 11925.4 11948.8 7244.1 8281.0 8294.9 8568.1 8632.3 9695.4 11295.3 11453.9 11587.2 11737.2 19:11:29 INFO - PROCESS | 1671 | 19:11:29 INFO - PROCESS | 1671 | [#3] mootools.html Cycles:180 Average:5810.02 Median:4880.42 stddev:3291.07 (67.4%) stddev-sans-first:3283.17 19:11:29 INFO - PROCESS | 1671 | Values: 1342.6 1544.4 1580.4 1810.2 1965.0 7927.3 8776.7 8986.4 9014.7 9110.3 8727.6 8870.4 8925.0 8938.6 8947.2 1776.9 2175.3 2363.6 2402.4 2406.5 2056.8 2403.4 2414.4 2419.7 2422.1 2345.8 2353.5 2366.6 2393.2 2407.6 2073.3 2302.1 2316.6 2334.1 2385.1 1955.6 2254.1 2264.0 2267.5 2269.7 8948.0 9028.0 9039.5 9068.9 9084.7 5201.7 5377.0 5378.6 5392.6 5394.8 3059.4 3443.2 3527.0 3623.7 3649.4 1052.2 1052.7 1058.3 1072.5 1073.7 11904.0 11916.3 11920.6 11926.6 11931.5 4617.0 4635.6 4853.7 4857.9 4862.0 10198.9 10200.6 10202.6 10218.4 10237.4 5400.2 5413.8 5415.3 5423.3 5424.5 4296.4 4474.4 4630.5 4796.5 4954.4 3981.5 4003.7 4029.9 4111.3 4142.9 1290.0 1293.8 1299.6 1311.5 1311.7 672.5 836.6 938.5 960.4 977.5 5652.4 5718.6 6078.1 6083.7 6273.7 955.1 977.3 983.6 983.6 985.0 3920.5 4057.6 4066.5 4114.0 4170.5 9548.2 11083.4 11088.7 11099.4 11108.8 10911.9 10958.9 11054.8 11065.4 11074.3 8343.2 8423.5 8437.3 8446.2 8446.2 5205.5 6112.2 6234.3 6322.2 6336.7 9825.2 9924.7 10223.3 10223.6 10318.9 10223.0 10366.6 10411.8 10443.9 10598.5 7607.7 7741.7 7756.5 7761.6 7773.7 8016.2 8032.4 8106.9 8168.0 8235.1 7417.8 7678.8 7684.5 7807.4 7842.1 7420.7 7536.7 7593.0 7656.5 7794.4 7113.2 7175.2 7178.9 7182.3 7191.1 4898.8 4902.9 5084.7 5165.9 5198.8 5177.0 5201.2 5234.5 5263.0 5327.8 19:11:29 INFO - PROCESS | 1671 | 19:11:29 INFO - PROCESS | 1671 | [#4] prototype.html Cycles:180 Average:4141.68 Median:4464.58 stddev:1218.58 (27.3%) stddev-sans-first:1187.52 19:11:29 INFO - PROCESS | 1671 | Values: 307.0 309.3 318.4 325.0 326.5 4623.9 5129.6 5375.0 5409.4 5443.2 4513.3 5367.1 5373.0 5405.7 5418.7 3685.6 4119.7 4154.9 4163.8 4184.8 3485.9 3770.8 4166.8 4180.4 4196.4 3777.4 3939.5 4100.9 4114.5 4152.5 3650.6 3999.9 4043.6 4107.7 4132.4 3065.9 3155.6 3593.5 3611.2 3632.7 4970.0 5243.2 5285.6 5298.8 5361.8 3747.8 4465.3 4672.9 4755.6 4787.9 4767.5 5473.6 5707.7 5740.3 5769.0 3342.6 3702.7 3801.3 3975.6 3978.4 4861.0 5820.7 5964.5 5968.7 5982.5 3860.4 4026.1 4040.3 4047.5 4073.0 3503.4 3655.7 3834.0 3949.6 4092.0 4408.7 4559.5 4569.8 4575.4 4608.8 5008.8 5607.5 5652.0 5685.3 5717.9 1326.7 1359.9 1370.9 1381.1 1382.8 713.5 733.6 745.6 860.3 867.7 4340.8 4415.3 4437.9 4439.4 4485.8 4306.7 4389.0 4651.6 4787.4 4811.8 3786.1 4081.8 4370.2 4399.7 4488.4 2286.6 2298.9 2350.7 2517.1 2546.6 4906.6 4934.7 4950.1 4973.0 4983.9 4418.9 4641.4 4642.2 4749.1 5008.4 4236.6 4244.5 4664.9 4993.0 5089.3 4556.7 4689.5 4704.7 4732.5 4765.9 4139.5 4675.8 4700.2 4832.5 4843.5 3922.5 4388.5 5029.2 5088.6 5092.8 3048.8 3488.8 3496.7 3505.8 3525.7 4463.8 4525.7 4733.7 4779.4 4808.6 4345.7 4362.5 4518.5 4553.3 4783.0 4039.5 4572.6 4671.6 4727.6 4774.7 4075.4 4262.7 4323.7 4451.2 4606.4 4130.9 4192.5 4245.9 4488.9 4722.7 3970.8 4189.4 4549.4 4604.9 4675.8 19:11:29 INFO - PROCESS | 1671 | 19:11:29 INFO - PROCESS | 1671 | [#5] yui.html Cycles:180 Average:23430.44 Median:4445.73 stddev:36403.79 (818.8%) stddev-sans-first:36466.04 19:11:29 INFO - PROCESS | 1671 | Values: 736.8 749.0 769.5 852.6 863.1 17706.6 18249.9 18275.6 18299.5 18376.8 17214.7 17259.9 17406.0 17406.4 17445.7 718.5 886.6 904.1 905.3 908.7 731.6 879.3 912.7 915.1 916.3 933.8 945.1 947.6 948.6 949.2 749.1 773.7 938.0 940.2 941.9 570.5 572.0 686.1 707.4 712.2 15615.9 15622.6 15658.0 15661.6 15666.4 13843.3 13904.2 13958.7 14084.2 14125.9 12384.8 12469.2 13081.8 13402.7 13874.9 4558.5 4619.8 4633.2 4637.6 4791.2 107755.0 108942.3 110354.4 110513.0 111754.4 10840.0 10895.8 11026.9 11078.6 11255.9 18280.5 21532.4 21547.8 21570.4 21573.8 7953.1 7964.7 8019.0 8026.8 8143.8 4501.8 4519.9 4553.5 4614.5 4708.9 3362.8 3460.6 3466.9 3469.7 3472.5 624.7 630.2 635.0 643.8 655.1 575.0 605.6 638.8 642.8 653.8 8780.1 8976.7 9006.2 9066.1 9227.0 557.4 615.8 633.2 644.1 650.5 4392.8 4442.1 4449.3 4468.0 4562.2 131639.3 132657.3 136282.3 137386.0 140809.0 109853.0 112302.4 112627.4 113373.4 113730.4 97470.6 97561.7 97898.5 98288.0 102202.5 8398.1 8481.2 8589.4 8718.8 8899.8 55721.2 56169.0 57398.0 57469.0 60093.4 85680.1 89513.6 89676.0 90496.5 91780.5 11225.7 11407.7 11427.6 11590.8 11815.5 12486.6 12590.6 12702.9 12723.4 12963.2 11678.2 11802.2 11874.9 12002.5 12012.9 10865.2 11518.1 11902.3 11990.5 12218.9 11448.9 11555.4 11645.2 11788.8 12186.8 10028.0 10110.5 10156.4 10160.3 10513.7 9787.3 9912.1 10096.2 11200.4 11785.6 19:11:29 INFO - PROCESS | 1671 | -------- Summary: end -------- 19:11:29 INFO - PROCESS | 1671 | 19:11:30 INFO - TEST-INFO | 1671: exit 0 19:11:30 INFO - TEST-OK | dromaeo_css | took 1116423ms 19:11:30 INFO - TEST-START | kraken 19:11:30 INFO - operating with platform_type : mac_ 19:11:30 INFO - Initialising browser for kraken test... 19:11:30 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/tmpGnSel_/profile http://localhost:49266/getInfo.html) 19:11:32 INFO - PROCESS | 1676 | __metrics Screen width/height:1600/1200 19:11:32 INFO - PROCESS | 1676 | colorDepth:24 19:11:32 INFO - PROCESS | 1676 | Browser inner width/height: 1024/689 19:11:32 INFO - PROCESS | 1676 | __metrics 19:11:32 INFO - PROCESS | 1676 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:11:32 INFO - PROCESS | 1676 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:11:33 INFO - TEST-INFO | 1676: exit 0 19:11:33 INFO - Browser initialized. 19:11:33 INFO - Running cycle 1/1 for kraken test... 19:11:33 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/tmpGnSel_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:11:45 INFO - PROCESS | 1677 | RSS: Main: 243593216 19:11:45 INFO - PROCESS | 1677 | 19:11:47 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection) 19:11:48 INFO - PROCESS | 1677 | RSS: Main: 293928960 19:11:48 INFO - PROCESS | 1677 | 19:11:51 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-dft) 19:11:51 INFO - PROCESS | 1677 | RSS: Main: 303972352 19:11:51 INFO - PROCESS | 1677 | 19:11:55 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-fft) 19:11:56 INFO - PROCESS | 1677 | RSS: Main: 389165056 19:11:56 INFO - PROCESS | 1677 | 19:11:57 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator) 19:11:58 INFO - PROCESS | 1677 | RSS: Main: 288829440 19:11:58 INFO - PROCESS | 1677 | 19:12:00 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:12:01 INFO - PROCESS | 1677 | RSS: Main: 293101568 19:12:01 INFO - PROCESS | 1677 | 19:12:04 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom) 19:12:04 INFO - PROCESS | 1677 | RSS: Main: 330223616 19:12:04 INFO - PROCESS | 1677 | 19:12:07 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate) 19:12:08 INFO - PROCESS | 1677 | RSS: Main: 330588160 19:12:08 INFO - PROCESS | 1677 | 19:12:11 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial) 19:12:11 INFO - PROCESS | 1677 | RSS: Main: 328761344 19:12:11 INFO - PROCESS | 1677 | 19:12:13 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:12:13 INFO - PROCESS | 1677 | RSS: Main: 311750656 19:12:13 INFO - PROCESS | 1677 | 19:12:15 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:12:16 INFO - PROCESS | 1677 | RSS: Main: 318803968 19:12:16 INFO - PROCESS | 1677 | 19:12:18 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:12:19 INFO - PROCESS | 1677 | RSS: Main: 316616704 19:12:19 INFO - PROCESS | 1677 | 19:12:22 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:12:22 INFO - PROCESS | 1677 | RSS: Main: 324902912 19:12:22 INFO - PROCESS | 1677 | 19:12:25 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:12:26 INFO - PROCESS | 1677 | RSS: Main: 327114752 19:12:26 INFO - PROCESS | 1677 | 19:12:27 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49266/tests/kraken/driver.html?testName=ai-astar) 19:12:28 INFO - PROCESS | 1677 | RSS: Main: 371142656 19:12:28 INFO - PROCESS | 1677 | 19:12:28 INFO - PROCESS | 1677 | __start_tp_report 19:12:28 INFO - PROCESS | 1677 | _x_x_mozilla_page_load 19:12:28 INFO - PROCESS | 1677 | _x_x_mozilla_page_load_details 19:12:28 INFO - PROCESS | 1677 | |i|pagename|runs| 19:12:28 INFO - PROCESS | 1677 | |0;ai-astar;101;113;116;110;113;113;113;113;114;113 19:12:28 INFO - PROCESS | 1677 | |1;audio-beat-detection;142;107;107;157;107;106;128;107;139;107 19:12:28 INFO - PROCESS | 1677 | |2;audio-dft;184;187;185;187;184;185;181;188;184;184 19:12:28 INFO - PROCESS | 1677 | |3;audio-fft;79;75;58;105;57;57;63;64;93;56 19:12:28 INFO - PROCESS | 1677 | |4;audio-oscillator;84;89;92;70;70;67;95;83;89;121 19:12:28 INFO - PROCESS | 1677 | |5;imaging-gaussian-blur;88;86;88;85;88;84;88;86;88;126 19:12:28 INFO - PROCESS | 1677 | |6;imaging-darkroom;97;94;95;94;95;94;94;97;94;93 19:12:28 INFO - PROCESS | 1677 | |7;imaging-desaturate;87;84;84;85;84;85;87;86;86;86 19:12:28 INFO - PROCESS | 1677 | |8;json-parse-financial;55;53;54;55;55;54;54;53;53;53 19:12:28 INFO - PROCESS | 1677 | |9;json-stringify-tinderbox;69;71;84;79;74;70;73;71;71;74 19:12:28 INFO - PROCESS | 1677 | |10;stanford-crypto-aes;84;82;79;93;93;93;95;79;82;84 19:12:28 INFO - PROCESS | 1677 | |11;stanford-crypto-ccm;132;136;134;157;128;143;140;139;226;133 19:12:28 INFO - PROCESS | 1677 | |12;stanford-crypto-pbkdf2;162;163;164;163;170;218;174;160;163;165 19:12:28 INFO - PROCESS | 1677 | |13;stanford-crypto-sha256-iterative;64;66;66;63;66;65;67;67;64;66 19:12:28 INFO - PROCESS | 1677 | __end_tp_report 19:12:28 INFO - PROCESS | 1677 | __start_cc_report 19:12:28 INFO - PROCESS | 1677 | _x_x_mozilla_cycle_collect,2066 19:12:28 INFO - PROCESS | 1677 | __end_cc_report 19:12:28 INFO - PROCESS | 1677 | __startTimestamp1468462348245__endTimestamp 19:12:28 INFO - PROCESS | 1677 | 19:12:28 INFO - PROCESS | 1677 | ------- Summary: start ------- 19:12:28 INFO - PROCESS | 1677 | Number of tests: 14 19:12:28 INFO - PROCESS | 1677 | 19:12:28 INFO - PROCESS | 1677 | [#0] ai-astar Cycles:10 Average:111.90 Median:113.00 stddev:4.09 (3.6%) stddev-sans-first:1.54 19:12:28 INFO - PROCESS | 1677 | Values: 101.0 113.0 116.0 110.0 113.0 113.0 113.0 113.0 114.0 113.0 19:12:28 INFO - PROCESS | 1677 | 19:12:28 INFO - PROCESS | 1677 | [#1] audio-beat-detection Cycles:10 Average:120.70 Median:117.50 stddev:19.19 (16.3%) stddev-sans-first:18.74 19:12:28 INFO - PROCESS | 1677 | Values: 142.0 107.0 107.0 157.0 107.0 106.0 128.0 107.0 139.0 107.0 19:12:28 INFO - PROCESS | 1677 | 19:12:28 INFO - PROCESS | 1677 | [#2] audio-dft Cycles:10 Average:184.90 Median:185.00 stddev:2.02 (1.1%) stddev-sans-first:2.12 19:12:28 INFO - PROCESS | 1677 | Values: 184.0 187.0 185.0 187.0 184.0 185.0 181.0 188.0 184.0 184.0 19:12:28 INFO - PROCESS | 1677 | 19:12:28 INFO - PROCESS | 1677 | [#3] audio-fft Cycles:10 Average:70.70 Median:63.50 stddev:17.06 (26.9%) stddev-sans-first:17.82 19:12:28 INFO - PROCESS | 1677 | Values: 79.0 75.0 58.0 105.0 57.0 57.0 63.0 64.0 93.0 56.0 19:12:28 INFO - PROCESS | 1677 | 19:12:28 INFO - PROCESS | 1677 | [#4] audio-oscillator Cycles:10 Average:86.00 Median:86.50 stddev:15.80 (18.3%) stddev-sans-first:16.74 19:12:28 INFO - PROCESS | 1677 | Values: 84.0 89.0 92.0 70.0 70.0 67.0 95.0 83.0 89.0 121.0 19:12:28 INFO - PROCESS | 1677 | 19:12:28 INFO - PROCESS | 1677 | [#5] imaging-gaussian-blur Cycles:10 Average:90.70 Median:88.00 stddev:12.49 (14.2%) stddev-sans-first:13.21 19:12:28 INFO - PROCESS | 1677 | Values: 88.0 86.0 88.0 85.0 88.0 84.0 88.0 86.0 88.0 126.0 19:12:28 INFO - PROCESS | 1677 | 19:12:28 INFO - PROCESS | 1677 | [#6] imaging-darkroom Cycles:10 Average:94.70 Median:94.50 stddev:1.34 (1.4%) stddev-sans-first:1.13 19:12:28 INFO - PROCESS | 1677 | Values: 97.0 94.0 95.0 94.0 95.0 94.0 94.0 97.0 94.0 93.0 19:12:28 INFO - PROCESS | 1677 | 19:12:28 INFO - PROCESS | 1677 | [#7] imaging-desaturate Cycles:10 Average:85.40 Median:86.00 stddev:1.17 (1.4%) stddev-sans-first:1.09 19:12:28 INFO - PROCESS | 1677 | Values: 87.0 84.0 84.0 85.0 84.0 85.0 87.0 86.0 86.0 86.0 19:12:28 INFO - PROCESS | 1677 | 19:12:28 INFO - PROCESS | 1677 | [#8] json-parse-financial Cycles:10 Average:53.90 Median:54.00 stddev:0.88 (1.6%) stddev-sans-first:0.83 19:12:28 INFO - PROCESS | 1677 | Values: 55.0 53.0 54.0 55.0 55.0 54.0 54.0 53.0 53.0 53.0 19:12:28 INFO - PROCESS | 1677 | 19:12:28 INFO - PROCESS | 1677 | [#9] json-stringify-tinderbox Cycles:10 Average:73.60 Median:73.50 stddev:4.62 (6.3%) stddev-sans-first:4.59 19:12:28 INFO - PROCESS | 1677 | Values: 69.0 71.0 84.0 79.0 74.0 70.0 73.0 71.0 71.0 74.0 19:12:28 INFO - PROCESS | 1677 | 19:12:28 INFO - PROCESS | 1677 | [#10] stanford-crypto-aes Cycles:10 Average:86.40 Median:88.50 stddev:6.36 (7.2%) stddev-sans-first:6.69 19:12:28 INFO - PROCESS | 1677 | Values: 84.0 82.0 79.0 93.0 93.0 93.0 95.0 79.0 82.0 84.0 19:12:28 INFO - PROCESS | 1677 | 19:12:28 INFO - PROCESS | 1677 | [#11] stanford-crypto-ccm Cycles:10 Average:146.80 Median:139.50 stddev:28.95 (20.8%) stddev-sans-first:30.20 19:12:28 INFO - PROCESS | 1677 | Values: 132.0 136.0 134.0 157.0 128.0 143.0 140.0 139.0 226.0 133.0 19:12:28 INFO - PROCESS | 1677 | 19:12:28 INFO - PROCESS | 1677 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.20 Median:164.50 stddev:17.29 (10.5%) stddev-sans-first:18.09 19:12:28 INFO - PROCESS | 1677 | Values: 162.0 163.0 164.0 163.0 170.0 218.0 174.0 160.0 163.0 165.0 19:12:28 INFO - PROCESS | 1677 | 19:12:28 INFO - PROCESS | 1677 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.40 Median:66.00 stddev:1.35 (2.0%) stddev-sans-first:1.33 19:12:28 INFO - PROCESS | 1677 | Values: 64.0 66.0 66.0 63.0 66.0 65.0 67.0 67.0 64.0 66.0 19:12:28 INFO - PROCESS | 1677 | -------- Summary: end -------- 19:12:28 INFO - PROCESS | 1677 | 19:12:29 INFO - TEST-INFO | 1677: exit 0 19:12:29 INFO - TEST-OK | kraken | took 58972ms 19:12:29 INFO - SUITE-END | took 1175s 19:12:29 INFO - Completed test suite (00:19:35) 19:12:29 INFO - javascript benchmark 19:12:29 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3368.360011597797, 3388.62725100239, 3397.830108494576, 3415.316936612678, 3468.497067925151, 5535.806246781363, 5605.775768969236, 5678.091505359143, 5755.424457554248, 5788.913166302509, 5663.688497132656, 5682.545396368291, 5714.857128571788, 5790.971045144768, 5804.709764511768, 4410.25025745624, 4987.800487980477, 5103.183490641498, 5118.513741194599, 5292.947070529303, 5295.920561191585, 5296.443873393296, 5307.416184219752, 5346.492083252104, 5391.379991300992, 5121.462246464105, 5176.171812510018, 5178.378594568639, 5232.659877107976, 5279.868003299891, 5207.3490813648295, 5220.947790522122, 5229.424763276036, 5229.660072095302, 5229.895402091975, 4378.562143785628, 4431.7340959542535, 5159.458256883011, 5161.922571161435, 5180.9740951295, 5586.720663966786, 5587.525060369873, 5702.201691763156, 5784.797532086399, 5813.157092221646, 6192.225971753531, 6200.534959878028, 6203.596766210182, 6210.254769427653, 6213.068039794022, 6030.879382412371, 6240.0, 6296.087067375251, 6300.810975670737, 6333.081703153063, 5732.799352022701, 5780.855478613068, 5789.160571717078, 5844.59087863854, 5873.471387575139, 6231.22109736283, 6240.843978900473, 6245.156903817927, 6246.562740608114, 6252.218472690914, 5921.467067963904, 5974.551908606872, 6139.4781443577795, 6149.0776383542825, 6160.815175544741, 6174.351693072253, 6207.193064901534, 6210.285817130997, 6278.434940855345, 6283.214598175228, 6203.937960620427, 6225.782097626652, 6235.594686345373, 6255.937440625536, 6257.937420625826, 5954.404559544011, 6091.0558863376245, 6256.249250090018, 6266.404691554295, 6273.0904018917, 4901.43633482147, 4918.213085906238, 4940.7035577865445, 4945.134601444733, 4947.010597880439, 4372.322290045048, 4411.20598292304, 4414.073044660658, 4424.3142312941545, 4446.4886538047895, 5424.376196737426, 5516.14499752539, 5696.6582005079, 5722.570807189478, 5739.8565035874435, 6042.305134909454, 6101.816945491642, 6110.755569777248, 6116.541259405473, 6130.111133885562, 5551.472610102114, 5551.528120109836, 5558.527525160326, 5559.833205003856, 5564.415736347706, 5317.388500322435, 5328.893422131536, 5331.0, 5334.839954801362, 5339.706316152571, 6164.815055548341, 6214.0, 6215.627062376272, 6233.96883015582, 6272.435480806749, 6258.280297765815, 6282.591631543935, 6289.308176100717, 6296.496280297657, 6306.527010474141, 6158.969205153946, 6167.814965551041, 6283.403076707706, 6291.056341548804, 6297.464715499051, 6097.706167156046, 6193.163922870535, 6211.223597050369, 6215.06773983906, 6217.4715149212825, 6231.53263505248, 6254.0, 6258.812235632939, 6262.185915830913, 6278.497720182283, 5953.493953014043, 6031.125486804301, 6101.29835068955, 6103.084537319438, 6108.419700128481, 5249.291345668286, 5792.189093526826, 5946.881062378815, 6030.396960303934, 6038.094285857156, 5146.665466744649, 5901.734421951109, 5906.320773111147, 5923.437273459014, 5928.4960778334325, 5824.912626310524, 5877.647341159544, 6054.878902422015, 6061.909071363845, 6104.725287362168, 5739.368669446274, 5782.190493330853, 5787.0, 5797.043487824631, 5801.767929282782], "value": 5651.065291776074, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [507.5888530290463, 514.7837908078607, 524.45456725006, 540.9883517821675, 559.2450192240476, 30908.845455772716, 43054.0, 43938.78030609842, 44072.779636101855, 44151.11697766043, 45012.77493612535, 45174.0, 45352.77323613386, 45360.09279814402, 45386.0, 8671.913280867206, 9671.274654400913, 9847.162991145726, 9853.901460985406, 9862.0, 8789.824203515958, 11676.1826672133, 11774.646760597196, 11982.70043248917, 11988.340641264727, 11286.98417142457, 12147.696307592292, 12155.817662735022, 12175.208611440272, 12194.146409751322, 8702.390832641655, 8884.555772211428, 11981.161318707695, 12050.578229761917, 12068.879311206863, 8844.11558844117, 8893.866592001124, 11703.7074073148, 11930.642080737593, 11939.641810745692, 42857.57142428567, 43042.0, 43070.0, 43113.35329970053, 43129.78435107836, 3104.6895310469, 3419.7264218862433, 3481.7736847104857, 3503.8773642922374, 3515.1212196950764, 25650.615240771403, 26006.0, 32216.678545972805, 33036.834815826005, 33465.33069338599, 2701.8649067546544, 3242.189452636841, 3372.37611041958, 3402.914927126817, 3402.9829850850833, 514498.0, 514708.0, 517738.0, 518592.0, 519515.0, 65898.67050664716, 67222.66388668025, 67294.0, 67298.99051514232, 67441.66279168573, 70430.0, 70492.0, 70549.0, 70585.0, 70673.64663176652, 16589.585260368345, 16884.831151688573, 16895.493135205965, 16906.830931690773, 16928.40750573724, 12754.426050827735, 12849.036322275866, 12851.550195743292, 12866.035047371484, 12877.227366358049, 17153.1016032847, 18975.33586324472, 18988.335408260646, 19010.904945475184, 19382.0, 7210.242924492957, 7310.780676579711, 7325.304096110861, 7370.8894366584545, 7391.408687305003, 913.1735779119774, 979.2949076664933, 980.8038392321422, 1008.8436292374694, 1069.198101423932, 30972.380552389277, 31750.682493174772, 31756.0, 31851.68148318487, 32051.03846884597, 1087.2063393722626, 1092.9890701092888, 1166.5450474315023, 1180.86420061694, 1250.3810613746255, 16107.677846443006, 16126.75809862853, 16235.675286494205, 16348.836511634969, 16570.0, 672753.0, 677875.0, 678992.0, 679261.0, 679423.0, 54046.0, 54363.56364363532, 60649.69675151596, 61853.38146618566, 62398.37601623926, 1424.886009119268, 1448.377197804954, 1460.6561962994072, 1462.4077248714289, 1462.5100591302032, 27331.31671708223, 31831.363372732878, 33351.83324083364, 33371.66398352092, 33513.16217094592, 58990.0, 59619.1057134152, 59828.205153845454, 59843.40156598378, 59996.0, 628838.0, 655388.0, 655975.0, 656428.0, 657060.0, 26887.327816804736, 27234.455310894067, 27518.862405687843, 27905.720942791126, 27951.860240698665, 49821.0, 50132.74933625309, 51409.228861567855, 52785.680357991354, 53268.73365633147, 39854.0, 40484.0, 40999.59000409958, 41187.0, 41716.539921102616, 41467.0, 41667.7916610415, 41789.37315940203, 42699.359509608, 42708.78645606752, 39460.60539394569, 40042.39936400898, 40176.79469615916, 40238.79880600578, 40338.39492407675, 33724.0, 35397.0, 35673.82163089168, 35857.90415629297, 36026.63973360233, 23765.405864853517, 25474.981000759763, 26352.0, 26534.734652654, 26808.329791754582], "value": 21205.310190977674, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [363.4403019350201, 383.9193769308445, 390.7245391998641, 391.256612436371, 412.2394182732859, 15286.414075777264, 16542.586435339108, 20418.0, 20585.38243852683, 20587.38237852867, 21320.0, 21564.89217553914, 21662.675059874113, 21664.89167554164, 21673.458163545918, 5953.345132035483, 5970.6119102258435, 6028.3067447243475, 6113.388661133873, 6176.505879529627, 6131.601445906024, 6154.938450615481, 6228.096925945735, 6229.6573688447115, 6232.594881332721, 5871.29544454666, 6146.508279337642, 6149.262088549381, 6178.38216178383, 6181.443670069692, 5843.298804143487, 6089.1170780236835, 6113.23584551931, 6158.0, 6163.969180154116, 5285.0, 5318.73406329682, 5320.654157479751, 5321.467853214647, 5324.54741346986, 26339.868300658567, 26812.86593567039, 26846.181150979257, 27191.193232947404, 27329.0, 2231.018351925164, 2274.727032756063, 2605.908793192229, 2788.9820215621153, 3188.872445102193, 13522.729545409036, 16029.679406411806, 16363.672726545403, 16406.1796910154, 16532.83467165325, 1912.6844070728314, 1940.4178746376172, 1942.2910637617167, 1968.7342208801915, 1987.0760096555027, 123196.38401807933, 123860.76139238583, 125972.37013814964, 126143.36928315391, 126946.36526817399, 10593.205509586813, 10688.679339619825, 11102.111831053497, 11263.887361126448, 11444.771104578029, 12426.192297500633, 13715.519956801663, 13897.79153312701, 13905.096168749, 13924.51264205748, 8576.099509551532, 8580.099089595502, 8592.269657079218, 8629.61166747498, 8724.651013959498, 7235.38499227561, 8743.868841967376, 8768.807798050488, 8885.822283554293, 8887.466751994902, 6643.949757492849, 6733.293004234485, 6736.764213252611, 6741.460683145337, 6832.555883867532, 1614.273576890404, 1849.9630007400046, 1885.5286178455385, 1888.5373083594607, 1897.6679081161017, 876.8027193881453, 990.3760630802549, 1010.8584798128268, 1016.8220561401725, 1034.6016783538391, 21065.157393704387, 21418.250361237282, 22624.321270361914, 22704.432389190402, 22740.4314892129, 947.346331031586, 948.3029972969862, 966.5602151021268, 980.0934135924241, 984.0995489127512, 11086.501107450184, 11511.251768635011, 11676.47455864488, 11955.16313858039, 12109.515619375303, 605869.0, 606596.0, 607316.0, 607790.0, 621851.7814821794, 106067.0, 119305.40347298382, 121605.0, 122092.0, 128683.0, 1013.9239557033252, 1034.849946757716, 1058.2063452410691, 1058.3491152940885, 1058.4866339825178, 19245.80754192496, 19701.40895773129, 20125.993700314633, 20148.496287592927, 20240.684355516845, 40394.0, 54670.453295466534, 54898.0, 55277.44722552723, 55961.44038559725, 77029.45941081035, 77363.61318193373, 77699.61150194213, 77977.0, 79419.60290198513, 7568.5458872467825, 7879.975603171551, 8026.799330016796, 8212.63043163071, 8407.621657025571, 11581.7104572383, 13571.253581053137, 13676.452941882213, 13902.37439315213, 13905.721885562434, 10965.683077520474, 11468.770624587294, 11527.88472115268, 11779.411029448322, 12029.097817663886, 11252.831207532057, 11420.371879546705, 11528.0, 11677.065834733032, 11777.941110294394, 9986.751656042994, 11622.244554103956, 11840.82238766402, 11925.4037298133, 11948.76102477963, 7244.130704315516, 8281.047679516692, 8294.87557686623, 8568.143185681382, 8632.309415246891, 9695.369800962915, 11295.322280663186, 11453.885461145283, 11587.246828956091, 11737.237079589799], "value": 9989.525482568317, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1342.5768685193702, 1544.3668096080605, 1580.3599542185416, 1810.176369751763, 1965.007671126081, 7927.326177274931, 8776.69281575145, 8986.415882967602, 9014.684486042997, 9110.271178305737, 8727.607257673404, 8870.37907346486, 8924.955375223131, 8938.553072346389, 8947.150020748035, 1776.9022703751289, 2175.3039027511204, 2363.550925324183, 2402.3513651314133, 2406.4585468269674, 2056.7634722006937, 2403.3631087761755, 2414.360194548446, 2419.697537807774, 2422.0553983946274, 2345.7771511706446, 2353.5410594933996, 2366.5740166769974, 2393.2102406205995, 2407.6268178432283, 2073.2950796729033, 2302.1482051640833, 2316.6177580699164, 2334.1480359668767, 2385.1294277588554, 1955.589326241491, 2254.109626697462, 2264.0, 2267.5238199978025, 2269.727632684072, 8947.955260223722, 9027.954860225656, 9039.45763254207, 9068.909310906873, 9084.727458176265, 5201.687898726089, 5377.0321342158395, 5378.623496355218, 5392.649477783932, 5394.757235924393, 3059.4340047091137, 3443.2424866529323, 3526.964730352715, 3623.691986181178, 3649.4160934250394, 1052.2003277509148, 1052.69998050556, 1058.2645061682133, 1072.5388083124253, 1073.683263437288, 11904.0, 11916.285022898566, 11920.582779602759, 11926.642200733992, 11931.522739090426, 4617.03042361108, 4635.605973492291, 4853.708777473293, 4857.878553036202, 4862.003289325679, 10198.898011019943, 10200.591976321013, 10202.591896324064, 10218.386896786216, 10237.385756854612, 5400.162974738922, 5413.756380962866, 5415.321250412385, 5423.3491980962535, 5424.484673955968, 4296.37702533131, 4474.39595654589, 4630.53694630541, 4796.472388037313, 4954.355933728664, 3981.5222173339384, 4003.6797056236014, 4029.9395509067385, 4111.342185250345, 4142.937855932103, 1290.0389210038581, 1293.8318018657703, 1299.5971248912867, 1311.5016293808292, 1311.7048664050512, 672.5460314287836, 836.6235194162651, 938.5401153434768, 960.415314730686, 977.4965892565334, 5652.406497317805, 5718.599698021182, 6078.057901025348, 6083.69581520931, 6273.686315684197, 955.1260596554075, 977.2914636888341, 983.5524836199512, 983.5770618634056, 984.9852252216223, 3920.4511368408444, 4057.5536690963972, 4066.4916885389325, 4113.979430102891, 4170.47869016373, 9548.18840398574, 11083.390413527333, 11088.722781930355, 11099.389533575732, 11108.833367499494, 10911.945440272748, 10958.890411095947, 11054.778904422028, 11065.391403472726, 11074.280171788809, 8343.249107580346, 8423.452475589067, 8437.282830959315, 8446.197611226924, 8446.197611226924, 5205.505476979681, 6112.205413296421, 6234.3453937337745, 6322.178116844781, 6336.6533369762665, 9825.213982881496, 9924.702258932244, 10223.28437009432, 10223.642172523925, 10318.948405257926, 10223.0, 10366.637167699095, 10411.791764164826, 10443.895561044292, 10598.470076496298, 7607.657655405409, 7741.651625676745, 7756.457048006811, 7761.573113478813, 7773.727919522789, 8016.238457346542, 8032.397570182143, 8106.8783968241705, 8168.0, 8235.13531079228, 7417.777466676009, 7678.8080297993, 7684.500507466997, 7807.375409967075, 7842.137364889972, 7420.666070026754, 7536.698532058876, 7592.962035189788, 7656.464047516621, 7794.376449883882, 7113.217546070032, 7175.174854891546, 7178.892316615359, 7182.317679820618, 7191.137063552407, 4898.828541001048, 4902.852914412573, 5084.720340381166, 5165.896682066413, 5198.766055527435, 5177.016366890279, 5201.1678131498775, 5234.450382709761, 5262.97368513155, 5327.760250788646], "value": 4641.540755765667, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [306.9585605943197, 309.3009797856844, 318.4490830862607, 324.98700051997923, 326.508604550152, 4623.907521849561, 5129.6409251352325, 5375.0, 5409.350877894653, 5443.176535860482, 4513.3455648930885, 5367.141257398817, 5373.032854086263, 5405.729713514328, 5418.674879507223, 3685.613010633885, 4119.732217405874, 4154.896127596804, 4163.791810409475, 4184.7907604619795, 3485.9477107843404, 3770.7926064066464, 4166.791660416982, 4180.372944058385, 4196.3705444183315, 3777.3578491656485, 3939.5272567291963, 4100.85647002355, 4114.506259248894, 4152.501699796028, 3650.6166852480374, 3999.8600048998146, 4043.575424580406, 4107.732997355162, 4132.359484279941, 3065.862036208376, 3155.5739975103525, 3593.460980852893, 3611.1694310308512, 3632.691221246197, 4969.975150124263, 5243.187305967581, 5285.550728188108, 5298.814541491068, 5361.828459473881, 3747.756395834262, 4465.307877279027, 4672.883177920545, 4755.595774359149, 4787.904241915142, 4767.523247675204, 5473.589480788957, 5707.657540547539, 5740.282464691913, 5768.971155144198, 3342.649021852719, 3702.740808143431, 3801.2967600993907, 3975.60243975603, 3978.428520734323, 4861.003494283698, 5820.708964551798, 5964.493018093423, 5968.67172305523, 5982.521398288127, 3860.3630400983525, 4026.134381108076, 4040.2727509048655, 4047.494063242095, 4073.0, 3503.404421248394, 3655.744097913174, 3833.9808300958316, 3949.6050394960275, 4091.959080409217, 4408.713433626804, 4559.540946896961, 4569.794359253841, 4575.382323386367, 4608.792604332813, 5008.847964968078, 5607.52336051432, 5652.0391533439415, 5685.260916080883, 5717.914231286534, 1326.7346530693899, 1359.9252041137634, 1370.869767372098, 1381.1022835157228, 1382.7925811128412, 713.4791602130371, 733.5891900535611, 745.5750222373196, 860.264473874828, 867.6659486097898, 4340.784580317516, 4415.2935530315, 4437.934895625027, 4439.4228750262255, 4485.842995495142, 4306.74159550428, 4389.0, 4651.62786977041, 4787.39622226558, 4811.789684026477, 3786.1258676955085, 4081.816228317836, 4370.164530896967, 4399.73601583906, 4488.3940668010055, 2286.622707253318, 2298.8735619540753, 2350.7296660884217, 2517.1315896015844, 2546.630738542901, 4906.552566992746, 4934.67924584908, 4950.118954797154, 4972.950270497321, 4983.903541220926, 4418.911621767546, 4641.396618439581, 4642.237868468256, 4749.126778299148, 5008.372279009263, 4236.6187043166265, 4244.469441319835, 4664.860054198311, 4992.950070499393, 5089.302669585126, 4556.749378784066, 4689.514557284645, 4704.741239231738, 4732.455767586634, 4765.928511072406, 4139.523954745243, 4675.779004972611, 4700.226866148989, 4832.468428472936, 4843.46721860588, 3922.490076290064, 4388.51726310112, 5029.164355010468, 5088.59291256706, 5092.796288148581, 3048.8018278811805, 3488.755787094879, 3496.7202623789526, 3505.789652620851, 3525.735569832324, 4463.843765468193, 4525.660575456788, 4733.650909490779, 4779.402574678165, 4808.6153107752, 4345.739255644671, 4362.498312693954, 4518.480374756945, 4553.271476563734, 4782.976085119552, 4039.4748682671066, 4572.588467037983, 4671.626269898332, 4727.565303868441, 4774.665773395968, 4075.4090656853996, 4262.7016108872085, 4323.702889133185, 4451.175018242505, 4606.401167848158, 4130.876073717794, 4192.4759405074365, 4245.9363109554, 4488.932666009947, 4722.669413141184, 3970.80145992705, 4189.4344263524035, 4549.4278250358175, 4604.907901841877, 4675.812967481263], "value": 3765.033401024276, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [736.7568702328232, 748.9625518724063, 769.5267410542518, 852.5737131434283, 863.0980625246617, 17706.645867082665, 18249.908750456263, 18275.634487310246, 18299.54251143722, 18376.816231837693, 17214.655706885856, 17259.913700431513, 17406.0, 17406.39077632283, 17445.651086978283, 718.5221827484717, 886.5965985476593, 904.0913881549067, 905.316486053029, 908.7364664247393, 731.6159016516318, 879.3492815316683, 912.7170577121113, 915.1351972386097, 916.3173435790345, 933.7898972731149, 945.0927109974433, 947.554649314821, 948.6347756113877, 949.2453499467939, 749.1459735901077, 773.6634563964693, 937.9812403751887, 940.2431043010374, 941.8869735631733, 570.5179123640518, 571.9656820590778, 686.128616656848, 707.3598393453881, 712.1596516110988, 15615.921920390325, 15622.609434764108, 15658.0, 15661.608459788482, 15666.373345066184, 13843.30783460833, 13904.235267060307, 13958.720825583432, 14084.22536760488, 14125.929370353082, 12384.75230495385, 12469.189502682388, 13081.803772943318, 13402.664933376645, 13874.861251387458, 4558.544145585415, 4619.838305659286, 4633.1660301145785, 4637.559431853969, 4791.209450440673, 107755.0, 108942.28461723123, 110354.44822775915, 110513.0, 111754.44122779416, 10840.030235518752, 10895.83656245157, 11026.889731102747, 11078.612248571262, 11255.943720281346, 18280.542986425447, 21532.354029379145, 21547.784522154892, 21570.35288941334, 21573.784262157493, 7953.12515623281, 7964.681412743425, 8019.037715474064, 8026.83946321082, 8143.83712325762, 4501.819927202875, 4519.932201016987, 4553.5218802025975, 4614.469336026333, 4708.929366059512, 3362.815045172539, 3460.602030766494, 3466.8959931202103, 3469.6703813138215, 3472.461768425898, 624.709510077816, 630.1870586942792, 634.9936500634934, 643.7521554201572, 655.0501772429996, 574.9880210828971, 605.6305653551331, 638.814743724324, 642.8489305013318, 653.7940548727231, 8780.121987801169, 8976.68581599654, 9006.189442950166, 9066.138716821892, 9226.953865230631, 557.4202829057815, 615.7813976038442, 633.1922361657494, 644.1239913717341, 650.4893658478072, 4392.846250381222, 4442.133783912106, 4449.3326001100095, 4467.975206611565, 4562.224421848294, 131639.34180329036, 132657.33671331775, 136282.3185884084, 137386.0, 140809.0, 109853.0, 112302.43848780867, 112627.43686281516, 113373.4331328338, 113730.43134784438, 97470.58852940255, 97561.65849219778, 97898.510507447, 98288.01711982931, 102202.48898755459, 8398.096511268333, 8481.194286542768, 8589.44168629037, 8718.782030449289, 8899.777505562413, 55721.164182536486, 56169.0, 57398.0, 57469.0, 60093.39906601053, 85680.13030289426, 89513.55243223743, 89676.0, 90496.54751726199, 91780.54109729409, 11225.71935701614, 11407.714807129889, 11427.60003399877, 11590.826137608112, 11815.468303926516, 12486.616171630336, 12590.622281331574, 12702.872971270539, 12723.427445764777, 12963.15739476931, 11678.24091434054, 11802.232854864405, 11874.940625297164, 12002.459889304828, 12012.879871201525, 10865.239433239914, 11518.136139789716, 11902.285862848257, 11990.460429280529, 12218.877811221773, 11448.885511145116, 11555.357231394915, 11645.243059201124, 11788.823167652663, 12186.817197741864, 10028.0, 10110.494475276353, 10156.39061656303, 10160.33957792741, 10513.737156570842, 9787.265955053543, 9912.058354456316, 10096.242781791248, 11200.439978000904, 11785.646430607096], "value": 7674.702389887928, "unit": "score"}], "name": "dromaeo_css", "value": 7372.433570895692, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 113.0, 116.0, 110.0, 113.0, 113.0, 113.0, 113.0, 114.0, 113.0], "value": 111.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 107.0, 107.0, 157.0, 107.0, 106.0, 128.0, 107.0, 139.0, 107.0], "value": 120.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 187.0, 185.0, 187.0, 184.0, 185.0, 181.0, 188.0, 184.0, 184.0], "value": 184.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 75.0, 58.0, 105.0, 57.0, 57.0, 63.0, 64.0, 93.0, 56.0], "value": 70.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 89.0, 92.0, 70.0, 70.0, 67.0, 95.0, 83.0, 89.0, 121.0], "value": 86.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 88.0, 85.0, 88.0, 84.0, 88.0, 86.0, 88.0, 126.0], "value": 90.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 94.0, 95.0, 94.0, 95.0, 94.0, 94.0, 97.0, 94.0, 93.0], "value": 94.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 84.0, 85.0, 84.0, 85.0, 87.0, 86.0, 86.0, 86.0], "value": 85.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 53.0, 54.0, 55.0, 55.0, 54.0, 54.0, 53.0, 53.0, 53.0], "value": 53.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 71.0, 84.0, 79.0, 74.0, 70.0, 73.0, 71.0, 71.0, 74.0], "value": 73.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 82.0, 79.0, 93.0, 93.0, 93.0, 95.0, 79.0, 82.0, 84.0], "value": 86.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 136.0, 134.0, 157.0, 128.0, 143.0, 140.0, 139.0, 226.0, 133.0], "value": 146.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 163.0, 164.0, 163.0, 170.0, 218.0, 174.0, 160.0, 163.0, 165.0], "value": 170.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 66.0, 66.0, 63.0, 66.0, 65.0, 67.0, 67.0, 64.0, 66.0], "value": 65.4, "unit": "score"}], "name": "kraken", "value": 1441.3000000000002, "alertThreshold": 2.0}]} 19:12:29 INFO - Return code: 0 19:12:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:12:29 INFO - # TBPL SUCCESS # 19:12:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:12:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:12:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:12:29 INFO - 2016-07-13 19:12:29.542 screenresolution[1681:10053] starting screenresolution argv=screenresolution get 19:12:29 INFO - 2016-07-13 19:12:29.545 screenresolution[1681:10053] Display 0: 1600x1200x32@60 19:12:29 INFO - 2016-07-13 19:12:29.551 screenresolution[1682:10056] starting screenresolution argv=screenresolution list 19:12:29 INFO - Available Modes on Display 0 19:12:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:12:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:12:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:12:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:12:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:12:29 INFO - Intel Iris: 19:12:29 INFO - Chipset Model: Intel Iris 19:12:29 INFO - Type: GPU 19:12:29 INFO - Bus: Built-In 19:12:29 INFO - VRAM (Dynamic, Max): 1536 MB 19:12:29 INFO - Vendor: Intel (0x8086) 19:12:29 INFO - Device ID: 0x0a2e 19:12:29 INFO - Revision ID: 0x0009 19:12:29 INFO - Displays: 19:12:29 INFO - Display: 19:12:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:12:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:12:29 INFO - Display Serial Number: 1600x1200 60 19:12:29 INFO - Main Display: Yes 19:12:29 INFO - Mirror: Off 19:12:29 INFO - Online: Yes 19:12:29 INFO - Rotation: Supported 19:12:29 INFO - Return code: 0 19:12:29 INFO - Running post-action listener: _resource_record_post_action 19:12:29 INFO - ##### 19:12:29 INFO - ##### Finished run-tests step (success) 19:12:29 INFO - ##### 19:12:29 INFO - Running post-run listener: _resource_record_post_run 19:12:29 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 32484864; Write bytes: 704093184; Read time: 322; Write time: 1857 19:12:29 INFO - TinderboxPrint: CPU usage
53.9% 19:12:29 INFO - TinderboxPrint: I/O read bytes / time
32,484,864 / 322 19:12:29 INFO - TinderboxPrint: I/O write bytes / time
704,093,184 / 1,857 19:12:29 INFO - TinderboxPrint: CPU idle
2,233.2 (46.1%) 19:12:29 INFO - TinderboxPrint: CPU system
970.8 (20.0%) 19:12:29 INFO - TinderboxPrint: CPU user
1,641.7 (33.9%) 19:12:29 INFO - TinderboxPrint: Swap in / out
435,572,736 / 0 19:12:29 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 407298560; Write bytes: 399369728; Read time: 31041; Write time: 707 19:12:29 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 28995072; Write bytes: 292939264; Read time: 289; Write time: 1116 19:12:30 INFO - Running post-run listener: _upload_blobber_files 19:12:30 INFO - Blob upload gear active. 19:12:30 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:12:30 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 19:12:30 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:12:30 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:12:30 INFO - (blobuploader) - INFO - Open directory for files ... 19:12:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:12:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:12:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:12:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:12:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:12:31 INFO - (blobuploader) - INFO - Done attempting. 19:12:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:12:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:12:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:12:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:12:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:12:32 INFO - (blobuploader) - INFO - Done attempting. 19:12:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:12:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:12:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:12:35 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:12:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:12:35 INFO - (blobuploader) - INFO - Done attempting. 19:12:35 INFO - (blobuploader) - INFO - Iteration through files over. 19:12:35 INFO - Return code: 0 19:12:35 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:12:35 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:12:35 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d2327c483584b5e837e79f83147d0c659ac1fb16d85a90a9c6a1b2a2d4a565157b242a4a99a6c9ea54dcb09f4c7a619f7725e30e2cd216a997980fd860c3ef36", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9e25f20f018cf0107bdd531e9066a2ee83fbe2c289f9e75a85a2d73dd0b511652c9382346fd2b9c573c09b6c9fb95a0877605d03ca450e9936a779e2c60c47d9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b7a80da3af351d526bb6d5b2e155371288ca0e01715f2e892895b3c8531036b7e025ba86a0f008cf179c6443eb8c969c8b1779b15f44287d990b3892a5745b20"} 19:12:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:12:35 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:12:35 INFO - Contents: 19:12:35 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d2327c483584b5e837e79f83147d0c659ac1fb16d85a90a9c6a1b2a2d4a565157b242a4a99a6c9ea54dcb09f4c7a619f7725e30e2cd216a997980fd860c3ef36", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9e25f20f018cf0107bdd531e9066a2ee83fbe2c289f9e75a85a2d73dd0b511652c9382346fd2b9c573c09b6c9fb95a0877605d03ca450e9936a779e2c60c47d9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b7a80da3af351d526bb6d5b2e155371288ca0e01715f2e892895b3c8531036b7e025ba86a0f008cf179c6443eb8c969c8b1779b15f44287d990b3892a5745b20"} 19:12:35 INFO - Running post-run listener: copy_logs_to_upload_dir 19:12:35 INFO - Copying logs to upload dir... 19:12:35 INFO - mkdir: /builds/slave/test/build/upload/logs 19:12:35 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.714803 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-07-13 19:12:35.880404) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-13 19:12:35.884428) ========= 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.LqGhbrBono/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.JgLK95z5xc/Listeners TMPDIR=/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d2327c483584b5e837e79f83147d0c659ac1fb16d85a90a9c6a1b2a2d4a565157b242a4a99a6c9ea54dcb09f4c7a619f7725e30e2cd216a997980fd860c3ef36", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9e25f20f018cf0107bdd531e9066a2ee83fbe2c289f9e75a85a2d73dd0b511652c9382346fd2b9c573c09b6c9fb95a0877605d03ca450e9936a779e2c60c47d9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b7a80da3af351d526bb6d5b2e155371288ca0e01715f2e892895b3c8531036b7e025ba86a0f008cf179c6443eb8c969c8b1779b15f44287d990b3892a5745b20"} build_url:https://queue.taskcluster.net/v1/task/R_8FiZH1RjuZQ02dNXyHVQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010946 build_url: 'https://queue.taskcluster.net/v1/task/R_8FiZH1RjuZQ02dNXyHVQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d2327c483584b5e837e79f83147d0c659ac1fb16d85a90a9c6a1b2a2d4a565157b242a4a99a6c9ea54dcb09f4c7a619f7725e30e2cd216a997980fd860c3ef36", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9e25f20f018cf0107bdd531e9066a2ee83fbe2c289f9e75a85a2d73dd0b511652c9382346fd2b9c573c09b6c9fb95a0877605d03ca450e9936a779e2c60c47d9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b7a80da3af351d526bb6d5b2e155371288ca0e01715f2e892895b3c8531036b7e025ba86a0f008cf179c6443eb8c969c8b1779b15f44287d990b3892a5745b20"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-13 19:12:35.916472) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 19:12:35.916776) ========= 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.LqGhbrBono/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.JgLK95z5xc/Listeners TMPDIR=/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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.005091 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 19:12:35.967247) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-13 19:12:35.967926) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-13 19:12:35.968605) ========= ========= Total master_lag: 0.56 =========