builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0099 starttime: 1467366848.89 results: success (0) buildid: 20160701015622 builduid: 8e67da969c944258b3b3a8fa70d6bd8f revision: c6ce12a74300f69b8a8aabc2677aa4710809194a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-01 02:54:08.893221) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-01 02:54:08.901474) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-01 02:54:08.901895) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-01 02:54:08.929834) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-01 02:54:08.930430) ========= 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.Mz4XZ4SMc2/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.UdATBcrsGs/Listeners TMPDIR=/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-07-01 02:54:08-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 163M=0s 2016-07-01 02:54:09 (163 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.448575 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-01 02:54:09.402179) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-01 02:54:09.402491) ========= 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.Mz4XZ4SMc2/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.UdATBcrsGs/Listeners TMPDIR=/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/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.038287 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-01 02:54:09.460743) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-01 02:54:09.461099) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev c6ce12a74300f69b8a8aabc2677aa4710809194a --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 c6ce12a74300f69b8a8aabc2677aa4710809194a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Mz4XZ4SMc2/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.UdATBcrsGs/Listeners TMPDIR=/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-07-01 02:54:09,534 truncating revision to first 12 chars 2016-07-01 02:54:09,534 Setting DEBUG logging. 2016-07-01 02:54:09,534 attempt 1/10 2016-07-01 02:54:09,534 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/c6ce12a74300?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-01 02:54:10,553 unpacking tar archive at: fx-team-c6ce12a74300/testing/mozharness/ program finished with exit code 0 elapsedTime=1.287004 ========= master_lag: 0.16 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-01 02:54:10.912008) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-01 02:54:10.912355) ========= script_repo_revision: c6ce12a74300f69b8a8aabc2677aa4710809194a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-01 02:54:10.912742) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-01 02:54:10.913048) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-01 02:54:10.935986) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-07-01 02:54:10.936393) ========= /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.Mz4XZ4SMc2/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.UdATBcrsGs/Listeners TMPDIR=/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/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 02:54:11 INFO - MultiFileLogger online at 20160701 02:54:11 in /builds/slave/test 02:54:11 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 02:54:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:54:11 INFO - {'append_to_log': False, 02:54:11 INFO - 'base_work_dir': '/builds/slave/test', 02:54:11 INFO - 'blob_upload_branch': 'Fx-Team', 02:54:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:54:11 INFO - 'branch': 'Fx-Team', 02:54:11 INFO - 'buildbot_json_path': 'buildprops.json', 02:54:11 INFO - 'config_files': ('talos/mac_config.py',), 02:54:11 INFO - 'default_actions': ('clobber', 02:54:11 INFO - 'read-buildbot-config', 02:54:11 INFO - 'download-and-extract', 02:54:11 INFO - 'populate-webroot', 02:54:11 INFO - 'create-virtualenv', 02:54:11 INFO - 'install', 02:54:11 INFO - 'run-tests'), 02:54:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:54:11 INFO - 'download_minidump_stackwalk': True, 02:54:11 INFO - 'download_symbols': 'ondemand', 02:54:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:54:11 INFO - 'tooltool.py': '/tools/tooltool.py', 02:54:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:54:11 INFO - '/tools/misc-python/virtualenv.py')}, 02:54:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:54:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:54:11 INFO - 'installer_path': 'installer.exe', 02:54:11 INFO - 'log_level': 'info', 02:54:11 INFO - 'log_name': 'talos', 02:54:11 INFO - 'log_to_console': True, 02:54:11 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:54:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:54:11 INFO - 'opt_config_files': (), 02:54:11 INFO - 'pip_index': False, 02:54:11 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:54:11 INFO - 'cmd': ('bash', 02:54:11 INFO - '-c', 02:54:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:54:11 INFO - 'enabled': True, 02:54:11 INFO - 'halt_on_failure': False, 02:54:11 INFO - 'name': 'check_screen_resolution'},), 02:54:11 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:54:11 INFO - 'cmd': ('bash', 02:54:11 INFO - '-c', 02:54:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:54:11 INFO - 'enabled': True, 02:54:11 INFO - 'halt_on_failure': False, 02:54:11 INFO - 'name': 'check_screen_resolution'},), 02:54:11 INFO - 'run_cmd_checks_enabled': True, 02:54:11 INFO - 'sps_profile': False, 02:54:11 INFO - 'sps_profile_interval': 0, 02:54:11 INFO - 'suite': 'dromaeojs', 02:54:11 INFO - 'system_bits': '32', 02:54:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:54:11 INFO - 'title': 't-yosemite-r7-0099', 02:54:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:54:11 INFO - 'use_talos_json': True, 02:54:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:54:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:54:11 INFO - 'work_dir': 'build'} 02:54:11 INFO - ##### 02:54:11 INFO - ##### Running clobber step. 02:54:11 INFO - ##### 02:54:11 INFO - Running pre-action listener: _resource_record_pre_action 02:54:11 INFO - Running main action method: clobber 02:54:11 INFO - rmtree: /builds/slave/test/build 02:54:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:54:13 INFO - Running post-action listener: _resource_record_post_action 02:54:13 INFO - ##### 02:54:13 INFO - ##### Finished clobber step (success) 02:54:13 INFO - ##### 02:54:13 INFO - ##### 02:54:13 INFO - ##### Running read-buildbot-config step. 02:54:13 INFO - ##### 02:54:13 INFO - Running pre-action listener: _resource_record_pre_action 02:54:13 INFO - Running main action method: read_buildbot_config 02:54:13 INFO - Using buildbot properties: 02:54:13 INFO - { 02:54:13 INFO - "project": "", 02:54:13 INFO - "product": "firefox", 02:54:13 INFO - "script_repo_revision": "production", 02:54:13 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 02:54:13 INFO - "repository": "", 02:54:13 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 02:54:13 INFO - "buildid": "20160701015622", 02:54:13 INFO - "pgo_build": "False", 02:54:13 INFO - "basedir": "/builds/slave/test", 02:54:13 INFO - "buildnumber": 834, 02:54:13 INFO - "slavename": "t-yosemite-r7-0099", 02:54:13 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 02:54:13 INFO - "platform": "yosemite_r7", 02:54:13 INFO - "scheduler": "tests-fx-team-macosx64-talos", 02:54:13 INFO - "branch": "fx-team", 02:54:13 INFO - "repo_path": "integration/fx-team", 02:54:13 INFO - "revision": "c6ce12a74300f69b8a8aabc2677aa4710809194a", 02:54:13 INFO - "stage_platform": "macosx64", 02:54:13 INFO - "builduid": "8e67da969c944258b3b3a8fa70d6bd8f", 02:54:13 INFO - "slavebuilddir": "test" 02:54:13 INFO - } 02:54:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/Cv2aUe3jSBykYf2hzVuHAA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 02:54:13 INFO - Running post-action listener: _resource_record_post_action 02:54:13 INFO - ##### 02:54:13 INFO - ##### Finished read-buildbot-config step (success) 02:54:13 INFO - ##### 02:54:13 INFO - ##### 02:54:13 INFO - ##### Running download-and-extract step. 02:54:13 INFO - ##### 02:54:13 INFO - Running pre-action listener: _resource_record_pre_action 02:54:13 INFO - Running main action method: download_and_extract 02:54:13 INFO - mkdir: /builds/slave/test/build/tests 02:54:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:54:13 INFO - https://queue.taskcluster.net/v1/task/Cv2aUe3jSBykYf2hzVuHAA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 02:54:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cv2aUe3jSBykYf2hzVuHAA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 02:54:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cv2aUe3jSBykYf2hzVuHAA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 02:54:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cv2aUe3jSBykYf2hzVuHAA/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 02:54:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cv2aUe3jSBykYf2hzVuHAA/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 02:54:22 INFO - Downloaded 1199 bytes. 02:54:22 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 02:54:22 INFO - Using the following test package requirements: 02:54:22 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 02:54:22 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 02:54:22 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 02:54:22 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 02:54:22 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 02:54:22 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 02:54:22 INFO - u'jsshell-mac.zip'], 02:54:22 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 02:54:22 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 02:54:22 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 02:54:22 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 02:54:22 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 02:54:22 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 02:54:22 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 02:54:22 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 02:54:22 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 02:54:22 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 02:54:22 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 02:54:22 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 02:54:22 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')]} 02:54:22 INFO - https://queue.taskcluster.net/v1/task/Cv2aUe3jSBykYf2hzVuHAA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:54:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cv2aUe3jSBykYf2hzVuHAA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 02:54:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cv2aUe3jSBykYf2hzVuHAA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 02:54:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cv2aUe3jSBykYf2hzVuHAA/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 02:54:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cv2aUe3jSBykYf2hzVuHAA/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 02:54:25 INFO - Downloaded 18788697 bytes. 02:54:25 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'] 02:54:25 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 02:54:28 INFO - Return code: 0 02:54:28 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 02:54:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 02:54:28 INFO - https://queue.taskcluster.net/v1/task/Cv2aUe3jSBykYf2hzVuHAA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:54:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cv2aUe3jSBykYf2hzVuHAA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 02:54:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cv2aUe3jSBykYf2hzVuHAA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 02:54:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cv2aUe3jSBykYf2hzVuHAA/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 02:54:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cv2aUe3jSBykYf2hzVuHAA/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 02:54:30 INFO - Downloaded 18788697 bytes. 02:54:30 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'] 02:54:30 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 02:54:34 INFO - Return code: 0 02:54:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:54:34 INFO - https://queue.taskcluster.net/v1/task/Cv2aUe3jSBykYf2hzVuHAA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 02:54:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cv2aUe3jSBykYf2hzVuHAA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 02:54:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cv2aUe3jSBykYf2hzVuHAA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 02:54:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cv2aUe3jSBykYf2hzVuHAA/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 02:54:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cv2aUe3jSBykYf2hzVuHAA/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 02:54:35 INFO - Downloaded 14405242 bytes. 02:54:35 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'] 02:54:35 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 02:54:35 INFO - Return code: 0 02:54: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')]} 02:54:35 INFO - https://queue.taskcluster.net/v1/task/Cv2aUe3jSBykYf2hzVuHAA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 02:54:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cv2aUe3jSBykYf2hzVuHAA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 02:54:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cv2aUe3jSBykYf2hzVuHAA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 02:54:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cv2aUe3jSBykYf2hzVuHAA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 02:54:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cv2aUe3jSBykYf2hzVuHAA/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 02:54:40 INFO - Downloaded 112407191 bytes. 02:54:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Cv2aUe3jSBykYf2hzVuHAA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 02:54:40 INFO - mkdir: /builds/slave/test/properties 02:54:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:54:40 INFO - Writing to file /builds/slave/test/properties/build_url 02:54:40 INFO - Contents: 02:54:40 INFO - build_url:https://queue.taskcluster.net/v1/task/Cv2aUe3jSBykYf2hzVuHAA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 02:54:42 INFO - Running post-action listener: _resource_record_post_action 02:54:42 INFO - Running post-action listener: set_extra_try_arguments 02:54:42 INFO - ##### 02:54:42 INFO - ##### Finished download-and-extract step (success) 02:54:42 INFO - ##### 02:54:42 INFO - ##### 02:54:42 INFO - ##### Running populate-webroot step. 02:54:42 INFO - ##### 02:54:42 INFO - Running pre-action listener: _resource_record_pre_action 02:54:42 INFO - Running main action method: populate_webroot 02:54:42 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:54:42 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:54:42 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:54:42 INFO - '--tppagecycles', 02:54:42 INFO - '7'], 02:54:42 INFO - 'tests': ['tsvgm']}}, 02:54:42 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:54:42 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:54:42 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:54:42 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:54:42 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:54:42 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:54:42 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:54:42 INFO - 'talos_options': ['--disable-e10s'], 02:54:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:54:42 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:54:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:54:42 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:54:42 INFO - 'talos_options': ['--disable-e10s'], 02:54:42 INFO - 'tests': ['damp', 'tps']}, 02:54:42 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:54:42 INFO - 'tests': ['damp', 'tps']}, 02:54:42 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:54:42 INFO - 'tests': ['dromaeo_dom']}, 02:54:42 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:54:42 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:54:42 INFO - 'tests': ['basic_compositor_video']}, 02:54:42 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:54:42 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:54:42 INFO - 'tests': ['a11yr', 02:54:42 INFO - 'ts_paint', 02:54:42 INFO - 'tpaint', 02:54:42 INFO - 'sessionrestore', 02:54:42 INFO - 'sessionrestore_no_auto_restore', 02:54:42 INFO - 'tabpaint']}, 02:54:42 INFO - 'other-e10s': {'tests': ['a11yr', 02:54:42 INFO - 'ts_paint', 02:54:42 INFO - 'tpaint', 02:54:42 INFO - 'sessionrestore', 02:54:42 INFO - 'sessionrestore_no_auto_restore', 02:54:42 INFO - 'tabpaint']}, 02:54:42 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:54:42 INFO - 'ts_paint', 02:54:42 INFO - 'tpaint', 02:54:42 INFO - 'sessionrestore', 02:54:42 INFO - 'sessionrestore_no_auto_restore', 02:54:42 INFO - 'tabpaint']}, 02:54:42 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:54:42 INFO - 'ts_paint', 02:54:42 INFO - 'tpaint', 02:54:42 INFO - 'sessionrestore', 02:54:42 INFO - 'sessionrestore_no_auto_restore', 02:54:42 INFO - 'tabpaint']}, 02:54:42 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:54:42 INFO - 'tests': ['a11yr', 02:54:42 INFO - 'ts_paint', 02:54:42 INFO - 'tpaint', 02:54:42 INFO - 'sessionrestore', 02:54:42 INFO - 'sessionrestore_no_auto_restore', 02:54:42 INFO - 'tabpaint']}, 02:54:42 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:54:42 INFO - 'tests': ['a11yr', 02:54:42 INFO - 'ts_paint', 02:54:42 INFO - 'tpaint', 02:54:42 INFO - 'sessionrestore', 02:54:42 INFO - 'sessionrestore_no_auto_restore', 02:54:42 INFO - 'tabpaint']}, 02:54:42 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:54:42 INFO - 'tests': ['tsvgx', 02:54:42 INFO - 'tsvgr_opacity', 02:54:42 INFO - 'tart', 02:54:42 INFO - 'tscrollx', 02:54:42 INFO - 'cart']}, 02:54:42 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:54:42 INFO - 'tsvgr_opacity', 02:54:42 INFO - 'tart', 02:54:42 INFO - 'tscrollx', 02:54:42 INFO - 'cart']}, 02:54:42 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:54:42 INFO - 'talos_options': ['--disable-e10s'], 02:54:42 INFO - 'tests': ['tp5o']}, 02:54:42 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:54:42 INFO - 'tests': ['tp5o']}, 02:54:42 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:54:42 INFO - 'talos_options': ['--disable-e10s', 02:54:42 INFO - '--xperf_path', 02:54:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:54:42 INFO - 'tests': ['tp5n']}, 02:54:42 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:54:42 INFO - 'talos_options': ['--xperf_path', 02:54:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:54:42 INFO - 'tests': ['tp5n']}}, 02:54:42 INFO - 'talos.zip': {'path': '', 02:54:42 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:54:42 INFO - Running post-action listener: _resource_record_post_action 02:54:42 INFO - ##### 02:54:42 INFO - ##### Finished populate-webroot step (success) 02:54:42 INFO - ##### 02:54:42 INFO - ##### 02:54:42 INFO - ##### Running create-virtualenv step. 02:54:42 INFO - ##### 02:54:42 INFO - Running pre-action listener: _resource_record_pre_action 02:54:42 INFO - Running main action method: create_virtualenv 02:54:42 INFO - Creating virtualenv /builds/slave/test/build/venv 02:54:42 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 02:54:42 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:54:42 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:54:42 INFO - Using real prefix '/tools/python27' 02:54:42 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:54:43 INFO - Installing distribute.............................................................................................................................................................................................done. 02:54:45 INFO - Installing pip.................done. 02:54:45 INFO - Return code: 0 02:54:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:54:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 02:54:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:54:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:54:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 02:54:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:54:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:54:45 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 0x101545a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015da618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff63a48d4f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014a9d30>, '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 0x10120edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1014a1b20>, '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.Mz4XZ4SMc2/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.UdATBcrsGs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/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 02:54:45 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 02:54:45 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 02:54:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Mz4XZ4SMc2/Render', 02:54:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:54:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:54:45 INFO - 'HOME': '/Users/cltbld', 02:54:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:54:45 INFO - 'LOGNAME': 'cltbld', 02:54:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:54:45 INFO - 'MOZ_NO_REMOTE': '1', 02:54:45 INFO - 'NO_EM_RESTART': '1', 02:54:45 INFO - 'PAGER': '/bin/cat', 02:54:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:54:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:54:45 INFO - 'PWD': '/builds/slave/test', 02:54:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:54:45 INFO - 'SHELL': '/bin/bash', 02:54:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UdATBcrsGs/Listeners', 02:54:45 INFO - 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/', 02:54:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:54:45 INFO - 'USER': 'cltbld', 02:54:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:54:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:54:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:54:45 INFO - 'XPC_FLAGS': '0x0', 02:54:45 INFO - 'XPC_SERVICE_NAME': '0', 02:54:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:54:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:54:46 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 02:54:46 INFO - Cleaning up... 02:54:46 INFO - Return code: 0 02:54:46 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:54: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')]} 02:54:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:54:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:54: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')]} 02:54:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:54:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:54:46 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 0x101545a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015da618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff63a48d4f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014a9d30>, '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 0x10120edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1014a1b20>, '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.Mz4XZ4SMc2/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.UdATBcrsGs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/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 02:54:46 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 02:54:46 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 02:54:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Mz4XZ4SMc2/Render', 02:54:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:54:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:54:46 INFO - 'HOME': '/Users/cltbld', 02:54:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:54:46 INFO - 'LOGNAME': 'cltbld', 02:54:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:54:46 INFO - 'MOZ_NO_REMOTE': '1', 02:54:46 INFO - 'NO_EM_RESTART': '1', 02:54:46 INFO - 'PAGER': '/bin/cat', 02:54:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:54:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:54:46 INFO - 'PWD': '/builds/slave/test', 02:54:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:54:46 INFO - 'SHELL': '/bin/bash', 02:54:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UdATBcrsGs/Listeners', 02:54:46 INFO - 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/', 02:54:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:54:46 INFO - 'USER': 'cltbld', 02:54:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:54:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:54:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:54:46 INFO - 'XPC_FLAGS': '0x0', 02:54:46 INFO - 'XPC_SERVICE_NAME': '0', 02:54:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:54:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:54:46 INFO - Downloading/unpacking psutil>=3.1.1 02:54:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:54:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:54:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:54:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:54:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:54:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:54:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:54:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:54:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:54:49 INFO - Installing collected packages: psutil 02:54:49 INFO - Running setup.py install for psutil 02:54:49 INFO - building 'psutil._psutil_osx' extension 02:54:49 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 02:54:50 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 02:54:50 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 02:54:50 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 02:54:50 INFO - building 'psutil._psutil_posix' extension 02:54:50 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 02:54:50 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:54:50 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:54:50 INFO - ^ 02:54:50 INFO - 1 warning generated. 02:54:50 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 02:54:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:54:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:54:50 INFO - Successfully installed psutil 02:54:50 INFO - Cleaning up... 02:54:50 INFO - Return code: 0 02:54:50 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:54:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:54:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:54:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:54:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:54:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:54:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:54:50 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 0x101545a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015da618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff63a48d4f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014a9d30>, '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 0x10120edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1014a1b20>, '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.Mz4XZ4SMc2/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.UdATBcrsGs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/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 02:54:50 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 02:54:50 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 02:54:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Mz4XZ4SMc2/Render', 02:54:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:54:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:54:50 INFO - 'HOME': '/Users/cltbld', 02:54:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:54:50 INFO - 'LOGNAME': 'cltbld', 02:54:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:54:50 INFO - 'MOZ_NO_REMOTE': '1', 02:54:50 INFO - 'NO_EM_RESTART': '1', 02:54:50 INFO - 'PAGER': '/bin/cat', 02:54:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:54:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:54:50 INFO - 'PWD': '/builds/slave/test', 02:54:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:54:50 INFO - 'SHELL': '/bin/bash', 02:54:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UdATBcrsGs/Listeners', 02:54:50 INFO - 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/', 02:54:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:54:50 INFO - 'USER': 'cltbld', 02:54:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:54:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:54:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:54:50 INFO - 'XPC_FLAGS': '0x0', 02:54:50 INFO - 'XPC_SERVICE_NAME': '0', 02:54:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:54:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:54:51 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:54:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:54:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:54:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:54:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:54:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:54:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:54:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:54:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:54:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:54:53 INFO - Installing collected packages: mozsystemmonitor 02:54:53 INFO - Running setup.py install for mozsystemmonitor 02:54:53 INFO - Successfully installed mozsystemmonitor 02:54:53 INFO - Cleaning up... 02:54:53 INFO - Return code: 0 02:54:53 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:54:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:54:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:54:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:54:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:54:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:54:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:54:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x101545a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015da618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff63a48d4f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014a9d30>, '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 0x10120edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1014a1b20>, '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.Mz4XZ4SMc2/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.UdATBcrsGs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/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 02:54:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:54:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 02:54:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Mz4XZ4SMc2/Render', 02:54:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:54:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:54:53 INFO - 'HOME': '/Users/cltbld', 02:54:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:54:53 INFO - 'LOGNAME': 'cltbld', 02:54:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:54:53 INFO - 'MOZ_NO_REMOTE': '1', 02:54:53 INFO - 'NO_EM_RESTART': '1', 02:54:53 INFO - 'PAGER': '/bin/cat', 02:54:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:54:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:54:53 INFO - 'PWD': '/builds/slave/test', 02:54:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:54:53 INFO - 'SHELL': '/bin/bash', 02:54:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UdATBcrsGs/Listeners', 02:54:53 INFO - 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/', 02:54:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:54:53 INFO - 'USER': 'cltbld', 02:54:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:54:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:54:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:54:53 INFO - 'XPC_FLAGS': '0x0', 02:54:53 INFO - 'XPC_SERVICE_NAME': '0', 02:54:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:54:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:54:54 INFO - Downloading/unpacking jsonschema==2.5.1 02:54:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:54:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:54:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:54:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:54:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:54:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:54:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:54:56 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:54:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:54:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:54:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:54:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:54:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:54:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:54:56 INFO - Downloading functools32-3.2.3-2.tar.gz 02:54:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:54:56 INFO - warning: no files found matching '*.txt' 02:54:56 INFO - no previously-included directories found matching 'build' 02:54:56 INFO - no previously-included directories found matching 'dist' 02:54:56 INFO - no previously-included directories found matching '.git*' 02:54:56 INFO - Installing collected packages: jsonschema, functools32 02:54:56 INFO - Running setup.py install for functools32 02:54:57 INFO - warning: no files found matching '*.txt' 02:54:57 INFO - no previously-included directories found matching 'build' 02:54:57 INFO - no previously-included directories found matching 'dist' 02:54:57 INFO - no previously-included directories found matching '.git*' 02:54:57 INFO - Successfully installed jsonschema functools32 02:54:57 INFO - Cleaning up... 02:54:57 INFO - Return code: 0 02:54:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:54:57 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')]} 02:54:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:54:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:54:57 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')]} 02:54:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:54:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:54:57 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 0x101545a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015da618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff63a48d4f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014a9d30>, '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 0x10120edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1014a1b20>, '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.Mz4XZ4SMc2/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.UdATBcrsGs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/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 02:54:57 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 02:54:57 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 02:54:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Mz4XZ4SMc2/Render', 02:54:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:54:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:54:57 INFO - 'HOME': '/Users/cltbld', 02:54:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:54:57 INFO - 'LOGNAME': 'cltbld', 02:54:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:54:57 INFO - 'MOZ_NO_REMOTE': '1', 02:54:57 INFO - 'NO_EM_RESTART': '1', 02:54:57 INFO - 'PAGER': '/bin/cat', 02:54:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:54:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:54:57 INFO - 'PWD': '/builds/slave/test', 02:54:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:54:57 INFO - 'SHELL': '/bin/bash', 02:54:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UdATBcrsGs/Listeners', 02:54:57 INFO - 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/', 02:54:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:54:57 INFO - 'USER': 'cltbld', 02:54:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:54:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:54:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:54:57 INFO - 'XPC_FLAGS': '0x0', 02:54:57 INFO - 'XPC_SERVICE_NAME': '0', 02:54:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:54:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:54:57 INFO - Downloading/unpacking blobuploader==1.2.4 02:54:57 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 02:54:57 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 02:54:57 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 02:54:57 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 02:54:57 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 02:54:57 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 02:54:59 INFO - Downloading blobuploader-1.2.4.tar.gz 02:54:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:54:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:54:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:54:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:54:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:54:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:54:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:54:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:55:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:55:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:55:00 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 02:55:00 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 02:55:00 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 02:55:00 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 02:55:00 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 02:55:00 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 02:55:00 INFO - Downloading docopt-0.6.1.tar.gz 02:55:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:55:01 INFO - Installing collected packages: blobuploader, requests, docopt 02:55:01 INFO - Running setup.py install for blobuploader 02:55:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:55:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:55:01 INFO - Running setup.py install for requests 02:55:01 INFO - Running setup.py install for docopt 02:55:01 INFO - Successfully installed blobuploader requests docopt 02:55:01 INFO - Cleaning up... 02:55:01 INFO - Return code: 0 02:55:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:55:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:55:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:55:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:55:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:55:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:55:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:55:01 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 0x101545a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015da618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff63a48d4f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014a9d30>, '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 0x10120edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1014a1b20>, '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.Mz4XZ4SMc2/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.UdATBcrsGs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/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 02:55:01 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 02:55:01 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 02:55:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Mz4XZ4SMc2/Render', 02:55:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:55:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:55:01 INFO - 'HOME': '/Users/cltbld', 02:55:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:55:01 INFO - 'LOGNAME': 'cltbld', 02:55:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:55:01 INFO - 'MOZ_NO_REMOTE': '1', 02:55:01 INFO - 'NO_EM_RESTART': '1', 02:55:01 INFO - 'PAGER': '/bin/cat', 02:55:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:55:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:55:01 INFO - 'PWD': '/builds/slave/test', 02:55:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:55:01 INFO - 'SHELL': '/bin/bash', 02:55:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UdATBcrsGs/Listeners', 02:55:01 INFO - 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/', 02:55:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:55:01 INFO - 'USER': 'cltbld', 02:55:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:55:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:55:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:55:01 INFO - 'XPC_FLAGS': '0x0', 02:55:01 INFO - 'XPC_SERVICE_NAME': '0', 02:55:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:55:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:55:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:55:02 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-QyJzDK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:55:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:55:02 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-59Dfxn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:55:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:55:02 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-fPgpO1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:55:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:55:02 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-7mpmHa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:55:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:55:02 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-mGmiAI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:55:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:55:02 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-D0jAwC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:55:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:55:02 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-EJEsWd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:55:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:55:02 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-80zOzB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:55:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:55:03 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-AaPNLK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:55:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:55:03 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-pM3sRg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:55:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:55:03 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-GUf1RZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:55:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:55:03 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-9phkhP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:55:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:55:03 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-fVfU5w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:55:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:55:03 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-o3f7qG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:55:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:55:03 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-G04r94-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:55:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:55:03 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-B0qYAP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:55:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:55:03 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-ZljoK4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:55:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:55:03 INFO - Running setup.py install for manifestparser 02:55:04 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:55:04 INFO - Running setup.py install for mozcrash 02:55:04 INFO - Running setup.py install for mozdebug 02:55:04 INFO - Running setup.py install for mozdevice 02:55:04 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:55:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:55:04 INFO - Running setup.py install for mozfile 02:55:04 INFO - Running setup.py install for mozhttpd 02:55:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:55:04 INFO - Running setup.py install for mozinfo 02:55:05 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:55:05 INFO - Running setup.py install for mozInstall 02:55:05 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:55:05 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:55:05 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:55:05 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:55:05 INFO - Running setup.py install for mozleak 02:55:05 INFO - Running setup.py install for mozlog 02:55:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:55:05 INFO - Running setup.py install for moznetwork 02:55:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:55:05 INFO - Running setup.py install for mozprocess 02:55:05 INFO - Running setup.py install for mozprofile 02:55:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:55:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:55:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:55:05 INFO - Running setup.py install for mozrunner 02:55:06 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:55:06 INFO - Running setup.py install for mozscreenshot 02:55:06 INFO - Running setup.py install for moztest 02:55:06 INFO - Running setup.py install for mozversion 02:55:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:55:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:55:06 INFO - Cleaning up... 02:55:06 INFO - Return code: 0 02:55:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:55:06 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')]} 02:55:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:55:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:55:06 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')]} 02:55:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:55:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:55:06 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 0x101545a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015da618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff63a48d4f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014a9d30>, '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 0x10120edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1014a1b20>, '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.Mz4XZ4SMc2/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.UdATBcrsGs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/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 02:55:06 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 02:55:06 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 02:55:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Mz4XZ4SMc2/Render', 02:55:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:55:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:55:06 INFO - 'HOME': '/Users/cltbld', 02:55:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:55:06 INFO - 'LOGNAME': 'cltbld', 02:55:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:55:06 INFO - 'MOZ_NO_REMOTE': '1', 02:55:06 INFO - 'NO_EM_RESTART': '1', 02:55:06 INFO - 'PAGER': '/bin/cat', 02:55:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:55:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:55:06 INFO - 'PWD': '/builds/slave/test', 02:55:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:55:06 INFO - 'SHELL': '/bin/bash', 02:55:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UdATBcrsGs/Listeners', 02:55:06 INFO - 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/', 02:55:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:55:06 INFO - 'USER': 'cltbld', 02:55:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:55:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:55:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:55:06 INFO - 'XPC_FLAGS': '0x0', 02:55:06 INFO - 'XPC_SERVICE_NAME': '0', 02:55:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:55:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:55:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:55:06 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-MNsScm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:55:06 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)) 02:55:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:55:06 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-jq5Y73-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:55:07 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)) 02:55:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:55:07 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-8JV1Bq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:55:07 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)) 02:55:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:55:07 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-zgNFgV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:55:07 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)) 02:55:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:55:07 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-BYlj95-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:55:07 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)) 02:55:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:55:07 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-bJxI6y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:55:07 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)) 02:55:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:55:07 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-y2WVKd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:55:07 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)) 02:55:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:55:07 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-7JPlBe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:55:07 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)) 02:55:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:55:07 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-UUyyO6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:55:07 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)) 02:55:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:55:07 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-uvjCde-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:55:07 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)) 02:55:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:55:07 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-YoE48d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:55:07 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)) 02:55:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:55:07 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-QW4vak-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:55:08 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)) 02:55:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:55:08 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-5W5gIr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:55:08 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)) 02:55:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:55:08 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-TV0h0s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:55:08 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)) 02:55:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:55:08 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-INlZyv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:55:08 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)) 02:55:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:55:08 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-edk2wu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:55:08 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)) 02:55:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:55:08 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-TOg1l3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:55:08 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)) 02:55:08 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)) 02:55:08 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)) 02:55:08 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)) 02:55:08 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)) 02:55:08 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)) 02:55:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:55:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:55:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:55:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:55:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:55:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:55:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:55:11 INFO - Downloading blessings-1.6.tar.gz 02:55:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:55:11 INFO - Installing collected packages: blessings 02:55:11 INFO - Running setup.py install for blessings 02:55:11 INFO - Successfully installed blessings 02:55:11 INFO - Cleaning up... 02:55:11 INFO - Return code: 0 02:55:11 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:55:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:55:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:55:11 INFO - Reading from file tmpfile_stdout 02:55:11 INFO - Current package versions: 02:55:11 INFO - blessings == 1.6 02:55:11 INFO - blobuploader == 1.2.4 02:55:11 INFO - docopt == 0.6.1 02:55:11 INFO - functools32 == 3.2.3-2 02:55:11 INFO - jsonschema == 2.5.1 02:55:11 INFO - manifestparser == 1.1 02:55:11 INFO - mozInstall == 1.12 02:55:11 INFO - mozcrash == 0.17 02:55:11 INFO - mozdebug == 0.1 02:55:11 INFO - mozdevice == 0.48 02:55:11 INFO - mozfile == 1.2 02:55:11 INFO - mozhttpd == 0.7 02:55:11 INFO - mozinfo == 0.9 02:55:11 INFO - mozleak == 0.1 02:55:11 INFO - mozlog == 3.2 02:55:11 INFO - moznetwork == 0.27 02:55:11 INFO - mozprocess == 0.22 02:55:11 INFO - mozprofile == 0.28 02:55:11 INFO - mozrunner == 6.11 02:55:11 INFO - mozscreenshot == 0.1 02:55:11 INFO - mozsystemmonitor == 0.3 02:55:11 INFO - moztest == 0.7 02:55:11 INFO - mozversion == 1.4 02:55:11 INFO - psutil == 3.1.1 02:55:11 INFO - requests == 1.2.3 02:55:11 INFO - wsgiref == 0.1.2 02:55:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:55: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')]} 02:55:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:55:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:55: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')]} 02:55:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:55:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:55:11 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 0x101545a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015da618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff63a48d4f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014a9d30>, '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 0x10120edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1014a1b20>, '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.Mz4XZ4SMc2/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.UdATBcrsGs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/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 02:55:11 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 02:55:11 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 02:55:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Mz4XZ4SMc2/Render', 02:55:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:55:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:55:11 INFO - 'HOME': '/Users/cltbld', 02:55:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:55:11 INFO - 'LOGNAME': 'cltbld', 02:55:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:55:11 INFO - 'MOZ_NO_REMOTE': '1', 02:55:11 INFO - 'NO_EM_RESTART': '1', 02:55:11 INFO - 'PAGER': '/bin/cat', 02:55:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:55:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:55:11 INFO - 'PWD': '/builds/slave/test', 02:55:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:55:11 INFO - 'SHELL': '/bin/bash', 02:55:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UdATBcrsGs/Listeners', 02:55:11 INFO - 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/', 02:55:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:55:11 INFO - 'USER': 'cltbld', 02:55:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:55:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:55:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:55:11 INFO - 'XPC_FLAGS': '0x0', 02:55:11 INFO - 'XPC_SERVICE_NAME': '0', 02:55:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:55:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:55:11 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)) 02:55:11 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)) 02:55:11 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)) 02:55:11 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)) 02:55:11 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)) 02:55:11 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)) 02:55:11 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)) 02:55:11 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)) 02:55:11 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)) 02:55:11 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)) 02:55:11 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)) 02:55:11 INFO - Cleaning up... 02:55:11 INFO - Return code: 0 02:55:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:55: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')]} 02:55:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:55:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:55: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')]} 02:55:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:55:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:55:11 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 0x101545a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015da618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff63a48d4f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014a9d30>, '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 0x10120edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1014a1b20>, '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.Mz4XZ4SMc2/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.UdATBcrsGs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/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 02:55:11 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 02:55:11 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 02:55:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Mz4XZ4SMc2/Render', 02:55:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:55:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:55:11 INFO - 'HOME': '/Users/cltbld', 02:55:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:55:11 INFO - 'LOGNAME': 'cltbld', 02:55:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:55:11 INFO - 'MOZ_NO_REMOTE': '1', 02:55:11 INFO - 'NO_EM_RESTART': '1', 02:55:11 INFO - 'PAGER': '/bin/cat', 02:55:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:55:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:55:11 INFO - 'PWD': '/builds/slave/test', 02:55:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:55:11 INFO - 'SHELL': '/bin/bash', 02:55:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UdATBcrsGs/Listeners', 02:55:11 INFO - 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/', 02:55:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:55:11 INFO - 'USER': 'cltbld', 02:55:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:55:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:55:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:55:11 INFO - 'XPC_FLAGS': '0x0', 02:55:11 INFO - 'XPC_SERVICE_NAME': '0', 02:55:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:55:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:55:12 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:55:12 INFO - Cleaning up... 02:55:12 INFO - Return code: 0 02:55:12 INFO - Running post-action listener: _resource_record_post_action 02:55:12 INFO - Running post-action listener: _start_resource_monitoring 02:55:12 INFO - Starting resource monitoring. 02:55:12 INFO - ##### 02:55:12 INFO - ##### Finished create-virtualenv step (success) 02:55:12 INFO - ##### 02:55:12 INFO - ##### 02:55:12 INFO - ##### Running install step. 02:55:12 INFO - ##### 02:55:12 INFO - Running pre-action listener: _resource_record_pre_action 02:55:12 INFO - Running main action method: install 02:55:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:55:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:55:12 INFO - Reading from file tmpfile_stdout 02:55:12 INFO - Detecting whether we're running mozinstall >=1.0... 02:55:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:55:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:55:12 INFO - Reading from file tmpfile_stdout 02:55:12 INFO - Output received: 02:55:12 INFO - Usage: mozinstall [options] installer 02:55:12 INFO - Options: 02:55:12 INFO - -h, --help show this help message and exit 02:55:12 INFO - -d DEST, --destination=DEST 02:55:12 INFO - Directory to install application into. [default: 02:55:12 INFO - "/builds/slave/test"] 02:55:12 INFO - --app=APP Application being installed. [default: firefox] 02:55:12 INFO - mkdir: /builds/slave/test/build/application 02:55:12 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'] 02:55:12 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 02:55:47 INFO - Reading from file tmpfile_stdout 02:55:47 INFO - Output received: 02:55:47 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 02:55:47 INFO - Running post-action listener: _resource_record_post_action 02:55:47 INFO - ##### 02:55:47 INFO - ##### Finished install step (success) 02:55:47 INFO - ##### 02:55:47 INFO - ##### 02:55:47 INFO - ##### Running run-tests step. 02:55:47 INFO - ##### 02:55:47 INFO - Running pre-action listener: _resource_record_pre_action 02:55:47 INFO - Running main action method: run_tests 02:55:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:55:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:55:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:55:47 INFO - 2016-07-01 02:55:47.107 screenresolution[1641:8004] starting screenresolution argv=screenresolution get 02:55:47 INFO - 2016-07-01 02:55:47.110 screenresolution[1641:8004] Display 0: 1600x1200x32@60 02:55:47 INFO - 2016-07-01 02:55:47.116 screenresolution[1642:8006] starting screenresolution argv=screenresolution list 02:55:47 INFO - Available Modes on Display 0 02:55:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:55:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:55:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:55:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:55:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:55:47 INFO - Intel Iris: 02:55:47 INFO - Chipset Model: Intel Iris 02:55:47 INFO - Type: GPU 02:55:47 INFO - Bus: Built-In 02:55:47 INFO - VRAM (Dynamic, Max): 1536 MB 02:55:47 INFO - Vendor: Intel (0x8086) 02:55:47 INFO - Device ID: 0x0a2e 02:55:47 INFO - Revision ID: 0x0009 02:55:47 INFO - Displays: 02:55:47 INFO - Display: 02:55:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:55:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:55:47 INFO - Display Serial Number: 1600x1200 60 02:55:47 INFO - Main Display: Yes 02:55:47 INFO - Mirror: Off 02:55:47 INFO - Online: Yes 02:55:47 INFO - Rotation: Supported 02:55:47 INFO - Return code: 0 02:55:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:55:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:55:47 INFO - Python 2.7.3 02:55:47 INFO - Return code: 0 02:55:47 INFO - grabbing minidump binary from tooltool 02:55:47 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')]} 02:55:47 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 0x1014a9d30>, '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 0x10120edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1014a1b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:55:47 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 02:55:47 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 02:55:47 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 02:55:47 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:55:47 INFO - Return code: 0 02:55:47 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:55:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:55:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:55:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:55:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:55:47 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/Cv2aUe3jSBykYf2hzVuHAA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0099', '--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 02:55:47 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/Cv2aUe3jSBykYf2hzVuHAA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0099 --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 02:55:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Mz4XZ4SMc2/Render', 02:55:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:55:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:55:47 INFO - 'HOME': '/Users/cltbld', 02:55:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:55:47 INFO - 'LOGNAME': 'cltbld', 02:55:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:55:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:55:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:55:47 INFO - 'MOZ_NO_REMOTE': '1', 02:55:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:55:47 INFO - 'NO_EM_RESTART': '1', 02:55:47 INFO - 'PAGER': '/bin/cat', 02:55:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:55:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:55:47 INFO - 'PWD': '/builds/slave/test', 02:55:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:55:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:55:47 INFO - 'SHELL': '/bin/bash', 02:55:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UdATBcrsGs/Listeners', 02:55:47 INFO - 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/', 02:55:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:55:47 INFO - 'USER': 'cltbld', 02:55:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:55:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:55:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:55:47 INFO - 'XPC_FLAGS': '0x0', 02:55:47 INFO - 'XPC_SERVICE_NAME': '0', 02:55:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:55:47 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/Cv2aUe3jSBykYf2hzVuHAA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0099', '--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 02:55:47 INFO - mozversion application_buildid: 20160701015622 02:55:47 INFO - mozversion application_changeset: c6ce12a74300f69b8a8aabc2677aa4710809194a 02:55:47 INFO - mozversion application_display_name: Nightly 02:55:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:55:47 INFO - mozversion application_name: Firefox 02:55:47 INFO - mozversion application_remotingname: firefox 02:55:47 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 02:55:47 INFO - mozversion application_vendor: Mozilla 02:55:47 INFO - mozversion application_version: 50.0a1 02:55:47 INFO - mozversion platform_buildid: 20160701015622 02:55:47 INFO - mozversion platform_changeset: c6ce12a74300f69b8a8aabc2677aa4710809194a 02:55:47 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 02:55:47 INFO - mozversion platform_version: 50.0a1 02:55:47 INFO - using testdate: 1467366947 02:55:47 INFO - actual date: 1467366947 02:55:47 INFO - starting webserver on 'localhost:49274' 02:55:47 INFO - SUITE-START | Running 2 tests 02:55:47 INFO - TEST-START | dromaeo_css 02:55:47 INFO - operating with platform_type : mac_ 02:55:47 INFO - Initialising browser for dromaeo_css test... 02:55:47 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/tmpTwMqjF/profile http://localhost:49274/getInfo.html) 02:55:50 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 02:55:50 INFO - PROCESS | 1652 | colorDepth:24 02:55:50 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 02:55:50 INFO - PROCESS | 1652 | __metrics 02:55:50 INFO - PROCESS | 1652 | 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" 02:55:50 INFO - PROCESS | 1652 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:55:50 INFO - TEST-INFO | 1652: exit 0 02:55:50 INFO - Browser initialized. 02:55:50 INFO - Running cycle 1/1 for dromaeo_css test... 02:55:50 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/tmpTwMqjF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:56:02 INFO - PROCESS | 1655 | RSS: Main: 244957184 02:56:02 INFO - PROCESS | 1655 | 02:56:54 INFO - PROCESS | 1655 | 1467367014373 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] 02:59:04 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49274/tests/dromaeo/cssquery-ext.html) 02:59:06 INFO - PROCESS | 1655 | RSS: Main: 388653056 02:59:06 INFO - PROCESS | 1655 | 03:02:09 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-ext.html (next: http://localhost:49274/tests/dromaeo/cssquery-jquery.html) 03:02:10 INFO - PROCESS | 1655 | RSS: Main: 375201792 03:02:10 INFO - PROCESS | 1655 | 03:05:12 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49274/tests/dromaeo/cssquery-mootools.html) 03:05:13 INFO - PROCESS | 1655 | RSS: Main: 373792768 03:05:13 INFO - PROCESS | 1655 | 03:08:15 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49274/tests/dromaeo/cssquery-prototype.html) 03:08:16 INFO - PROCESS | 1655 | RSS: Main: 370126848 03:08:16 INFO - PROCESS | 1655 | 03:11:19 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49274/tests/dromaeo/cssquery-yui.html) 03:11:20 INFO - PROCESS | 1655 | RSS: Main: 347340800 03:11:20 INFO - PROCESS | 1655 | 03:14:23 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-yui.html (next: http://localhost:49274/tests/dromaeo/cssquery-dojo.html) 03:14:23 INFO - PROCESS | 1655 | RSS: Main: 425005056 03:14:23 INFO - PROCESS | 1655 | 03:14:23 INFO - PROCESS | 1655 | __start_tp_report 03:14:23 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 03:14:23 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 03:14:23 INFO - PROCESS | 1655 | |i|pagename|runs| 03:14:23 INFO - PROCESS | 1655 | |0;dojo.html;3318.2202182487117;3370.106921665759;3381.797092174469;3388.9491657625126;3437.0891713695873;5620.1288800235925;5649.689267090308;5686.65880047197;5691.146328050794;5702.429757024301;4653.930191047137;5678.687672178028;5806.332271788746;5821.068629019358;5840.0947853082735;5187.948120518803;5196.610254230929;5264.052470555318;5279.70961597112;5385.757640906149;5348.144296912495;5360.249565060875;5370.140777475604;5376.059189641816;5401.675899446026;5189.117849965515;5242.973785131088;5252.264682944391;5289.127293996486;5322.281491998569;5206.453322401131;5219.634625576211;5222.764975576109;5225.16397376418;5243.501867322599;5269.473052694738;5270.868228294285;5276;5283.735813209324;5330.253764472977;5617.522510586605;5668.206451096849;5760.0783874579865;5805.883882322372;5854.795082172146;6195.163652906845;6248.062790581449;6273.466755325803;6275.560710750249;6295.748170073191;6314.494840412802;6324.462420694201;6336.429721325103;6362.618242905441;6366.9681651591445;5654.15187721845;5699.487046165865;5724.084146536534;5792.27596550427;5843.883122337529;6308.495320374359;6317;6332.33510481402;6334.429901308904;6352;6189.721462534197;6207.944649409521;6213.440790328892;6227.034809604518;6228.252609686867;6130.356312587203;6172.351903050205;6259.374062593795;6263.311035786151;6310.810675679737;6243.094751261042;6259.843503912439;6270.717817698214;6280.5603607747935;6294.150289710922;6069.605475644069;6286.717097730521;6308.779192728232;6311.873762524724;6312.7790527332245;4897.853064408002;4921.384195263644;4946.183879659888;4946.975265123723;4952.281919121707;4061.1877624475223;4314.439122914001;4508.549145085465;4528.049109687003;4541.069080838421;5621.072523033736;5670.092785154356;5673.432656734293;5684.459976302244;5689.487946084872;6227.09707092469;6271.749130034838;6321.336259692758;6331.303556608769;6351.55539112267;5706.885862282731;5713.457221563945;5724.914126288026;5744;5770.336411312669;5354.678719276856;5419.674819510842;5432.103702889058;5456.7544460499375;5458.099413596712;6249.500039996789;6278.152449419361;6284.717187726564;6347.270063942706;6362.586431881913;6334.144890439732;6339.873202535831;6343.429091381798;6343.492520598249;6344.651044192429;6252.906206406817;6259.96870015647;6323.525735569942;6355.3644635536075;6368.203974503187;6168.19813424252;6259.2488901332135;6265.216847894013;6279.2464904209955;6281.623102613858;6312.4634406076;6332.95506241465;6351.936480635134;6356.173697419306;6359.3640635936035;5938.049912014057;6027.427394397525;6068.423499767515;6153.723082461211;6166.506679465721;6095.2685677718955;6113.388661133851;6156.353582873734;6162.845928851814;6167.629942203482;5975.521958243417;5991.340952495135;5991.940080599138;6001.819945401645;6027.6986150693165;5830.2129212555765;5847.883042339215;5987.371326010706;6166.722497487714;6177.722002509988;4889.828855990023;6038.788642397495;6043.8186854394435;6044.304904935989;6096.817095487142 03:14:23 INFO - PROCESS | 1655 | |1;ext.html;494.33511926458925;524.6196507532039;532.7682458130713;545.8335207761631;558.606182641238;30185.54721679174;41473.79263103684;42588.14823703532;42645.36031959523;42646.786766066165;43574;43928.78035609826;44061.559384406144;44192.3371149433;44206.33690494637;8741.169588889077;9660.323777335572;9859.802803943916;9870.161036311887;9890.604375824958;8668.653253869837;11871.169018168732;12067.3966301685;12071.155019148619;12409.441575129096;12002.039836813077;12102.81845772314;12156.392180390989;12160.513579456856;12204.877951220464;8704.173103555153;8886.200241978253;8898.73303800887;12012.579559715412;12088.395580220953;8885.555722213929;11577.594784182513;11826.11304152192;11842.171048026641;12148.271103733805;41875.371869421986;41938.79030604858;42092.368614470804;42163.57836421658;42299.57700422987;3447.9482807757904;3471.3230919970615;3907.98046009771;4095.7133000689964;4335.544767799367;32215.677843221736;32252.92427509369;33163.005109846745;33405.16487087842;33971.6602833971;3316.767826252163;3354.714849237818;3410.556627638391;3457.6888080072663;3462.23830757233;525169;525736;527001.3649931764;527638;529420;33885.830570846985;46109.769451152526;58160.4183958155;58291.12563311554;58332.54168645818;33709.15727106793;35786.46320305197;35890.28219435597;35896.64103358933;35944.46083308753;14693.55919322422;14962.551123466314;14965.476208332657;14972.401103955719;14979.101253924799;10419.42693151884;11449.54201831934;11543;12430.937845310897;12542;7934.603269836601;7957.5225486471;7960.442769006114;8017.959910200411;8034.758957231293;2778.9166325010283;3065.7700672449655;3222.8388580570877;3254.8698052078125;3288.5724855769076;767.4014268870291;979.8383266760904;986.5954958466995;1035.5598870480017;1062.8140075486758;30756.231094222825;30802.845985770375;30940.5631282461;31234.375312494078;31540.738370464926;1104.26566333389;1124.2299025167786;1127.177160672697;1131.8075927092414;1146.529922731676;15860.92069539645;16262.918685406497;16285.430009949594;16308.347666093461;16730.6653866922;706852;710897;713000;713541;713833;59480.40519594749;60356.39643603595;60612;60668.39331606627;60710;1210.975780484403;1248.9812652810215;1276.4575055601333;1380.9309534523434;1421.3035612549984;29613.5557966635;30459.543106853856;32042.519362210052;32874.91814014319;32927.835360824;51223;51342.48657513526;51882;51889.48110518847;52045;456063;538890;542788;542973.2851335718;543423;24430.022799087837;26975.055873044348;27361;27407.17778466649;28376.148715538566;48234;48560.02879942452;49043.50956490532;49850;50063;40049;40474;40883.97790055272;41381.58618413898;41764.3735343964;39468.82921439348;40255.798721006206;40906.590934091466;41278.587214127474;42240.78879605582;35021;39029.6097039026;39553;39893.80053099832;40046.39814407391;33828.492572610936;34035.65964340424;35408.46887296744;36343.63656363403;36564.451533227555;24659.753402465747;25062;25879.870600646875;26549.660392777776;26639.334016648965 03:14:23 INFO - PROCESS | 1655 | |2;jquery.html;358.653898987477;376.84360990189066;383.97312188146833;385.5893473450773;399.2354640862748;15276.847231527696;15749.60625984351;19937.900310498426;20458.386248412553;20514.97425128745;20789.272375466862;21297.467563310925;21421.821799801004;21454.89272553639;21653;5821.67980761058;5851.941480585182;6108.389161083901;6128.540359473035;6775.457963362944;6746.224184218829;6756.155480564929;6788.898166527506;6803.795886123424;6825.488088393366;5913.201717768089;6470.773522926675;6588;6751.459883209332;6754.898676519856;6389.616623002635;6652.334766523333;6681.732730690766;6689.163854518185;6707.094542236833;5754.913676294859;5772.5670574707065;5781.479666830005;5802.419758024206;5803.9709801451145;20565;26007.479850403077;26255.606165907528;26818.061367852228;26846.865765671046;2251.2683377902085;2322.988385058064;2793.5809628555676;3085.5834462347743;3191.856366463515;15246.771298430533;15762.842371576367;15925.124118173495;15951.883368164234;15986.440474583334;1819.372316550788;1888.7355770192185;1935.6709359408935;1960.2746983616012;1962.0287957461005;113676;119046;119942.40028799887;120648;120880.39559802233;8761.956190219009;10409.375437473776;10562.31344962575;10832.40421776794;11031.448427578589;13404.597862064154;13497.527586534425;13532;13534.864651353557;13535.729285414434;6949.583025018515;8274.793130171794;8440.366972477088;8524;8564.272036876811;8494.405391622651;8523.616437260338;8528.658853645784;8634.747961545667;8682.652693892174;6543.705533251015;6644.800655980328;6650;6658.679300624873;6715.664216789141;1834.8807327523668;1864.1518109260253;1868.1593283022694;1871.8783279087088;1873.9437816865516;896.7399454158351;900.3697411811758;903.7379160043514;908.8954770201511;912.8767616371838;21685.67471487929;22029.449263768533;22746;22792.430189245402;22837.771622283566;954.71358592422;971.121135372489;988.3723835364596;990.9306348555676;1021.6451080259924;11320.150988675883;11558.884411155948;11964.042876569854;12001;12107.576234831915;548693.0261394749;589066.2733431698;593018.0349098227;598423;616220;143427;145134;146310.26844865707;148588;151668.48331516545;1012.0739523336213;1014.6753039027441;1037.8339465685453;1064.8668916385452;1070.925035247525;19419.51451213731;19953.75849550291;19995.921027105294;20003.483540272096;20111.040719919154;57237.71381143068;59504.40495595162;60069.69965150146;60499.39500604937;60670.179894603236;87521;88017;88043;88819;92204;7204.747833826001;7470.663820127998;7492.7752167435065;7833;8528.701495447629;9328.673496427593;11830.763384732429;13415.530456433979;13623.250721210032;13843.446262149804;11346.773064538827;11714.648560543197;11763.242107987333;11824.98462891957;11906;11298.209125361474;11332.6033588824;11472.254303470248;11572.537098515964;11605.477753501282;11034.503447345049;11040.33757974524;11764.88235117672;12049.698757531132;12076.51693932233;7269.055022846996;7569.962150189213;7581;7589.772306830804;7710.8843367350655;9401.811963760823;11434;11468.369239691898;11682.532698692297;11701.239419437708 03:14:23 INFO - PROCESS | 1655 | |3;mootools.html;1382.4539306973752;1508.117751115597;1550.9844901550985;1722.9569260768478;1888.2541396148522;7790.766277011691;8662.047174810774;8895.866562001558;8896.06591307913;9025.503597302146;8681.262092722127;8896.822063558724;8902.73291801247;8906.866397004034;8911.821763564725;2073.637113505138;2143.3677065265706;2148.376970678509;2322.1059891941595;2351.0360752091647;1865.664180447519;2097.2974053692033;2112.6408510553165;2139.6683514055267;2217.0466699319286;2336.275754516097;2338.1232037985756;2338.3920180753043;2343.250159948817;2344.4373350395954;2290.0839664134314;2333.6266197408504;2341.203990643181;2344.050659482912;2344.2732752846673;1825.3611236067402;2133.07211363056;2306.215886598548;2383.1420688552107;2392.1986134644917;8924.776880577972;8990.910090899139;8991.685291014848;9001.459912405277;9006.86489702655;5263.552598029172;5446.373667028302;5447.618666693333;5449.509544141047;5457;3483.878064267764;3606.549181352331;3649.8540058397634;3664.2488289900507;3671.9082022949374;919.6689191890914;1033.1166852341214;1080.9783804323947;1086.9239153259275;1094.5074716377583;11986.640400787903;12059.758804823941;12067.396630168545;12070;12099.637010889688;4696.322754251781;4710.29345598163;4768.594669453136;4864.318995340655;4888.6577939543895;10012.799744005079;10117.797644047225;10131.341462804894;10135.645252416278;10144.695659130239;5438.809641662523;5441.183822426588;5441.347038355423;5442.91835622466;5450.04624190765;4395.912081758347;4919.1391506486225;4935.087008903364;4993.076280888047;4995.025969935828;3382.983085084608;3789.810509474515;3951.7233793634746;3985.8604948827233;4092.5907409258834;1267.1953309648256;1289.2457912121326;1293.482606957206;1298.539018648385;1317.9406926688323;905.4431524612447;913.182701482183;916.4913473022403;928.8250363290443;974.3277138774223;5912;5915.37888521699;6041.093835924647;6090.695465226721;6167;891.6032365597376;901.4073296554046;933.4026223217146;969.9515024248759;997.7954519323521;4009.298372784751;4046.858359957387;4081.0817566047995;4122.907429531177;4142.772147531854;10449.529771160316;11128.833067503994;11163.330200188015;11187.944060279646;11197.832032519356;10280.228982826318;10920.12638988879;11084.279521831055;11122.49948752308;11122.61070862516;8060.637271322805;8356.749297521084;8363.498190108492;8370.074882776995;8375.120612335739;5656.717164141694;6004.219451471281;6054.636721796706;6230.190075290183;6340.778072767431;9568.904310956801;10248.026437488426;10251;10253.07722304996;10273.178145748472;10089.991000900145;10225.386476811414;10239.027292407209;10249;10252.538635761557;6636.303188165173;7359.705611775469;7471.215522370073;7657.234276572521;7668.846623067619;7728;8531.74404767858;8534;8542.06037335905;8544.145585441405;7385.409167266498;7450.403967682681;7690.961545192238;7847.803804904923;7857.410694197843;7329.120505539369;7426.257374262746;7468;7601.467897247361;7624.237576242332;7163.2478589747325;7191.964040179766;7227.638618069181;7230.45771567124;7241.239669834592;4951.455339912534;5061.468545802638;5121.334226550524;5178.741062946762;5244.868878278074;5092.185250359925;5141.434442211428;5187.325647665779;5218.321618189611;5271.288376069181 03:14:23 INFO - PROCESS | 1655 | |4;prototype.html;296.20469040625915;304.03014384114675;309.1976321445848;309.365800109775;312.28486765307446;4739.2701663892685;5221.477852214776;5239.607029472786;5250.405894842013;5270.259465078157;4354.62829208799;5162.974185129078;5206.709048018869;5240.292560504331;5244.973775131129;3362.9574831802097;3753.999999999993;3845.750026248299;3887.961120388788;3925.5878132796074;3646.3072016316814;3862.5558060822946;3891.54832455554;3930.3711406175016;3949.3483575210057;3975.145343751093;3981.203759248162;3997.9400308995387;4000.439938408625;4001.2397644447606;3903.590123037083;4001.299772539823;4009.759414435114;4011.618896204856;4030.697697672657;3354.7819391739704;3392.8812491562676;3418.8461519231696;3479.6520347965;3519.1905861651962;4637.976810115928;5006.799728010875;5094.770735316883;5113.590912726973;5129.409648127709;4174.70777045607;4542.432195975503;4559.8372415033955;4560.224761790503;4592.655550833668;4722.338872557844;5164.561012313958;5480.671159730429;5498.725063746837;5536;3794.354959656865;3832.616738326173;3835.7314987950576;3840.7311488195837;3846.615338466159;4739.170645137087;5001;5359.490848369438;5591.10542313228;5762.855928601776;3315.254067834766;3483.494893240517;3752.9061773455883;3774.7168962327937;3778.962210377861;3190.5373720810353;3623.329684008414;3804.860989683467;3868.9226215475533;3877.410261792708;3726.1988672435014;3980.8606698765984;4344.956550434518;4542.478269779661;4574.679772415899;5493.752781124859;5509.917351239735;5514.36584792754;5523.309586301712;5542.533572560253;1132.9689982116236;1326.2970625568464;1328.5084518728133;1330.1619979412908;1331.3875617215995;633.832034510855;793.9480188749814;806.9798255043553;818.6602559937678;824.9752507424787;3725.701943844486;3831;4012.7792971386853;4335.3063509838275;4353.9522334366075;3804.6385593368586;4366.781660916941;4687.882802929954;4721.551452611996;4724.71651700899;4264.867566216892;4267.893302667459;4276.615104640535;4279.1971258825515;4281.97859010703;1817.0006496426913;2226.7884550967633;2257.401788526042;2416.1637155761746;2466.6793316868693;4057.634812866856;4351.543087975717;4710.599599034052;4882.340883980688;4888.340074089952;3869.690424766012;3989.8204580793936;4102.138550904284;4846.933674591584;4862;4465.910681786411;4556.6582506312825;4882.926756098661;4885.410732311881;5955.315138759098;5747.712614369349;5754.280714910637;5762.250907382014;5784.681842498703;5789.813088316969;4880.902381952412;5313.787448502017;5379.273798037215;5563.304586926634;5637.098064309691;4437.025523641948;4908.576512811244;5922.851928701817;5941.465268125889;5950.31571369298;3190.7287880530416;3449.49982252577;3749.6250374962287;3852.210296889075;3853.9421908670834;4875.562728276627;5130.743462826769;5537.2557644341905;5580.107182850725;5735.254416925773;4683.297505374221;5306.8407947761625;5583.330000399816;5588.77644894216;5591.552675786009;4594.655400845017;4810.783514741775;5357.19642053695;5537.612367134423;5575.442455754554;4985.551300382983;5465.836024919259;5471.398146203994;5479.890402191854;5490.725463726878;5200.817971371135;5489.972550137224;5542.8337149885565;5550.888982220414;5594.244776955222;4689;5199.428062913152;5435.928700284327;5462.235287059894;5496.230527726201 03:14:23 INFO - PROCESS | 1655 | |5;yui.html;702.8769965256079;732.4213871041874;777.7550071727405;784.282381620817;828.7058094376495;14985.700285994273;16144.354225830954;16145.273462694178;16163.353465861366;16164.434244801434;14634.26828658568;14695.926520367384;14749;14798.334074966626;14816.407343706238;673.4612310151883;693.3724978894109;818.3698552114868;828.1428721273484;848.0374774630808;850.5534594337992;857.3312816003527;857.6697971281039;860.5052095045343;861.4142383179435;896.318797713739;910.6903652758061;911.116217269251;911.4212475078307;912.0423555266976;714.4498735973324;871.8953725552974;898.1198425542939;904.4121321141245;904.4844438669928;658.5554750543364;658.9736410543572;659.4921910129221;659.5251418978328;661.6856992928368;14939.850601493954;14960.476383326532;14983.700325993528;14998.550043498712;15005.549833505012;13690.041697081208;13885.4445822167;13888.027838051341;14005.439782408692;14313.355898984571;12543.121981461301;12637.368131593475;12723.55467558631;12818.551350702774;13192.142510736772;4517.954820451786;4527.388802511685;4546.613537849286;4670.579647831678;4699.812007519695;107645.46177269141;109405;111129.77740445227;111444.88555114427;112126.43936780264;10905.182111341523;10923.836142457869;11043.392613406178;11199.160062995308;11537.307761534335;21199.94000299979;21629.56740865174;21661.891690541448;21667.67498487524;21677.67483487749;6685.799426017227;7839.216078392115;7879.251471110236;7928.1675424080795;8024.799380015546;4402.933955990663;4426.889327766831;4444.088961762831;4453.821847126143;4553.4991150973365;3395.0663567518636;3471.6701913318193;3499.9825000874835;3512.3326567952518;3515.7538972271695;644.091830518966;650.847050943028;652.2955208374943;657.4871600151889;673.1518286958468;593.964362138273;669.2270427656065;677.2144312597363;688.2484078021447;719.022129903331;8308.5430301334;8679.001914779881;8777.42946708462;9091.31815113869;9607.711768646952;636.2778246689948;664.8470851704135;691.8754624167598;696.944244460442;699.384541603396;4520.547945205519;4545.477270113979;4616.907661846744;4634.83778067766;4675.251959686434;92256;113858.86141138649;148982;160010;174729.1263543674;127279;132129;133036;133064;135621.3218933899;97780;108168;112479;120370.7962920377;126422;9536.332456727963;9884.555195016384;9986.300958933096;9999.35004224723;10020.64927727526;62708;63435.36564634479;63875.68062159659;64724;65066.024009638946;70245.64877175582;90253.54873225592;99437.00562994278;100263;102676;11506.597269095542;13187.472501099848;13196;13298.734025319245;13301.933490332487;15098.320575573905;15299.541013769605;15680.607984800472;15752.054876707434;15773.05361678303;13614;14094.859051409354;14098.577042688736;14433.92783036078;14500.202488863213;12547.811782823448;13389.933050334686;13687.863121368659;13754.243516606683;14309.64225894361;13773.517926872511;13816.101953372998;13990;14433.35049922777;14580.125192488485;11317.377544235145;11333.320000799979;11581.88418115808;11831.645050648494;11864.228825126684;10145.695629131138;10402.947985260327;10980.176486763685;11109.277896936674;11673.708157296136 03:14:23 INFO - PROCESS | 1655 | __end_tp_report 03:14:23 INFO - PROCESS | 1655 | __start_cc_report 03:14:23 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,1439 03:14:23 INFO - PROCESS | 1655 | __end_cc_report 03:14:23 INFO - PROCESS | 1655 | __startTimestamp1467368063664__endTimestamp 03:14:23 INFO - PROCESS | 1655 | 03:14:23 INFO - PROCESS | 1655 | ------- Summary: start ------- 03:14:23 INFO - PROCESS | 1655 | Number of tests: 6 03:14:23 INFO - PROCESS | 1655 | 03:14:23 INFO - PROCESS | 1655 | [#0] dojo.html Cycles:165 Average:5770.39 Median:5991.94 stddev:655.82 (10.9%) stddev-sans-first:628.99 03:14:23 INFO - PROCESS | 1655 | Values: 3318.2 3370.1 3381.8 3388.9 3437.1 5620.1 5649.7 5686.7 5691.1 5702.4 4653.9 5678.7 5806.3 5821.1 5840.1 5187.9 5196.6 5264.1 5279.7 5385.8 5348.1 5360.2 5370.1 5376.1 5401.7 5189.1 5243.0 5252.3 5289.1 5322.3 5206.5 5219.6 5222.8 5225.2 5243.5 5269.5 5270.9 5276.0 5283.7 5330.3 5617.5 5668.2 5760.1 5805.9 5854.8 6195.2 6248.1 6273.5 6275.6 6295.7 6314.5 6324.5 6336.4 6362.6 6367.0 5654.2 5699.5 5724.1 5792.3 5843.9 6308.5 6317.0 6332.3 6334.4 6352.0 6189.7 6207.9 6213.4 6227.0 6228.3 6130.4 6172.4 6259.4 6263.3 6310.8 6243.1 6259.8 6270.7 6280.6 6294.2 6069.6 6286.7 6308.8 6311.9 6312.8 4897.9 4921.4 4946.2 4947.0 4952.3 4061.2 4314.4 4508.5 4528.0 4541.1 5621.1 5670.1 5673.4 5684.5 5689.5 6227.1 6271.7 6321.3 6331.3 6351.6 5706.9 5713.5 5724.9 5744.0 5770.3 5354.7 5419.7 5432.1 5456.8 5458.1 6249.5 6278.2 6284.7 6347.3 6362.6 6334.1 6339.9 6343.4 6343.5 6344.7 6252.9 6260.0 6323.5 6355.4 6368.2 6168.2 6259.2 6265.2 6279.2 6281.6 6312.5 6333.0 6351.9 6356.2 6359.4 5938.0 6027.4 6068.4 6153.7 6166.5 6095.3 6113.4 6156.4 6162.8 6167.6 5975.5 5991.3 5991.9 6001.8 6027.7 5830.2 5847.9 5987.4 6166.7 6177.7 4889.8 6038.8 6043.8 6044.3 6096.8 03:14:23 INFO - PROCESS | 1655 | 03:14:23 INFO - PROCESS | 1655 | [#1] ext.html Cycles:180 Average:71130.46 Median:36454.04 stddev:159301.10 (437.0%) stddev-sans-first:159659.70 03:14:23 INFO - PROCESS | 1655 | Values: 494.3 524.6 532.8 545.8 558.6 30185.5 41473.8 42588.1 42645.4 42646.8 43574.0 43928.8 44061.6 44192.3 44206.3 8741.2 9660.3 9859.8 9870.2 9890.6 8668.7 11871.2 12067.4 12071.2 12409.4 12002.0 12102.8 12156.4 12160.5 12204.9 8704.2 8886.2 8898.7 12012.6 12088.4 8885.6 11577.6 11826.1 11842.2 12148.3 41875.4 41938.8 42092.4 42163.6 42299.6 3447.9 3471.3 3908.0 4095.7 4335.5 32215.7 32252.9 33163.0 33405.2 33971.7 3316.8 3354.7 3410.6 3457.7 3462.2 525169.0 525736.0 527001.4 527638.0 529420.0 33885.8 46109.8 58160.4 58291.1 58332.5 33709.2 35786.5 35890.3 35896.6 35944.5 14693.6 14962.6 14965.5 14972.4 14979.1 10419.4 11449.5 11543.0 12430.9 12542.0 7934.6 7957.5 7960.4 8018.0 8034.8 2778.9 3065.8 3222.8 3254.9 3288.6 767.4 979.8 986.6 1035.6 1062.8 30756.2 30802.8 30940.6 31234.4 31540.7 1104.3 1124.2 1127.2 1131.8 1146.5 15860.9 16262.9 16285.4 16308.3 16730.7 706852.0 710897.0 713000.0 713541.0 713833.0 59480.4 60356.4 60612.0 60668.4 60710.0 1211.0 1249.0 1276.5 1380.9 1421.3 29613.6 30459.5 32042.5 32874.9 32927.8 51223.0 51342.5 51882.0 51889.5 52045.0 456063.0 538890.0 542788.0 542973.3 543423.0 24430.0 26975.1 27361.0 27407.2 28376.1 48234.0 48560.0 49043.5 49850.0 50063.0 40049.0 40474.0 40884.0 41381.6 41764.4 39468.8 40255.8 40906.6 41278.6 42240.8 35021.0 39029.6 39553.0 39893.8 40046.4 33828.5 34035.7 35408.5 36343.6 36564.5 24659.8 25062.0 25879.9 26549.7 26639.3 03:14:23 INFO - PROCESS | 1655 | 03:14:23 INFO - PROCESS | 1655 | [#2] jquery.html Cycles:180 Average:36131.59 Median:14165.23 stddev:99152.99 (700.0%) stddev-sans-first:99394.75 03:14:23 INFO - PROCESS | 1655 | Values: 358.7 376.8 384.0 385.6 399.2 15276.8 15749.6 19937.9 20458.4 20515.0 20789.3 21297.5 21421.8 21454.9 21653.0 5821.7 5851.9 6108.4 6128.5 6775.5 6746.2 6756.2 6788.9 6803.8 6825.5 5913.2 6470.8 6588.0 6751.5 6754.9 6389.6 6652.3 6681.7 6689.2 6707.1 5754.9 5772.6 5781.5 5802.4 5804.0 20565.0 26007.5 26255.6 26818.1 26846.9 2251.3 2323.0 2793.6 3085.6 3191.9 15246.8 15762.8 15925.1 15951.9 15986.4 1819.4 1888.7 1935.7 1960.3 1962.0 113676.0 119046.0 119942.4 120648.0 120880.4 8762.0 10409.4 10562.3 10832.4 11031.4 13404.6 13497.5 13532.0 13534.9 13535.7 6949.6 8274.8 8440.4 8524.0 8564.3 8494.4 8523.6 8528.7 8634.7 8682.7 6543.7 6644.8 6650.0 6658.7 6715.7 1834.9 1864.2 1868.2 1871.9 1873.9 896.7 900.4 903.7 908.9 912.9 21685.7 22029.4 22746.0 22792.4 22837.8 954.7 971.1 988.4 990.9 1021.6 11320.2 11558.9 11964.0 12001.0 12107.6 548693.0 589066.3 593018.0 598423.0 616220.0 143427.0 145134.0 146310.3 148588.0 151668.5 1012.1 1014.7 1037.8 1064.9 1070.9 19419.5 19953.8 19995.9 20003.5 20111.0 57237.7 59504.4 60069.7 60499.4 60670.2 87521.0 88017.0 88043.0 88819.0 92204.0 7204.7 7470.7 7492.8 7833.0 8528.7 9328.7 11830.8 13415.5 13623.3 13843.4 11346.8 11714.6 11763.2 11825.0 11906.0 11298.2 11332.6 11472.3 11572.5 11605.5 11034.5 11040.3 11764.9 12049.7 12076.5 7269.1 7570.0 7581.0 7589.8 7710.9 9401.8 11434.0 11468.4 11682.5 11701.2 03:14:23 INFO - PROCESS | 1655 | 03:14:23 INFO - PROCESS | 1655 | [#3] mootools.html Cycles:180 Average:5797.18 Median:4943.27 stddev:3291.13 (66.6%) stddev-sans-first:3283.64 03:14:23 INFO - PROCESS | 1655 | Values: 1382.5 1508.1 1551.0 1723.0 1888.3 7790.8 8662.0 8895.9 8896.1 9025.5 8681.3 8896.8 8902.7 8906.9 8911.8 2073.6 2143.4 2148.4 2322.1 2351.0 1865.7 2097.3 2112.6 2139.7 2217.0 2336.3 2338.1 2338.4 2343.3 2344.4 2290.1 2333.6 2341.2 2344.1 2344.3 1825.4 2133.1 2306.2 2383.1 2392.2 8924.8 8990.9 8991.7 9001.5 9006.9 5263.6 5446.4 5447.6 5449.5 5457.0 3483.9 3606.5 3649.9 3664.2 3671.9 919.7 1033.1 1081.0 1086.9 1094.5 11986.6 12059.8 12067.4 12070.0 12099.6 4696.3 4710.3 4768.6 4864.3 4888.7 10012.8 10117.8 10131.3 10135.6 10144.7 5438.8 5441.2 5441.3 5442.9 5450.0 4395.9 4919.1 4935.1 4993.1 4995.0 3383.0 3789.8 3951.7 3985.9 4092.6 1267.2 1289.2 1293.5 1298.5 1317.9 905.4 913.2 916.5 928.8 974.3 5912.0 5915.4 6041.1 6090.7 6167.0 891.6 901.4 933.4 970.0 997.8 4009.3 4046.9 4081.1 4122.9 4142.8 10449.5 11128.8 11163.3 11187.9 11197.8 10280.2 10920.1 11084.3 11122.5 11122.6 8060.6 8356.7 8363.5 8370.1 8375.1 5656.7 6004.2 6054.6 6230.2 6340.8 9568.9 10248.0 10251.0 10253.1 10273.2 10090.0 10225.4 10239.0 10249.0 10252.5 6636.3 7359.7 7471.2 7657.2 7668.8 7728.0 8531.7 8534.0 8542.1 8544.1 7385.4 7450.4 7691.0 7847.8 7857.4 7329.1 7426.3 7468.0 7601.5 7624.2 7163.2 7192.0 7227.6 7230.5 7241.2 4951.5 5061.5 5121.3 5178.7 5244.9 5092.2 5141.4 5187.3 5218.3 5271.3 03:14:23 INFO - PROCESS | 1655 | 03:14:23 INFO - PROCESS | 1655 | [#4] prototype.html Cycles:180 Average:4266.14 Median:4593.66 stddev:1337.91 (29.1%) stddev-sans-first:1308.07 03:14:23 INFO - PROCESS | 1655 | Values: 296.2 304.0 309.2 309.4 312.3 4739.3 5221.5 5239.6 5250.4 5270.3 4354.6 5163.0 5206.7 5240.3 5245.0 3363.0 3754.0 3845.8 3888.0 3925.6 3646.3 3862.6 3891.5 3930.4 3949.3 3975.1 3981.2 3997.9 4000.4 4001.2 3903.6 4001.3 4009.8 4011.6 4030.7 3354.8 3392.9 3418.8 3479.7 3519.2 4638.0 5006.8 5094.8 5113.6 5129.4 4174.7 4542.4 4559.8 4560.2 4592.7 4722.3 5164.6 5480.7 5498.7 5536.0 3794.4 3832.6 3835.7 3840.7 3846.6 4739.2 5001.0 5359.5 5591.1 5762.9 3315.3 3483.5 3752.9 3774.7 3779.0 3190.5 3623.3 3804.9 3868.9 3877.4 3726.2 3980.9 4345.0 4542.5 4574.7 5493.8 5509.9 5514.4 5523.3 5542.5 1133.0 1326.3 1328.5 1330.2 1331.4 633.8 793.9 807.0 818.7 825.0 3725.7 3831.0 4012.8 4335.3 4354.0 3804.6 4366.8 4687.9 4721.6 4724.7 4264.9 4267.9 4276.6 4279.2 4282.0 1817.0 2226.8 2257.4 2416.2 2466.7 4057.6 4351.5 4710.6 4882.3 4888.3 3869.7 3989.8 4102.1 4846.9 4862.0 4465.9 4556.7 4882.9 4885.4 5955.3 5747.7 5754.3 5762.3 5784.7 5789.8 4880.9 5313.8 5379.3 5563.3 5637.1 4437.0 4908.6 5922.9 5941.5 5950.3 3190.7 3449.5 3749.6 3852.2 3853.9 4875.6 5130.7 5537.3 5580.1 5735.3 4683.3 5306.8 5583.3 5588.8 5591.6 4594.7 4810.8 5357.2 5537.6 5575.4 4985.6 5465.8 5471.4 5479.9 5490.7 5200.8 5490.0 5542.8 5550.9 5594.2 4689.0 5199.4 5435.9 5462.2 5496.2 03:14:23 INFO - PROCESS | 1655 | 03:14:23 INFO - PROCESS | 1655 | [#5] yui.html Cycles:180 Average:24951.72 Median:12536.37 stddev:39586.44 (315.8%) stddev-sans-first:39655.62 03:14:23 INFO - PROCESS | 1655 | Values: 702.9 732.4 777.8 784.3 828.7 14985.7 16144.4 16145.3 16163.4 16164.4 14634.3 14695.9 14749.0 14798.3 14816.4 673.5 693.4 818.4 828.1 848.0 850.6 857.3 857.7 860.5 861.4 896.3 910.7 911.1 911.4 912.0 714.4 871.9 898.1 904.4 904.5 658.6 659.0 659.5 659.5 661.7 14939.9 14960.5 14983.7 14998.6 15005.5 13690.0 13885.4 13888.0 14005.4 14313.4 12543.1 12637.4 12723.6 12818.6 13192.1 4518.0 4527.4 4546.6 4670.6 4699.8 107645.5 109405.0 111129.8 111444.9 112126.4 10905.2 10923.8 11043.4 11199.2 11537.3 21199.9 21629.6 21661.9 21667.7 21677.7 6685.8 7839.2 7879.3 7928.2 8024.8 4402.9 4426.9 4444.1 4453.8 4553.5 3395.1 3471.7 3500.0 3512.3 3515.8 644.1 650.8 652.3 657.5 673.2 594.0 669.2 677.2 688.2 719.0 8308.5 8679.0 8777.4 9091.3 9607.7 636.3 664.8 691.9 696.9 699.4 4520.5 4545.5 4616.9 4634.8 4675.3 92256.0 113858.9 148982.0 160010.0 174729.1 127279.0 132129.0 133036.0 133064.0 135621.3 97780.0 108168.0 112479.0 120370.8 126422.0 9536.3 9884.6 9986.3 9999.4 10020.6 62708.0 63435.4 63875.7 64724.0 65066.0 70245.6 90253.5 99437.0 100263.0 102676.0 11506.6 13187.5 13196.0 13298.7 13301.9 15098.3 15299.5 15680.6 15752.1 15773.1 13614.0 14094.9 14098.6 14433.9 14500.2 12547.8 13389.9 13687.9 13754.2 14309.6 13773.5 13816.1 13990.0 14433.4 14580.1 11317.4 11333.3 11581.9 11831.6 11864.2 10145.7 10402.9 10980.2 11109.3 11673.7 03:14:23 INFO - PROCESS | 1655 | -------- Summary: end -------- 03:14:23 INFO - PROCESS | 1655 | 03:14:24 INFO - TEST-INFO | 1655: exit 0 03:14:24 INFO - TEST-OK | dromaeo_css | took 1116570ms 03:14:24 INFO - TEST-START | kraken 03:14:24 INFO - operating with platform_type : mac_ 03:14:24 INFO - Initialising browser for kraken test... 03:14:24 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/tmpoVEDM2/profile http://localhost:49274/getInfo.html) 03:14:26 INFO - PROCESS | 1660 | __metrics Screen width/height:1600/1200 03:14:26 INFO - PROCESS | 1660 | colorDepth:24 03:14:26 INFO - PROCESS | 1660 | Browser inner width/height: 1024/689 03:14:26 INFO - PROCESS | 1660 | __metrics 03:14:26 INFO - PROCESS | 1660 | 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" 03:14:26 INFO - PROCESS | 1660 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:14:27 INFO - TEST-INFO | 1660: exit 0 03:14:27 INFO - Browser initialized. 03:14:27 INFO - Running cycle 1/1 for kraken test... 03:14:27 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/tmpoVEDM2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:14:39 INFO - PROCESS | 1661 | RSS: Main: 243572736 03:14:39 INFO - PROCESS | 1661 | 03:14:41 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection) 03:14:42 INFO - PROCESS | 1661 | RSS: Main: 293949440 03:14:42 INFO - PROCESS | 1661 | 03:14:45 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-dft) 03:14:45 INFO - PROCESS | 1661 | RSS: Main: 304033792 03:14:45 INFO - PROCESS | 1661 | 03:14:49 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-fft) 03:14:50 INFO - PROCESS | 1661 | RSS: Main: 389246976 03:14:50 INFO - PROCESS | 1661 | 03:14:52 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator) 03:14:52 INFO - PROCESS | 1661 | RSS: Main: 290611200 03:14:52 INFO - PROCESS | 1661 | 03:14:55 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:14:55 INFO - PROCESS | 1661 | RSS: Main: 295022592 03:14:55 INFO - PROCESS | 1661 | 03:14:58 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom) 03:14:59 INFO - PROCESS | 1661 | RSS: Main: 330104832 03:14:59 INFO - PROCESS | 1661 | 03:15:02 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate) 03:15:02 INFO - PROCESS | 1661 | RSS: Main: 332791808 03:15:02 INFO - PROCESS | 1661 | 03:15:05 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial) 03:15:06 INFO - PROCESS | 1661 | RSS: Main: 333221888 03:15:06 INFO - PROCESS | 1661 | 03:15:07 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:15:08 INFO - PROCESS | 1661 | RSS: Main: 313225216 03:15:08 INFO - PROCESS | 1661 | 03:15:10 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:15:10 INFO - PROCESS | 1661 | RSS: Main: 319516672 03:15:10 INFO - PROCESS | 1661 | 03:15:12 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:15:13 INFO - PROCESS | 1661 | RSS: Main: 317251584 03:15:13 INFO - PROCESS | 1661 | 03:15:16 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:15:17 INFO - PROCESS | 1661 | RSS: Main: 326717440 03:15:17 INFO - PROCESS | 1661 | 03:15:20 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:15:20 INFO - PROCESS | 1661 | RSS: Main: 328069120 03:15:20 INFO - PROCESS | 1661 | 03:15:22 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49274/tests/kraken/driver.html?testName=ai-astar) 03:15:22 INFO - PROCESS | 1661 | RSS: Main: 371838976 03:15:22 INFO - PROCESS | 1661 | 03:15:22 INFO - PROCESS | 1661 | __start_tp_report 03:15:22 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 03:15:22 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 03:15:22 INFO - PROCESS | 1661 | |i|pagename|runs| 03:15:22 INFO - PROCESS | 1661 | |0;ai-astar;101;113;113;112;114;113;113;114;113;114 03:15:22 INFO - PROCESS | 1661 | |1;audio-beat-detection;143;112;108;156;106;106;128;106;145;133 03:15:22 INFO - PROCESS | 1661 | |2;audio-dft;183;182;208;258;193;184;192;257;249;243 03:15:22 INFO - PROCESS | 1661 | |3;audio-fft;80;74;58;107;58;57;62;60;96;56 03:15:22 INFO - PROCESS | 1661 | |4;audio-oscillator;83;91;92;70;72;71;117;103;98;106 03:15:22 INFO - PROCESS | 1661 | |5;imaging-gaussian-blur;90;87;90;87;89;87;90;87;90;86 03:15:22 INFO - PROCESS | 1661 | |6;imaging-darkroom;94;95;95;100;95;95;96;96;95;94 03:15:22 INFO - PROCESS | 1661 | |7;imaging-desaturate;84;85;84;83;84;83;86;84;83;84 03:15:22 INFO - PROCESS | 1661 | |8;json-parse-financial;52;54;54;55;55;54;58;53;54;54 03:15:22 INFO - PROCESS | 1661 | |9;json-stringify-tinderbox;67;66;83;74;73;72;70;71;71;71 03:15:22 INFO - PROCESS | 1661 | |10;stanford-crypto-aes;82;83;84;83;82;82;84;85;85;82 03:15:22 INFO - PROCESS | 1661 | |11;stanford-crypto-ccm;142;149;152;141;136;136;137;137;186;139 03:15:22 INFO - PROCESS | 1661 | |12;stanford-crypto-pbkdf2;173;173;173;174;180;241;166;227;238;190 03:15:22 INFO - PROCESS | 1661 | |13;stanford-crypto-sha256-iterative;69;69;68;67;68;68;67;68;67;68 03:15:22 INFO - PROCESS | 1661 | __end_tp_report 03:15:22 INFO - PROCESS | 1661 | __start_cc_report 03:15:22 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,2026 03:15:22 INFO - PROCESS | 1661 | __end_cc_report 03:15:22 INFO - PROCESS | 1661 | __startTimestamp1467368122872__endTimestamp 03:15:22 INFO - PROCESS | 1661 | 03:15:22 INFO - PROCESS | 1661 | ------- Summary: start ------- 03:15:22 INFO - PROCESS | 1661 | Number of tests: 14 03:15:22 INFO - PROCESS | 1661 | 03:15:22 INFO - PROCESS | 1661 | [#0] ai-astar Cycles:10 Average:112.00 Median:113.00 stddev:3.92 (3.5%) stddev-sans-first:0.67 03:15:22 INFO - PROCESS | 1661 | Values: 101.0 113.0 113.0 112.0 114.0 113.0 113.0 114.0 113.0 114.0 03:15:22 INFO - PROCESS | 1661 | 03:15:22 INFO - PROCESS | 1661 | [#1] audio-beat-detection Cycles:10 Average:124.30 Median:130.50 stddev:19.13 (14.7%) stddev-sans-first:19.06 03:15:22 INFO - PROCESS | 1661 | Values: 143.0 112.0 108.0 156.0 106.0 106.0 128.0 106.0 145.0 133.0 03:15:22 INFO - PROCESS | 1661 | 03:15:22 INFO - PROCESS | 1661 | [#2] audio-dft Cycles:10 Average:214.90 Median:225.50 stddev:32.81 (14.6%) stddev-sans-first:32.71 03:15:22 INFO - PROCESS | 1661 | Values: 183.0 182.0 208.0 258.0 193.0 184.0 192.0 257.0 249.0 243.0 03:15:22 INFO - PROCESS | 1661 | 03:15:22 INFO - PROCESS | 1661 | [#3] audio-fft Cycles:10 Average:70.80 Median:61.00 stddev:18.17 (29.8%) stddev-sans-first:18.97 03:15:22 INFO - PROCESS | 1661 | Values: 80.0 74.0 58.0 107.0 58.0 57.0 62.0 60.0 96.0 56.0 03:15:22 INFO - PROCESS | 1661 | 03:15:22 INFO - PROCESS | 1661 | [#4] audio-oscillator Cycles:10 Average:90.30 Median:77.50 stddev:16.18 (20.9%) stddev-sans-first:16.94 03:15:22 INFO - PROCESS | 1661 | Values: 83.0 91.0 92.0 70.0 72.0 71.0 117.0 103.0 98.0 106.0 03:15:22 INFO - PROCESS | 1661 | 03:15:22 INFO - PROCESS | 1661 | [#5] imaging-gaussian-blur Cycles:10 Average:88.30 Median:89.50 stddev:1.64 (1.8%) stddev-sans-first:1.62 03:15:22 INFO - PROCESS | 1661 | Values: 90.0 87.0 90.0 87.0 89.0 87.0 90.0 87.0 90.0 86.0 03:15:22 INFO - PROCESS | 1661 | 03:15:22 INFO - PROCESS | 1661 | [#6] imaging-darkroom Cycles:10 Average:95.50 Median:95.00 stddev:1.72 (1.8%) stddev-sans-first:1.73 03:15:22 INFO - PROCESS | 1661 | Values: 94.0 95.0 95.0 100.0 95.0 95.0 96.0 96.0 95.0 94.0 03:15:22 INFO - PROCESS | 1661 | 03:15:22 INFO - PROCESS | 1661 | [#7] imaging-desaturate Cycles:10 Average:84.00 Median:84.00 stddev:0.94 (1.1%) stddev-sans-first:1.00 03:15:22 INFO - PROCESS | 1661 | Values: 84.0 85.0 84.0 83.0 84.0 83.0 86.0 84.0 83.0 84.0 03:15:22 INFO - PROCESS | 1661 | 03:15:22 INFO - PROCESS | 1661 | [#8] json-parse-financial Cycles:10 Average:54.30 Median:54.00 stddev:1.57 (2.9%) stddev-sans-first:1.42 03:15:22 INFO - PROCESS | 1661 | Values: 52.0 54.0 54.0 55.0 55.0 54.0 58.0 53.0 54.0 54.0 03:15:22 INFO - PROCESS | 1661 | 03:15:22 INFO - PROCESS | 1661 | [#9] json-stringify-tinderbox Cycles:10 Average:71.80 Median:71.50 stddev:4.64 (6.5%) stddev-sans-first:4.58 03:15:22 INFO - PROCESS | 1661 | Values: 67.0 66.0 83.0 74.0 73.0 72.0 70.0 71.0 71.0 71.0 03:15:22 INFO - PROCESS | 1661 | 03:15:22 INFO - PROCESS | 1661 | [#10] stanford-crypto-aes Cycles:10 Average:83.20 Median:83.50 stddev:1.23 (1.5%) stddev-sans-first:1.22 03:15:22 INFO - PROCESS | 1661 | Values: 82.0 83.0 84.0 83.0 82.0 82.0 84.0 85.0 85.0 82.0 03:15:22 INFO - PROCESS | 1661 | 03:15:22 INFO - PROCESS | 1661 | [#11] stanford-crypto-ccm Cycles:10 Average:145.50 Median:141.50 stddev:15.26 (10.8%) stddev-sans-first:16.13 03:15:22 INFO - PROCESS | 1661 | Values: 142.0 149.0 152.0 141.0 136.0 136.0 137.0 137.0 186.0 139.0 03:15:22 INFO - PROCESS | 1661 | 03:15:22 INFO - PROCESS | 1661 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:193.50 Median:185.00 stddev:29.72 (16.1%) stddev-sans-first:30.59 03:15:22 INFO - PROCESS | 1661 | Values: 173.0 173.0 173.0 174.0 180.0 241.0 166.0 227.0 238.0 190.0 03:15:22 INFO - PROCESS | 1661 | 03:15:22 INFO - PROCESS | 1661 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.90 Median:68.00 stddev:0.74 (1.1%) stddev-sans-first:0.67 03:15:22 INFO - PROCESS | 1661 | Values: 69.0 69.0 68.0 67.0 68.0 68.0 67.0 68.0 67.0 68.0 03:15:22 INFO - PROCESS | 1661 | -------- Summary: end -------- 03:15:22 INFO - PROCESS | 1661 | 03:15:23 INFO - TEST-INFO | 1661: exit 0 03:15:23 INFO - TEST-OK | kraken | took 59422ms 03:15:23 INFO - SUITE-END | took 1175s 03:15:24 INFO - Completed test suite (00:19:36) 03:15:24 INFO - javascript benchmark 03:15:24 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3318.2202182487117, 3370.106921665759, 3381.797092174469, 3388.9491657625126, 3437.0891713695873, 5620.1288800235925, 5649.689267090308, 5686.65880047197, 5691.146328050794, 5702.429757024301, 4653.930191047137, 5678.687672178028, 5806.332271788746, 5821.068629019358, 5840.0947853082735, 5187.948120518803, 5196.610254230929, 5264.052470555318, 5279.70961597112, 5385.757640906149, 5348.144296912495, 5360.249565060875, 5370.140777475604, 5376.059189641816, 5401.675899446026, 5189.117849965515, 5242.973785131088, 5252.264682944391, 5289.127293996486, 5322.281491998569, 5206.453322401131, 5219.634625576211, 5222.764975576109, 5225.16397376418, 5243.501867322599, 5269.473052694738, 5270.868228294285, 5276.0, 5283.735813209324, 5330.253764472977, 5617.522510586605, 5668.206451096849, 5760.0783874579865, 5805.883882322372, 5854.795082172146, 6195.163652906845, 6248.062790581449, 6273.466755325803, 6275.560710750249, 6295.748170073191, 6314.494840412802, 6324.462420694201, 6336.429721325103, 6362.618242905441, 6366.9681651591445, 5654.15187721845, 5699.487046165865, 5724.084146536534, 5792.27596550427, 5843.883122337529, 6308.495320374359, 6317.0, 6332.33510481402, 6334.429901308904, 6352.0, 6189.721462534197, 6207.944649409521, 6213.440790328892, 6227.034809604518, 6228.252609686867, 6130.356312587203, 6172.351903050205, 6259.374062593795, 6263.311035786151, 6310.810675679737, 6243.094751261042, 6259.843503912439, 6270.717817698214, 6280.5603607747935, 6294.150289710922, 6069.605475644069, 6286.717097730521, 6308.779192728232, 6311.873762524724, 6312.7790527332245, 4897.853064408002, 4921.384195263644, 4946.183879659888, 4946.975265123723, 4952.281919121707, 4061.1877624475223, 4314.439122914001, 4508.549145085465, 4528.049109687003, 4541.069080838421, 5621.072523033736, 5670.092785154356, 5673.432656734293, 5684.459976302244, 5689.487946084872, 6227.09707092469, 6271.749130034838, 6321.336259692758, 6331.303556608769, 6351.55539112267, 5706.885862282731, 5713.457221563945, 5724.914126288026, 5744.0, 5770.336411312669, 5354.678719276856, 5419.674819510842, 5432.103702889058, 5456.7544460499375, 5458.099413596712, 6249.500039996789, 6278.152449419361, 6284.717187726564, 6347.270063942706, 6362.586431881913, 6334.144890439732, 6339.873202535831, 6343.429091381798, 6343.492520598249, 6344.651044192429, 6252.906206406817, 6259.96870015647, 6323.525735569942, 6355.3644635536075, 6368.203974503187, 6168.19813424252, 6259.2488901332135, 6265.216847894013, 6279.2464904209955, 6281.623102613858, 6312.4634406076, 6332.95506241465, 6351.936480635134, 6356.173697419306, 6359.3640635936035, 5938.049912014057, 6027.427394397525, 6068.423499767515, 6153.723082461211, 6166.506679465721, 6095.2685677718955, 6113.388661133851, 6156.353582873734, 6162.845928851814, 6167.629942203482, 5975.521958243417, 5991.340952495135, 5991.940080599138, 6001.819945401645, 6027.6986150693165, 5830.2129212555765, 5847.883042339215, 5987.371326010706, 6166.722497487714, 6177.722002509988, 4889.828855990023, 6038.788642397495, 6043.8186854394435, 6044.304904935989, 6096.817095487142], "value": 5728.006502718857, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [494.33511926458925, 524.6196507532039, 532.7682458130713, 545.8335207761631, 558.606182641238, 30185.54721679174, 41473.79263103684, 42588.14823703532, 42645.36031959523, 42646.786766066165, 43574.0, 43928.78035609826, 44061.559384406144, 44192.3371149433, 44206.33690494637, 8741.169588889077, 9660.323777335572, 9859.802803943916, 9870.161036311887, 9890.604375824958, 8668.653253869837, 11871.169018168732, 12067.3966301685, 12071.155019148619, 12409.441575129096, 12002.039836813077, 12102.81845772314, 12156.392180390989, 12160.513579456856, 12204.877951220464, 8704.173103555153, 8886.200241978253, 8898.73303800887, 12012.579559715412, 12088.395580220953, 8885.555722213929, 11577.594784182513, 11826.11304152192, 11842.171048026641, 12148.271103733805, 41875.371869421986, 41938.79030604858, 42092.368614470804, 42163.57836421658, 42299.57700422987, 3447.9482807757904, 3471.3230919970615, 3907.98046009771, 4095.7133000689964, 4335.544767799367, 32215.677843221736, 32252.92427509369, 33163.005109846745, 33405.16487087842, 33971.6602833971, 3316.767826252163, 3354.714849237818, 3410.556627638391, 3457.6888080072663, 3462.23830757233, 525169.0, 525736.0, 527001.3649931764, 527638.0, 529420.0, 33885.830570846985, 46109.769451152526, 58160.4183958155, 58291.12563311554, 58332.54168645818, 33709.15727106793, 35786.46320305197, 35890.28219435597, 35896.64103358933, 35944.46083308753, 14693.55919322422, 14962.551123466314, 14965.476208332657, 14972.401103955719, 14979.101253924799, 10419.42693151884, 11449.54201831934, 11543.0, 12430.937845310897, 12542.0, 7934.603269836601, 7957.5225486471, 7960.442769006114, 8017.959910200411, 8034.758957231293, 2778.9166325010283, 3065.7700672449655, 3222.8388580570877, 3254.8698052078125, 3288.5724855769076, 767.4014268870291, 979.8383266760904, 986.5954958466995, 1035.5598870480017, 1062.8140075486758, 30756.231094222825, 30802.845985770375, 30940.5631282461, 31234.375312494078, 31540.738370464926, 1104.26566333389, 1124.2299025167786, 1127.177160672697, 1131.8075927092414, 1146.529922731676, 15860.92069539645, 16262.918685406497, 16285.430009949594, 16308.347666093461, 16730.6653866922, 706852.0, 710897.0, 713000.0, 713541.0, 713833.0, 59480.40519594749, 60356.39643603595, 60612.0, 60668.39331606627, 60710.0, 1210.975780484403, 1248.9812652810215, 1276.4575055601333, 1380.9309534523434, 1421.3035612549984, 29613.5557966635, 30459.543106853856, 32042.519362210052, 32874.91814014319, 32927.835360824, 51223.0, 51342.48657513526, 51882.0, 51889.48110518847, 52045.0, 456063.0, 538890.0, 542788.0, 542973.2851335718, 543423.0, 24430.022799087837, 26975.055873044348, 27361.0, 27407.17778466649, 28376.148715538566, 48234.0, 48560.02879942452, 49043.50956490532, 49850.0, 50063.0, 40049.0, 40474.0, 40883.97790055272, 41381.58618413898, 41764.3735343964, 39468.82921439348, 40255.798721006206, 40906.590934091466, 41278.587214127474, 42240.78879605582, 35021.0, 39029.6097039026, 39553.0, 39893.80053099832, 40046.39814407391, 33828.492572610936, 34035.65964340424, 35408.46887296744, 36343.63656363403, 36564.451533227555, 24659.753402465747, 25062.0, 25879.870600646875, 26549.660392777776, 26639.334016648965], "value": 19375.558377344696, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [358.653898987477, 376.84360990189066, 383.97312188146833, 385.5893473450773, 399.2354640862748, 15276.847231527696, 15749.60625984351, 19937.900310498426, 20458.386248412553, 20514.97425128745, 20789.272375466862, 21297.467563310925, 21421.821799801004, 21454.89272553639, 21653.0, 5821.67980761058, 5851.941480585182, 6108.389161083901, 6128.540359473035, 6775.457963362944, 6746.224184218829, 6756.155480564929, 6788.898166527506, 6803.795886123424, 6825.488088393366, 5913.201717768089, 6470.773522926675, 6588.0, 6751.459883209332, 6754.898676519856, 6389.616623002635, 6652.334766523333, 6681.732730690766, 6689.163854518185, 6707.094542236833, 5754.913676294859, 5772.5670574707065, 5781.479666830005, 5802.419758024206, 5803.9709801451145, 20565.0, 26007.479850403077, 26255.606165907528, 26818.061367852228, 26846.865765671046, 2251.2683377902085, 2322.988385058064, 2793.5809628555676, 3085.5834462347743, 3191.856366463515, 15246.771298430533, 15762.842371576367, 15925.124118173495, 15951.883368164234, 15986.440474583334, 1819.372316550788, 1888.7355770192185, 1935.6709359408935, 1960.2746983616012, 1962.0287957461005, 113676.0, 119046.0, 119942.40028799887, 120648.0, 120880.39559802233, 8761.956190219009, 10409.375437473776, 10562.31344962575, 10832.40421776794, 11031.448427578589, 13404.597862064154, 13497.527586534425, 13532.0, 13534.864651353557, 13535.729285414434, 6949.583025018515, 8274.793130171794, 8440.366972477088, 8524.0, 8564.272036876811, 8494.405391622651, 8523.616437260338, 8528.658853645784, 8634.747961545667, 8682.652693892174, 6543.705533251015, 6644.800655980328, 6650.0, 6658.679300624873, 6715.664216789141, 1834.8807327523668, 1864.1518109260253, 1868.1593283022694, 1871.8783279087088, 1873.9437816865516, 896.7399454158351, 900.3697411811758, 903.7379160043514, 908.8954770201511, 912.8767616371838, 21685.67471487929, 22029.449263768533, 22746.0, 22792.430189245402, 22837.771622283566, 954.71358592422, 971.121135372489, 988.3723835364596, 990.9306348555676, 1021.6451080259924, 11320.150988675883, 11558.884411155948, 11964.042876569854, 12001.0, 12107.576234831915, 548693.0261394749, 589066.2733431698, 593018.0349098227, 598423.0, 616220.0, 143427.0, 145134.0, 146310.26844865707, 148588.0, 151668.48331516545, 1012.0739523336213, 1014.6753039027441, 1037.8339465685453, 1064.8668916385452, 1070.925035247525, 19419.51451213731, 19953.75849550291, 19995.921027105294, 20003.483540272096, 20111.040719919154, 57237.71381143068, 59504.40495595162, 60069.69965150146, 60499.39500604937, 60670.179894603236, 87521.0, 88017.0, 88043.0, 88819.0, 92204.0, 7204.747833826001, 7470.663820127998, 7492.7752167435065, 7833.0, 8528.701495447629, 9328.673496427593, 11830.763384732429, 13415.530456433979, 13623.250721210032, 13843.446262149804, 11346.773064538827, 11714.648560543197, 11763.242107987333, 11824.98462891957, 11906.0, 11298.209125361474, 11332.6033588824, 11472.254303470248, 11572.537098515964, 11605.477753501282, 11034.503447345049, 11040.33757974524, 11764.88235117672, 12049.698757531132, 12076.51693932233, 7269.055022846996, 7569.962150189213, 7581.0, 7589.772306830804, 7710.8843367350655, 9401.811963760823, 11434.0, 11468.369239691898, 11682.532698692297, 11701.239419437708], "value": 10072.711671198738, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1382.4539306973752, 1508.117751115597, 1550.9844901550985, 1722.9569260768478, 1888.2541396148522, 7790.766277011691, 8662.047174810774, 8895.866562001558, 8896.06591307913, 9025.503597302146, 8681.262092722127, 8896.822063558724, 8902.73291801247, 8906.866397004034, 8911.821763564725, 2073.637113505138, 2143.3677065265706, 2148.376970678509, 2322.1059891941595, 2351.0360752091647, 1865.664180447519, 2097.2974053692033, 2112.6408510553165, 2139.6683514055267, 2217.0466699319286, 2336.275754516097, 2338.1232037985756, 2338.3920180753043, 2343.250159948817, 2344.4373350395954, 2290.0839664134314, 2333.6266197408504, 2341.203990643181, 2344.050659482912, 2344.2732752846673, 1825.3611236067402, 2133.07211363056, 2306.215886598548, 2383.1420688552107, 2392.1986134644917, 8924.776880577972, 8990.910090899139, 8991.685291014848, 9001.459912405277, 9006.86489702655, 5263.552598029172, 5446.373667028302, 5447.618666693333, 5449.509544141047, 5457.0, 3483.878064267764, 3606.549181352331, 3649.8540058397634, 3664.2488289900507, 3671.9082022949374, 919.6689191890914, 1033.1166852341214, 1080.9783804323947, 1086.9239153259275, 1094.5074716377583, 11986.640400787903, 12059.758804823941, 12067.396630168545, 12070.0, 12099.637010889688, 4696.322754251781, 4710.29345598163, 4768.594669453136, 4864.318995340655, 4888.6577939543895, 10012.799744005079, 10117.797644047225, 10131.341462804894, 10135.645252416278, 10144.695659130239, 5438.809641662523, 5441.183822426588, 5441.347038355423, 5442.91835622466, 5450.04624190765, 4395.912081758347, 4919.1391506486225, 4935.087008903364, 4993.076280888047, 4995.025969935828, 3382.983085084608, 3789.810509474515, 3951.7233793634746, 3985.8604948827233, 4092.5907409258834, 1267.1953309648256, 1289.2457912121326, 1293.482606957206, 1298.539018648385, 1317.9406926688323, 905.4431524612447, 913.182701482183, 916.4913473022403, 928.8250363290443, 974.3277138774223, 5912.0, 5915.37888521699, 6041.093835924647, 6090.695465226721, 6167.0, 891.6032365597376, 901.4073296554046, 933.4026223217146, 969.9515024248759, 997.7954519323521, 4009.298372784751, 4046.858359957387, 4081.0817566047995, 4122.907429531177, 4142.772147531854, 10449.529771160316, 11128.833067503994, 11163.330200188015, 11187.944060279646, 11197.832032519356, 10280.228982826318, 10920.12638988879, 11084.279521831055, 11122.49948752308, 11122.61070862516, 8060.637271322805, 8356.749297521084, 8363.498190108492, 8370.074882776995, 8375.120612335739, 5656.717164141694, 6004.219451471281, 6054.636721796706, 6230.190075290183, 6340.778072767431, 9568.904310956801, 10248.026437488426, 10251.0, 10253.07722304996, 10273.178145748472, 10089.991000900145, 10225.386476811414, 10239.027292407209, 10249.0, 10252.538635761557, 6636.303188165173, 7359.705611775469, 7471.215522370073, 7657.234276572521, 7668.846623067619, 7728.0, 8531.74404767858, 8534.0, 8542.06037335905, 8544.145585441405, 7385.409167266498, 7450.403967682681, 7690.961545192238, 7847.803804904923, 7857.410694197843, 7329.120505539369, 7426.257374262746, 7468.0, 7601.467897247361, 7624.237576242332, 7163.2478589747325, 7191.964040179766, 7227.638618069181, 7230.45771567124, 7241.239669834592, 4951.455339912534, 5061.468545802638, 5121.334226550524, 5178.741062946762, 5244.868878278074, 5092.185250359925, 5141.434442211428, 5187.325647665779, 5218.321618189611, 5271.288376069181], "value": 4623.493686343241, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [296.20469040625915, 304.03014384114675, 309.1976321445848, 309.365800109775, 312.28486765307446, 4739.2701663892685, 5221.477852214776, 5239.607029472786, 5250.405894842013, 5270.259465078157, 4354.62829208799, 5162.974185129078, 5206.709048018869, 5240.292560504331, 5244.973775131129, 3362.9574831802097, 3753.999999999993, 3845.750026248299, 3887.961120388788, 3925.5878132796074, 3646.3072016316814, 3862.5558060822946, 3891.54832455554, 3930.3711406175016, 3949.3483575210057, 3975.145343751093, 3981.203759248162, 3997.9400308995387, 4000.439938408625, 4001.2397644447606, 3903.590123037083, 4001.299772539823, 4009.759414435114, 4011.618896204856, 4030.697697672657, 3354.7819391739704, 3392.8812491562676, 3418.8461519231696, 3479.6520347965, 3519.1905861651962, 4637.976810115928, 5006.799728010875, 5094.770735316883, 5113.590912726973, 5129.409648127709, 4174.70777045607, 4542.432195975503, 4559.8372415033955, 4560.224761790503, 4592.655550833668, 4722.338872557844, 5164.561012313958, 5480.671159730429, 5498.725063746837, 5536.0, 3794.354959656865, 3832.616738326173, 3835.7314987950576, 3840.7311488195837, 3846.615338466159, 4739.170645137087, 5001.0, 5359.490848369438, 5591.10542313228, 5762.855928601776, 3315.254067834766, 3483.494893240517, 3752.9061773455883, 3774.7168962327937, 3778.962210377861, 3190.5373720810353, 3623.329684008414, 3804.860989683467, 3868.9226215475533, 3877.410261792708, 3726.1988672435014, 3980.8606698765984, 4344.956550434518, 4542.478269779661, 4574.679772415899, 5493.752781124859, 5509.917351239735, 5514.36584792754, 5523.309586301712, 5542.533572560253, 1132.9689982116236, 1326.2970625568464, 1328.5084518728133, 1330.1619979412908, 1331.3875617215995, 633.832034510855, 793.9480188749814, 806.9798255043553, 818.6602559937678, 824.9752507424787, 3725.701943844486, 3831.0, 4012.7792971386853, 4335.3063509838275, 4353.9522334366075, 3804.6385593368586, 4366.781660916941, 4687.882802929954, 4721.551452611996, 4724.71651700899, 4264.867566216892, 4267.893302667459, 4276.615104640535, 4279.1971258825515, 4281.97859010703, 1817.0006496426913, 2226.7884550967633, 2257.401788526042, 2416.1637155761746, 2466.6793316868693, 4057.634812866856, 4351.543087975717, 4710.599599034052, 4882.340883980688, 4888.340074089952, 3869.690424766012, 3989.8204580793936, 4102.138550904284, 4846.933674591584, 4862.0, 4465.910681786411, 4556.6582506312825, 4882.926756098661, 4885.410732311881, 5955.315138759098, 5747.712614369349, 5754.280714910637, 5762.250907382014, 5784.681842498703, 5789.813088316969, 4880.902381952412, 5313.787448502017, 5379.273798037215, 5563.304586926634, 5637.098064309691, 4437.025523641948, 4908.576512811244, 5922.851928701817, 5941.465268125889, 5950.31571369298, 3190.7287880530416, 3449.49982252577, 3749.6250374962287, 3852.210296889075, 3853.9421908670834, 4875.562728276627, 5130.743462826769, 5537.2557644341905, 5580.107182850725, 5735.254416925773, 4683.297505374221, 5306.8407947761625, 5583.330000399816, 5588.77644894216, 5591.552675786009, 4594.655400845017, 4810.783514741775, 5357.19642053695, 5537.612367134423, 5575.442455754554, 4985.551300382983, 5465.836024919259, 5471.398146203994, 5479.890402191854, 5490.725463726878, 5200.817971371135, 5489.972550137224, 5542.8337149885565, 5550.888982220414, 5594.244776955222, 4689.0, 5199.428062913152, 5435.928700284327, 5462.235287059894, 5496.230527726201], "value": 3842.9264897810526, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [702.8769965256079, 732.4213871041874, 777.7550071727405, 784.282381620817, 828.7058094376495, 14985.700285994273, 16144.354225830954, 16145.273462694178, 16163.353465861366, 16164.434244801434, 14634.26828658568, 14695.926520367384, 14749.0, 14798.334074966626, 14816.407343706238, 673.4612310151883, 693.3724978894109, 818.3698552114868, 828.1428721273484, 848.0374774630808, 850.5534594337992, 857.3312816003527, 857.6697971281039, 860.5052095045343, 861.4142383179435, 896.318797713739, 910.6903652758061, 911.116217269251, 911.4212475078307, 912.0423555266976, 714.4498735973324, 871.8953725552974, 898.1198425542939, 904.4121321141245, 904.4844438669928, 658.5554750543364, 658.9736410543572, 659.4921910129221, 659.5251418978328, 661.6856992928368, 14939.850601493954, 14960.476383326532, 14983.700325993528, 14998.550043498712, 15005.549833505012, 13690.041697081208, 13885.4445822167, 13888.027838051341, 14005.439782408692, 14313.355898984571, 12543.121981461301, 12637.368131593475, 12723.55467558631, 12818.551350702774, 13192.142510736772, 4517.954820451786, 4527.388802511685, 4546.613537849286, 4670.579647831678, 4699.812007519695, 107645.46177269141, 109405.0, 111129.77740445227, 111444.88555114427, 112126.43936780264, 10905.182111341523, 10923.836142457869, 11043.392613406178, 11199.160062995308, 11537.307761534335, 21199.94000299979, 21629.56740865174, 21661.891690541448, 21667.67498487524, 21677.67483487749, 6685.799426017227, 7839.216078392115, 7879.251471110236, 7928.1675424080795, 8024.799380015546, 4402.933955990663, 4426.889327766831, 4444.088961762831, 4453.821847126143, 4553.4991150973365, 3395.0663567518636, 3471.6701913318193, 3499.9825000874835, 3512.3326567952518, 3515.7538972271695, 644.091830518966, 650.847050943028, 652.2955208374943, 657.4871600151889, 673.1518286958468, 593.964362138273, 669.2270427656065, 677.2144312597363, 688.2484078021447, 719.022129903331, 8308.5430301334, 8679.001914779881, 8777.42946708462, 9091.31815113869, 9607.711768646952, 636.2778246689948, 664.8470851704135, 691.8754624167598, 696.944244460442, 699.384541603396, 4520.547945205519, 4545.477270113979, 4616.907661846744, 4634.83778067766, 4675.251959686434, 92256.0, 113858.86141138649, 148982.0, 160010.0, 174729.1263543674, 127279.0, 132129.0, 133036.0, 133064.0, 135621.3218933899, 97780.0, 108168.0, 112479.0, 120370.7962920377, 126422.0, 9536.332456727963, 9884.555195016384, 9986.300958933096, 9999.35004224723, 10020.64927727526, 62708.0, 63435.36564634479, 63875.68062159659, 64724.0, 65066.024009638946, 70245.64877175582, 90253.54873225592, 99437.00562994278, 100263.0, 102676.0, 11506.597269095542, 13187.472501099848, 13196.0, 13298.734025319245, 13301.933490332487, 15098.320575573905, 15299.541013769605, 15680.607984800472, 15752.054876707434, 15773.05361678303, 13614.0, 14094.859051409354, 14098.577042688736, 14433.92783036078, 14500.202488863213, 12547.811782823448, 13389.933050334686, 13687.863121368659, 13754.243516606683, 14309.64225894361, 13773.517926872511, 13816.101953372998, 13990.0, 14433.35049922777, 14580.125192488485, 11317.377544235145, 11333.320000799979, 11581.88418115808, 11831.645050648494, 11864.228825126684, 10145.695629131138, 10402.947985260327, 10980.176486763685, 11109.277896936674, 11673.708157296136], "value": 7932.159222311273, "unit": "score"}], "name": "dromaeo_css", "value": 7349.301461106931, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 113.0, 113.0, 112.0, 114.0, 113.0, 113.0, 114.0, 113.0, 114.0], "value": 112.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 112.0, 108.0, 156.0, 106.0, 106.0, 128.0, 106.0, 145.0, 133.0], "value": 124.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 182.0, 208.0, 258.0, 193.0, 184.0, 192.0, 257.0, 249.0, 243.0], "value": 214.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 74.0, 58.0, 107.0, 58.0, 57.0, 62.0, 60.0, 96.0, 56.0], "value": 70.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 91.0, 92.0, 70.0, 72.0, 71.0, 117.0, 103.0, 98.0, 106.0], "value": 90.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 90.0, 87.0, 89.0, 87.0, 90.0, 87.0, 90.0, 86.0], "value": 88.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 100.0, 95.0, 95.0, 96.0, 96.0, 95.0, 94.0], "value": 95.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 84.0, 83.0, 84.0, 83.0, 86.0, 84.0, 83.0, 84.0], "value": 84.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 54.0, 54.0, 55.0, 55.0, 54.0, 58.0, 53.0, 54.0, 54.0], "value": 54.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 83.0, 74.0, 73.0, 72.0, 70.0, 71.0, 71.0, 71.0], "value": 71.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 84.0, 83.0, 82.0, 82.0, 84.0, 85.0, 85.0, 82.0], "value": 83.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 149.0, 152.0, 141.0, 136.0, 136.0, 137.0, 137.0, 186.0, 139.0], "value": 145.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 173.0, 173.0, 174.0, 180.0, 241.0, 166.0, 227.0, 238.0, 190.0], "value": 193.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 68.0, 67.0, 68.0, 68.0, 67.0, 68.0, 67.0, 68.0], "value": 67.9, "unit": "score"}], "name": "kraken", "value": 1496.3, "alertThreshold": 2.0}]} 03:15:24 INFO - Return code: 0 03:15:24 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:15:24 INFO - # TBPL SUCCESS # 03:15:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:15:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:15:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:15:24 INFO - 2016-07-01 03:15:24.193 screenresolution[1665:10168] starting screenresolution argv=screenresolution get 03:15:24 INFO - 2016-07-01 03:15:24.195 screenresolution[1665:10168] Display 0: 1600x1200x32@60 03:15:24 INFO - 2016-07-01 03:15:24.201 screenresolution[1666:10171] starting screenresolution argv=screenresolution list 03:15:24 INFO - Available Modes on Display 0 03:15:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:15:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:15:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:15:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:15:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:15:24 INFO - Intel Iris: 03:15:24 INFO - Chipset Model: Intel Iris 03:15:24 INFO - Type: GPU 03:15:24 INFO - Bus: Built-In 03:15:24 INFO - VRAM (Dynamic, Max): 1536 MB 03:15:24 INFO - Vendor: Intel (0x8086) 03:15:24 INFO - Device ID: 0x0a2e 03:15:24 INFO - Revision ID: 0x0009 03:15:24 INFO - Displays: 03:15:24 INFO - Display: 03:15:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:15:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:15:24 INFO - Display Serial Number: 1600x1200 60 03:15:24 INFO - Main Display: Yes 03:15:24 INFO - Mirror: Off 03:15:24 INFO - Online: Yes 03:15:24 INFO - Rotation: Supported 03:15:24 INFO - Return code: 0 03:15:24 INFO - Running post-action listener: _resource_record_post_action 03:15:24 INFO - ##### 03:15:24 INFO - ##### Finished run-tests step (success) 03:15:24 INFO - ##### 03:15:24 INFO - Running post-run listener: _resource_record_post_run 03:15:24 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 31697408; Write bytes: 706142720; Read time: 306; Write time: 2194 03:15:24 INFO - TinderboxPrint: CPU usage
53.5% 03:15:24 INFO - TinderboxPrint: I/O read bytes / time
31,697,408 / 306 03:15:24 INFO - TinderboxPrint: I/O write bytes / time
706,142,720 / 2,194 03:15:24 INFO - TinderboxPrint: CPU idle
2,255.3 (46.5%) 03:15:24 INFO - TinderboxPrint: CPU system
967.7 (20.0%) 03:15:24 INFO - TinderboxPrint: CPU user
1,622.6 (33.5%) 03:15:24 INFO - TinderboxPrint: Swap in / out
435,052,544 / 0 03:15:24 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 389954048; Write bytes: 385168896; Read time: 29028; Write time: 784 03:15:24 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 30775808; Write bytes: 295672832; Read time: 293; Write time: 1361 03:15:24 INFO - Running post-run listener: _upload_blobber_files 03:15:24 INFO - Blob upload gear active. 03:15:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:15:24 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 03:15:24 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'] 03:15:24 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 03:15:25 INFO - (blobuploader) - INFO - Open directory for files ... 03:15:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:15:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:15:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:15:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:15:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:15:26 INFO - (blobuploader) - INFO - Done attempting. 03:15:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:15:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:15:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:15:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:15:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:15:27 INFO - (blobuploader) - INFO - Done attempting. 03:15:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:15:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:15:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:15:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:15:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:15:29 INFO - (blobuploader) - INFO - Done attempting. 03:15:29 INFO - (blobuploader) - INFO - Iteration through files over. 03:15:29 INFO - Return code: 0 03:15:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:15:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:15:29 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/13bd9970bb67f7c3395720ca4d5ab6b29a631c186bfd7c3698411e5573b4193ec8191e67db017253389b42379483ae8b8f3f745330edc0cc3e5c0472d68b6230", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/63b69e1d6662989be2cf65ee6c0146de91bb13567fea28bfff8c18027de92bfbee2b1c66ac7ce94f47ad4726a25b6da8f6b840e5931b8529f1b5342b79fffe5f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e6f39e941b345d6dbf7731ae2a22805ea0891c8b22778f511c71ea0f1f92ba7fa0e673dfa3dcc2ca42df0538225cc8351b863de8f816f8b8889a4fa633d3ae57"} 03:15:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:15:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:15:29 INFO - Contents: 03:15:29 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/13bd9970bb67f7c3395720ca4d5ab6b29a631c186bfd7c3698411e5573b4193ec8191e67db017253389b42379483ae8b8f3f745330edc0cc3e5c0472d68b6230", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/63b69e1d6662989be2cf65ee6c0146de91bb13567fea28bfff8c18027de92bfbee2b1c66ac7ce94f47ad4726a25b6da8f6b840e5931b8529f1b5342b79fffe5f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e6f39e941b345d6dbf7731ae2a22805ea0891c8b22778f511c71ea0f1f92ba7fa0e673dfa3dcc2ca42df0538225cc8351b863de8f816f8b8889a4fa633d3ae57"} 03:15:29 INFO - Running post-run listener: copy_logs_to_upload_dir 03:15:29 INFO - Copying logs to upload dir... 03:15:29 INFO - mkdir: /builds/slave/test/build/upload/logs 03:15:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1278.943089 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-07-01 03:15:29.924701) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-01 03:15:29.928415) ========= 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.Mz4XZ4SMc2/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.UdATBcrsGs/Listeners TMPDIR=/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/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/13bd9970bb67f7c3395720ca4d5ab6b29a631c186bfd7c3698411e5573b4193ec8191e67db017253389b42379483ae8b8f3f745330edc0cc3e5c0472d68b6230", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/63b69e1d6662989be2cf65ee6c0146de91bb13567fea28bfff8c18027de92bfbee2b1c66ac7ce94f47ad4726a25b6da8f6b840e5931b8529f1b5342b79fffe5f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e6f39e941b345d6dbf7731ae2a22805ea0891c8b22778f511c71ea0f1f92ba7fa0e673dfa3dcc2ca42df0538225cc8351b863de8f816f8b8889a4fa633d3ae57"} build_url:https://queue.taskcluster.net/v1/task/Cv2aUe3jSBykYf2hzVuHAA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011078 build_url: 'https://queue.taskcluster.net/v1/task/Cv2aUe3jSBykYf2hzVuHAA/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/13bd9970bb67f7c3395720ca4d5ab6b29a631c186bfd7c3698411e5573b4193ec8191e67db017253389b42379483ae8b8f3f745330edc0cc3e5c0472d68b6230", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/63b69e1d6662989be2cf65ee6c0146de91bb13567fea28bfff8c18027de92bfbee2b1c66ac7ce94f47ad4726a25b6da8f6b840e5931b8529f1b5342b79fffe5f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e6f39e941b345d6dbf7731ae2a22805ea0891c8b22778f511c71ea0f1f92ba7fa0e673dfa3dcc2ca42df0538225cc8351b863de8f816f8b8889a4fa633d3ae57"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-01 03:15:29.962547) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-01 03:15:29.963084) ========= 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.Mz4XZ4SMc2/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.UdATBcrsGs/Listeners TMPDIR=/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/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.004983 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-01 03:15:30.012676) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-01 03:15:30.013000) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-01 03:15:30.013431) ========= ========= Total master_lag: 0.32 =========