builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0088 starttime: 1467229206.86 results: success (0) buildid: 20160629105128 builduid: e96829a52d4c4dbba2b1592de22250a1 revision: 56fc33e6b14eb446916cdf28edee82df4c548082 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-29 12:40:06.857600) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-29 12:40:06.858112) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-29 12:40:06.858410) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-29 12:40:06.883400) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 12:40:06.883822) ========= 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.VmHeM4MBQX/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.ZSls3JCIBK/Listeners TMPDIR=/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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-06-29 12:40:06-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 6.82M=0.002s 2016-06-29 12:40:06 (6.82 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.108762 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 12:40:07.015205) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 12:40:07.015521) ========= 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.VmHeM4MBQX/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.ZSls3JCIBK/Listeners TMPDIR=/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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.031197 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 12:40:07.067634) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-06-29 12:40:07.068123) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 56fc33e6b14eb446916cdf28edee82df4c548082 --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 56fc33e6b14eb446916cdf28edee82df4c548082 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.VmHeM4MBQX/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.ZSls3JCIBK/Listeners TMPDIR=/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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-06-29 12:40:07,141 truncating revision to first 12 chars 2016-06-29 12:40:07,141 Setting DEBUG logging. 2016-06-29 12:40:07,141 attempt 1/10 2016-06-29 12:40:07,141 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/56fc33e6b14e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-29 12:40:08,938 unpacking tar archive at: fx-team-56fc33e6b14e/testing/mozharness/ program finished with exit code 0 elapsedTime=2.056727 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-06-29 12:40:09.148931) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-29 12:40:09.149478) ========= script_repo_revision: 56fc33e6b14eb446916cdf28edee82df4c548082 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-29 12:40:09.150115) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-29 12:40:09.150558) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-29 12:40:09.170441) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-06-29 12:40:09.170899) ========= /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.VmHeM4MBQX/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.ZSls3JCIBK/Listeners TMPDIR=/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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 12:40:09 INFO - MultiFileLogger online at 20160629 12:40:09 in /builds/slave/test 12:40:09 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 12:40:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:40:09 INFO - {'append_to_log': False, 12:40:09 INFO - 'base_work_dir': '/builds/slave/test', 12:40:09 INFO - 'blob_upload_branch': 'Fx-Team', 12:40:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:40:09 INFO - 'branch': 'Fx-Team', 12:40:09 INFO - 'buildbot_json_path': 'buildprops.json', 12:40:09 INFO - 'config_files': ('talos/mac_config.py',), 12:40:09 INFO - 'default_actions': ('clobber', 12:40:09 INFO - 'read-buildbot-config', 12:40:09 INFO - 'download-and-extract', 12:40:09 INFO - 'populate-webroot', 12:40:09 INFO - 'create-virtualenv', 12:40:09 INFO - 'install', 12:40:09 INFO - 'run-tests'), 12:40:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:40:09 INFO - 'download_minidump_stackwalk': True, 12:40:09 INFO - 'download_symbols': 'ondemand', 12:40:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:40:09 INFO - 'tooltool.py': '/tools/tooltool.py', 12:40:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:40:09 INFO - '/tools/misc-python/virtualenv.py')}, 12:40:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:40:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:40:09 INFO - 'installer_path': 'installer.exe', 12:40:09 INFO - 'log_level': 'info', 12:40:09 INFO - 'log_name': 'talos', 12:40:09 INFO - 'log_to_console': True, 12:40:09 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:40:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:40:09 INFO - 'opt_config_files': (), 12:40:09 INFO - 'pip_index': False, 12:40:09 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:40:09 INFO - 'cmd': ('bash', 12:40:09 INFO - '-c', 12:40:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:40:09 INFO - 'enabled': True, 12:40:09 INFO - 'halt_on_failure': False, 12:40:09 INFO - 'name': 'check_screen_resolution'},), 12:40:09 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:40:09 INFO - 'cmd': ('bash', 12:40:09 INFO - '-c', 12:40:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:40:09 INFO - 'enabled': True, 12:40:09 INFO - 'halt_on_failure': False, 12:40:09 INFO - 'name': 'check_screen_resolution'},), 12:40:09 INFO - 'run_cmd_checks_enabled': True, 12:40:09 INFO - 'sps_profile': False, 12:40:09 INFO - 'sps_profile_interval': 0, 12:40:09 INFO - 'suite': 'dromaeojs', 12:40:09 INFO - 'system_bits': '32', 12:40:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:40:09 INFO - 'title': 't-yosemite-r7-0088', 12:40:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:40:09 INFO - 'use_talos_json': True, 12:40:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:40:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:40:09 INFO - 'work_dir': 'build'} 12:40:09 INFO - ##### 12:40:09 INFO - ##### Running clobber step. 12:40:09 INFO - ##### 12:40:09 INFO - Running pre-action listener: _resource_record_pre_action 12:40:09 INFO - Running main action method: clobber 12:40:09 INFO - rmtree: /builds/slave/test/build 12:40:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:40:11 INFO - Running post-action listener: _resource_record_post_action 12:40:11 INFO - ##### 12:40:11 INFO - ##### Finished clobber step (success) 12:40:11 INFO - ##### 12:40:11 INFO - ##### 12:40:11 INFO - ##### Running read-buildbot-config step. 12:40:11 INFO - ##### 12:40:11 INFO - Running pre-action listener: _resource_record_pre_action 12:40:11 INFO - Running main action method: read_buildbot_config 12:40:11 INFO - Using buildbot properties: 12:40:11 INFO - { 12:40:11 INFO - "project": "", 12:40:11 INFO - "product": "firefox", 12:40:11 INFO - "script_repo_revision": "production", 12:40:11 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 12:40:11 INFO - "repository": "", 12:40:11 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 12:40:11 INFO - "buildid": "20160629105128", 12:40:11 INFO - "pgo_build": "False", 12:40:11 INFO - "basedir": "/builds/slave/test", 12:40:11 INFO - "buildnumber": 830, 12:40:11 INFO - "slavename": "t-yosemite-r7-0088", 12:40:11 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 12:40:11 INFO - "platform": "yosemite_r7", 12:40:11 INFO - "scheduler": "tests-fx-team-macosx64-talos", 12:40:11 INFO - "branch": "fx-team", 12:40:11 INFO - "repo_path": "integration/fx-team", 12:40:11 INFO - "revision": "56fc33e6b14eb446916cdf28edee82df4c548082", 12:40:11 INFO - "stage_platform": "macosx64", 12:40:11 INFO - "builduid": "e96829a52d4c4dbba2b1592de22250a1", 12:40:11 INFO - "slavebuilddir": "test" 12:40:11 INFO - } 12:40:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/Dh2h0gYgSLq8DWVcBY81tw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 12:40:11 INFO - Running post-action listener: _resource_record_post_action 12:40:11 INFO - ##### 12:40:11 INFO - ##### Finished read-buildbot-config step (success) 12:40:11 INFO - ##### 12:40:11 INFO - ##### 12:40:11 INFO - ##### Running download-and-extract step. 12:40:11 INFO - ##### 12:40:11 INFO - Running pre-action listener: _resource_record_pre_action 12:40:11 INFO - Running main action method: download_and_extract 12:40:11 INFO - mkdir: /builds/slave/test/build/tests 12:40:11 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')]} 12:40:11 INFO - https://queue.taskcluster.net/v1/task/Dh2h0gYgSLq8DWVcBY81tw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 12:40:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dh2h0gYgSLq8DWVcBY81tw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 12:40:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dh2h0gYgSLq8DWVcBY81tw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 12:40:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dh2h0gYgSLq8DWVcBY81tw/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 12:40:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dh2h0gYgSLq8DWVcBY81tw/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 12:40:12 INFO - Downloaded 1199 bytes. 12:40:12 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 12:40:12 INFO - Using the following test package requirements: 12:40:12 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 12:40:12 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:40:12 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 12:40:12 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:40:12 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 12:40:12 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:40:12 INFO - u'jsshell-mac.zip'], 12:40:12 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:40:12 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 12:40:12 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 12:40:12 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:40:12 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 12:40:12 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:40:12 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 12:40:12 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:40:12 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 12:40:12 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:40:12 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 12:40:12 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:40: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')]} 12:40:12 INFO - https://queue.taskcluster.net/v1/task/Dh2h0gYgSLq8DWVcBY81tw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:40:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dh2h0gYgSLq8DWVcBY81tw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 12:40:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dh2h0gYgSLq8DWVcBY81tw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 12:40:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dh2h0gYgSLq8DWVcBY81tw/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 12:40:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dh2h0gYgSLq8DWVcBY81tw/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 12:40:13 INFO - Downloaded 18788450 bytes. 12:40:13 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'] 12:40:13 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 12:40:16 INFO - Return code: 0 12:40:16 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 12:40:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:40:16 INFO - https://queue.taskcluster.net/v1/task/Dh2h0gYgSLq8DWVcBY81tw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:40:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dh2h0gYgSLq8DWVcBY81tw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 12:40:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dh2h0gYgSLq8DWVcBY81tw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 12:40:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dh2h0gYgSLq8DWVcBY81tw/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 12:40:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dh2h0gYgSLq8DWVcBY81tw/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 12:40:17 INFO - Downloaded 18788450 bytes. 12:40:17 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'] 12:40:17 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 12:40:21 INFO - Return code: 0 12:40:21 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')]} 12:40:21 INFO - https://queue.taskcluster.net/v1/task/Dh2h0gYgSLq8DWVcBY81tw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 12:40:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dh2h0gYgSLq8DWVcBY81tw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 12:40:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dh2h0gYgSLq8DWVcBY81tw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 12:40:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dh2h0gYgSLq8DWVcBY81tw/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 12:40:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dh2h0gYgSLq8DWVcBY81tw/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 12:40:22 INFO - Downloaded 14405245 bytes. 12:40:22 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'] 12:40:22 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 12:40:23 INFO - Return code: 0 12:40:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:40:23 INFO - https://queue.taskcluster.net/v1/task/Dh2h0gYgSLq8DWVcBY81tw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 12:40:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dh2h0gYgSLq8DWVcBY81tw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 12:40:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dh2h0gYgSLq8DWVcBY81tw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 12:40:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dh2h0gYgSLq8DWVcBY81tw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 12:40:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dh2h0gYgSLq8DWVcBY81tw/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 12:40:26 INFO - Downloaded 112467155 bytes. 12:40:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Dh2h0gYgSLq8DWVcBY81tw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 12:40:26 INFO - mkdir: /builds/slave/test/properties 12:40:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:40:26 INFO - Writing to file /builds/slave/test/properties/build_url 12:40:26 INFO - Contents: 12:40:26 INFO - build_url:https://queue.taskcluster.net/v1/task/Dh2h0gYgSLq8DWVcBY81tw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 12:40:26 INFO - Running post-action listener: _resource_record_post_action 12:40:26 INFO - Running post-action listener: set_extra_try_arguments 12:40:26 INFO - ##### 12:40:26 INFO - ##### Finished download-and-extract step (success) 12:40:26 INFO - ##### 12:40:26 INFO - ##### 12:40:26 INFO - ##### Running populate-webroot step. 12:40:26 INFO - ##### 12:40:26 INFO - Running pre-action listener: _resource_record_pre_action 12:40:26 INFO - Running main action method: populate_webroot 12:40:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:40:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:40:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:40:26 INFO - '--tppagecycles', 12:40:26 INFO - '7'], 12:40:26 INFO - 'tests': ['tsvgm']}}, 12:40:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:40:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:40:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:40:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:40:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:40:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:40:26 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:40:26 INFO - 'talos_options': ['--disable-e10s'], 12:40:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:40:26 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:40:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:40:26 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:40:26 INFO - 'talos_options': ['--disable-e10s'], 12:40:26 INFO - 'tests': ['damp', 'tps']}, 12:40:26 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:40:26 INFO - 'tests': ['damp', 'tps']}, 12:40:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:40:26 INFO - 'tests': ['dromaeo_dom']}, 12:40:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:40:26 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:40:26 INFO - 'tests': ['basic_compositor_video']}, 12:40:26 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:40:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:40:26 INFO - 'tests': ['a11yr', 12:40:26 INFO - 'ts_paint', 12:40:26 INFO - 'tpaint', 12:40:26 INFO - 'sessionrestore', 12:40:26 INFO - 'sessionrestore_no_auto_restore', 12:40:26 INFO - 'tabpaint']}, 12:40:26 INFO - 'other-e10s': {'tests': ['a11yr', 12:40:26 INFO - 'ts_paint', 12:40:26 INFO - 'tpaint', 12:40:26 INFO - 'sessionrestore', 12:40:26 INFO - 'sessionrestore_no_auto_restore', 12:40:26 INFO - 'tabpaint']}, 12:40:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:40:26 INFO - 'ts_paint', 12:40:26 INFO - 'tpaint', 12:40:26 INFO - 'sessionrestore', 12:40:26 INFO - 'sessionrestore_no_auto_restore', 12:40:26 INFO - 'tabpaint']}, 12:40:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:40:26 INFO - 'ts_paint', 12:40:26 INFO - 'tpaint', 12:40:26 INFO - 'sessionrestore', 12:40:26 INFO - 'sessionrestore_no_auto_restore', 12:40:26 INFO - 'tabpaint']}, 12:40:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:40:26 INFO - 'tests': ['a11yr', 12:40:26 INFO - 'ts_paint', 12:40:26 INFO - 'tpaint', 12:40:26 INFO - 'sessionrestore', 12:40:26 INFO - 'sessionrestore_no_auto_restore', 12:40:26 INFO - 'tabpaint']}, 12:40:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:40:26 INFO - 'tests': ['a11yr', 12:40:26 INFO - 'ts_paint', 12:40:26 INFO - 'tpaint', 12:40:26 INFO - 'sessionrestore', 12:40:26 INFO - 'sessionrestore_no_auto_restore', 12:40:26 INFO - 'tabpaint']}, 12:40:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:40:26 INFO - 'tests': ['tsvgx', 12:40:26 INFO - 'tsvgr_opacity', 12:40:26 INFO - 'tart', 12:40:26 INFO - 'tscrollx', 12:40:26 INFO - 'cart']}, 12:40:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:40:26 INFO - 'tsvgr_opacity', 12:40:26 INFO - 'tart', 12:40:26 INFO - 'tscrollx', 12:40:26 INFO - 'cart']}, 12:40:26 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:40:26 INFO - 'talos_options': ['--disable-e10s'], 12:40:26 INFO - 'tests': ['tp5o']}, 12:40:26 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:40:26 INFO - 'tests': ['tp5o']}, 12:40:26 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:40:26 INFO - 'talos_options': ['--disable-e10s', 12:40:26 INFO - '--xperf_path', 12:40:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:40:26 INFO - 'tests': ['tp5n']}, 12:40:26 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:40:26 INFO - 'talos_options': ['--xperf_path', 12:40:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:40:26 INFO - 'tests': ['tp5n']}}, 12:40:26 INFO - 'talos.zip': {'path': '', 12:40:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:40:26 INFO - Running post-action listener: _resource_record_post_action 12:40:26 INFO - ##### 12:40:26 INFO - ##### Finished populate-webroot step (success) 12:40:26 INFO - ##### 12:40:26 INFO - ##### 12:40:26 INFO - ##### Running create-virtualenv step. 12:40:26 INFO - ##### 12:40:26 INFO - Running pre-action listener: _resource_record_pre_action 12:40:26 INFO - Running main action method: create_virtualenv 12:40:26 INFO - Creating virtualenv /builds/slave/test/build/venv 12:40:26 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 12:40:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:40:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:40:26 INFO - Using real prefix '/tools/python27' 12:40:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:40:27 INFO - Installing distribute.............................................................................................................................................................................................done. 12:40:30 INFO - Installing pip.................done. 12:40:30 INFO - Return code: 0 12:40:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:40: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')]} 12:40:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:40:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40: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')]} 12:40:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:40:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40:30 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 0x10c223a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2b8618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7e0497680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c187d30>, '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 0x10beecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c17fb20>, '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.VmHeM4MBQX/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.ZSls3JCIBK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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 12:40:30 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 12:40:30 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 12:40:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VmHeM4MBQX/Render', 12:40:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:40:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:40:30 INFO - 'HOME': '/Users/cltbld', 12:40:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:40:30 INFO - 'LOGNAME': 'cltbld', 12:40:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:40:30 INFO - 'MOZ_NO_REMOTE': '1', 12:40:30 INFO - 'NO_EM_RESTART': '1', 12:40:30 INFO - 'PAGER': '/bin/cat', 12:40:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:40:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:40:30 INFO - 'PWD': '/builds/slave/test', 12:40:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:40:30 INFO - 'SHELL': '/bin/bash', 12:40:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZSls3JCIBK/Listeners', 12:40:30 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 12:40:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:40:30 INFO - 'USER': 'cltbld', 12:40:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:40:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:40:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:40:30 INFO - 'XPC_FLAGS': '0x0', 12:40:30 INFO - 'XPC_SERVICE_NAME': '0', 12:40:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:40:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:40:30 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 12:40:30 INFO - Cleaning up... 12:40:30 INFO - Return code: 0 12:40:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:40: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')]} 12:40:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:40:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40: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')]} 12:40:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:40:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40:30 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 0x10c223a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2b8618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7e0497680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c187d30>, '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 0x10beecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c17fb20>, '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.VmHeM4MBQX/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.ZSls3JCIBK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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 12:40:30 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 12:40:30 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 12:40:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VmHeM4MBQX/Render', 12:40:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:40:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:40:30 INFO - 'HOME': '/Users/cltbld', 12:40:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:40:30 INFO - 'LOGNAME': 'cltbld', 12:40:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:40:30 INFO - 'MOZ_NO_REMOTE': '1', 12:40:30 INFO - 'NO_EM_RESTART': '1', 12:40:30 INFO - 'PAGER': '/bin/cat', 12:40:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:40:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:40:30 INFO - 'PWD': '/builds/slave/test', 12:40:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:40:30 INFO - 'SHELL': '/bin/bash', 12:40:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZSls3JCIBK/Listeners', 12:40:30 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 12:40:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:40:30 INFO - 'USER': 'cltbld', 12:40:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:40:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:40:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:40:30 INFO - 'XPC_FLAGS': '0x0', 12:40:30 INFO - 'XPC_SERVICE_NAME': '0', 12:40:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:40:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:40:31 INFO - Downloading/unpacking psutil>=3.1.1 12:40:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:40:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:40:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:40:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:40:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:40:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:40:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:40:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:40:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:40:34 INFO - Installing collected packages: psutil 12:40:34 INFO - Running setup.py install for psutil 12:40:34 INFO - building 'psutil._psutil_osx' extension 12:40:34 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 12:40:34 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 12:40:34 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 12:40:34 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 12:40:34 INFO - building 'psutil._psutil_posix' extension 12:40:34 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 12:40:35 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:40:35 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:40:35 INFO - ^ 12:40:35 INFO - 1 warning generated. 12:40:35 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 12:40:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:40:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:40:35 INFO - Successfully installed psutil 12:40:35 INFO - Cleaning up... 12:40:35 INFO - Return code: 0 12:40:35 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:40:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:40:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:40:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:40:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:40:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c223a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2b8618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7e0497680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c187d30>, '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 0x10beecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c17fb20>, '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.VmHeM4MBQX/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.ZSls3JCIBK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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 12:40:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:40:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 12:40:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VmHeM4MBQX/Render', 12:40:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:40:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:40:35 INFO - 'HOME': '/Users/cltbld', 12:40:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:40:35 INFO - 'LOGNAME': 'cltbld', 12:40:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:40:35 INFO - 'MOZ_NO_REMOTE': '1', 12:40:35 INFO - 'NO_EM_RESTART': '1', 12:40:35 INFO - 'PAGER': '/bin/cat', 12:40:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:40:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:40:35 INFO - 'PWD': '/builds/slave/test', 12:40:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:40:35 INFO - 'SHELL': '/bin/bash', 12:40:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZSls3JCIBK/Listeners', 12:40:35 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 12:40:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:40:35 INFO - 'USER': 'cltbld', 12:40:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:40:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:40:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:40:35 INFO - 'XPC_FLAGS': '0x0', 12:40:35 INFO - 'XPC_SERVICE_NAME': '0', 12:40:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:40:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:40:35 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:40:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:40:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:40:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:40:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:40:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:40:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:40:37 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:40:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:40:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:40:38 INFO - Installing collected packages: mozsystemmonitor 12:40:38 INFO - Running setup.py install for mozsystemmonitor 12:40:38 INFO - Successfully installed mozsystemmonitor 12:40:38 INFO - Cleaning up... 12:40:38 INFO - Return code: 0 12:40:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:40:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:40:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:40:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:40:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:40:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40:38 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 0x10c223a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2b8618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7e0497680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c187d30>, '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 0x10beecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c17fb20>, '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.VmHeM4MBQX/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.ZSls3JCIBK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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 12:40:38 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 12:40:38 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 12:40:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VmHeM4MBQX/Render', 12:40:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:40:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:40:38 INFO - 'HOME': '/Users/cltbld', 12:40:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:40:38 INFO - 'LOGNAME': 'cltbld', 12:40:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:40:38 INFO - 'MOZ_NO_REMOTE': '1', 12:40:38 INFO - 'NO_EM_RESTART': '1', 12:40:38 INFO - 'PAGER': '/bin/cat', 12:40:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:40:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:40:38 INFO - 'PWD': '/builds/slave/test', 12:40:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:40:38 INFO - 'SHELL': '/bin/bash', 12:40:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZSls3JCIBK/Listeners', 12:40:38 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 12:40:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:40:38 INFO - 'USER': 'cltbld', 12:40:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:40:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:40:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:40:38 INFO - 'XPC_FLAGS': '0x0', 12:40:38 INFO - 'XPC_SERVICE_NAME': '0', 12:40:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:40:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:40:38 INFO - Downloading/unpacking jsonschema==2.5.1 12:40:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:40:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:40:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:40:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:40:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:40:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:40:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:40:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:40:40 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 12:40:40 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 12:40:40 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 12:40:40 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 12:40:40 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 12:40:40 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 12:40:41 INFO - Downloading functools32-3.2.3-2.tar.gz 12:40:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:40:41 INFO - warning: no files found matching '*.txt' 12:40:41 INFO - no previously-included directories found matching 'build' 12:40:41 INFO - no previously-included directories found matching 'dist' 12:40:41 INFO - no previously-included directories found matching '.git*' 12:40:41 INFO - Installing collected packages: jsonschema, functools32 12:40:41 INFO - Running setup.py install for functools32 12:40:41 INFO - warning: no files found matching '*.txt' 12:40:41 INFO - no previously-included directories found matching 'build' 12:40:41 INFO - no previously-included directories found matching 'dist' 12:40:41 INFO - no previously-included directories found matching '.git*' 12:40:41 INFO - Successfully installed jsonschema functools32 12:40:41 INFO - Cleaning up... 12:40:41 INFO - Return code: 0 12:40:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:40: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')]} 12:40:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:40:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40: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')]} 12:40:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:40:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40:41 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 0x10c223a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2b8618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7e0497680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c187d30>, '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 0x10beecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c17fb20>, '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.VmHeM4MBQX/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.ZSls3JCIBK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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 12:40:41 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 12:40:41 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 12:40:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VmHeM4MBQX/Render', 12:40:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:40:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:40:41 INFO - 'HOME': '/Users/cltbld', 12:40:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:40:41 INFO - 'LOGNAME': 'cltbld', 12:40:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:40:41 INFO - 'MOZ_NO_REMOTE': '1', 12:40:41 INFO - 'NO_EM_RESTART': '1', 12:40:41 INFO - 'PAGER': '/bin/cat', 12:40:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:40:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:40:41 INFO - 'PWD': '/builds/slave/test', 12:40:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:40:41 INFO - 'SHELL': '/bin/bash', 12:40:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZSls3JCIBK/Listeners', 12:40:41 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 12:40:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:40:41 INFO - 'USER': 'cltbld', 12:40:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:40:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:40:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:40:41 INFO - 'XPC_FLAGS': '0x0', 12:40:41 INFO - 'XPC_SERVICE_NAME': '0', 12:40:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:40:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:40:41 INFO - Downloading/unpacking blobuploader==1.2.4 12:40:41 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 12:40:41 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 12:40:41 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 12:40:41 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 12:40:41 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 12:40:41 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 12:40:44 INFO - Downloading blobuploader-1.2.4.tar.gz 12:40:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:40:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:40:44 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 12:40:44 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 12:40:44 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 12:40:44 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 12:40:44 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 12:40:44 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 12:40:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:40:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:40:45 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 12:40:45 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 12:40:45 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 12:40:45 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 12:40:45 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 12:40:45 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 12:40:45 INFO - Downloading docopt-0.6.1.tar.gz 12:40:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:40:45 INFO - Installing collected packages: blobuploader, requests, docopt 12:40:45 INFO - Running setup.py install for blobuploader 12:40:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:40:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:40:45 INFO - Running setup.py install for requests 12:40:46 INFO - Running setup.py install for docopt 12:40:46 INFO - Successfully installed blobuploader requests docopt 12:40:46 INFO - Cleaning up... 12:40:46 INFO - Return code: 0 12:40:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:40:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:40:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:40:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:40:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:40:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40:46 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 0x10c223a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2b8618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7e0497680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c187d30>, '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 0x10beecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c17fb20>, '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.VmHeM4MBQX/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.ZSls3JCIBK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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 12:40:46 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 12:40:46 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 12:40:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VmHeM4MBQX/Render', 12:40:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:40:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:40:46 INFO - 'HOME': '/Users/cltbld', 12:40:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:40:46 INFO - 'LOGNAME': 'cltbld', 12:40:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:40:46 INFO - 'MOZ_NO_REMOTE': '1', 12:40:46 INFO - 'NO_EM_RESTART': '1', 12:40:46 INFO - 'PAGER': '/bin/cat', 12:40:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:40:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:40:46 INFO - 'PWD': '/builds/slave/test', 12:40:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:40:46 INFO - 'SHELL': '/bin/bash', 12:40:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZSls3JCIBK/Listeners', 12:40:46 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 12:40:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:40:46 INFO - 'USER': 'cltbld', 12:40:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:40:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:40:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:40:46 INFO - 'XPC_FLAGS': '0x0', 12:40:46 INFO - 'XPC_SERVICE_NAME': '0', 12:40:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:40:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:40:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:40:46 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-QfexDC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:40:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:40:47 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-LqvK7P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:40:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:40:47 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-HJYhYE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:40:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:40:47 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-P5yuKt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:40:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:40:47 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-hyjhvc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:40:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:40:47 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-QDJ6u1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:40:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:40:47 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-INbN7u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:40:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:40:47 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-3y4NbN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:40:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:40:47 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-6G2S9J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:40:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:40:47 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-tb0sd2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:40:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:40:47 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-UqYtpm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:40:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:40:48 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-xWVHU2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:40:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:40:48 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-nVMFb6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:40:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:40:48 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-XDMJXy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:40:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:40:48 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-cYEhVK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:40:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:40:48 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-hLeu5n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:40:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:40:48 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-5a6agi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:40:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:40:48 INFO - Running setup.py install for manifestparser 12:40:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:40:48 INFO - Running setup.py install for mozcrash 12:40:48 INFO - Running setup.py install for mozdebug 12:40:49 INFO - Running setup.py install for mozdevice 12:40:49 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:40:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:40:49 INFO - Running setup.py install for mozfile 12:40:49 INFO - Running setup.py install for mozhttpd 12:40:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:40:49 INFO - Running setup.py install for mozinfo 12:40:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:40:49 INFO - Running setup.py install for mozInstall 12:40:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:40:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:40:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:40:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:40:49 INFO - Running setup.py install for mozleak 12:40:49 INFO - Running setup.py install for mozlog 12:40:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:40:50 INFO - Running setup.py install for moznetwork 12:40:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:40:50 INFO - Running setup.py install for mozprocess 12:40:50 INFO - Running setup.py install for mozprofile 12:40:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:40:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:40:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:40:50 INFO - Running setup.py install for mozrunner 12:40:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:40:50 INFO - Running setup.py install for mozscreenshot 12:40:50 INFO - Running setup.py install for moztest 12:40:51 INFO - Running setup.py install for mozversion 12:40:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:40:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:40:51 INFO - Cleaning up... 12:40:51 INFO - Return code: 0 12:40:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:40: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')]} 12:40:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:40:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40: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')]} 12:40:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:40:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40:51 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 0x10c223a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2b8618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7e0497680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c187d30>, '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 0x10beecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c17fb20>, '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.VmHeM4MBQX/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.ZSls3JCIBK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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 12:40:51 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 12:40:51 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 12:40:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VmHeM4MBQX/Render', 12:40:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:40:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:40:51 INFO - 'HOME': '/Users/cltbld', 12:40:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:40:51 INFO - 'LOGNAME': 'cltbld', 12:40:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:40:51 INFO - 'MOZ_NO_REMOTE': '1', 12:40:51 INFO - 'NO_EM_RESTART': '1', 12:40:51 INFO - 'PAGER': '/bin/cat', 12:40:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:40:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:40:51 INFO - 'PWD': '/builds/slave/test', 12:40:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:40:51 INFO - 'SHELL': '/bin/bash', 12:40:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZSls3JCIBK/Listeners', 12:40:51 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 12:40:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:40:51 INFO - 'USER': 'cltbld', 12:40:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:40:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:40:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:40:51 INFO - 'XPC_FLAGS': '0x0', 12:40:51 INFO - 'XPC_SERVICE_NAME': '0', 12:40:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:40:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:40:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:40:51 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-c5eimq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:40:51 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)) 12:40:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:40:51 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-sJjwLX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:40:51 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)) 12:40:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:40:51 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-kt_3yo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:40:51 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)) 12:40:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:40:51 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-e0a27R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:40:51 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)) 12:40:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:40:51 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-nxIH9L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:40:52 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)) 12:40:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:40:52 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-CRcebk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:40:52 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)) 12:40:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:40:52 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-Sunxkb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:40:52 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)) 12:40:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:40:52 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-3BbbkJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:40:52 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)) 12:40:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:40:52 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-UDvVIo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:40:52 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)) 12:40:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:40:52 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-YhuMc1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:40:52 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)) 12:40:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:40:52 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-trgBfa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:40:52 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)) 12:40:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:40:52 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-NlpAPz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:40:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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)) 12:40:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:40:52 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-aZgykI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:40:52 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)) 12:40:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:40:52 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-QuiZZJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:40:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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)) 12:40:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:40:53 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-Q20VVP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:40:53 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)) 12:40:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:40:53 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-hPv5ug-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:40:53 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)) 12:40:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:40:53 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-bz2fXM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:40:53 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)) 12:40:53 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)) 12:40:53 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)) 12:40:53 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)) 12:40:53 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)) 12:40:53 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)) 12:40:53 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:40:53 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 12:40:53 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 12:40:53 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 12:40:53 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 12:40:53 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 12:40:53 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 12:40:55 INFO - Downloading blessings-1.6.tar.gz 12:40:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:40:55 INFO - Installing collected packages: blessings 12:40:55 INFO - Running setup.py install for blessings 12:40:56 INFO - Successfully installed blessings 12:40:56 INFO - Cleaning up... 12:40:56 INFO - Return code: 0 12:40:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:40:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:40:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:40:56 INFO - Reading from file tmpfile_stdout 12:40:56 INFO - Current package versions: 12:40:56 INFO - blessings == 1.6 12:40:56 INFO - blobuploader == 1.2.4 12:40:56 INFO - docopt == 0.6.1 12:40:56 INFO - functools32 == 3.2.3-2 12:40:56 INFO - jsonschema == 2.5.1 12:40:56 INFO - manifestparser == 1.1 12:40:56 INFO - mozInstall == 1.12 12:40:56 INFO - mozcrash == 0.17 12:40:56 INFO - mozdebug == 0.1 12:40:56 INFO - mozdevice == 0.48 12:40:56 INFO - mozfile == 1.2 12:40:56 INFO - mozhttpd == 0.7 12:40:56 INFO - mozinfo == 0.9 12:40:56 INFO - mozleak == 0.1 12:40:56 INFO - mozlog == 3.2 12:40:56 INFO - moznetwork == 0.27 12:40:56 INFO - mozprocess == 0.22 12:40:56 INFO - mozprofile == 0.28 12:40:56 INFO - mozrunner == 6.11 12:40:56 INFO - mozscreenshot == 0.1 12:40:56 INFO - mozsystemmonitor == 0.3 12:40:56 INFO - moztest == 0.7 12:40:56 INFO - mozversion == 1.4 12:40:56 INFO - psutil == 3.1.1 12:40:56 INFO - requests == 1.2.3 12:40:56 INFO - wsgiref == 0.1.2 12:40:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:40: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')]} 12:40:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:40:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40: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')]} 12:40:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:40:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40:56 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 0x10c223a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2b8618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7e0497680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c187d30>, '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 0x10beecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c17fb20>, '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.VmHeM4MBQX/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.ZSls3JCIBK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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 12:40:56 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 12:40:56 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 12:40:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VmHeM4MBQX/Render', 12:40:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:40:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:40:56 INFO - 'HOME': '/Users/cltbld', 12:40:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:40:56 INFO - 'LOGNAME': 'cltbld', 12:40:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:40:56 INFO - 'MOZ_NO_REMOTE': '1', 12:40:56 INFO - 'NO_EM_RESTART': '1', 12:40:56 INFO - 'PAGER': '/bin/cat', 12:40:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:40:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:40:56 INFO - 'PWD': '/builds/slave/test', 12:40:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:40:56 INFO - 'SHELL': '/bin/bash', 12:40:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZSls3JCIBK/Listeners', 12:40:56 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 12:40:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:40:56 INFO - 'USER': 'cltbld', 12:40:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:40:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:40:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:40:56 INFO - 'XPC_FLAGS': '0x0', 12:40:56 INFO - 'XPC_SERVICE_NAME': '0', 12:40:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:40:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:40:56 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)) 12:40:56 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)) 12:40:56 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)) 12:40:56 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)) 12:40:56 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)) 12:40:56 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)) 12:40:56 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)) 12:40:56 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)) 12:40:56 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)) 12:40:56 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)) 12:40:56 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)) 12:40:56 INFO - Cleaning up... 12:40:56 INFO - Return code: 0 12:40:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:40: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')]} 12:40:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:40:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40: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')]} 12:40:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:40:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c223a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2b8618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7e0497680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c187d30>, '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 0x10beecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c17fb20>, '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.VmHeM4MBQX/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.ZSls3JCIBK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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 12:40: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', 'jsonschema'] in /builds/slave/test/build 12:40: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 jsonschema 12:40:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VmHeM4MBQX/Render', 12:40:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:40:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:40:56 INFO - 'HOME': '/Users/cltbld', 12:40:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:40:56 INFO - 'LOGNAME': 'cltbld', 12:40:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:40:56 INFO - 'MOZ_NO_REMOTE': '1', 12:40:56 INFO - 'NO_EM_RESTART': '1', 12:40:56 INFO - 'PAGER': '/bin/cat', 12:40:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:40:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:40:56 INFO - 'PWD': '/builds/slave/test', 12:40:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:40:56 INFO - 'SHELL': '/bin/bash', 12:40:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZSls3JCIBK/Listeners', 12:40:56 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 12:40:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:40:56 INFO - 'USER': 'cltbld', 12:40:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:40:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:40:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:40:56 INFO - 'XPC_FLAGS': '0x0', 12:40:56 INFO - 'XPC_SERVICE_NAME': '0', 12:40:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:40:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:40:56 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:40:56 INFO - Cleaning up... 12:40:56 INFO - Return code: 0 12:40:56 INFO - Running post-action listener: _resource_record_post_action 12:40:56 INFO - Running post-action listener: _start_resource_monitoring 12:40:56 INFO - Starting resource monitoring. 12:40:56 INFO - ##### 12:40:56 INFO - ##### Finished create-virtualenv step (success) 12:40:56 INFO - ##### 12:40:56 INFO - ##### 12:40:56 INFO - ##### Running install step. 12:40:56 INFO - ##### 12:40:56 INFO - Running pre-action listener: _resource_record_pre_action 12:40:56 INFO - Running main action method: install 12:40:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:40:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:40:57 INFO - Reading from file tmpfile_stdout 12:40:57 INFO - Detecting whether we're running mozinstall >=1.0... 12:40:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:40:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:40:57 INFO - Reading from file tmpfile_stdout 12:40:57 INFO - Output received: 12:40:57 INFO - Usage: mozinstall [options] installer 12:40:57 INFO - Options: 12:40:57 INFO - -h, --help show this help message and exit 12:40:57 INFO - -d DEST, --destination=DEST 12:40:57 INFO - Directory to install application into. [default: 12:40:57 INFO - "/builds/slave/test"] 12:40:57 INFO - --app=APP Application being installed. [default: firefox] 12:40:57 INFO - mkdir: /builds/slave/test/build/application 12:40:57 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'] 12:40:57 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 12:41:31 INFO - Reading from file tmpfile_stdout 12:41:31 INFO - Output received: 12:41:31 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:41:31 INFO - Running post-action listener: _resource_record_post_action 12:41:31 INFO - ##### 12:41:31 INFO - ##### Finished install step (success) 12:41:31 INFO - ##### 12:41:31 INFO - ##### 12:41:31 INFO - ##### Running run-tests step. 12:41:31 INFO - ##### 12:41:31 INFO - Running pre-action listener: _resource_record_pre_action 12:41:31 INFO - Running main action method: run_tests 12:41:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:41:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:41:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:41:31 INFO - 2016-06-29 12:41:31.776 screenresolution[1631:7517] starting screenresolution argv=screenresolution get 12:41:31 INFO - 2016-06-29 12:41:31.779 screenresolution[1631:7517] Display 0: 1600x1200x32@60 12:41:31 INFO - 2016-06-29 12:41:31.784 screenresolution[1632:7519] starting screenresolution argv=screenresolution list 12:41:31 INFO - Available Modes on Display 0 12:41:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:41:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:41:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:41:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:41:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:41:32 INFO - Intel Iris: 12:41:32 INFO - Chipset Model: Intel Iris 12:41:32 INFO - Type: GPU 12:41:32 INFO - Bus: Built-In 12:41:32 INFO - VRAM (Dynamic, Max): 1536 MB 12:41:32 INFO - Vendor: Intel (0x8086) 12:41:32 INFO - Device ID: 0x0a2e 12:41:32 INFO - Revision ID: 0x0009 12:41:32 INFO - Displays: 12:41:32 INFO - Display: 12:41:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:41:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:41:32 INFO - Display Serial Number: 1600x1200 60 12:41:32 INFO - Main Display: Yes 12:41:32 INFO - Mirror: Off 12:41:32 INFO - Online: Yes 12:41:32 INFO - Rotation: Supported 12:41:32 INFO - Return code: 0 12:41:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:41:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:41:32 INFO - Python 2.7.3 12:41:32 INFO - Return code: 0 12:41:32 INFO - grabbing minidump binary from tooltool 12:41:32 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')]} 12:41:32 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 0x10c187d30>, '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 0x10beecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c17fb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:41:32 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 12:41:32 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 12:41:32 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 12:41:32 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:41:32 INFO - Return code: 0 12:41:32 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:41:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:41:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:41:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:41:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:41:32 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/Dh2h0gYgSLq8DWVcBY81tw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0088', '--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 12:41:32 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/Dh2h0gYgSLq8DWVcBY81tw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0088 --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 12:41:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VmHeM4MBQX/Render', 12:41:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:41:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:41:32 INFO - 'HOME': '/Users/cltbld', 12:41:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:41:32 INFO - 'LOGNAME': 'cltbld', 12:41:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:41:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:41:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:41:32 INFO - 'MOZ_NO_REMOTE': '1', 12:41:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:41:32 INFO - 'NO_EM_RESTART': '1', 12:41:32 INFO - 'PAGER': '/bin/cat', 12:41:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:41:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:41:32 INFO - 'PWD': '/builds/slave/test', 12:41:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:41:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:41:32 INFO - 'SHELL': '/bin/bash', 12:41:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZSls3JCIBK/Listeners', 12:41:32 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 12:41:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:41:32 INFO - 'USER': 'cltbld', 12:41:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:41:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:41:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:41:32 INFO - 'XPC_FLAGS': '0x0', 12:41:32 INFO - 'XPC_SERVICE_NAME': '0', 12:41:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:41:32 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/Dh2h0gYgSLq8DWVcBY81tw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0088', '--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 12:41:32 INFO - mozversion application_buildid: 20160629105128 12:41:32 INFO - mozversion application_changeset: 56fc33e6b14eb446916cdf28edee82df4c548082 12:41:32 INFO - mozversion application_display_name: Nightly 12:41:32 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:41:32 INFO - mozversion application_name: Firefox 12:41:32 INFO - mozversion application_remotingname: firefox 12:41:32 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 12:41:32 INFO - mozversion application_vendor: Mozilla 12:41:32 INFO - mozversion application_version: 50.0a1 12:41:32 INFO - mozversion platform_buildid: 20160629105128 12:41:32 INFO - mozversion platform_changeset: 56fc33e6b14eb446916cdf28edee82df4c548082 12:41:32 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 12:41:32 INFO - mozversion platform_version: 50.0a1 12:41:32 INFO - using testdate: 1467229292 12:41:32 INFO - actual date: 1467229292 12:41:32 INFO - starting webserver on 'localhost:49278' 12:41:32 INFO - SUITE-START | Running 2 tests 12:41:32 INFO - TEST-START | dromaeo_css 12:41:32 INFO - operating with platform_type : mac_ 12:41:32 INFO - Initialising browser for dromaeo_css test... 12:41:32 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/tmprqH1kq/profile http://localhost:49278/getInfo.html) 12:41:34 INFO - PROCESS | 1642 | __metrics Screen width/height:1600/1200 12:41:34 INFO - PROCESS | 1642 | colorDepth:24 12:41:34 INFO - PROCESS | 1642 | Browser inner width/height: 1024/689 12:41:34 INFO - PROCESS | 1642 | __metrics 12:41:34 INFO - PROCESS | 1642 | 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" 12:41:34 INFO - PROCESS | 1642 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:41:35 INFO - TEST-INFO | 1642: exit 0 12:41:35 INFO - Browser initialized. 12:41:35 INFO - Running cycle 1/1 for dromaeo_css test... 12:41:35 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/tmprqH1kq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:41:47 INFO - PROCESS | 1644 | RSS: Main: 244793344 12:41:47 INFO - PROCESS | 1644 | 12:42:39 INFO - PROCESS | 1644 | 1467229359198 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] 12:44:49 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49278/tests/dromaeo/cssquery-ext.html) 12:44:50 INFO - PROCESS | 1644 | RSS: Main: 347041792 12:44:50 INFO - PROCESS | 1644 | 12:47:53 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-ext.html (next: http://localhost:49278/tests/dromaeo/cssquery-jquery.html) 12:47:54 INFO - PROCESS | 1644 | RSS: Main: 379019264 12:47:54 INFO - PROCESS | 1644 | 12:50:56 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49278/tests/dromaeo/cssquery-mootools.html) 12:50:57 INFO - PROCESS | 1644 | RSS: Main: 374206464 12:50:57 INFO - PROCESS | 1644 | 12:53:59 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49278/tests/dromaeo/cssquery-prototype.html) 12:54:00 INFO - PROCESS | 1644 | RSS: Main: 370479104 12:54:00 INFO - PROCESS | 1644 | 12:57:03 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49278/tests/dromaeo/cssquery-yui.html) 12:57:04 INFO - PROCESS | 1644 | RSS: Main: 341225472 12:57:04 INFO - PROCESS | 1644 | 13:00:07 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-yui.html (next: http://localhost:49278/tests/dromaeo/cssquery-dojo.html) 13:00:07 INFO - PROCESS | 1644 | RSS: Main: 382464000 13:00:07 INFO - PROCESS | 1644 | 13:00:07 INFO - PROCESS | 1644 | __start_tp_report 13:00:07 INFO - PROCESS | 1644 | _x_x_mozilla_page_load 13:00:07 INFO - PROCESS | 1644 | _x_x_mozilla_page_load_details 13:00:07 INFO - PROCESS | 1644 | |i|pagename|runs| 13:00:07 INFO - PROCESS | 1644 | |0;dojo.html;3370.544976428182;3399.8980030599073;3420.127867393814;3433.0559096248544;3461.688448039676;5507.972460137694;5576.191452239423;5688.146777983299;5766.365699773027;5798.594098413113;4505.662075344354;5754.223179870716;5830.242068531085;5853.941460585393;5866.589338746289;5126.666766660155;5147.305113809625;5214.217867319913;5241.2400201970695;5307.893842123155;5298.814541491049;5330.840074797762;5337.359516857982;5365.114756065264;5367.490088441592;5148.922766158511;5185.533302002819;5236.633435659505;5243.9213411798855;5257.789688412459;5170.767315470813;5180.740962951837;5187.066328060948;5191.870203244911;5204.973975130138;5197.1424714922;5223.555997740196;5225.242339860737;5229.6115416241155;5253.238280449314;5657.717114144318;5706.971465142689;5730.226419433365;5809.419058094199;5812.070068789016;6287.49700023997;6331.810045698637;6349.1428657131155;6360.9045864312075;6367.235931688227;6316.336784637639;6378.298387177407;6382.553221274513;6386.776462823779;6400.743970241185;5705.372409035002;5797;5865.384134665884;5868.266466691621;5941.940580594225;6311.179546658905;6312.810615681537;6319.557630965788;6324.367563243639;6325.367463253638;6231.127642130105;6250.656213908279;6254.874902502015;6256.74973001084;6262.342454042351;6061.30042989009;6219.378062193835;6253.218347706536;6277.5605707600025;6344.809655710336;6253.468455181361;6289.77985770505;6292.370762923671;6300.873982520416;6301.495880329563;6293.559450838397;6313.77901773445;6322.0516922462;6327.462165715873;6333.366663333722;4905.288733133676;4917.557419832232;4951.96008838148;5002.799888004512;5003.699778013331;4362.760048197316;4527.388802511685;4536.138133754576;4538.523455037173;4551.431071116111;5726.112452569859;5772.538196944234;5834.445727655866;5838.649681019067;5844.795432159854;6305.621662700161;6351.618902865843;6354.618722876642;6377.5216858735785;6379.457746091542;5706.572007099484;5781.53747700183;5786.450287222707;5795;5854.677992710356;5334.67991920486;5399.730013499309;5407.648502847303;5466.398696143421;5466.808661696821;6249.316946677164;6308.873822523524;6315.084312774622;6338.778142764981;6347.873042539123;6278.121063051085;6351.364863513611;6366.9681651591445;6372.330905254881;6376.713047912763;5466.2620546227345;6124.47247923558;6271.216097987752;6271.278802937538;6335.778247761306;6242.781502647385;6259.749610015549;6267.5299352549655;6271.560990730605;6288.056791481315;6149.508039356931;6187.597806142587;6321.051842223703;6352.841178970562;6364.681765911779;6095.908561371494;6121.331035925051;6136.417040381157;6189.195404597373;6194.473469755121;6128.080787881675;6156.507479401726;6166.599171053867;6173.783917562864;6199.16311297969;5845.883082338244;5912.733926973383;6018.759249629966;6044.376434583105;6066.3630318815885;5647.971760141173;5688.516476099412;6110;6196.721147548281;6211.503079753699;6050.485708714808;6062.333143354315;6068.271807383142;6077.179580756718;6078.848028799315 13:00:07 INFO - PROCESS | 1644 | |1;ext.html;449.67847988688084;510.70634385228493;513.3377942454234;516.6770768269831;550.8762125264461;39399.80300098499;40779.796101019456;40784;40801;43081.784591077;43484;43916.78041609788;43932;43956.560434395644;44179.77910110453;8325.999999999985;9941.304108712393;9945.303828731992;9945.552450139725;9950.452725100153;8653.350998675092;9501.337265978425;9634.855477167848;9738;9738.756531086708;8793.95603021984;9745.561449734743;9760.707178784613;9760.951195244013;9837.311388202828;9210.263178945701;9753;9755.853662195073;9770.511474426286;9772.657956971472;9697.612095516171;9746.610135594568;9748.220142388593;9748.658796942073;9762.121409073217;41650.375244371055;41935.22582967547;42042.159156817;42088.57911420877;42116;3374.6287908330064;3424.4520876659863;3447.77589456687;3454.65453454655;3589.4795254687924;25334.986600535958;30915;31438.528422073687;31470.741170353158;31840.840795796103;3250.756193285489;3290.3583801158784;3290.901272961815;3298.7855789373616;3435.9828200858838;373981.1300943505;376969;377747;379149;379523;53828.46171538313;58938;59344.70327648334;59451.40548594459;59531.40468595345;61367.6931615348;61959.380406196266;62276.688616556625;64914.67542662256;65173;16270.26783794732;16379;16406.425775098054;16416.1791910404;16417.17914104314;11522.135839812048;12576.993840492738;12713;12807.103502754904;12817.359132043546;14666.340014699364;15801.36794528229;15930.68138637244;15954;15967.526379829598;5560.582956278296;5648.802291919763;5720.313562372458;6229.563930524911;6292.622442653455;863.5725315968635;931.4830269200523;978.192990782593;980.666573365045;990.8414653655525;23224;30054.24864378364;30434;31070.844645777077;31160.376792464478;1001.8697569316088;1085.9837102443469;1095.8575385199872;1117.458032854065;1131.4116659337099;14936.551903442914;15952.944695062728;16023.439179628656;16201.270942807601;16436.424725134562;638514;640531;640732;641834;650148;59003.409965899795;59009.524761881294;59012.70493647504;59150.52123696942;59197.81604367975;1272.5482453728978;1393.0596847128147;1402.8176337076522;1405.5150972914328;1418.3546486348687;31199.84400077985;31291.84354078215;31373;31657;31968.840155799073;49226.50773492219;49652.006959861326;50307;50355.49644503508;50398;544367;544472;545142;545255;547751;34614.480782787774;39328.2134357317;39365.803170985106;40542.39186412147;41582.376264356666;47204.7639761799;48210.5178948206;48722.51277487321;48731.02537949292;48750.756246218545;38257.23485530329;39719;40284.798576006935;40429.19141617092;41158.588414115475;39339.60660393474;39692;40117.1976560473;40845.59154408418;40885.97785055397;29980.100596982127;38980.610193898836;39099.82700518875;39235.019124522114;39240.215195696495;33947.03003359146;34416.640881551655;35152.12119697028;35165.94502164939;35525.28949420946;25446.109386172135;25678.61482077733;25778;25833.741662583132;26382.604260935717 13:00:07 INFO - PROCESS | 1644 | |2;jquery.html;357.392432864131;377.4150067395537;381.90643292393366;384.3523662628471;403.987880363589;15228.619284517892;15610.531684049469;20710.792892071076;20788.272410465637;21147.894260528716;18981.766185197954;19878.403647890587;20546.69179962298;20701.482462938435;21489.355319340404;5263.210518422229;5858.999999999989;6016.939830601704;6082.178905847721;6084.421979911901;5917.053271476565;5921.200637913891;5951.345352011275;5960.672163031031;5966.522678185756;5793.971030144843;5912.201852749867;5945.643261404308;5955.285365756115;5974.074018527135;5946.643201407929;5948.524118070565;5986.2217911671205;5990.880182396371;6008.699565021731;5016.2224855147515;5265.341832270966;5276.4459731728;5280.709560974145;5293.788248470095;13391.732165356736;15391.384344626202;24784.876075619686;26091.478170436676;26094.608580871303;2125.968110478344;2195.1987524553583;2481.404462928902;2771.3071732066983;2855.129185598392;10240.28318017739;10556.155507559377;14442.277886105758;14656.780148297677;15529.922350388175;1720.3720641965717;1738.617504149085;1801.882877612964;1837.7978422373528;1891.5743957609566;63492.61187235895;112236;112569.74860502745;126642.36678816639;128264.7173528262;9814.411135331902;11682.824757628643;11737.295762254122;11743.001844843113;11866.88133118675;12257.570985015482;12751.171173873669;12761.489540418279;12989;13676.97422693302;7272.927270727225;8617.526036067951;8663.740087797376;8822.735317940473;8933.553322333857;7070.469714771412;8828.602712877808;8835.64657413709;8945.46327220369;8949.418287811402;4010.63904248619;4405.07493426384;4493.528179541101;4590.311453282035;4622.768861556909;1855.276442187548;1865.3657756362902;1882.5952420229717;1912.2637784453086;1914.5979344337566;822.5434883639518;894.6242578117207;910.8406028944908;942.9198518125899;1036.0468369100445;20414.897925510573;21029.68455473169;21515.462113447287;21532.246371376925;21684.89157554202;902.7562558109405;918.8346097702344;929.5166513412987;936.1293996583242;947.2138125355938;10987.340759554452;11243.156763242627;11251.931066548665;11350;11383.487743051583;490266;602046;603985;607383;612797;135311;135920;139047;140105;140237;951.619352259088;954.7613096725819;969.5055521683992;987.151050096916;989.6486747204639;18448.446546603624;18850.434486965412;18871.90564047171;18880.905595472483;19046;52817.7359113202;55270.44729552653;55477;56547.434525654215;60017.39982600118;78799;81520;82159.58920205361;84611.15388846032;86602.56698716467;7455.440841936985;8314.08545060055;8340.291075258428;8393.286570641563;8546.743597692079;9257.688969583272;13321.267330296927;13354.399052042876;13383.866161338652;13578.524751633646;11508.309501429942;11522.53909843597;11693.239939403911;11697;11718.414079296172;9880.110790028932;11168.609098681507;11255.437228138724;11479.713007174887;11638.24351417189;10899.29154604948;11459.713507162054;11655.766884662089;11866;11923.403829808301;8149.633266502903;8203.507789532647;8310.542920139209;8438.493690378597;8460.238578527962;10824.4046577439;11397.031252343644;11544.480498377761;11569.942150289195;11641.301521908712 13:00:07 INFO - PROCESS | 1644 | |3;mootools.html;1355.9389827457762;1443.4298452111414;1506.954791356259;1721.6987027270225;1921.9615607687847;6695.330466953303;7773.222677732224;8402.285805706515;8638.956805215974;9050.32122590805;8199.83600327993;8529.872051919227;8531.146885311482;8618;8666.350023748213;1692.8476437120657;2072.668373060311;2170.5550362175795;2318.513112246426;2377.108584280895;2181.4764456530397;2190.76996915324;2337.4039619896903;2412.264259400882;2415.1184817541557;2365.31405892291;2367.171489978511;2369.7393286738443;2371.1226846066893;2383.2016274548046;1993.252530301137;2279.623862062766;2335.7664233576675;2341.4731685370825;2341.543399037171;2100.737407824022;2317.8145748340094;2391.421661703285;2394.772496612819;2397.424618091663;8602.569871506399;8689.608967596474;8700.521471319074;8708.17272359125;8744.081871403538;5200.8179713710215;5348.946510534884;5357.624966252364;5358.517733403974;5361.195820626898;3454.3264063507627;3563.5901871284873;3607.098225443639;3611.927761444783;3629.782213067198;1030.469308306223;1055.1295181475314;1068.64200492835;1075.8977897099762;1085.7448499602588;11282.887171128266;11386.54453821846;11425.828612570818;11446.828297575543;11463.885361146366;4529.38853254813;4623.745693986796;4799.736014519165;4818.132736107535;4834.395700537433;9902.60389584423;9980.600775969026;9984.750381240381;10015.899841001643;10027.398356098658;5352.277442545206;5353.063213937546;5357.812476563302;5360.0351936650995;5361.812336568201;4423.181711383404;4712.787924543404;4835.44392394872;4893.241547560063;4898.093852637274;3614.9277014459562;3761.0254616326647;3805.543334799842;3857.3056849767313;3880.6507414332846;1154.520873837348;1161.181367136167;1164.8427462292652;1177.5584155941522;1177.9646610601696;791.6160662078887;880.7225723896952;890.1543533643065;931.725141083382;936.5109475933299;5692.288463942007;5771.307443106854;5923.822285331447;5986.5809393342915;6006.309274433409;903.7333986474006;920.7329874336367;946.2004606107828;947.2232769129248;975.2441857560448;3845.942310865339;3935.193285376491;3963.3613697057312;4009.7794621295525;4027.536833264212;10326.896731032744;10595.682129536126;10691.893081069089;10694.62568810088;10750.946245268724;10476;10483.47582620866;10519.94740026295;10658.946705266424;10663.84004239937;8069.991251093613;8165.591720413956;8167.591620418955;8178.0186377636255;8179.141190175065;5421.756020978986;5757.8560535986935;5764.279465066867;5803.535717142703;5833.066709326487;9803.362781419184;9941.701748947544;10020.498975051363;10050.195984321383;10073.597056117673;9788.706338809847;9820.4107753535;10084.747881303027;10095.141912937483;10102.040306170902;6530.183727034121;6712.966435167793;7018.824529386806;7090.751823686146;7109.82225444368;8059.597020149086;8328.375371847256;8336.87494687568;8392.622331994953;8410.915890841014;7267.30960558746;7405.518641288299;7474.5888976106835;7544.585047822203;7558.941748155153;7256.7822965311125;7259.165196002527;7284.38082762971;7326.04761381017;7389.963050184715;6886.208086070166;6898.379145876896;6949.478789090939;6962.582245065312;6963.9303606963285;4884.682495637772;4930.901381972412;4949.7030178189425;4981.327520784793;5044.646874718881;4904.950950490449;4964.503549645151;5004.42449118356;5122.590192784493;5138.9743051286 13:00:07 INFO - PROCESS | 1644 | |4;prototype.html;301.46038590922245;306.51876553810513;308.84557721139424;311.9937601247975;315.55191627888394;4919.450373132461;4939.753012349382;5401.567874570035;5408.4591540845895;5450.264214331064;4840.927386089203;5343.946560534394;5387.353517577896;5395;5434.592405569589;3171.3192008235555;3204.8077115373153;3322.734181265505;3593.443016332473;3868.2843673920343;3450.7411944104165;3620.9094772630633;3725.981370093146;4059.5331536873196;4091.6931230157707;4026.375911733686;4033.536143343509;4047.271491131595;4077.7961101944934;4078.6125318094732;3135.702108299708;3149.8740050397955;3965.940510892339;4061.065954830376;4064.471618689581;2892.956605650917;3517.3317069756663;3524;3527.1358517163203;3538.02704256329;5066.898662026776;5198.922016169723;5201.869953251161;5242.737863106868;5270.262163297141;3894.357431023906;4337.97831010846;4465.508794032654;4665.626749860003;4673.696209746356;4709.670323077386;5399.704071022966;5426.21319908615;5585.804496842632;5758.913616295759;3237.5953005874267;3400.285939952613;3864.671502922255;3866.690664746842;3871.98656698518;4905.656604037683;5665.716714164317;5946.286445626509;6028.7588496460085;6069.393060693939;3406.0292816547167;3524.859005639797;3615.367310720613;3741.7006639468777;3873.2640798248244;3275.0022486283347;3914.236723838881;3942.940855887164;3943.3493473577114;3945.270125026879;4044.69664775143;4608.861734147981;4646.83736069236;4647.651426142985;4651.697639653412;4730.905381892412;5352.581565885043;5353.892922141536;5503.642263252876;5729.512991395694;1194.0030074887566;1214.5141943222604;1282.6344491819787;1343.6775173958179;1345.6232254968625;784.0552134677699;800.4476910931438;818.469462105854;833.5582141465034;836.8995720513577;4634.536546345338;4998.825276060123;5039.571636410946;5232.502912223333;5271.604629652791;5659.434056594308;5756.424357564294;5882.117682347682;5905.379935106838;5939.970300148558;4623.006053698471;5285.365756109291;5303.946960530422;5335.919961200585;5342.9465705342445;3028.243618701148;3048.542718592229;3055.92360190997;3058.785884988074;3069.478188707925;5574.972125139429;5898.174255604219;5983.730732117066;6011.368806275278;6091.269047714214;4817.325574419584;4942.9011419771405;5272.472752724773;5950.7917222896995;6047.6069055512135;5065.873353166126;5878;6104.847378815565;6133.570650054453;6142.402975226384;5169.560587350117;5729.975288920051;5945.702714864326;5947.197128387612;5955.67243801595;4768.165571025126;5791.507721843522;5803.970980145073;5805.274340707411;5813.447722466359;5992.850178745566;6142.846428839136;6143.447089761943;6155.35368786271;6156.244690738736;3776.86780962665;3885.048163200034;3891.5330160380936;3892.746971446847;3900.5124359455067;5002.149634562061;5776.220210271559;5813.9534883721335;5839.146163532048;5911.4630196148455;4764.142454358249;5756.481916627524;5763.36602973681;5764.365919748908;5799.5070419014855;4740.862193073706;4835.588974937029;4885.8045678172475;5515.4760297771645;5660.37735849048;5562.22128901946;5605.383407825218;5605.523530499953;5661.622118710069;5661.915071273852;4352.912941741084;5166.844994650167;5496.5053145217125;5609.831705048855;5650.378458369494;5436.782528698966;5578.963678257325;5585.636933599303;5594.86012849682;5606.551475881838 13:00:07 INFO - PROCESS | 1644 | |5;yui.html;705.5146237999553;730.5908691132969;773.0491495460583;775.951458381913;819.6069860319099;17118.315267389306;18006;18077.367292144776;18078.819211807877;18269.36057237997;15443.382264709398;16689.91655041726;16695.33218671255;16895.66208675829;17054.658906821856;841.146236569882;856.7087190355278;866.3632230310717;868.079835374505;868.583080121542;851.2423942690979;853.4154104438448;853.5007020892754;853.9914600854005;855.2772906893665;903.0472851142042;908.3142227618139;909.2044461096541;909.3634455880876;909.790748127931;901.9819603607924;902.7201567514091;903.1149473515926;904.1817155474273;905.3526728389234;666.9099671544328;667.7495939022866;668.7525615522239;668.8662267546462;669.8559809640902;14406.783898241534;14431.35058922351;14440.205788681618;14445.78331325031;15068.171250581214;13829.79255311171;13968.650783730387;14322.140671559635;14560.490382836655;14594.197319147443;9955.5022248888;11818.704532386673;11868.347240901747;12327.506899724;12424.689382765413;3387.4580067189377;3715.8142092895246;3864.4589757433982;3868.6905047596406;3957.98021009896;109426.45286773595;116965;117843;118068.40965795203;118285;10822.78354432907;10964.396958167377;11111.943765830822;11183.32900026001;11313.717157070974;21615.891920540296;21872.45318867041;22338.77661223367;22397.888010560167;22403.663945040837;7968.880466793003;8023.679052837938;8068.3948703847445;8139.511629302261;8309.00291964956;4577.056204068237;4618.376519169936;4636.79134438951;4696.272077827942;4817.5182481752245;3667.74325797197;3781.789827255252;3784.5836957934607;3785.432185172246;3788.886333409947;582.2430839908103;681.8806708826035;688.3185646210316;693.1889689063808;693.320545865045;600.4625859855405;604.1813342920368;662.7117204016268;675.1560549313358;700.9088818453671;7447.702091916371;8331.541765202972;8636.827263454696;9224.723258302261;9354.532273386303;579.756502269048;602.8598722016278;681.9727210911508;689.8965155226756;733.5561984998999;3724.925501489955;4466.7766611669285;4501.662375321798;4594.793234304464;4763.285507173951;86728.69906951401;99695.50152249192;122370.38814805869;141777.29111354583;145744;131541.34229328792;135141.3242933779;136211.31894340465;136506;137467.31266343803;68628;69992.65003674949;92713;107002;112725.43637281761;8657.523836188819;9757.951210243904;10010.349327293703;10012.19902407791;10105.242106842163;65341;66117.66941165263;66794;67272.3272767266;67594.98607520989;99500.00499995197;100134;101447.49276253571;102756.48621756844;103921;13894;14038.1577105374;14570.34433450476;14661.780073298696;14906.329215185126;15175.696486070437;15201.087934723952;15382.461613843461;15554.9222253888;15837.920810395874;13861.10075785812;14147.575572732834;14244.572662820132;14435.422583096559;14752.262386880828;13968.511102111377;14200.21898795576;14410.855891440951;14716.55850324492;14811.407543698131;10424.791504169722;13359.660084978756;13831.100978436369;13953.790693139408;14792.334344954717;11217.439128043401;11335.489902954221;11352.318860868374;11623.709407264885;11872;8774.429662071945;11175.776484470427;11484.841668541218;11644.35956022427;11696.415179240834 13:00:07 INFO - PROCESS | 1644 | __end_tp_report 13:00:07 INFO - PROCESS | 1644 | __start_cc_report 13:00:07 INFO - PROCESS | 1644 | _x_x_mozilla_cycle_collect,1210 13:00:07 INFO - PROCESS | 1644 | __end_cc_report 13:00:07 INFO - PROCESS | 1644 | __startTimestamp1467230407335__endTimestamp 13:00:07 INFO - PROCESS | 1644 | 13:00:07 INFO - PROCESS | 1644 | ------- Summary: start ------- 13:00:07 INFO - PROCESS | 1644 | Number of tests: 6 13:00:07 INFO - PROCESS | 1644 | 13:00:07 INFO - PROCESS | 1644 | [#0] dojo.html Cycles:165 Average:5786.67 Median:6050.49 stddev:653.21 (10.8%) stddev-sans-first:627.11 13:00:07 INFO - PROCESS | 1644 | Values: 3370.5 3399.9 3420.1 3433.1 3461.7 5508.0 5576.2 5688.1 5766.4 5798.6 4505.7 5754.2 5830.2 5853.9 5866.6 5126.7 5147.3 5214.2 5241.2 5307.9 5298.8 5330.8 5337.4 5365.1 5367.5 5148.9 5185.5 5236.6 5243.9 5257.8 5170.8 5180.7 5187.1 5191.9 5205.0 5197.1 5223.6 5225.2 5229.6 5253.2 5657.7 5707.0 5730.2 5809.4 5812.1 6287.5 6331.8 6349.1 6360.9 6367.2 6316.3 6378.3 6382.6 6386.8 6400.7 5705.4 5797.0 5865.4 5868.3 5941.9 6311.2 6312.8 6319.6 6324.4 6325.4 6231.1 6250.7 6254.9 6256.7 6262.3 6061.3 6219.4 6253.2 6277.6 6344.8 6253.5 6289.8 6292.4 6300.9 6301.5 6293.6 6313.8 6322.1 6327.5 6333.4 4905.3 4917.6 4952.0 5002.8 5003.7 4362.8 4527.4 4536.1 4538.5 4551.4 5726.1 5772.5 5834.4 5838.6 5844.8 6305.6 6351.6 6354.6 6377.5 6379.5 5706.6 5781.5 5786.5 5795.0 5854.7 5334.7 5399.7 5407.6 5466.4 5466.8 6249.3 6308.9 6315.1 6338.8 6347.9 6278.1 6351.4 6367.0 6372.3 6376.7 5466.3 6124.5 6271.2 6271.3 6335.8 6242.8 6259.7 6267.5 6271.6 6288.1 6149.5 6187.6 6321.1 6352.8 6364.7 6095.9 6121.3 6136.4 6189.2 6194.5 6128.1 6156.5 6166.6 6173.8 6199.2 5845.9 5912.7 6018.8 6044.4 6066.4 5648.0 5688.5 6110.0 6196.7 6211.5 6050.5 6062.3 6068.3 6077.2 6078.8 13:00:07 INFO - PROCESS | 1644 | 13:00:07 INFO - PROCESS | 1644 | [#1] ext.html Cycles:180 Average:66776.64 Median:40865.78 stddev:142344.87 (348.3%) stddev-sans-first:142657.07 13:00:07 INFO - PROCESS | 1644 | Values: 449.7 510.7 513.3 516.7 550.9 39399.8 40779.8 40784.0 40801.0 43081.8 43484.0 43916.8 43932.0 43956.6 44179.8 8326.0 9941.3 9945.3 9945.6 9950.5 8653.4 9501.3 9634.9 9738.0 9738.8 8794.0 9745.6 9760.7 9761.0 9837.3 9210.3 9753.0 9755.9 9770.5 9772.7 9697.6 9746.6 9748.2 9748.7 9762.1 41650.4 41935.2 42042.2 42088.6 42116.0 3374.6 3424.5 3447.8 3454.7 3589.5 25335.0 30915.0 31438.5 31470.7 31840.8 3250.8 3290.4 3290.9 3298.8 3436.0 373981.1 376969.0 377747.0 379149.0 379523.0 53828.5 58938.0 59344.7 59451.4 59531.4 61367.7 61959.4 62276.7 64914.7 65173.0 16270.3 16379.0 16406.4 16416.2 16417.2 11522.1 12577.0 12713.0 12807.1 12817.4 14666.3 15801.4 15930.7 15954.0 15967.5 5560.6 5648.8 5720.3 6229.6 6292.6 863.6 931.5 978.2 980.7 990.8 23224.0 30054.2 30434.0 31070.8 31160.4 1001.9 1086.0 1095.9 1117.5 1131.4 14936.6 15952.9 16023.4 16201.3 16436.4 638514.0 640531.0 640732.0 641834.0 650148.0 59003.4 59009.5 59012.7 59150.5 59197.8 1272.5 1393.1 1402.8 1405.5 1418.4 31199.8 31291.8 31373.0 31657.0 31968.8 49226.5 49652.0 50307.0 50355.5 50398.0 544367.0 544472.0 545142.0 545255.0 547751.0 34614.5 39328.2 39365.8 40542.4 41582.4 47204.8 48210.5 48722.5 48731.0 48750.8 38257.2 39719.0 40284.8 40429.2 41158.6 39339.6 39692.0 40117.2 40845.6 40886.0 29980.1 38980.6 39099.8 39235.0 39240.2 33947.0 34416.6 35152.1 35165.9 35525.3 25446.1 25678.6 25778.0 25833.7 26382.6 13:00:07 INFO - PROCESS | 1644 | 13:00:07 INFO - PROCESS | 1644 | [#2] jquery.html Cycles:180 Average:34780.77 Median:2813.22 stddev:98042.35 (3485.1%) stddev-sans-first:98283.31 13:00:07 INFO - PROCESS | 1644 | Values: 357.4 377.4 381.9 384.4 404.0 15228.6 15610.5 20710.8 20788.3 21147.9 18981.8 19878.4 20546.7 20701.5 21489.4 5263.2 5859.0 6016.9 6082.2 6084.4 5917.1 5921.2 5951.3 5960.7 5966.5 5794.0 5912.2 5945.6 5955.3 5974.1 5946.6 5948.5 5986.2 5990.9 6008.7 5016.2 5265.3 5276.4 5280.7 5293.8 13391.7 15391.4 24784.9 26091.5 26094.6 2126.0 2195.2 2481.4 2771.3 2855.1 10240.3 10556.2 14442.3 14656.8 15529.9 1720.4 1738.6 1801.9 1837.8 1891.6 63492.6 112236.0 112569.7 126642.4 128264.7 9814.4 11682.8 11737.3 11743.0 11866.9 12257.6 12751.2 12761.5 12989.0 13677.0 7272.9 8617.5 8663.7 8822.7 8933.6 7070.5 8828.6 8835.6 8945.5 8949.4 4010.6 4405.1 4493.5 4590.3 4622.8 1855.3 1865.4 1882.6 1912.3 1914.6 822.5 894.6 910.8 942.9 1036.0 20414.9 21029.7 21515.5 21532.2 21684.9 902.8 918.8 929.5 936.1 947.2 10987.3 11243.2 11251.9 11350.0 11383.5 490266.0 602046.0 603985.0 607383.0 612797.0 135311.0 135920.0 139047.0 140105.0 140237.0 951.6 954.8 969.5 987.2 989.6 18448.4 18850.4 18871.9 18880.9 19046.0 52817.7 55270.4 55477.0 56547.4 60017.4 78799.0 81520.0 82159.6 84611.2 86602.6 7455.4 8314.1 8340.3 8393.3 8546.7 9257.7 13321.3 13354.4 13383.9 13578.5 11508.3 11522.5 11693.2 11697.0 11718.4 9880.1 11168.6 11255.4 11479.7 11638.2 10899.3 11459.7 11655.8 11866.0 11923.4 8149.6 8203.5 8310.5 8438.5 8460.2 10824.4 11397.0 11544.5 11569.9 11641.3 13:00:07 INFO - PROCESS | 1644 | 13:00:07 INFO - PROCESS | 1644 | [#3] mootools.html Cycles:180 Average:5637.06 Median:4940.30 stddev:3162.19 (64.0%) stddev-sans-first:3154.69 13:00:07 INFO - PROCESS | 1644 | Values: 1355.9 1443.4 1507.0 1721.7 1922.0 6695.3 7773.2 8402.3 8639.0 9050.3 8199.8 8529.9 8531.1 8618.0 8666.4 1692.8 2072.7 2170.6 2318.5 2377.1 2181.5 2190.8 2337.4 2412.3 2415.1 2365.3 2367.2 2369.7 2371.1 2383.2 1993.3 2279.6 2335.8 2341.5 2341.5 2100.7 2317.8 2391.4 2394.8 2397.4 8602.6 8689.6 8700.5 8708.2 8744.1 5200.8 5348.9 5357.6 5358.5 5361.2 3454.3 3563.6 3607.1 3611.9 3629.8 1030.5 1055.1 1068.6 1075.9 1085.7 11282.9 11386.5 11425.8 11446.8 11463.9 4529.4 4623.7 4799.7 4818.1 4834.4 9902.6 9980.6 9984.8 10015.9 10027.4 5352.3 5353.1 5357.8 5360.0 5361.8 4423.2 4712.8 4835.4 4893.2 4898.1 3614.9 3761.0 3805.5 3857.3 3880.7 1154.5 1161.2 1164.8 1177.6 1178.0 791.6 880.7 890.2 931.7 936.5 5692.3 5771.3 5923.8 5986.6 6006.3 903.7 920.7 946.2 947.2 975.2 3845.9 3935.2 3963.4 4009.8 4027.5 10326.9 10595.7 10691.9 10694.6 10750.9 10476.0 10483.5 10519.9 10658.9 10663.8 8070.0 8165.6 8167.6 8178.0 8179.1 5421.8 5757.9 5764.3 5803.5 5833.1 9803.4 9941.7 10020.5 10050.2 10073.6 9788.7 9820.4 10084.7 10095.1 10102.0 6530.2 6713.0 7018.8 7090.8 7109.8 8059.6 8328.4 8336.9 8392.6 8410.9 7267.3 7405.5 7474.6 7544.6 7558.9 7256.8 7259.2 7284.4 7326.0 7390.0 6886.2 6898.4 6949.5 6962.6 6963.9 4884.7 4930.9 4949.7 4981.3 5044.6 4905.0 4964.5 5004.4 5122.6 5139.0 13:00:07 INFO - PROCESS | 1644 | 13:00:07 INFO - PROCESS | 1644 | [#4] prototype.html Cycles:180 Average:4536.32 Median:5116.87 stddev:1451.82 (28.4%) stddev-sans-first:1420.68 13:00:07 INFO - PROCESS | 1644 | Values: 301.5 306.5 308.8 312.0 315.6 4919.5 4939.8 5401.6 5408.5 5450.3 4840.9 5343.9 5387.4 5395.0 5434.6 3171.3 3204.8 3322.7 3593.4 3868.3 3450.7 3620.9 3726.0 4059.5 4091.7 4026.4 4033.5 4047.3 4077.8 4078.6 3135.7 3149.9 3965.9 4061.1 4064.5 2893.0 3517.3 3524.0 3527.1 3538.0 5066.9 5198.9 5201.9 5242.7 5270.3 3894.4 4338.0 4465.5 4665.6 4673.7 4709.7 5399.7 5426.2 5585.8 5758.9 3237.6 3400.3 3864.7 3866.7 3872.0 4905.7 5665.7 5946.3 6028.8 6069.4 3406.0 3524.9 3615.4 3741.7 3873.3 3275.0 3914.2 3942.9 3943.3 3945.3 4044.7 4608.9 4646.8 4647.7 4651.7 4730.9 5352.6 5353.9 5503.6 5729.5 1194.0 1214.5 1282.6 1343.7 1345.6 784.1 800.4 818.5 833.6 836.9 4634.5 4998.8 5039.6 5232.5 5271.6 5659.4 5756.4 5882.1 5905.4 5940.0 4623.0 5285.4 5303.9 5335.9 5342.9 3028.2 3048.5 3055.9 3058.8 3069.5 5575.0 5898.2 5983.7 6011.4 6091.3 4817.3 4942.9 5272.5 5950.8 6047.6 5065.9 5878.0 6104.8 6133.6 6142.4 5169.6 5730.0 5945.7 5947.2 5955.7 4768.2 5791.5 5804.0 5805.3 5813.4 5992.9 6142.8 6143.4 6155.4 6156.2 3776.9 3885.0 3891.5 3892.7 3900.5 5002.1 5776.2 5814.0 5839.1 5911.5 4764.1 5756.5 5763.4 5764.4 5799.5 4740.9 4835.6 4885.8 5515.5 5660.4 5562.2 5605.4 5605.5 5661.6 5661.9 4352.9 5166.8 5496.5 5609.8 5650.4 5436.8 5579.0 5585.6 5594.9 5606.6 13:00:07 INFO - PROCESS | 1644 | 13:00:07 INFO - PROCESS | 1644 | [#5] yui.html Cycles:180 Average:24486.27 Median:3527.60 stddev:37958.60 (1076.0%) stddev-sans-first:38023.08 13:00:07 INFO - PROCESS | 1644 | Values: 705.5 730.6 773.0 776.0 819.6 17118.3 18006.0 18077.4 18078.8 18269.4 15443.4 16689.9 16695.3 16895.7 17054.7 841.1 856.7 866.4 868.1 868.6 851.2 853.4 853.5 854.0 855.3 903.0 908.3 909.2 909.4 909.8 902.0 902.7 903.1 904.2 905.4 666.9 667.7 668.8 668.9 669.9 14406.8 14431.4 14440.2 14445.8 15068.2 13829.8 13968.7 14322.1 14560.5 14594.2 9955.5 11818.7 11868.3 12327.5 12424.7 3387.5 3715.8 3864.5 3868.7 3958.0 109426.5 116965.0 117843.0 118068.4 118285.0 10822.8 10964.4 11111.9 11183.3 11313.7 21615.9 21872.5 22338.8 22397.9 22403.7 7968.9 8023.7 8068.4 8139.5 8309.0 4577.1 4618.4 4636.8 4696.3 4817.5 3667.7 3781.8 3784.6 3785.4 3788.9 582.2 681.9 688.3 693.2 693.3 600.5 604.2 662.7 675.2 700.9 7447.7 8331.5 8636.8 9224.7 9354.5 579.8 602.9 682.0 689.9 733.6 3724.9 4466.8 4501.7 4594.8 4763.3 86728.7 99695.5 122370.4 141777.3 145744.0 131541.3 135141.3 136211.3 136506.0 137467.3 68628.0 69992.7 92713.0 107002.0 112725.4 8657.5 9758.0 10010.3 10012.2 10105.2 65341.0 66117.7 66794.0 67272.3 67595.0 99500.0 100134.0 101447.5 102756.5 103921.0 13894.0 14038.2 14570.3 14661.8 14906.3 15175.7 15201.1 15382.5 15554.9 15837.9 13861.1 14147.6 14244.6 14435.4 14752.3 13968.5 14200.2 14410.9 14716.6 14811.4 10424.8 13359.7 13831.1 13953.8 14792.3 11217.4 11335.5 11352.3 11623.7 11872.0 8774.4 11175.8 11484.8 11644.4 11696.4 13:00:07 INFO - PROCESS | 1644 | -------- Summary: end -------- 13:00:07 INFO - PROCESS | 1644 | 13:00:07 INFO - TEST-INFO | 1644: exit 0 13:00:07 INFO - TEST-OK | dromaeo_css | took 1115540ms 13:00:07 INFO - TEST-START | kraken 13:00:07 INFO - operating with platform_type : mac_ 13:00:07 INFO - Initialising browser for kraken test... 13:00:07 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/tmpIbyokt/profile http://localhost:49278/getInfo.html) 13:00:10 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 13:00:10 INFO - PROCESS | 1654 | colorDepth:24 13:00:10 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 13:00:10 INFO - PROCESS | 1654 | __metrics 13:00:10 INFO - PROCESS | 1654 | 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" 13:00:10 INFO - PROCESS | 1654 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:00:10 INFO - TEST-INFO | 1654: exit 0 13:00:10 INFO - Browser initialized. 13:00:10 INFO - Running cycle 1/1 for kraken test... 13:00:10 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/tmpIbyokt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:00:22 INFO - PROCESS | 1655 | RSS: Main: 244727808 13:00:22 INFO - PROCESS | 1655 | 13:00:25 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection) 13:00:26 INFO - PROCESS | 1655 | RSS: Main: 294404096 13:00:26 INFO - PROCESS | 1655 | 13:00:29 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-dft) 13:00:29 INFO - PROCESS | 1655 | RSS: Main: 304877568 13:00:29 INFO - PROCESS | 1655 | 13:00:33 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-fft) 13:00:33 INFO - PROCESS | 1655 | RSS: Main: 390553600 13:00:33 INFO - PROCESS | 1655 | 13:00:36 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator) 13:00:36 INFO - PROCESS | 1655 | RSS: Main: 293523456 13:00:36 INFO - PROCESS | 1655 | 13:00:38 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:00:39 INFO - PROCESS | 1655 | RSS: Main: 297078784 13:00:39 INFO - PROCESS | 1655 | 13:00:42 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom) 13:00:42 INFO - PROCESS | 1655 | RSS: Main: 334819328 13:00:42 INFO - PROCESS | 1655 | 13:00:45 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate) 13:00:46 INFO - PROCESS | 1655 | RSS: Main: 335908864 13:00:46 INFO - PROCESS | 1655 | 13:00:49 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial) 13:00:49 INFO - PROCESS | 1655 | RSS: Main: 334155776 13:00:49 INFO - PROCESS | 1655 | 13:00:51 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:00:51 INFO - PROCESS | 1655 | RSS: Main: 313651200 13:00:51 INFO - PROCESS | 1655 | 13:00:53 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:00:54 INFO - PROCESS | 1655 | RSS: Main: 319696896 13:00:54 INFO - PROCESS | 1655 | 13:00:56 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:00:57 INFO - PROCESS | 1655 | RSS: Main: 322228224 13:00:57 INFO - PROCESS | 1655 | 13:01:00 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:01:00 INFO - PROCESS | 1655 | RSS: Main: 327647232 13:01:00 INFO - PROCESS | 1655 | 13:01:03 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:01:04 INFO - PROCESS | 1655 | RSS: Main: 330240000 13:01:04 INFO - PROCESS | 1655 | 13:01:06 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49278/tests/kraken/driver.html?testName=ai-astar) 13:01:06 INFO - PROCESS | 1655 | RSS: Main: 373760000 13:01:06 INFO - PROCESS | 1655 | 13:01:06 INFO - PROCESS | 1655 | __start_tp_report 13:01:06 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 13:01:06 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 13:01:06 INFO - PROCESS | 1655 | |i|pagename|runs| 13:01:06 INFO - PROCESS | 1655 | |0;ai-astar;99;159;165;113;111;112;114;112;112;113 13:01:06 INFO - PROCESS | 1655 | |1;audio-beat-detection;147;109;110;159;107;109;148;134;176;108 13:01:06 INFO - PROCESS | 1655 | |2;audio-dft;183;185;185;185;185;182;186;185;182;179 13:01:06 INFO - PROCESS | 1655 | |3;audio-fft;80;75;59;109;59;46;54;68;141;79 13:01:06 INFO - PROCESS | 1655 | |4;audio-oscillator;83;93;95;71;73;68;94;69;70;124 13:01:06 INFO - PROCESS | 1655 | |5;imaging-gaussian-blur;88;85;87;85;88;85;88;84;87;87 13:01:06 INFO - PROCESS | 1655 | |6;imaging-darkroom;93;94;97;95;94;94;95;95;94;93 13:01:06 INFO - PROCESS | 1655 | |7;imaging-desaturate;87;86;85;96;123;85;87;85;84;87 13:01:06 INFO - PROCESS | 1655 | |8;json-parse-financial;52;53;53;54;53;54;55;52;53;54 13:01:06 INFO - PROCESS | 1655 | |9;json-stringify-tinderbox;65;72;79;73;70;68;68;70;68;70 13:01:06 INFO - PROCESS | 1655 | |10;stanford-crypto-aes;83;83;84;84;83;80;85;84;87;84 13:01:06 INFO - PROCESS | 1655 | |11;stanford-crypto-ccm;138;124;139;134;140;139;127;140;242;154 13:01:06 INFO - PROCESS | 1655 | |12;stanford-crypto-pbkdf2;216;165;162;164;164;163;163;163;216;216 13:01:06 INFO - PROCESS | 1655 | |13;stanford-crypto-sha256-iterative;67;67;67;65;66;65;67;68;66;65 13:01:06 INFO - PROCESS | 1655 | __end_tp_report 13:01:06 INFO - PROCESS | 1655 | __start_cc_report 13:01:06 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,2032 13:01:06 INFO - PROCESS | 1655 | __end_cc_report 13:01:06 INFO - PROCESS | 1655 | __startTimestamp1467230466410__endTimestamp 13:01:06 INFO - PROCESS | 1655 | 13:01:06 INFO - PROCESS | 1655 | ------- Summary: start ------- 13:01:06 INFO - PROCESS | 1655 | Number of tests: 14 13:01:06 INFO - PROCESS | 1655 | 13:01:06 INFO - PROCESS | 1655 | [#0] ai-astar Cycles:10 Average:121.00 Median:113.50 stddev:22.07 (19.4%) stddev-sans-first:21.93 13:01:06 INFO - PROCESS | 1655 | Values: 99.0 159.0 165.0 113.0 111.0 112.0 114.0 112.0 112.0 113.0 13:01:06 INFO - PROCESS | 1655 | 13:01:06 INFO - PROCESS | 1655 | [#1] audio-beat-detection Cycles:10 Average:130.70 Median:140.50 stddev:25.55 (18.2%) stddev-sans-first:26.41 13:01:06 INFO - PROCESS | 1655 | Values: 147.0 109.0 110.0 159.0 107.0 109.0 148.0 134.0 176.0 108.0 13:01:06 INFO - PROCESS | 1655 | 13:01:06 INFO - PROCESS | 1655 | [#2] audio-dft Cycles:10 Average:183.70 Median:185.00 stddev:2.16 (1.2%) stddev-sans-first:2.28 13:01:06 INFO - PROCESS | 1655 | Values: 183.0 185.0 185.0 185.0 185.0 182.0 186.0 185.0 182.0 179.0 13:01:06 INFO - PROCESS | 1655 | 13:01:06 INFO - PROCESS | 1655 | [#3] audio-fft Cycles:10 Average:77.00 Median:63.50 stddev:28.59 (45.0%) stddev-sans-first:30.30 13:01:06 INFO - PROCESS | 1655 | Values: 80.0 75.0 59.0 109.0 59.0 46.0 54.0 68.0 141.0 79.0 13:01:06 INFO - PROCESS | 1655 | 13:01:06 INFO - PROCESS | 1655 | [#4] audio-oscillator Cycles:10 Average:84.00 Median:78.00 stddev:17.86 (22.9%) stddev-sans-first:18.94 13:01:06 INFO - PROCESS | 1655 | Values: 83.0 93.0 95.0 71.0 73.0 68.0 94.0 69.0 70.0 124.0 13:01:06 INFO - PROCESS | 1655 | 13:01:06 INFO - PROCESS | 1655 | [#5] imaging-gaussian-blur Cycles:10 Average:86.40 Median:87.00 stddev:1.51 (1.7%) stddev-sans-first:1.48 13:01:06 INFO - PROCESS | 1655 | Values: 88.0 85.0 87.0 85.0 88.0 85.0 88.0 84.0 87.0 87.0 13:01:06 INFO - PROCESS | 1655 | 13:01:06 INFO - PROCESS | 1655 | [#6] imaging-darkroom Cycles:10 Average:94.40 Median:94.50 stddev:1.17 (1.2%) stddev-sans-first:1.13 13:01:06 INFO - PROCESS | 1655 | Values: 93.0 94.0 97.0 95.0 94.0 94.0 95.0 95.0 94.0 93.0 13:01:06 INFO - PROCESS | 1655 | 13:01:06 INFO - PROCESS | 1655 | [#7] imaging-desaturate Cycles:10 Average:90.50 Median:86.50 stddev:11.91 (13.8%) stddev-sans-first:12.56 13:01:06 INFO - PROCESS | 1655 | Values: 87.0 86.0 85.0 96.0 123.0 85.0 87.0 85.0 84.0 87.0 13:01:06 INFO - PROCESS | 1655 | 13:01:06 INFO - PROCESS | 1655 | [#8] json-parse-financial Cycles:10 Average:53.30 Median:53.50 stddev:0.95 (1.8%) stddev-sans-first:0.88 13:01:06 INFO - PROCESS | 1655 | Values: 52.0 53.0 53.0 54.0 53.0 54.0 55.0 52.0 53.0 54.0 13:01:06 INFO - PROCESS | 1655 | 13:01:06 INFO - PROCESS | 1655 | [#9] json-stringify-tinderbox Cycles:10 Average:70.30 Median:70.00 stddev:3.80 (5.4%) stddev-sans-first:3.52 13:01:06 INFO - PROCESS | 1655 | Values: 65.0 72.0 79.0 73.0 70.0 68.0 68.0 70.0 68.0 70.0 13:01:06 INFO - PROCESS | 1655 | 13:01:06 INFO - PROCESS | 1655 | [#10] stanford-crypto-aes Cycles:10 Average:83.70 Median:84.00 stddev:1.77 (2.1%) stddev-sans-first:1.86 13:01:06 INFO - PROCESS | 1655 | Values: 83.0 83.0 84.0 84.0 83.0 80.0 85.0 84.0 87.0 84.0 13:01:06 INFO - PROCESS | 1655 | 13:01:06 INFO - PROCESS | 1655 | [#11] stanford-crypto-ccm Cycles:10 Average:147.70 Median:139.50 stddev:34.11 (24.5%) stddev-sans-first:36.00 13:01:06 INFO - PROCESS | 1655 | Values: 138.0 124.0 139.0 134.0 140.0 139.0 127.0 140.0 242.0 154.0 13:01:06 INFO - PROCESS | 1655 | 13:01:06 INFO - PROCESS | 1655 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.20 Median:164.50 stddev:25.41 (15.4%) stddev-sans-first:23.20 13:01:06 INFO - PROCESS | 1655 | Values: 216.0 165.0 162.0 164.0 164.0 163.0 163.0 163.0 216.0 216.0 13:01:06 INFO - PROCESS | 1655 | 13:01:06 INFO - PROCESS | 1655 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.30 Median:67.00 stddev:1.06 (1.6%) stddev-sans-first:1.09 13:01:06 INFO - PROCESS | 1655 | Values: 67.0 67.0 67.0 65.0 66.0 65.0 67.0 68.0 66.0 65.0 13:01:06 INFO - PROCESS | 1655 | -------- Summary: end -------- 13:01:06 INFO - PROCESS | 1655 | 13:01:07 INFO - TEST-INFO | 1655: exit 0 13:01:07 INFO - TEST-OK | kraken | took 59301ms 13:01:07 INFO - SUITE-END | took 1174s 13:01:07 INFO - Completed test suite (00:19:35) 13:01:07 INFO - javascript benchmark 13:01:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3370.544976428182, 3399.8980030599073, 3420.127867393814, 3433.0559096248544, 3461.688448039676, 5507.972460137694, 5576.191452239423, 5688.146777983299, 5766.365699773027, 5798.594098413113, 4505.662075344354, 5754.223179870716, 5830.242068531085, 5853.941460585393, 5866.589338746289, 5126.666766660155, 5147.305113809625, 5214.217867319913, 5241.2400201970695, 5307.893842123155, 5298.814541491049, 5330.840074797762, 5337.359516857982, 5365.114756065264, 5367.490088441592, 5148.922766158511, 5185.533302002819, 5236.633435659505, 5243.9213411798855, 5257.789688412459, 5170.767315470813, 5180.740962951837, 5187.066328060948, 5191.870203244911, 5204.973975130138, 5197.1424714922, 5223.555997740196, 5225.242339860737, 5229.6115416241155, 5253.238280449314, 5657.717114144318, 5706.971465142689, 5730.226419433365, 5809.419058094199, 5812.070068789016, 6287.49700023997, 6331.810045698637, 6349.1428657131155, 6360.9045864312075, 6367.235931688227, 6316.336784637639, 6378.298387177407, 6382.553221274513, 6386.776462823779, 6400.743970241185, 5705.372409035002, 5797.0, 5865.384134665884, 5868.266466691621, 5941.940580594225, 6311.179546658905, 6312.810615681537, 6319.557630965788, 6324.367563243639, 6325.367463253638, 6231.127642130105, 6250.656213908279, 6254.874902502015, 6256.74973001084, 6262.342454042351, 6061.30042989009, 6219.378062193835, 6253.218347706536, 6277.5605707600025, 6344.809655710336, 6253.468455181361, 6289.77985770505, 6292.370762923671, 6300.873982520416, 6301.495880329563, 6293.559450838397, 6313.77901773445, 6322.0516922462, 6327.462165715873, 6333.366663333722, 4905.288733133676, 4917.557419832232, 4951.96008838148, 5002.799888004512, 5003.699778013331, 4362.760048197316, 4527.388802511685, 4536.138133754576, 4538.523455037173, 4551.431071116111, 5726.112452569859, 5772.538196944234, 5834.445727655866, 5838.649681019067, 5844.795432159854, 6305.621662700161, 6351.618902865843, 6354.618722876642, 6377.5216858735785, 6379.457746091542, 5706.572007099484, 5781.53747700183, 5786.450287222707, 5795.0, 5854.677992710356, 5334.67991920486, 5399.730013499309, 5407.648502847303, 5466.398696143421, 5466.808661696821, 6249.316946677164, 6308.873822523524, 6315.084312774622, 6338.778142764981, 6347.873042539123, 6278.121063051085, 6351.364863513611, 6366.9681651591445, 6372.330905254881, 6376.713047912763, 5466.2620546227345, 6124.47247923558, 6271.216097987752, 6271.278802937538, 6335.778247761306, 6242.781502647385, 6259.749610015549, 6267.5299352549655, 6271.560990730605, 6288.056791481315, 6149.508039356931, 6187.597806142587, 6321.051842223703, 6352.841178970562, 6364.681765911779, 6095.908561371494, 6121.331035925051, 6136.417040381157, 6189.195404597373, 6194.473469755121, 6128.080787881675, 6156.507479401726, 6166.599171053867, 6173.783917562864, 6199.16311297969, 5845.883082338244, 5912.733926973383, 6018.759249629966, 6044.376434583105, 6066.3630318815885, 5647.971760141173, 5688.516476099412, 6110.0, 6196.721147548281, 6211.503079753699, 6050.485708714808, 6062.333143354315, 6068.271807383142, 6077.179580756718, 6078.848028799315], "value": 5744.955784982845, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [449.67847988688084, 510.70634385228493, 513.3377942454234, 516.6770768269831, 550.8762125264461, 39399.80300098499, 40779.796101019456, 40784.0, 40801.0, 43081.784591077, 43484.0, 43916.78041609788, 43932.0, 43956.560434395644, 44179.77910110453, 8325.999999999985, 9941.304108712393, 9945.303828731992, 9945.552450139725, 9950.452725100153, 8653.350998675092, 9501.337265978425, 9634.855477167848, 9738.0, 9738.756531086708, 8793.95603021984, 9745.561449734743, 9760.707178784613, 9760.951195244013, 9837.311388202828, 9210.263178945701, 9753.0, 9755.853662195073, 9770.511474426286, 9772.657956971472, 9697.612095516171, 9746.610135594568, 9748.220142388593, 9748.658796942073, 9762.121409073217, 41650.375244371055, 41935.22582967547, 42042.159156817, 42088.57911420877, 42116.0, 3374.6287908330064, 3424.4520876659863, 3447.77589456687, 3454.65453454655, 3589.4795254687924, 25334.986600535958, 30915.0, 31438.528422073687, 31470.741170353158, 31840.840795796103, 3250.756193285489, 3290.3583801158784, 3290.901272961815, 3298.7855789373616, 3435.9828200858838, 373981.1300943505, 376969.0, 377747.0, 379149.0, 379523.0, 53828.46171538313, 58938.0, 59344.70327648334, 59451.40548594459, 59531.40468595345, 61367.6931615348, 61959.380406196266, 62276.688616556625, 64914.67542662256, 65173.0, 16270.26783794732, 16379.0, 16406.425775098054, 16416.1791910404, 16417.17914104314, 11522.135839812048, 12576.993840492738, 12713.0, 12807.103502754904, 12817.359132043546, 14666.340014699364, 15801.36794528229, 15930.68138637244, 15954.0, 15967.526379829598, 5560.582956278296, 5648.802291919763, 5720.313562372458, 6229.563930524911, 6292.622442653455, 863.5725315968635, 931.4830269200523, 978.192990782593, 980.666573365045, 990.8414653655525, 23224.0, 30054.24864378364, 30434.0, 31070.844645777077, 31160.376792464478, 1001.8697569316088, 1085.9837102443469, 1095.8575385199872, 1117.458032854065, 1131.4116659337099, 14936.551903442914, 15952.944695062728, 16023.439179628656, 16201.270942807601, 16436.424725134562, 638514.0, 640531.0, 640732.0, 641834.0, 650148.0, 59003.409965899795, 59009.524761881294, 59012.70493647504, 59150.52123696942, 59197.81604367975, 1272.5482453728978, 1393.0596847128147, 1402.8176337076522, 1405.5150972914328, 1418.3546486348687, 31199.84400077985, 31291.84354078215, 31373.0, 31657.0, 31968.840155799073, 49226.50773492219, 49652.006959861326, 50307.0, 50355.49644503508, 50398.0, 544367.0, 544472.0, 545142.0, 545255.0, 547751.0, 34614.480782787774, 39328.2134357317, 39365.803170985106, 40542.39186412147, 41582.376264356666, 47204.7639761799, 48210.5178948206, 48722.51277487321, 48731.02537949292, 48750.756246218545, 38257.23485530329, 39719.0, 40284.798576006935, 40429.19141617092, 41158.588414115475, 39339.60660393474, 39692.0, 40117.1976560473, 40845.59154408418, 40885.97785055397, 29980.100596982127, 38980.610193898836, 39099.82700518875, 39235.019124522114, 39240.215195696495, 33947.03003359146, 34416.640881551655, 35152.12119697028, 35165.94502164939, 35525.28949420946, 25446.109386172135, 25678.61482077733, 25778.0, 25833.741662583132, 26382.604260935717], "value": 19948.663698891323, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [357.392432864131, 377.4150067395537, 381.90643292393366, 384.3523662628471, 403.987880363589, 15228.619284517892, 15610.531684049469, 20710.792892071076, 20788.272410465637, 21147.894260528716, 18981.766185197954, 19878.403647890587, 20546.69179962298, 20701.482462938435, 21489.355319340404, 5263.210518422229, 5858.999999999989, 6016.939830601704, 6082.178905847721, 6084.421979911901, 5917.053271476565, 5921.200637913891, 5951.345352011275, 5960.672163031031, 5966.522678185756, 5793.971030144843, 5912.201852749867, 5945.643261404308, 5955.285365756115, 5974.074018527135, 5946.643201407929, 5948.524118070565, 5986.2217911671205, 5990.880182396371, 6008.699565021731, 5016.2224855147515, 5265.341832270966, 5276.4459731728, 5280.709560974145, 5293.788248470095, 13391.732165356736, 15391.384344626202, 24784.876075619686, 26091.478170436676, 26094.608580871303, 2125.968110478344, 2195.1987524553583, 2481.404462928902, 2771.3071732066983, 2855.129185598392, 10240.28318017739, 10556.155507559377, 14442.277886105758, 14656.780148297677, 15529.922350388175, 1720.3720641965717, 1738.617504149085, 1801.882877612964, 1837.7978422373528, 1891.5743957609566, 63492.61187235895, 112236.0, 112569.74860502745, 126642.36678816639, 128264.7173528262, 9814.411135331902, 11682.824757628643, 11737.295762254122, 11743.001844843113, 11866.88133118675, 12257.570985015482, 12751.171173873669, 12761.489540418279, 12989.0, 13676.97422693302, 7272.927270727225, 8617.526036067951, 8663.740087797376, 8822.735317940473, 8933.553322333857, 7070.469714771412, 8828.602712877808, 8835.64657413709, 8945.46327220369, 8949.418287811402, 4010.63904248619, 4405.07493426384, 4493.528179541101, 4590.311453282035, 4622.768861556909, 1855.276442187548, 1865.3657756362902, 1882.5952420229717, 1912.2637784453086, 1914.5979344337566, 822.5434883639518, 894.6242578117207, 910.8406028944908, 942.9198518125899, 1036.0468369100445, 20414.897925510573, 21029.68455473169, 21515.462113447287, 21532.246371376925, 21684.89157554202, 902.7562558109405, 918.8346097702344, 929.5166513412987, 936.1293996583242, 947.2138125355938, 10987.340759554452, 11243.156763242627, 11251.931066548665, 11350.0, 11383.487743051583, 490266.0, 602046.0, 603985.0, 607383.0, 612797.0, 135311.0, 135920.0, 139047.0, 140105.0, 140237.0, 951.619352259088, 954.7613096725819, 969.5055521683992, 987.151050096916, 989.6486747204639, 18448.446546603624, 18850.434486965412, 18871.90564047171, 18880.905595472483, 19046.0, 52817.7359113202, 55270.44729552653, 55477.0, 56547.434525654215, 60017.39982600118, 78799.0, 81520.0, 82159.58920205361, 84611.15388846032, 86602.56698716467, 7455.440841936985, 8314.08545060055, 8340.291075258428, 8393.286570641563, 8546.743597692079, 9257.688969583272, 13321.267330296927, 13354.399052042876, 13383.866161338652, 13578.524751633646, 11508.309501429942, 11522.53909843597, 11693.239939403911, 11697.0, 11718.414079296172, 9880.110790028932, 11168.609098681507, 11255.437228138724, 11479.713007174887, 11638.24351417189, 10899.29154604948, 11459.713507162054, 11655.766884662089, 11866.0, 11923.403829808301, 8149.633266502903, 8203.507789532647, 8310.542920139209, 8438.493690378597, 8460.238578527962, 10824.4046577439, 11397.031252343644, 11544.480498377761, 11569.942150289195, 11641.301521908712], "value": 9594.519430686678, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1355.9389827457762, 1443.4298452111414, 1506.954791356259, 1721.6987027270225, 1921.9615607687847, 6695.330466953303, 7773.222677732224, 8402.285805706515, 8638.956805215974, 9050.32122590805, 8199.83600327993, 8529.872051919227, 8531.146885311482, 8618.0, 8666.350023748213, 1692.8476437120657, 2072.668373060311, 2170.5550362175795, 2318.513112246426, 2377.108584280895, 2181.4764456530397, 2190.76996915324, 2337.4039619896903, 2412.264259400882, 2415.1184817541557, 2365.31405892291, 2367.171489978511, 2369.7393286738443, 2371.1226846066893, 2383.2016274548046, 1993.252530301137, 2279.623862062766, 2335.7664233576675, 2341.4731685370825, 2341.543399037171, 2100.737407824022, 2317.8145748340094, 2391.421661703285, 2394.772496612819, 2397.424618091663, 8602.569871506399, 8689.608967596474, 8700.521471319074, 8708.17272359125, 8744.081871403538, 5200.8179713710215, 5348.946510534884, 5357.624966252364, 5358.517733403974, 5361.195820626898, 3454.3264063507627, 3563.5901871284873, 3607.098225443639, 3611.927761444783, 3629.782213067198, 1030.469308306223, 1055.1295181475314, 1068.64200492835, 1075.8977897099762, 1085.7448499602588, 11282.887171128266, 11386.54453821846, 11425.828612570818, 11446.828297575543, 11463.885361146366, 4529.38853254813, 4623.745693986796, 4799.736014519165, 4818.132736107535, 4834.395700537433, 9902.60389584423, 9980.600775969026, 9984.750381240381, 10015.899841001643, 10027.398356098658, 5352.277442545206, 5353.063213937546, 5357.812476563302, 5360.0351936650995, 5361.812336568201, 4423.181711383404, 4712.787924543404, 4835.44392394872, 4893.241547560063, 4898.093852637274, 3614.9277014459562, 3761.0254616326647, 3805.543334799842, 3857.3056849767313, 3880.6507414332846, 1154.520873837348, 1161.181367136167, 1164.8427462292652, 1177.5584155941522, 1177.9646610601696, 791.6160662078887, 880.7225723896952, 890.1543533643065, 931.725141083382, 936.5109475933299, 5692.288463942007, 5771.307443106854, 5923.822285331447, 5986.5809393342915, 6006.309274433409, 903.7333986474006, 920.7329874336367, 946.2004606107828, 947.2232769129248, 975.2441857560448, 3845.942310865339, 3935.193285376491, 3963.3613697057312, 4009.7794621295525, 4027.536833264212, 10326.896731032744, 10595.682129536126, 10691.893081069089, 10694.62568810088, 10750.946245268724, 10476.0, 10483.47582620866, 10519.94740026295, 10658.946705266424, 10663.84004239937, 8069.991251093613, 8165.591720413956, 8167.591620418955, 8178.0186377636255, 8179.141190175065, 5421.756020978986, 5757.8560535986935, 5764.279465066867, 5803.535717142703, 5833.066709326487, 9803.362781419184, 9941.701748947544, 10020.498975051363, 10050.195984321383, 10073.597056117673, 9788.706338809847, 9820.4107753535, 10084.747881303027, 10095.141912937483, 10102.040306170902, 6530.183727034121, 6712.966435167793, 7018.824529386806, 7090.751823686146, 7109.82225444368, 8059.597020149086, 8328.375371847256, 8336.87494687568, 8392.622331994953, 8410.915890841014, 7267.30960558746, 7405.518641288299, 7474.5888976106835, 7544.585047822203, 7558.941748155153, 7256.7822965311125, 7259.165196002527, 7284.38082762971, 7326.04761381017, 7389.963050184715, 6886.208086070166, 6898.379145876896, 6949.478789090939, 6962.582245065312, 6963.9303606963285, 4884.682495637772, 4930.901381972412, 4949.7030178189425, 4981.327520784793, 5044.646874718881, 4904.950950490449, 4964.503549645151, 5004.42449118356, 5122.590192784493, 5138.9743051286], "value": 4520.353764850139, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [301.46038590922245, 306.51876553810513, 308.84557721139424, 311.9937601247975, 315.55191627888394, 4919.450373132461, 4939.753012349382, 5401.567874570035, 5408.4591540845895, 5450.264214331064, 4840.927386089203, 5343.946560534394, 5387.353517577896, 5395.0, 5434.592405569589, 3171.3192008235555, 3204.8077115373153, 3322.734181265505, 3593.443016332473, 3868.2843673920343, 3450.7411944104165, 3620.9094772630633, 3725.981370093146, 4059.5331536873196, 4091.6931230157707, 4026.375911733686, 4033.536143343509, 4047.271491131595, 4077.7961101944934, 4078.6125318094732, 3135.702108299708, 3149.8740050397955, 3965.940510892339, 4061.065954830376, 4064.471618689581, 2892.956605650917, 3517.3317069756663, 3524.0, 3527.1358517163203, 3538.02704256329, 5066.898662026776, 5198.922016169723, 5201.869953251161, 5242.737863106868, 5270.262163297141, 3894.357431023906, 4337.97831010846, 4465.508794032654, 4665.626749860003, 4673.696209746356, 4709.670323077386, 5399.704071022966, 5426.21319908615, 5585.804496842632, 5758.913616295759, 3237.5953005874267, 3400.285939952613, 3864.671502922255, 3866.690664746842, 3871.98656698518, 4905.656604037683, 5665.716714164317, 5946.286445626509, 6028.7588496460085, 6069.393060693939, 3406.0292816547167, 3524.859005639797, 3615.367310720613, 3741.7006639468777, 3873.2640798248244, 3275.0022486283347, 3914.236723838881, 3942.940855887164, 3943.3493473577114, 3945.270125026879, 4044.69664775143, 4608.861734147981, 4646.83736069236, 4647.651426142985, 4651.697639653412, 4730.905381892412, 5352.581565885043, 5353.892922141536, 5503.642263252876, 5729.512991395694, 1194.0030074887566, 1214.5141943222604, 1282.6344491819787, 1343.6775173958179, 1345.6232254968625, 784.0552134677699, 800.4476910931438, 818.469462105854, 833.5582141465034, 836.8995720513577, 4634.536546345338, 4998.825276060123, 5039.571636410946, 5232.502912223333, 5271.604629652791, 5659.434056594308, 5756.424357564294, 5882.117682347682, 5905.379935106838, 5939.970300148558, 4623.006053698471, 5285.365756109291, 5303.946960530422, 5335.919961200585, 5342.9465705342445, 3028.243618701148, 3048.542718592229, 3055.92360190997, 3058.785884988074, 3069.478188707925, 5574.972125139429, 5898.174255604219, 5983.730732117066, 6011.368806275278, 6091.269047714214, 4817.325574419584, 4942.9011419771405, 5272.472752724773, 5950.7917222896995, 6047.6069055512135, 5065.873353166126, 5878.0, 6104.847378815565, 6133.570650054453, 6142.402975226384, 5169.560587350117, 5729.975288920051, 5945.702714864326, 5947.197128387612, 5955.67243801595, 4768.165571025126, 5791.507721843522, 5803.970980145073, 5805.274340707411, 5813.447722466359, 5992.850178745566, 6142.846428839136, 6143.447089761943, 6155.35368786271, 6156.244690738736, 3776.86780962665, 3885.048163200034, 3891.5330160380936, 3892.746971446847, 3900.5124359455067, 5002.149634562061, 5776.220210271559, 5813.9534883721335, 5839.146163532048, 5911.4630196148455, 4764.142454358249, 5756.481916627524, 5763.36602973681, 5764.365919748908, 5799.5070419014855, 4740.862193073706, 4835.588974937029, 4885.8045678172475, 5515.4760297771645, 5660.37735849048, 5562.22128901946, 5605.383407825218, 5605.523530499953, 5661.622118710069, 5661.915071273852, 4352.912941741084, 5166.844994650167, 5496.5053145217125, 5609.831705048855, 5650.378458369494, 5436.782528698966, 5578.963678257325, 5585.636933599303, 5594.86012849682, 5606.551475881838], "value": 4070.126053815035, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [705.5146237999553, 730.5908691132969, 773.0491495460583, 775.951458381913, 819.6069860319099, 17118.315267389306, 18006.0, 18077.367292144776, 18078.819211807877, 18269.36057237997, 15443.382264709398, 16689.91655041726, 16695.33218671255, 16895.66208675829, 17054.658906821856, 841.146236569882, 856.7087190355278, 866.3632230310717, 868.079835374505, 868.583080121542, 851.2423942690979, 853.4154104438448, 853.5007020892754, 853.9914600854005, 855.2772906893665, 903.0472851142042, 908.3142227618139, 909.2044461096541, 909.3634455880876, 909.790748127931, 901.9819603607924, 902.7201567514091, 903.1149473515926, 904.1817155474273, 905.3526728389234, 666.9099671544328, 667.7495939022866, 668.7525615522239, 668.8662267546462, 669.8559809640902, 14406.783898241534, 14431.35058922351, 14440.205788681618, 14445.78331325031, 15068.171250581214, 13829.79255311171, 13968.650783730387, 14322.140671559635, 14560.490382836655, 14594.197319147443, 9955.5022248888, 11818.704532386673, 11868.347240901747, 12327.506899724, 12424.689382765413, 3387.4580067189377, 3715.8142092895246, 3864.4589757433982, 3868.6905047596406, 3957.98021009896, 109426.45286773595, 116965.0, 117843.0, 118068.40965795203, 118285.0, 10822.78354432907, 10964.396958167377, 11111.943765830822, 11183.32900026001, 11313.717157070974, 21615.891920540296, 21872.45318867041, 22338.77661223367, 22397.888010560167, 22403.663945040837, 7968.880466793003, 8023.679052837938, 8068.3948703847445, 8139.511629302261, 8309.00291964956, 4577.056204068237, 4618.376519169936, 4636.79134438951, 4696.272077827942, 4817.5182481752245, 3667.74325797197, 3781.789827255252, 3784.5836957934607, 3785.432185172246, 3788.886333409947, 582.2430839908103, 681.8806708826035, 688.3185646210316, 693.1889689063808, 693.320545865045, 600.4625859855405, 604.1813342920368, 662.7117204016268, 675.1560549313358, 700.9088818453671, 7447.702091916371, 8331.541765202972, 8636.827263454696, 9224.723258302261, 9354.532273386303, 579.756502269048, 602.8598722016278, 681.9727210911508, 689.8965155226756, 733.5561984998999, 3724.925501489955, 4466.7766611669285, 4501.662375321798, 4594.793234304464, 4763.285507173951, 86728.69906951401, 99695.50152249192, 122370.38814805869, 141777.29111354583, 145744.0, 131541.34229328792, 135141.3242933779, 136211.31894340465, 136506.0, 137467.31266343803, 68628.0, 69992.65003674949, 92713.0, 107002.0, 112725.43637281761, 8657.523836188819, 9757.951210243904, 10010.349327293703, 10012.19902407791, 10105.242106842163, 65341.0, 66117.66941165263, 66794.0, 67272.3272767266, 67594.98607520989, 99500.00499995197, 100134.0, 101447.49276253571, 102756.48621756844, 103921.0, 13894.0, 14038.1577105374, 14570.34433450476, 14661.780073298696, 14906.329215185126, 15175.696486070437, 15201.087934723952, 15382.461613843461, 15554.9222253888, 15837.920810395874, 13861.10075785812, 14147.575572732834, 14244.572662820132, 14435.422583096559, 14752.262386880828, 13968.511102111377, 14200.21898795576, 14410.855891440951, 14716.55850324492, 14811.407543698131, 10424.791504169722, 13359.660084978756, 13831.100978436369, 13953.790693139408, 14792.334344954717, 11217.439128043401, 11335.489902954221, 11352.318860868374, 11623.709407264885, 11872.0, 8774.429662071945, 11175.776484470427, 11484.841668541218, 11644.35956022427, 11696.415179240834], "value": 7957.27655105794, "unit": "score"}], "name": "dromaeo_css", "value": 7375.687779822757, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 159.0, 165.0, 113.0, 111.0, 112.0, 114.0, 112.0, 112.0, 113.0], "value": 121.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 109.0, 110.0, 159.0, 107.0, 109.0, 148.0, 134.0, 176.0, 108.0], "value": 130.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 185.0, 185.0, 185.0, 185.0, 182.0, 186.0, 185.0, 182.0, 179.0], "value": 183.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 75.0, 59.0, 109.0, 59.0, 46.0, 54.0, 68.0, 141.0, 79.0], "value": 77.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 93.0, 95.0, 71.0, 73.0, 68.0, 94.0, 69.0, 70.0, 124.0], "value": 84.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 87.0, 85.0, 88.0, 85.0, 88.0, 84.0, 87.0, 87.0], "value": 86.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 97.0, 95.0, 94.0, 94.0, 95.0, 95.0, 94.0, 93.0], "value": 94.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 85.0, 96.0, 123.0, 85.0, 87.0, 85.0, 84.0, 87.0], "value": 90.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 53.0, 53.0, 54.0, 53.0, 54.0, 55.0, 52.0, 53.0, 54.0], "value": 53.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 72.0, 79.0, 73.0, 70.0, 68.0, 68.0, 70.0, 68.0, 70.0], "value": 70.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 84.0, 84.0, 83.0, 80.0, 85.0, 84.0, 87.0, 84.0], "value": 83.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 124.0, 139.0, 134.0, 140.0, 139.0, 127.0, 140.0, 242.0, 154.0], "value": 147.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [216.0, 165.0, 162.0, 164.0, 164.0, 163.0, 163.0, 163.0, 216.0, 216.0], "value": 179.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 67.0, 65.0, 66.0, 65.0, 67.0, 68.0, 66.0, 65.0], "value": 66.3, "unit": "score"}], "name": "kraken", "value": 1468.1999999999998, "alertThreshold": 2.0}]} 13:01:07 INFO - Return code: 0 13:01:07 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:01:07 INFO - # TBPL SUCCESS # 13:01:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:01:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:01:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:01:07 INFO - 2016-06-29 13:01:07.699 screenresolution[1659:9948] starting screenresolution argv=screenresolution get 13:01:07 INFO - 2016-06-29 13:01:07.701 screenresolution[1659:9948] Display 0: 1600x1200x32@60 13:01:07 INFO - 2016-06-29 13:01:07.707 screenresolution[1660:9951] starting screenresolution argv=screenresolution list 13:01:07 INFO - Available Modes on Display 0 13:01:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:01:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:01:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:01:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:01:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:01:07 INFO - Intel Iris: 13:01:07 INFO - Chipset Model: Intel Iris 13:01:07 INFO - Type: GPU 13:01:07 INFO - Bus: Built-In 13:01:07 INFO - VRAM (Dynamic, Max): 1536 MB 13:01:07 INFO - Vendor: Intel (0x8086) 13:01:07 INFO - Device ID: 0x0a2e 13:01:07 INFO - Revision ID: 0x0009 13:01:07 INFO - Displays: 13:01:07 INFO - Display: 13:01:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:01:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:01:07 INFO - Display Serial Number: 1600x1200 60 13:01:07 INFO - Main Display: Yes 13:01:07 INFO - Mirror: Off 13:01:07 INFO - Online: Yes 13:01:07 INFO - Rotation: Supported 13:01:07 INFO - Return code: 0 13:01:07 INFO - Running post-action listener: _resource_record_post_action 13:01:07 INFO - ##### 13:01:07 INFO - ##### Finished run-tests step (success) 13:01:07 INFO - ##### 13:01:07 INFO - Running post-run listener: _resource_record_post_run 13:01:08 INFO - Total resource usage - Wall time: 1210s; CPU: 53.0%; Read bytes: 38102528; Write bytes: 705421824; Read time: 387; Write time: 2025 13:01:08 INFO - TinderboxPrint: CPU usage
53.3% 13:01:08 INFO - TinderboxPrint: I/O read bytes / time
38,102,528 / 387 13:01:08 INFO - TinderboxPrint: I/O write bytes / time
705,421,824 / 2,025 13:01:08 INFO - TinderboxPrint: CPU idle
2,259.7 (46.7%) 13:01:08 INFO - TinderboxPrint: CPU system
964.4 (19.9%) 13:01:08 INFO - TinderboxPrint: CPU user
1,617.5 (33.4%) 13:01:08 INFO - TinderboxPrint: Swap in / out
444,989,440 / 0 13:01:08 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 390291968; Write bytes: 383600128; Read time: 29234; Write time: 679 13:01:08 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 37017088; Write bytes: 296532992; Read time: 368; Write time: 1299 13:01:08 INFO - Running post-run listener: _upload_blobber_files 13:01:08 INFO - Blob upload gear active. 13:01:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:01:08 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 13:01:08 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'] 13:01:08 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 13:01:08 INFO - (blobuploader) - INFO - Open directory for files ... 13:01:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:01:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:01:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:01:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:01:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:01:10 INFO - (blobuploader) - INFO - Done attempting. 13:01:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:01:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:01:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:01:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:01:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:01:11 INFO - (blobuploader) - INFO - Done attempting. 13:01:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:01:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:01:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:01:13 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:01:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:01:13 INFO - (blobuploader) - INFO - Done attempting. 13:01:13 INFO - (blobuploader) - INFO - Iteration through files over. 13:01:13 INFO - Return code: 0 13:01:13 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:01:13 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:01:13 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0f5f754de2a4eafe14b6f225ce5f5dc87c618e2c4e672896ece9cb49c1ebd03d83d6f66c9aca93a3343cacf076be97af6ba266aa494830152cde7bfc7fabb668", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f85cc3f1a47652779697bbdec9b34e4956ccf36807895035c79877240a6ee0a4f5b828fb6df9deba4f71ef0fc39a27b5dd5345ec29377d1f599d9852cc40a2b8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/832136e65fd5ded756c49d78e23299856e964d942dd2d694b1de3fdd5e62dfa432592b662f5048c44f4df5c2e3e97c6c8215df0dd56e88193a6381c5939be801"} 13:01:13 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:01:13 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:01:13 INFO - Contents: 13:01:13 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0f5f754de2a4eafe14b6f225ce5f5dc87c618e2c4e672896ece9cb49c1ebd03d83d6f66c9aca93a3343cacf076be97af6ba266aa494830152cde7bfc7fabb668", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f85cc3f1a47652779697bbdec9b34e4956ccf36807895035c79877240a6ee0a4f5b828fb6df9deba4f71ef0fc39a27b5dd5345ec29377d1f599d9852cc40a2b8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/832136e65fd5ded756c49d78e23299856e964d942dd2d694b1de3fdd5e62dfa432592b662f5048c44f4df5c2e3e97c6c8215df0dd56e88193a6381c5939be801"} 13:01:13 INFO - Running post-run listener: copy_logs_to_upload_dir 13:01:13 INFO - Copying logs to upload dir... 13:01:13 INFO - mkdir: /builds/slave/test/build/upload/logs 13:01:13 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.626224 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-06-29 13:01:13.835992) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-29 13:01:13.839474) ========= 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.VmHeM4MBQX/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.ZSls3JCIBK/Listeners TMPDIR=/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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/0f5f754de2a4eafe14b6f225ce5f5dc87c618e2c4e672896ece9cb49c1ebd03d83d6f66c9aca93a3343cacf076be97af6ba266aa494830152cde7bfc7fabb668", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f85cc3f1a47652779697bbdec9b34e4956ccf36807895035c79877240a6ee0a4f5b828fb6df9deba4f71ef0fc39a27b5dd5345ec29377d1f599d9852cc40a2b8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/832136e65fd5ded756c49d78e23299856e964d942dd2d694b1de3fdd5e62dfa432592b662f5048c44f4df5c2e3e97c6c8215df0dd56e88193a6381c5939be801"} build_url:https://queue.taskcluster.net/v1/task/Dh2h0gYgSLq8DWVcBY81tw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011015 build_url: 'https://queue.taskcluster.net/v1/task/Dh2h0gYgSLq8DWVcBY81tw/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/0f5f754de2a4eafe14b6f225ce5f5dc87c618e2c4e672896ece9cb49c1ebd03d83d6f66c9aca93a3343cacf076be97af6ba266aa494830152cde7bfc7fabb668", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f85cc3f1a47652779697bbdec9b34e4956ccf36807895035c79877240a6ee0a4f5b828fb6df9deba4f71ef0fc39a27b5dd5345ec29377d1f599d9852cc40a2b8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/832136e65fd5ded756c49d78e23299856e964d942dd2d694b1de3fdd5e62dfa432592b662f5048c44f4df5c2e3e97c6c8215df0dd56e88193a6381c5939be801"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-29 13:01:13.871849) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 13:01:13.872239) ========= 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.VmHeM4MBQX/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.ZSls3JCIBK/Listeners TMPDIR=/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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.005125 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 13:01:13.920748) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-29 13:01:13.921066) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-29 13:01:13.921389) ========= ========= Total master_lag: 0.17 =========