builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0089 starttime: 1461217392.1 results: success (0) buildid: 20160420210806 builduid: 50b3629257d7438cae535c69ac15a39a revision: 85616706a29b8944f3a09f5cc32e9e219f6ceb40 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-20 22:43:12.099041) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-20 22:43:12.099490) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-20 22:43:12.099817) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-20 22:43:12.119105) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-20 22:43:12.119389) ========= 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.fEGx2oPaql/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.lmVuL14aRz/Listeners TMPDIR=/var/folders/b2/qr0y6yxn2s32nywswzqqd0bc00000w/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-04-20 22:43:12-- 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% 18.3M=0.001s 2016-04-20 22:43:12 (18.3 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.126534 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-20 22:43:12.263361) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-20 22:43:12.263689) ========= 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.fEGx2oPaql/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.lmVuL14aRz/Listeners TMPDIR=/var/folders/b2/qr0y6yxn2s32nywswzqqd0bc00000w/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.027548 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-20 22:43:12.311982) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-04-20 22:43:12.312339) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 85616706a29b8944f3a09f5cc32e9e219f6ceb40 --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 85616706a29b8944f3a09f5cc32e9e219f6ceb40 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.fEGx2oPaql/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.lmVuL14aRz/Listeners TMPDIR=/var/folders/b2/qr0y6yxn2s32nywswzqqd0bc00000w/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-04-20 22:43:12,370 truncating revision to first 12 chars 2016-04-20 22:43:12,370 Setting DEBUG logging. 2016-04-20 22:43:12,370 attempt 1/10 2016-04-20 22:43:12,370 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/85616706a29b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-20 22:43:14,085 unpacking tar archive at: fx-team-85616706a29b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.985626 ========= master_lag: 0.09 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-04-20 22:43:14.390299) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-20 22:43:14.390607) ========= script_repo_revision: 85616706a29b8944f3a09f5cc32e9e219f6ceb40 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-20 22:43:14.391002) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-20 22:43:14.391278) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-20 22:43:14.405844) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 23 mins, 15 secs) (at 2016-04-20 22:43:14.406121) ========= /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.fEGx2oPaql/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.lmVuL14aRz/Listeners TMPDIR=/var/folders/b2/qr0y6yxn2s32nywswzqqd0bc00000w/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 22:43:14 INFO - MultiFileLogger online at 20160420 22:43:14 in /builds/slave/test 22:43:14 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 22:43:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:43:14 INFO - {'append_to_log': False, 22:43:14 INFO - 'base_work_dir': '/builds/slave/test', 22:43:14 INFO - 'blob_upload_branch': 'Fx-Team', 22:43:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:43:14 INFO - 'branch': 'Fx-Team', 22:43:14 INFO - 'buildbot_json_path': 'buildprops.json', 22:43:14 INFO - 'config_files': ('talos/mac_config.py',), 22:43:14 INFO - 'default_actions': ('clobber', 22:43:14 INFO - 'read-buildbot-config', 22:43:14 INFO - 'download-and-extract', 22:43:14 INFO - 'populate-webroot', 22:43:14 INFO - 'create-virtualenv', 22:43:14 INFO - 'install', 22:43:14 INFO - 'run-tests'), 22:43:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:43:14 INFO - 'download_minidump_stackwalk': True, 22:43:14 INFO - 'download_symbols': 'ondemand', 22:43:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:43:14 INFO - 'tooltool.py': '/tools/tooltool.py', 22:43:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:43:14 INFO - '/tools/misc-python/virtualenv.py')}, 22:43:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:43:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:43:14 INFO - 'installer_path': 'installer.exe', 22:43:14 INFO - 'log_level': 'info', 22:43:14 INFO - 'log_name': 'talos', 22:43:14 INFO - 'log_to_console': True, 22:43:14 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:43:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:43:14 INFO - 'opt_config_files': (), 22:43:14 INFO - 'pip_index': False, 22:43:14 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:43:14 INFO - 'cmd': ('bash', 22:43:14 INFO - '-c', 22:43:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:43:14 INFO - 'enabled': True, 22:43:14 INFO - 'halt_on_failure': False, 22:43:14 INFO - 'name': 'check_screen_resolution'},), 22:43:14 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:43:14 INFO - 'cmd': ('bash', 22:43:14 INFO - '-c', 22:43:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:43:14 INFO - 'enabled': True, 22:43:14 INFO - 'halt_on_failure': False, 22:43:14 INFO - 'name': 'check_screen_resolution'},), 22:43:14 INFO - 'run_cmd_checks_enabled': True, 22:43:14 INFO - 'sps_profile': False, 22:43:14 INFO - 'sps_profile_interval': 0, 22:43:14 INFO - 'suite': 'dromaeojs', 22:43:14 INFO - 'system_bits': '32', 22:43:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:43:14 INFO - 'title': 't-yosemite-r7-0089', 22:43:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:43:14 INFO - 'use_talos_json': True, 22:43:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:43:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:43:14 INFO - 'work_dir': 'build'} 22:43:14 INFO - ##### 22:43:14 INFO - ##### Running clobber step. 22:43:14 INFO - ##### 22:43:14 INFO - Running pre-action listener: _resource_record_pre_action 22:43:14 INFO - Running main action method: clobber 22:43:14 INFO - rmtree: /builds/slave/test/build 22:43:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:43:16 INFO - Running post-action listener: _resource_record_post_action 22:43:16 INFO - ##### 22:43:16 INFO - ##### Running read-buildbot-config step. 22:43:16 INFO - ##### 22:43:16 INFO - Running pre-action listener: _resource_record_pre_action 22:43:16 INFO - Running main action method: read_buildbot_config 22:43:16 INFO - Using buildbot properties: 22:43:16 INFO - { 22:43:16 INFO - "project": "", 22:43:16 INFO - "product": "firefox", 22:43:16 INFO - "script_repo_revision": "production", 22:43:16 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 22:43:16 INFO - "repository": "", 22:43:16 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 22:43:16 INFO - "buildid": "20160420210806", 22:43:16 INFO - "pgo_build": "False", 22:43:16 INFO - "basedir": "/builds/slave/test", 22:43:16 INFO - "buildnumber": 681, 22:43:16 INFO - "slavename": "t-yosemite-r7-0089", 22:43:16 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 22:43:16 INFO - "platform": "yosemite_r7", 22:43:16 INFO - "scheduler": "tests-fx-team-macosx64-talos", 22:43:16 INFO - "branch": "fx-team", 22:43:16 INFO - "repo_path": "integration/fx-team", 22:43:16 INFO - "revision": "85616706a29b8944f3a09f5cc32e9e219f6ceb40", 22:43:16 INFO - "stage_platform": "macosx64", 22:43:16 INFO - "builduid": "50b3629257d7438cae535c69ac15a39a", 22:43:16 INFO - "slavebuilddir": "test" 22:43:16 INFO - } 22:43:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/f--P_RvtTeqg9fmWAJp0DQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 22:43:16 INFO - Running post-action listener: _resource_record_post_action 22:43:16 INFO - ##### 22:43:16 INFO - ##### Running download-and-extract step. 22:43:16 INFO - ##### 22:43:16 INFO - Running pre-action listener: _resource_record_pre_action 22:43:16 INFO - Running main action method: download_and_extract 22:43:16 INFO - mkdir: /builds/slave/test/build/tests 22:43:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:43:16 INFO - https://queue.taskcluster.net/v1/task/f--P_RvtTeqg9fmWAJp0DQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 22:43:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f--P_RvtTeqg9fmWAJp0DQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 22:43:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f--P_RvtTeqg9fmWAJp0DQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 22:43:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f--P_RvtTeqg9fmWAJp0DQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 22:43:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f--P_RvtTeqg9fmWAJp0DQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #1 22:43:17 INFO - Downloaded 1199 bytes. 22:43:17 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 22:43:17 INFO - Using the following test package requirements: 22:43:17 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 22:43:17 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 22:43:17 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 22:43:17 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 22:43:17 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 22:43:17 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 22:43:17 INFO - u'jsshell-mac.zip'], 22:43:17 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 22:43:17 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 22:43:17 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 22:43:17 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 22:43:17 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 22:43:17 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 22:43:17 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 22:43:17 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 22:43:17 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 22:43:17 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 22:43:17 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 22:43:17 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 22:43:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:43:17 INFO - https://queue.taskcluster.net/v1/task/f--P_RvtTeqg9fmWAJp0DQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 22:43:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f--P_RvtTeqg9fmWAJp0DQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 22:43:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f--P_RvtTeqg9fmWAJp0DQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 22:43:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f--P_RvtTeqg9fmWAJp0DQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 22:43:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f--P_RvtTeqg9fmWAJp0DQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:43:18 INFO - Downloaded 18099880 bytes. 22:43:18 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:43:18 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 22:43:21 INFO - Return code: 0 22:43:21 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip', u'firefox-48.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 22:43:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:43:21 INFO - https://queue.taskcluster.net/v1/task/f--P_RvtTeqg9fmWAJp0DQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 22:43:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f--P_RvtTeqg9fmWAJp0DQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 22:43:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f--P_RvtTeqg9fmWAJp0DQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 22:43:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f--P_RvtTeqg9fmWAJp0DQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 22:43:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f--P_RvtTeqg9fmWAJp0DQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:43:23 INFO - Downloaded 18099880 bytes. 22:43:23 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:43:23 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 22:43:27 INFO - Return code: 0 22:43:27 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')]} 22:43:27 INFO - https://queue.taskcluster.net/v1/task/f--P_RvtTeqg9fmWAJp0DQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 22:43:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f--P_RvtTeqg9fmWAJp0DQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 22:43:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f--P_RvtTeqg9fmWAJp0DQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 22:43:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f--P_RvtTeqg9fmWAJp0DQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 22:43:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f--P_RvtTeqg9fmWAJp0DQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip'}, attempt #1 22:43:28 INFO - Downloaded 10845952 bytes. 22:43:28 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:43:28 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 22:43:28 INFO - Return code: 0 22:43: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')]} 22:43:28 INFO - https://queue.taskcluster.net/v1/task/f--P_RvtTeqg9fmWAJp0DQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 22:43:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f--P_RvtTeqg9fmWAJp0DQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 22:43:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f--P_RvtTeqg9fmWAJp0DQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 22:43:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f--P_RvtTeqg9fmWAJp0DQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 22:43:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f--P_RvtTeqg9fmWAJp0DQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 22:43:31 INFO - Downloaded 110031979 bytes. 22:43:31 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/f--P_RvtTeqg9fmWAJp0DQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 22:43:31 INFO - mkdir: /builds/slave/test/properties 22:43:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:43:31 INFO - Writing to file /builds/slave/test/properties/build_url 22:43:31 INFO - Contents: 22:43:31 INFO - build_url:https://queue.taskcluster.net/v1/task/f--P_RvtTeqg9fmWAJp0DQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 22:43:32 INFO - Running post-action listener: _resource_record_post_action 22:43:32 INFO - Running post-action listener: set_extra_try_arguments 22:43:32 INFO - ##### 22:43:32 INFO - ##### Running populate-webroot step. 22:43:32 INFO - ##### 22:43:32 INFO - Running pre-action listener: _resource_record_pre_action 22:43:32 INFO - Running main action method: populate_webroot 22:43:32 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:43:32 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:43:32 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:43:32 INFO - '--tppagecycles', 22:43:32 INFO - '7'], 22:43:32 INFO - 'tests': ['tsvgm']}}, 22:43:32 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:43:32 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:43:32 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:43:32 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:43:32 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:43:32 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:43:32 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:43:32 INFO - 'talos_options': ['--disable-e10s'], 22:43:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:43:32 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:43:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:43:32 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:43:32 INFO - 'talos_options': ['--disable-e10s'], 22:43:32 INFO - 'tests': ['damp', 'tps']}, 22:43:32 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:43:32 INFO - 'tests': ['damp', 'tps']}, 22:43:32 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:43:32 INFO - 'tests': ['dromaeo_dom']}, 22:43:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:43:32 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:43:32 INFO - 'tests': ['a11yr', 22:43:32 INFO - 'ts_paint', 22:43:32 INFO - 'tpaint', 22:43:32 INFO - 'sessionrestore', 22:43:32 INFO - 'sessionrestore_no_auto_restore', 22:43:32 INFO - 'tabpaint']}, 22:43:32 INFO - 'other-e10s': {'tests': ['a11yr', 22:43:32 INFO - 'ts_paint', 22:43:32 INFO - 'tpaint', 22:43:32 INFO - 'sessionrestore', 22:43:32 INFO - 'sessionrestore_no_auto_restore', 22:43:32 INFO - 'tabpaint']}, 22:43:32 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:43:32 INFO - 'ts_paint', 22:43:32 INFO - 'tpaint', 22:43:32 INFO - 'sessionrestore', 22:43:32 INFO - 'sessionrestore_no_auto_restore', 22:43:32 INFO - 'tabpaint']}, 22:43:32 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:43:32 INFO - 'ts_paint', 22:43:32 INFO - 'tpaint', 22:43:32 INFO - 'sessionrestore', 22:43:32 INFO - 'sessionrestore_no_auto_restore', 22:43:32 INFO - 'tabpaint']}, 22:43:32 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:43:32 INFO - 'tests': ['a11yr', 22:43:32 INFO - 'ts_paint', 22:43:32 INFO - 'tpaint', 22:43:32 INFO - 'sessionrestore', 22:43:32 INFO - 'sessionrestore_no_auto_restore', 22:43:32 INFO - 'tabpaint']}, 22:43:32 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:43:32 INFO - 'tests': ['a11yr', 22:43:32 INFO - 'ts_paint', 22:43:32 INFO - 'tpaint', 22:43:32 INFO - 'sessionrestore', 22:43:32 INFO - 'sessionrestore_no_auto_restore', 22:43:32 INFO - 'tabpaint']}, 22:43:32 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:43:32 INFO - 'tests': ['tsvgx', 22:43:32 INFO - 'tsvgr_opacity', 22:43:32 INFO - 'tart', 22:43:32 INFO - 'tscrollx', 22:43:32 INFO - 'cart']}, 22:43:32 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:43:32 INFO - 'tsvgr_opacity', 22:43:32 INFO - 'tart', 22:43:32 INFO - 'tscrollx', 22:43:32 INFO - 'cart']}, 22:43:32 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:43:32 INFO - 'talos_options': ['--disable-e10s'], 22:43:32 INFO - 'tests': ['tp5o']}, 22:43:32 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:43:32 INFO - 'tests': ['tp5o']}, 22:43:32 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:43:32 INFO - 'talos_options': ['--disable-e10s', 22:43:32 INFO - '--xperf_path', 22:43:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:43:32 INFO - 'tests': ['tp5n']}, 22:43:32 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:43:32 INFO - 'talos_options': ['--xperf_path', 22:43:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:43:32 INFO - 'tests': ['tp5n']}}, 22:43:32 INFO - 'talos.zip': {'path': '', 22:43:32 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:43:32 INFO - Running post-action listener: _resource_record_post_action 22:43:32 INFO - ##### 22:43:32 INFO - ##### Running create-virtualenv step. 22:43:32 INFO - ##### 22:43:32 INFO - Running pre-action listener: _resource_record_pre_action 22:43:32 INFO - Running main action method: create_virtualenv 22:43:32 INFO - Creating virtualenv /builds/slave/test/build/venv 22:43:32 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 22:43:32 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:43:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:43:33 INFO - Using real prefix '/tools/python27' 22:43:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:43:33 INFO - Installing distribute.............................................................................................................................................................................................done. 22:43:36 INFO - Installing pip.................done. 22:43:36 INFO - Return code: 0 22:43:36 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:43:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:43:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:43:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:43:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:43:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:43:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:43:36 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 0x10e334a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e344420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd378cbc6a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e1d3e00>, '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 0x10dffcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e1ceb20>, '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.fEGx2oPaql/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.lmVuL14aRz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b2/qr0y6yxn2s32nywswzqqd0bc00000w/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 22:43:36 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 22:43:36 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 22:43:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fEGx2oPaql/Render', 22:43:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:43:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:43:36 INFO - 'HOME': '/Users/cltbld', 22:43:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:43:36 INFO - 'LOGNAME': 'cltbld', 22:43:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:43:36 INFO - 'MOZ_NO_REMOTE': '1', 22:43:36 INFO - 'NO_EM_RESTART': '1', 22:43:36 INFO - 'PAGER': '/bin/cat', 22:43:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:43:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:43:36 INFO - 'PWD': '/builds/slave/test', 22:43:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:43:36 INFO - 'SHELL': '/bin/bash', 22:43:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lmVuL14aRz/Listeners', 22:43:36 INFO - 'TMPDIR': '/var/folders/b2/qr0y6yxn2s32nywswzqqd0bc00000w/T/', 22:43:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:43:36 INFO - 'USER': 'cltbld', 22:43:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:43:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:43:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:43:36 INFO - 'XPC_FLAGS': '0x0', 22:43:36 INFO - 'XPC_SERVICE_NAME': '0', 22:43:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:43:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:43:36 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 22:43:36 INFO - Cleaning up... 22:43:36 INFO - Return code: 0 22:43:36 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 22:43:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:43:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:43:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:43:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:43:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:43:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:43:36 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>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e334a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e344420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd378cbc6a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e1d3e00>, '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 0x10dffcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e1ceb20>, '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.fEGx2oPaql/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.lmVuL14aRz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b2/qr0y6yxn2s32nywswzqqd0bc00000w/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 22:43:36 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>=0.7.1'] in /builds/slave/test/build 22:43:36 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>=0.7.1 22:43:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fEGx2oPaql/Render', 22:43:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:43:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:43:36 INFO - 'HOME': '/Users/cltbld', 22:43:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:43:36 INFO - 'LOGNAME': 'cltbld', 22:43:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:43:36 INFO - 'MOZ_NO_REMOTE': '1', 22:43:36 INFO - 'NO_EM_RESTART': '1', 22:43:36 INFO - 'PAGER': '/bin/cat', 22:43:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:43:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:43:36 INFO - 'PWD': '/builds/slave/test', 22:43:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:43:36 INFO - 'SHELL': '/bin/bash', 22:43:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lmVuL14aRz/Listeners', 22:43:36 INFO - 'TMPDIR': '/var/folders/b2/qr0y6yxn2s32nywswzqqd0bc00000w/T/', 22:43:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:43:36 INFO - 'USER': 'cltbld', 22:43:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:43:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:43:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:43:36 INFO - 'XPC_FLAGS': '0x0', 22:43:36 INFO - 'XPC_SERVICE_NAME': '0', 22:43:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:43:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:43:37 INFO - Downloading/unpacking psutil>=0.7.1 22:43:37 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 22:43:37 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 22:43:37 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 22:43:37 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 22:43:37 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 22:43:37 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 22:43:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:43:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:43:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:43:40 INFO - Installing collected packages: psutil 22:43:40 INFO - Running setup.py install for psutil 22:43:40 INFO - building 'psutil._psutil_osx' extension 22:43:40 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 22:43:40 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 22:43:40 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 22:43:40 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 22:43:40 INFO - building 'psutil._psutil_posix' extension 22:43:40 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 22:43:41 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:43:41 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:43:41 INFO - ^ 22:43:41 INFO - 1 warning generated. 22:43:41 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 22:43:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:43:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:43:41 INFO - Successfully installed psutil 22:43:41 INFO - Cleaning up... 22:43:41 INFO - Return code: 0 22:43:41 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 22:43:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:43:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:43:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:43:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:43:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:43:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:43:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e334a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e344420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd378cbc6a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e1d3e00>, '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 0x10dffcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e1ceb20>, '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.fEGx2oPaql/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.lmVuL14aRz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b2/qr0y6yxn2s32nywswzqqd0bc00000w/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 22:43:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 22:43:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 22:43:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fEGx2oPaql/Render', 22:43:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:43:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:43:41 INFO - 'HOME': '/Users/cltbld', 22:43:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:43:41 INFO - 'LOGNAME': 'cltbld', 22:43:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:43:41 INFO - 'MOZ_NO_REMOTE': '1', 22:43:41 INFO - 'NO_EM_RESTART': '1', 22:43:41 INFO - 'PAGER': '/bin/cat', 22:43:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:43:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:43:41 INFO - 'PWD': '/builds/slave/test', 22:43:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:43:41 INFO - 'SHELL': '/bin/bash', 22:43:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lmVuL14aRz/Listeners', 22:43:41 INFO - 'TMPDIR': '/var/folders/b2/qr0y6yxn2s32nywswzqqd0bc00000w/T/', 22:43:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:43:41 INFO - 'USER': 'cltbld', 22:43:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:43:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:43:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:43:41 INFO - 'XPC_FLAGS': '0x0', 22:43:41 INFO - 'XPC_SERVICE_NAME': '0', 22:43:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:43:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:43:41 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 22:43:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:43:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:43:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:43:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:43:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:43:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:43:43 INFO - Downloading mozsystemmonitor-0.0.tar.gz 22:43:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:43:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 22:43:44 INFO - Installing collected packages: mozsystemmonitor 22:43:44 INFO - Running setup.py install for mozsystemmonitor 22:43:44 INFO - Successfully installed mozsystemmonitor 22:43:44 INFO - Cleaning up... 22:43:44 INFO - Return code: 0 22:43:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:43:44 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')]} 22:43:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:43:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:43:44 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')]} 22:43:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:43:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:43:44 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 0x10e334a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e344420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd378cbc6a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e1d3e00>, '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 0x10dffcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e1ceb20>, '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.fEGx2oPaql/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.lmVuL14aRz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b2/qr0y6yxn2s32nywswzqqd0bc00000w/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 22:43:44 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 22:43:44 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 22:43:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fEGx2oPaql/Render', 22:43:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:43:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:43:44 INFO - 'HOME': '/Users/cltbld', 22:43:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:43:44 INFO - 'LOGNAME': 'cltbld', 22:43:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:43:44 INFO - 'MOZ_NO_REMOTE': '1', 22:43:44 INFO - 'NO_EM_RESTART': '1', 22:43:44 INFO - 'PAGER': '/bin/cat', 22:43:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:43:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:43:44 INFO - 'PWD': '/builds/slave/test', 22:43:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:43:44 INFO - 'SHELL': '/bin/bash', 22:43:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lmVuL14aRz/Listeners', 22:43:44 INFO - 'TMPDIR': '/var/folders/b2/qr0y6yxn2s32nywswzqqd0bc00000w/T/', 22:43:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:43:44 INFO - 'USER': 'cltbld', 22:43:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:43:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:43:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:43:44 INFO - 'XPC_FLAGS': '0x0', 22:43:44 INFO - 'XPC_SERVICE_NAME': '0', 22:43:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:43:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:43:44 INFO - Downloading/unpacking blobuploader==1.2.4 22:43:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:43:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:43:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:43:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:43:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:43:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:43:46 INFO - Downloading blobuploader-1.2.4.tar.gz 22:43:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:43:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:43:47 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 22:43:47 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 22:43:47 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 22:43:47 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 22:43:47 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 22:43:47 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 22:43:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:43:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:43:47 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 22:43:47 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 22:43:47 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 22:43:47 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 22:43:47 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 22:43:47 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 22:43:48 INFO - Downloading docopt-0.6.1.tar.gz 22:43:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:43:48 INFO - Installing collected packages: blobuploader, requests, docopt 22:43:48 INFO - Running setup.py install for blobuploader 22:43:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:43:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:43:48 INFO - Running setup.py install for requests 22:43:48 INFO - Running setup.py install for docopt 22:43:49 INFO - Successfully installed blobuploader requests docopt 22:43:49 INFO - Cleaning up... 22:43:49 INFO - Return code: 0 22:43:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:43:49 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')]} 22:43:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:43:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:43:49 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')]} 22:43:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:43:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:43:49 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 0x10e334a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e344420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd378cbc6a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e1d3e00>, '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 0x10dffcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e1ceb20>, '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.fEGx2oPaql/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.lmVuL14aRz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b2/qr0y6yxn2s32nywswzqqd0bc00000w/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 22:43:49 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 22:43:49 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 22:43:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fEGx2oPaql/Render', 22:43:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:43:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:43:49 INFO - 'HOME': '/Users/cltbld', 22:43:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:43:49 INFO - 'LOGNAME': 'cltbld', 22:43:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:43:49 INFO - 'MOZ_NO_REMOTE': '1', 22:43:49 INFO - 'NO_EM_RESTART': '1', 22:43:49 INFO - 'PAGER': '/bin/cat', 22:43:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:43:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:43:49 INFO - 'PWD': '/builds/slave/test', 22:43:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:43:49 INFO - 'SHELL': '/bin/bash', 22:43:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lmVuL14aRz/Listeners', 22:43:49 INFO - 'TMPDIR': '/var/folders/b2/qr0y6yxn2s32nywswzqqd0bc00000w/T/', 22:43:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:43:49 INFO - 'USER': 'cltbld', 22:43:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:43:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:43:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:43:49 INFO - 'XPC_FLAGS': '0x0', 22:43:49 INFO - 'XPC_SERVICE_NAME': '0', 22:43:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:43:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:43:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:43:49 INFO - Running setup.py (path:/var/folders/b2/qr0y6yxn2s32nywswzqqd0bc00000w/T/pip-V0ylvX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:43:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:43:49 INFO - Running setup.py (path:/var/folders/b2/qr0y6yxn2s32nywswzqqd0bc00000w/T/pip-n308Uk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:43:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:43:49 INFO - Running setup.py (path:/var/folders/b2/qr0y6yxn2s32nywswzqqd0bc00000w/T/pip-O3qvC6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:43:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:43:49 INFO - Running setup.py (path:/var/folders/b2/qr0y6yxn2s32nywswzqqd0bc00000w/T/pip-DvKYNx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:43:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:43:49 INFO - Running setup.py (path:/var/folders/b2/qr0y6yxn2s32nywswzqqd0bc00000w/T/pip-operbP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:43:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:43:49 INFO - Running setup.py (path:/var/folders/b2/qr0y6yxn2s32nywswzqqd0bc00000w/T/pip-g1uFun-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:43:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:43:50 INFO - Running setup.py (path:/var/folders/b2/qr0y6yxn2s32nywswzqqd0bc00000w/T/pip-P1c8vt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:43:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:43:50 INFO - Running setup.py (path:/var/folders/b2/qr0y6yxn2s32nywswzqqd0bc00000w/T/pip-sGQ31k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:43:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:43:50 INFO - Running setup.py (path:/var/folders/b2/qr0y6yxn2s32nywswzqqd0bc00000w/T/pip-ASOdJV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:43:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:43:50 INFO - Running setup.py (path:/var/folders/b2/qr0y6yxn2s32nywswzqqd0bc00000w/T/pip-eFXWtL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:43:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:43:50 INFO - Running setup.py (path:/var/folders/b2/qr0y6yxn2s32nywswzqqd0bc00000w/T/pip-t9R2Xp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:43:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:43:50 INFO - Running setup.py (path:/var/folders/b2/qr0y6yxn2s32nywswzqqd0bc00000w/T/pip-xSqcRr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:43:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:43:50 INFO - Running setup.py (path:/var/folders/b2/qr0y6yxn2s32nywswzqqd0bc00000w/T/pip-rxWCTo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:43:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:43:50 INFO - Running setup.py (path:/var/folders/b2/qr0y6yxn2s32nywswzqqd0bc00000w/T/pip-bVKmCz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:43:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:43:50 INFO - Running setup.py (path:/var/folders/b2/qr0y6yxn2s32nywswzqqd0bc00000w/T/pip-AKGiAO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:43:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:43:50 INFO - Running setup.py (path:/var/folders/b2/qr0y6yxn2s32nywswzqqd0bc00000w/T/pip-MnRF_B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:43:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:43:51 INFO - Running setup.py (path:/var/folders/b2/qr0y6yxn2s32nywswzqqd0bc00000w/T/pip-sB7qVJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:43:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:43:51 INFO - Running setup.py install for manifestparser 22:43:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:43:51 INFO - Running setup.py install for mozcrash 22:43:51 INFO - Running setup.py install for mozdebug 22:43:51 INFO - Running setup.py install for mozdevice 22:43:51 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:43:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:43:51 INFO - Running setup.py install for mozfile 22:43:51 INFO - Running setup.py install for mozhttpd 22:43:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:43:52 INFO - Running setup.py install for mozinfo 22:43:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:43:52 INFO - Running setup.py install for mozInstall 22:43:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:43:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:43:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:43:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:43:52 INFO - Running setup.py install for mozleak 22:43:52 INFO - Running setup.py install for mozlog 22:43:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:43:52 INFO - Running setup.py install for moznetwork 22:43:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:43:52 INFO - Running setup.py install for mozprocess 22:43:52 INFO - Running setup.py install for mozprofile 22:43:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:43:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:43:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:43:53 INFO - Running setup.py install for mozrunner 22:43:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:43:53 INFO - Running setup.py install for mozscreenshot 22:43:53 INFO - Running setup.py install for moztest 22:43:53 INFO - Running setup.py install for mozversion 22:43:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:43:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:43:53 INFO - Cleaning up... 22:43:53 INFO - Return code: 0 22:43:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:43: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')]} 22:43:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:43:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:43: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')]} 22:43:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:43:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:43:53 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 0x10e334a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e344420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd378cbc6a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e1d3e00>, '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 0x10dffcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e1ceb20>, '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.fEGx2oPaql/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.lmVuL14aRz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b2/qr0y6yxn2s32nywswzqqd0bc00000w/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 22:43:53 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 22:43:53 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 22:43:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fEGx2oPaql/Render', 22:43:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:43:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:43:53 INFO - 'HOME': '/Users/cltbld', 22:43:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:43:53 INFO - 'LOGNAME': 'cltbld', 22:43:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:43:53 INFO - 'MOZ_NO_REMOTE': '1', 22:43:53 INFO - 'NO_EM_RESTART': '1', 22:43:53 INFO - 'PAGER': '/bin/cat', 22:43:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:43:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:43:53 INFO - 'PWD': '/builds/slave/test', 22:43:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:43:53 INFO - 'SHELL': '/bin/bash', 22:43:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lmVuL14aRz/Listeners', 22:43:53 INFO - 'TMPDIR': '/var/folders/b2/qr0y6yxn2s32nywswzqqd0bc00000w/T/', 22:43:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:43:53 INFO - 'USER': 'cltbld', 22:43:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:43:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:43:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:43:53 INFO - 'XPC_FLAGS': '0x0', 22:43:53 INFO - 'XPC_SERVICE_NAME': '0', 22:43:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:43:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:43:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:43:54 INFO - Running setup.py (path:/var/folders/b2/qr0y6yxn2s32nywswzqqd0bc00000w/T/pip-ZZIV3x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:43:54 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)) 22:43:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:43:54 INFO - Running setup.py (path:/var/folders/b2/qr0y6yxn2s32nywswzqqd0bc00000w/T/pip-Jn5p9P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:43:54 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)) 22:43:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:43:54 INFO - Running setup.py (path:/var/folders/b2/qr0y6yxn2s32nywswzqqd0bc00000w/T/pip-L6Zcua-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:43:54 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)) 22:43:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:43:54 INFO - Running setup.py (path:/var/folders/b2/qr0y6yxn2s32nywswzqqd0bc00000w/T/pip-4SO0Gk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:43:54 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)) 22:43:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:43:54 INFO - Running setup.py (path:/var/folders/b2/qr0y6yxn2s32nywswzqqd0bc00000w/T/pip-KHHvo_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:43:54 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)) 22:43:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:43:54 INFO - Running setup.py (path:/var/folders/b2/qr0y6yxn2s32nywswzqqd0bc00000w/T/pip-3ImL4d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:43:54 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)) 22:43:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:43:54 INFO - Running setup.py (path:/var/folders/b2/qr0y6yxn2s32nywswzqqd0bc00000w/T/pip-kwMRhZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:43:54 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)) 22:43:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:43:54 INFO - Running setup.py (path:/var/folders/b2/qr0y6yxn2s32nywswzqqd0bc00000w/T/pip-_rs_yE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:43:54 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)) 22:43:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:43:54 INFO - Running setup.py (path:/var/folders/b2/qr0y6yxn2s32nywswzqqd0bc00000w/T/pip-fUfzCj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:43:54 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)) 22:43:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:43:54 INFO - Running setup.py (path:/var/folders/b2/qr0y6yxn2s32nywswzqqd0bc00000w/T/pip-kFeTm6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:43:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 22:43:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:43:55 INFO - Running setup.py (path:/var/folders/b2/qr0y6yxn2s32nywswzqqd0bc00000w/T/pip-DGOKKa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:43:55 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)) 22:43:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:43:55 INFO - Running setup.py (path:/var/folders/b2/qr0y6yxn2s32nywswzqqd0bc00000w/T/pip-CeENiW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:43:55 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)) 22:43:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:43:55 INFO - Running setup.py (path:/var/folders/b2/qr0y6yxn2s32nywswzqqd0bc00000w/T/pip-wocqVe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:43:55 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)) 22:43:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:43:55 INFO - Running setup.py (path:/var/folders/b2/qr0y6yxn2s32nywswzqqd0bc00000w/T/pip-XxYqdE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:43:55 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)) 22:43:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:43:55 INFO - Running setup.py (path:/var/folders/b2/qr0y6yxn2s32nywswzqqd0bc00000w/T/pip-5g1UdA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:43:55 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)) 22:43:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:43:55 INFO - Running setup.py (path:/var/folders/b2/qr0y6yxn2s32nywswzqqd0bc00000w/T/pip-xclUQT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:43:55 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)) 22:43:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:43:55 INFO - Running setup.py (path:/var/folders/b2/qr0y6yxn2s32nywswzqqd0bc00000w/T/pip-8AYobH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:43:55 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)) 22:43:55 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)) 22:43:55 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)) 22:43:55 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)) 22:43:55 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)) 22:43:55 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)) 22:43:55 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:43:55 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 22:43:55 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 22:43:55 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 22:43:55 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 22:43:55 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 22:43:55 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 22:43:58 INFO - Downloading blessings-1.6.tar.gz 22:43:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:43:58 INFO - Installing collected packages: blessings 22:43:58 INFO - Running setup.py install for blessings 22:43:58 INFO - Successfully installed blessings 22:43:58 INFO - Cleaning up... 22:43:58 INFO - Return code: 0 22:43:58 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:43:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:43:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:43:58 INFO - Reading from file tmpfile_stdout 22:43:58 INFO - Current package versions: 22:43:58 INFO - blessings == 1.6 22:43:58 INFO - blobuploader == 1.2.4 22:43:58 INFO - docopt == 0.6.1 22:43:58 INFO - manifestparser == 1.1 22:43:58 INFO - mozInstall == 1.12 22:43:58 INFO - mozcrash == 0.17 22:43:58 INFO - mozdebug == 0.1 22:43:58 INFO - mozdevice == 0.48 22:43:58 INFO - mozfile == 1.2 22:43:58 INFO - mozhttpd == 0.7 22:43:58 INFO - mozinfo == 0.9 22:43:58 INFO - mozleak == 0.1 22:43:58 INFO - mozlog == 3.1 22:43:58 INFO - moznetwork == 0.27 22:43:58 INFO - mozprocess == 0.22 22:43:58 INFO - mozprofile == 0.28 22:43:58 INFO - mozrunner == 6.11 22:43:58 INFO - mozscreenshot == 0.1 22:43:58 INFO - mozsystemmonitor == 0.0 22:43:58 INFO - moztest == 0.7 22:43:58 INFO - mozversion == 1.4 22:43:58 INFO - psutil == 3.1.1 22:43:58 INFO - requests == 1.2.3 22:43:58 INFO - wsgiref == 0.1.2 22:43:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:43:58 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')]} 22:43:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:43:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:43:58 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')]} 22:43:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:43:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:43:58 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 0x10e334a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e344420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd378cbc6a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e1d3e00>, '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 0x10dffcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e1ceb20>, '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.fEGx2oPaql/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.lmVuL14aRz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b2/qr0y6yxn2s32nywswzqqd0bc00000w/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 22:43:58 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 22:43:58 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 22:43:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fEGx2oPaql/Render', 22:43:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:43:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:43:58 INFO - 'HOME': '/Users/cltbld', 22:43:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:43:58 INFO - 'LOGNAME': 'cltbld', 22:43:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:43:58 INFO - 'MOZ_NO_REMOTE': '1', 22:43:58 INFO - 'NO_EM_RESTART': '1', 22:43:58 INFO - 'PAGER': '/bin/cat', 22:43:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:43:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:43:58 INFO - 'PWD': '/builds/slave/test', 22:43:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:43:58 INFO - 'SHELL': '/bin/bash', 22:43:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lmVuL14aRz/Listeners', 22:43:58 INFO - 'TMPDIR': '/var/folders/b2/qr0y6yxn2s32nywswzqqd0bc00000w/T/', 22:43:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:43:58 INFO - 'USER': 'cltbld', 22:43:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:43:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:43:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:43:58 INFO - 'XPC_FLAGS': '0x0', 22:43:58 INFO - 'XPC_SERVICE_NAME': '0', 22:43:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:43:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:43:59 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)) 22:43:59 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)) 22:43:59 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)) 22:43:59 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)) 22:43:59 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)) 22:43:59 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)) 22:43:59 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)) 22:43:59 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)) 22:43:59 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)) 22:43:59 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)) 22:43:59 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)) 22:43:59 INFO - Cleaning up... 22:43:59 INFO - Return code: 0 22:43:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:43:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:43:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:43:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:43:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:43:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:43:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:43:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e334a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e344420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd378cbc6a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e1d3e00>, '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 0x10dffcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e1ceb20>, '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.fEGx2oPaql/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.lmVuL14aRz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b2/qr0y6yxn2s32nywswzqqd0bc00000w/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 22:43:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 22:43:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 22:43:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fEGx2oPaql/Render', 22:43:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:43:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:43:59 INFO - 'HOME': '/Users/cltbld', 22:43:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:43:59 INFO - 'LOGNAME': 'cltbld', 22:43:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:43:59 INFO - 'MOZ_NO_REMOTE': '1', 22:43:59 INFO - 'NO_EM_RESTART': '1', 22:43:59 INFO - 'PAGER': '/bin/cat', 22:43:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:43:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:43:59 INFO - 'PWD': '/builds/slave/test', 22:43:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:43:59 INFO - 'SHELL': '/bin/bash', 22:43:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lmVuL14aRz/Listeners', 22:43:59 INFO - 'TMPDIR': '/var/folders/b2/qr0y6yxn2s32nywswzqqd0bc00000w/T/', 22:43:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:43:59 INFO - 'USER': 'cltbld', 22:43:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:43:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:43:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:43:59 INFO - 'XPC_FLAGS': '0x0', 22:43:59 INFO - 'XPC_SERVICE_NAME': '0', 22:43:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:43:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:43:59 INFO - Downloading/unpacking jsonschema 22:43: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 22:43: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 22:43: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 22:43: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 22:43: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 22:43: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 22:44:01 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:44:01 INFO - Downloading/unpacking functools32 (from jsonschema) 22:44:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:44:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:44:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:44:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:44:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:44:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:44:02 INFO - Downloading functools32-3.2.3-2.tar.gz 22:44:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:44:02 INFO - warning: no files found matching '*.txt' 22:44:02 INFO - no previously-included directories found matching 'build' 22:44:02 INFO - no previously-included directories found matching 'dist' 22:44:02 INFO - no previously-included directories found matching '.git*' 22:44:02 INFO - Installing collected packages: jsonschema, functools32 22:44:02 INFO - Running setup.py install for functools32 22:44:02 INFO - warning: no files found matching '*.txt' 22:44:02 INFO - no previously-included directories found matching 'build' 22:44:02 INFO - no previously-included directories found matching 'dist' 22:44:02 INFO - no previously-included directories found matching '.git*' 22:44:02 INFO - Successfully installed jsonschema functools32 22:44:02 INFO - Cleaning up... 22:44:02 INFO - Return code: 0 22:44:02 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 22:44:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:44:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:44:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:44:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:44:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:44:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:44:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e334a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e344420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd378cbc6a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e1d3e00>, '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 0x10dffcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e1ceb20>, '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.fEGx2oPaql/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.lmVuL14aRz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b2/qr0y6yxn2s32nywswzqqd0bc00000w/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 22:44:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 22:44:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 22:44:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fEGx2oPaql/Render', 22:44:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:44:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:44:02 INFO - 'HOME': '/Users/cltbld', 22:44:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:44:02 INFO - 'LOGNAME': 'cltbld', 22:44:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:44:02 INFO - 'MOZ_NO_REMOTE': '1', 22:44:02 INFO - 'NO_EM_RESTART': '1', 22:44:02 INFO - 'PAGER': '/bin/cat', 22:44:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:44:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:44:02 INFO - 'PWD': '/builds/slave/test', 22:44:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:44:02 INFO - 'SHELL': '/bin/bash', 22:44:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lmVuL14aRz/Listeners', 22:44:02 INFO - 'TMPDIR': '/var/folders/b2/qr0y6yxn2s32nywswzqqd0bc00000w/T/', 22:44:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:44:02 INFO - 'USER': 'cltbld', 22:44:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:44:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:44:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:44:02 INFO - 'XPC_FLAGS': '0x0', 22:44:02 INFO - 'XPC_SERVICE_NAME': '0', 22:44:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:44:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:44:02 INFO - Downloading/unpacking flake8 22:44:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:44:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:44:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:44:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:44:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:44:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:44:05 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:44:05 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:44:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:44:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:44:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:44:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:44:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:44:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:44:05 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:44:05 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:44:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:44:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:44:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:44:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:44:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:44:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:44:06 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:44:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:44:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:44:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:44:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:44:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:44:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:44:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 22:44:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:44:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:44:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:44:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:44:06 INFO - no previously-included directories found matching 'docs/_build' 22:44:06 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:44:06 INFO - Running setup.py install for pep8 22:44:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:44:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:44:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:44:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:44:06 INFO - no previously-included directories found matching 'docs/_build' 22:44:06 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 22:44:06 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:44:06 INFO - Cleaning up... 22:44:07 INFO - Return code: 0 22:44:07 INFO - Running post-action listener: _resource_record_post_action 22:44:07 INFO - Running post-action listener: _start_resource_monitoring 22:44:07 INFO - Starting resource monitoring. 22:44:07 INFO - ##### 22:44:07 INFO - ##### Running install step. 22:44:07 INFO - ##### 22:44:07 INFO - Running pre-action listener: _resource_record_pre_action 22:44:07 INFO - Running main action method: install 22:44:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:44:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:44:07 INFO - Reading from file tmpfile_stdout 22:44:07 INFO - Detecting whether we're running mozinstall >=1.0... 22:44:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:44:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:44:07 INFO - Reading from file tmpfile_stdout 22:44:07 INFO - Output received: 22:44:07 INFO - Usage: mozinstall [options] installer 22:44:07 INFO - Options: 22:44:07 INFO - -h, --help show this help message and exit 22:44:07 INFO - -d DEST, --destination=DEST 22:44:07 INFO - Directory to install application into. [default: 22:44:07 INFO - "/builds/slave/test"] 22:44:07 INFO - --app=APP Application being installed. [default: firefox] 22:44:07 INFO - mkdir: /builds/slave/test/build/application 22:44:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 22:44:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 22:44:41 INFO - Reading from file tmpfile_stdout 22:44:41 INFO - Output received: 22:44:41 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 22:44:41 INFO - Running post-action listener: _resource_record_post_action 22:44:41 INFO - ##### 22:44:41 INFO - ##### Running run-tests step. 22:44:41 INFO - ##### 22:44:41 INFO - Running pre-action listener: _resource_record_pre_action 22:44:41 INFO - Running main action method: run_tests 22:44:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:44:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:44:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:44:41 INFO - 2016-04-20 22:44:41.159 screenresolution[1638:7627] starting screenresolution argv=screenresolution get 22:44:41 INFO - 2016-04-20 22:44:41.161 screenresolution[1638:7627] Display 0: 1600x1200x32@60 22:44:41 INFO - 2016-04-20 22:44:41.166 screenresolution[1639:7629] starting screenresolution argv=screenresolution list 22:44:41 INFO - Available Modes on Display 0 22:44:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:44:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:44:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:44:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:44:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:44:41 INFO - Intel Iris: 22:44:41 INFO - Chipset Model: Intel Iris 22:44:41 INFO - Type: GPU 22:44:41 INFO - Bus: Built-In 22:44:41 INFO - VRAM (Dynamic, Max): 1536 MB 22:44:41 INFO - Vendor: Intel (0x8086) 22:44:41 INFO - Device ID: 0x0a2e 22:44:41 INFO - Revision ID: 0x0009 22:44:41 INFO - Displays: 22:44:41 INFO - Display: 22:44:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:44:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:44:41 INFO - Display Serial Number: 1600x1200 60 22:44:41 INFO - Main Display: Yes 22:44:41 INFO - Mirror: Off 22:44:41 INFO - Online: Yes 22:44:41 INFO - Rotation: Supported 22:44:41 INFO - Return code: 0 22:44:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:44:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:44:41 INFO - Python 2.7.3 22:44:41 INFO - Return code: 0 22:44:41 INFO - grabbing minidump binary from tooltool 22:44:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:44:41 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e1d3e00>, '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 0x10dffcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e1ceb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:44:41 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 22:44:41 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 22:44:41 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:44:41 INFO - Return code: 0 22:44:41 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:44:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:44:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:44:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:44:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:44:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 22:44:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 22:44:42 INFO - Return code: 0 22:44:42 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/f--P_RvtTeqg9fmWAJp0DQ/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0089', '--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 22:44:42 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/f--P_RvtTeqg9fmWAJp0DQ/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0089 --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 22:44:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fEGx2oPaql/Render', 22:44:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:44:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:44:42 INFO - 'HOME': '/Users/cltbld', 22:44:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:44:42 INFO - 'LOGNAME': 'cltbld', 22:44:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:44:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:44:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:44:42 INFO - 'MOZ_NO_REMOTE': '1', 22:44:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:44:42 INFO - 'NO_EM_RESTART': '1', 22:44:42 INFO - 'PAGER': '/bin/cat', 22:44:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:44:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:44:42 INFO - 'PWD': '/builds/slave/test', 22:44:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:44:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:44:42 INFO - 'SHELL': '/bin/bash', 22:44:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lmVuL14aRz/Listeners', 22:44:42 INFO - 'TMPDIR': '/var/folders/b2/qr0y6yxn2s32nywswzqqd0bc00000w/T/', 22:44:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:44:42 INFO - 'USER': 'cltbld', 22:44:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:44:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:44:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:44:42 INFO - 'XPC_FLAGS': '0x0', 22:44:42 INFO - 'XPC_SERVICE_NAME': '0', 22:44:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:44:42 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/f--P_RvtTeqg9fmWAJp0DQ/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0089', '--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 22:44:42 INFO - mozversion application_buildid: 20160420210806 22:44:42 INFO - mozversion application_changeset: 85616706a29b8944f3a09f5cc32e9e219f6ceb40 22:44:42 INFO - mozversion application_display_name: Nightly 22:44:42 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:44:42 INFO - mozversion application_name: Firefox 22:44:42 INFO - mozversion application_remotingname: firefox 22:44:42 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 22:44:42 INFO - mozversion application_vendor: Mozilla 22:44:42 INFO - mozversion application_version: 48.0a1 22:44:42 INFO - mozversion platform_buildid: 20160420210806 22:44:42 INFO - mozversion platform_changeset: 85616706a29b8944f3a09f5cc32e9e219f6ceb40 22:44:42 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 22:44:42 INFO - mozversion platform_version: 48.0a1 22:44:42 INFO - using testdate: 1461217482 22:44:42 INFO - actual date: 1461217482 22:44:42 INFO - starting webserver on 'localhost:49278' 22:44:42 INFO - SUITE-START | Running 2 tests 22:44:42 INFO - TEST-START | dromaeo_css 22:44:42 INFO - operating with platform_type : mac_ 22:44:42 INFO - Initialising browser for dromaeo_css test... 22:44:42 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/b2/qr0y6yxn2s32nywswzqqd0bc00000w/T/tmp1KcUdm/profile http://localhost:49278/getInfo.html) 22:44:45 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 22:44:45 INFO - PROCESS | 1657 | colorDepth:24 22:44:45 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 22:44:45 INFO - PROCESS | 1657 | __metrics 22:44:45 INFO - PROCESS | 1657 | 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" 22:44:45 ERROR - PROCESS | 1657 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:44:46 INFO - TEST-INFO | 1657: exit 0 22:44:46 INFO - Browser initialized. 22:44:46 INFO - Running cycle 1/1 for dromaeo_css test... 22:44:46 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/b2/qr0y6yxn2s32nywswzqqd0bc00000w/T/tmp1KcUdm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:44:58 INFO - PROCESS | 1659 | RSS: Main: 238796800 22:44:58 INFO - PROCESS | 1659 | 22:45:49 INFO - PROCESS | 1659 | 1461217549556 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] 22:47:59 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49278/tests/dromaeo/cssquery-ext.html) 22:48:01 INFO - PROCESS | 1659 | RSS: Main: 347938816 22:48:01 INFO - PROCESS | 1659 | 22:51:03 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-ext.html (next: http://localhost:49278/tests/dromaeo/cssquery-jquery.html) 22:51:04 INFO - PROCESS | 1659 | RSS: Main: 375406592 22:51:04 INFO - PROCESS | 1659 | 22:54:06 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49278/tests/dromaeo/cssquery-mootools.html) 22:54:07 INFO - PROCESS | 1659 | RSS: Main: 375156736 22:54:07 INFO - PROCESS | 1659 | 22:57:09 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49278/tests/dromaeo/cssquery-prototype.html) 22:57:09 INFO - PROCESS | 1659 | RSS: Main: 377499648 22:57:09 INFO - PROCESS | 1659 | 23:00:12 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49278/tests/dromaeo/cssquery-yui.html) 23:00:13 INFO - PROCESS | 1659 | RSS: Main: 342855680 23:00:13 INFO - PROCESS | 1659 | 23:03:16 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-yui.html (next: http://localhost:49278/tests/dromaeo/cssquery-dojo.html) 23:03:16 INFO - PROCESS | 1659 | RSS: Main: 383188992 23:03:16 INFO - PROCESS | 1659 | 23:03:16 INFO - PROCESS | 1659 | __start_tp_report 23:03:16 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 23:03:16 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 23:03:16 INFO - PROCESS | 1659 | |i|pagename|runs| 23:03:16 INFO - PROCESS | 1659 | |0;dojo.html;3347.5815523059614;3394.779339342941;3423.640517745638;3427.2288735034613;3462.8268586570666;5562.5271851892585;5566.972165139179;5594.468525490082;5612.803551875685;5626.943730562703;5594.720263986805;5628.943710562893;5674.120511320742;5727.684977326246;5741.540676745861;5095.464976177504;5103.311053007834;5104.030234255489;5134.691918484903;5253.684778913258;5264.315638966929;5279.683219006853;5283.947160528384;5292.92060619091;5323.068463018975;3784.0350710568905;4221.725587836811;4457.375967364572;5069.898602027975;5186.844394668166;5148.202028685522;5151.871203219949;5161.483851614846;5172.27588137661;5172.741362931876;5126.846194614168;5162.5095615916425;5194.961007798418;5216.947830521722;5220.2430647556275;5454.945450545483;5480.698561579112;5568.972155139199;5595.412481689405;5624.971875140598;6149.661768602725;6181.845453863644;6182.814515564541;6196.318404975449;6202.41077097675;6143.539234557381;6222.782202622931;6247.9062814057825;6257.280412752546;6261.405166509175;5620.9437905620825;5693.829185124411;5723.942760572382;5802.535797136304;5809.477147056785;6231.781887633911;6239.064140378889;6244.937550624527;6250.937490625036;6252.218472690914;6076.756929722762;6084.330723620398;6098.420650038239;6105.603135796159;6123.908141377883;6077.9392206078255;6082.574219804571;6120.66336351496;6159.4456498915315;6176.814695559051;6095.543686224553;6137.41694539018;6151.108089327023;6152.969235153795;6167.599106058092;6159.938400615936;6166.290876549165;6169.136320915076;6171.1668924694595;6172.043333283348;3837.6737977272246;4189.015581338383;4783.6412269079265;4820.132376172322;4855.344528488608;4205.579442055831;4408.096340250241;4409.603135717801;4434.933475997863;4444.2444784386735;5555.194496798023;5564.221009058735;5581.246531718247;5602.943970560324;5617.578681598897;6092.969535152296;6157.353477884847;6174.783882564088;6175.104609831639;6186.226721659793;4302.354646802942;5490.423505531941;5550.27846379968;5570.582206334541;5584.692841893735;4871.683340582851;5349.973250133802;5356.625036247426;5364.061289274361;5390.51485366319;6281.2462504498835;6308.684565771785;6310.747570097236;6311.211098612674;6322.4942004639515;6120.846978825565;6167.414095660905;6211.782587609593;6218.06728990655;6268.874622507616;6263.592866463665;6287.119803227643;6302.779402721067;6311.621302721851;6316.368363163647;6120.081987701702;6141;6150.7847225346895;6157.199564056644;6183.196184495987;6061.727222275096;6138.815835524942;6162.938370616236;6200.472959798287;6205.193324867739;5933.2880054393745;5991.310999235144;6020.548458865515;6039.607425517327;6063.605865618897;6013.188219590299;6041;6043.335233124265;6051.152838602511;6072.757089716539;5745.971270143623;5833.270841144857;5885.705714714161;5899.262592175978;5904.350521442724;5927.7036148191555;6030.761264109715;6037.607555508878;6048.425399587032;6054.273487181566;5868.2077919482035;5892.587518873809;5900.616459930091;5917.082852157922;5952.553558483218 23:03:16 INFO - PROCESS | 1659 | |1;ext.html;497.85313332566886;501.3582614253752;520.0742678033102;534.0787142179739;555.1256770586326;36806;37438;38194.854154375345;38236.04409889754;38425.80787096068;40612.98467538313;41151.58848411515;41416;41475.37786933191;41504.792476037575;8495.447795893278;9626.085521875428;9711.805763884719;9749.805003899917;11239.775204495905;11424;11588.072954163647;11590.014848737868;11596.652100436959;11600.709982250426;11548.422578871065;11644.475998580041;11648.126390520703;11657.766844663101;11670.066394688447;11520.711982200428;11598.594049208237;11614.18700690952;11623.535058597647;11626.709332266677;11369.829452558219;11580.131490138274;11607.4196290186;11612;11616.303021818718;32925;39689;40076.398854017214;40185.59814401877;40215.396769048486;2926.590277361171;3487.163080860575;3623.7825730455993;3642.8907132786057;3705.481232627434;22972.65541016886;27008.1897543074;28917;29781.851090744407;30092;2718.7417195366406;2737.4525094980886;2751.8073734838567;2786.275568352243;2804.677462091865;450919;453244;453884.03637799906;455173.72413138056;456421;60844;61242.38757612367;61284.38715612876;61296.69351653302;61341;64530.032049519294;64778.02832957509;64804;64804;64933;15933.920330398274;16373.345066197457;16397.180140992903;16400.507984760476;16409.507714768577;12673.429695663717;12731.55439559611;12740.80888786669;12753.043521735906;12754.744905101847;16433.424830130887;16734.74897876533;16849.915750421416;16900.577485562713;16941;6730.399236408498;6740.764073257413;6774.762883299061;6823.931760682429;6857.7256909723055;957.4351132831663;961.9615215391445;1076.9730756730987;1090.1224514265934;1106.7509810292781;26928.865355673097;29264.707352926624;29695.703042969726;29788.255293617833;29941.963638544923;988.4316518002178;1077.380506208933;1101.8347247912716;1123.7696272264166;1211.6304527119314;13818.101823381448;14203.78694319586;14489.275536223147;14684.632884177981;15127.773083403757;584077;589807;590087;590579;590634;57518.42481575214;59938;59966.700166498886;59968.400315997154;60116.09825852616;1369.760291948925;1372.34813463604;1413.3993052952453;1493.1489051240906;1495.9177245251615;19435.227533156038;26500;29928.25179370533;30354.84822575961;30470.085897423112;50050;50353.49646503488;50606.49393506018;50887.49112508827;50919;394332.0283398565;396889;397481;397676;398381;25796.61305080463;26134.477310453305;26735.46529069369;26752.19743407701;36154.4576831353;42076.78961605173;42584.5741542593;42610.78694606631;42929.35605965975;43149.56850431581;33624.99125026253;35016;35572;35719;36036.09909752277;34340.82829585836;34656.82671586727;35517.82241088778;35545.466817997236;35986.820065899505;36410.63589364073;36682.26635467329;36799.264014720095;36922;37351.43972840464;26636.7336326642;30347.089587311533;30713.846430767702;30735.385292294475;30808.383832323678;20378.79621203769;21888.233911813648;23186.884065579565;23536.646950295417;23641.881790590938 23:03:16 INFO - PROCESS | 1659 | |2;jquery.html;397.6143141153082;397.85876014015025;400.1716446954804;405.8518640696143;411.83938264076994;19382.93393863337;19636.410907672776;20355.28756493523;20358.89820550897;20510.487237819023;19827.603447931033;19961.401157965247;21536.246231381865;21753.456163595918;21765.67351489725;5377.300950876391;5987.251593550806;5992;6001.60989535681;6026.035834266519;5652.943470565303;5787.50806181475;5944.405559444064;5954.880902381949;5966.37353077927;5869.354371019184;5874.618149820248;5889.469947704706;5904.822855314347;5913.733881975321;5616.185653080322;5864.179014937912;5885.176075349433;5887.82150604277;5889.911651325234;5131.589472842164;5134.127198376285;5170.844874653766;5175.404828444739;5177.301064356301;24140.396490087714;25457.745422545908;25997.350066248306;26022.73977260222;26148.477030459286;2058.423641380401;2115.6509175985943;2154.3859999899955;2194.6488561830192;2324.6629238760456;14373.78439323411;15024.399024039025;15066;15147.469838555598;15402.46091386796;1679.2191630891573;1711.2384988680042;1732.2984191402502;1739.339051160551;1752.7049147602554;60586;61215;102257;114550;121205.39397302957;10928.398938058324;11344.886551134383;11347.659570212907;11352.545898164146;11461.312321260752;14457.92771036138;14564.708705825824;14567.635809104857;14578.343974521173;14584.48954286595;6707.692000060039;6903.275156108536;7605.315521603082;8308.418410711192;8749.343799215085;6253.7185826637915;8386.245237928615;8434.409591328671;8515.701950431705;8610.268127209258;6782.2200446948245;6849.24658287598;6927.445804335641;6928.954721926812;6972.163340399185;1853.2657735822768;1866.4214093631017;1874.4282993687057;1878.3050271399397;1928.9807101929082;944.4003058058182;974.8001659659752;1044.6865940217904;1053.3048188195753;1079.6760971708457;18462.723059154123;20571;20876.895615521826;21624.459388515414;21768.89115554444;949.5584553182803;982.6020461712875;1002.7593377589471;1007.1238022920106;1063.6064656077301;11233.38216398106;11563;11593.6521904343;11624.88375116238;11634.418279086181;606238;608569;621858;640019;641710.7914460398;118895;119328.40335798265;119948.40025799815;120906;122591.8704064811;451.60484575996;474.18203598792496;474.8029567729354;476.8879313361427;998.3061772068488;10315;10659.25385223027;11141.38722370245;11261.774764504828;11376.317420954769;49542.256866146316;51580.74209628928;51917.740411299215;52785.7360713194;53544.73227633837;75500.86748698664;76955;77051.2294877044;77598.44803104019;79276;8128.9593552031865;8176.550289734122;8177.918220817715;8398.790030249293;8506.106858779738;9158.084191581054;10107.545160467944;11944.402779860799;12861.807072894102;12941.417636206204;9587.808243835223;10956.411320358662;11019.779604407706;11247.156463265124;11276.436178190894;10818.891811081789;10964.122870170208;11108.111351091731;11145.442727863412;11181.888181118085;11160.720981975192;11302.773944520899;11541.422928853692;11543.653690389301;11707.356095414834;7103.467239956921;7210.206877243604;8191.508509489449;8373.246407823324;8398.20217079401;9097.226735727538;11069.778604427705;11318.151138664798;11405.486753095965;11446.37044962535 23:03:16 INFO - PROCESS | 1659 | |3;mootools.html;1434.340203506387;1611.838816118388;1628.4463282483957;1922.2311075569778;2048.8975551222425;7871.724489642864;8520.318374530038;8769.473831570094;8772.254358379529;8781.473111613308;8400.453970491913;8611.87082193766;8622.180892815188;8624.223819856212;8624.655013799456;2455.496623192246;2489.390099425634;2492.239866840613;2492.264781889338;2493.4140476987895;2400.159944019588;2484.3416494628937;2485.3538080099124;2488.552060629086;2492.464120214154;2231.877246751428;2298.908043678251;2418.177819541356;2444.8533088014688;2450.4976479821685;2002.9499262518407;2442.487077713682;2445.0342114864616;2446.8287219894523;2448.902043918241;2345.9765402346097;2417.601095819188;2425.4300239443714;2429.149797570854;2430.5260474207535;7452.441066920002;7558.811029724246;7931;8496.78758031048;8652.783680407978;5187.481251874782;5391.487808658171;5392.0294347017525;5393.1370980643305;5403.027455058088;3386.6613338666184;3414.2830005698834;3481.56480439945;3491.720662347006;3493.441049432104;981.6760469045198;999.0558921818885;1007.0936157458273;1008.4671299624603;1010.2322235101302;11241.325520468798;11362.71593210168;11379.829302560469;11575.652730418102;11592.999999999916;4745.311929770164;4809.278608208797;4826.710397376169;4922.237053256751;4941.431735350408;8762.77321175036;10034.845992710785;10054.145397641283;10064;10070.144037756872;5315.920261196085;5429.755660995264;5432.809851655173;5439.428859969726;5459.1265397537;4861.562459378673;4862.197737373364;4932.260160975882;4933.50664933511;4964.60283177345;3759.7180211484247;3788.3749181384765;3876;3893.357595996629;3899.902502437462;1296.8314119164447;1305.477808876457;1307.2744626732065;1319.683276013769;1321.4053675845907;755.7014979083232;780.6284805091556;887.573964497045;916.5279880861361;938.6667732954703;5564.693941833238;5618.747156377891;5668.121441176624;5734.283214598176;5869.178315035899;439.2620397731777;452.85055931542513;461.60302140159433;462.8703962890396;468.76480473951386;3750.681192098702;3851.730378873508;3872.934942890671;3878.166194268246;3891.1439483313625;10138.290319677551;10507.264491485676;10728.731781705364;10730.839037414446;10822;10654.62708805188;10654.840177397346;10661.040506354466;10672.78654426907;10679.199060070527;7974.920250797534;7994.800129996796;8006.439549231498;8059.717909873244;8127.146649601825;5595.272614560081;5605.663660180402;5685.374608792947;5698.629589076697;5818.526179380366;9413.52932353366;9743.805123897624;9770;9898.356606820533;9934.900650993397;9364.20404265645;9897.208223341971;9942.701718948443;9987.900120998696;10032;7299.197088320174;7341.375983041287;7365.2634736525915;7378.483506154517;7438.293362130589;8015.6392962318005;8090.919090809017;8119.959400202961;8126.918730812853;8170.591470426574;6768.424683901923;6988.301169882971;7298.781036568911;7346.485745997943;7483.251674832473;7094.53885497441;7104.218535961144;7262.9273707262255;7314.195438501867;7359.300866417681;6660.400563949074;6829.453643708392;6932.064171336805;6937.271586483548;6943.930560694328;4202.726822756511;4685.882852928704;4730.172219861442;4815.542523460265;4937.086638971802;4762.166620841408;4765.8093676252565;4815.879603009813;4845.781939812788;4900.558949694545 23:03:16 INFO - PROCESS | 1659 | |4;prototype.html;254.30194117148432;308.4555759085214;322.5790343601599;329.5995365630758;330.3673465313924;5591.608587398884;5677.120046392815;5790.536757059435;5804.2744656917885;5864.1203819427055;4561.863144105674;5108.8211912583065;5767.192593036978;5825.213596164516;5831.008728516152;3596.1549035976523;4390.968122491212;4393.143337049277;4422.248217802965;4424.955750442503;3558.8255875561003;3807.562130355003;4167.895507690464;4293.334533147352;4475.664325175625;3944.895429279792;4319.805608747598;4371;4393.07745373472;4401.141777353418;3669.853205871762;4153.317906247959;4361.323994780814;4378.934315985262;4401.757903315301;3309.3712194682935;3759.6052414496357;3816.5229346331707;3834.654881060718;3851.73037887348;4531.728096314198;4973.632251130968;5022.347094877679;5384.703841288728;5615.831525054255;4161;5002.6748261363255;5012.8496145115705;5029.647924645276;5040.798368065273;5510.401983424802;5809.51857276396;6107.541934354941;6113.602615829956;6225.470834979033;3657.7439579229463;3952.8179815248714;4077.7145599808027;4134.627883490471;4145.150244199932;5308.495692909168;5347.679139251657;5372.677639341613;6517.859374609472;6541.803745887631;3682.3924052531033;4026.5973402660084;4047.9070650924086;4158.9376159357635;4179.665626749853;4193.580641935782;4223.260929337353;4231.597998190167;4237.216115018731;4248.107897341592;3903.4535165076913;4538.727676339364;4675.064987002613;4959.454460009396;4974.452810190876;5814.592978491465;6206.937930620636;6238.1890354254565;6251.343608921089;6271.560990730605;1244.9128561000823;1308.3065975033248;1420.5383250443647;1454.7090581883665;1455.1196526101767;938.3859761210707;941.4539567051094;957.6887511558771;960.8798900137483;965.4593427680521;5567.248421463131;5670.149477578314;5675.517581005578;5680.829575112753;5701.515371193412;5952.285725712855;6273;6290;6290.716917738713;6295.307516173218;4949.935763810798;5504.862378440571;5658.434156584391;5791.536677065825;5839.970800145972;2961.4965455872552;3387.0516255448515;3408.9716618611887;3412.505186747908;3414.9487757683655;6479.740810367627;6628;6777.830554236085;6788.389044985975;6848.280930502226;5992.280926288873;6271.937280627227;6585.73657053722;6657.700403481855;6668.233153187349;5558.832645144484;6542.051402546699;6719.328067193339;6738.350538128676;6749.291324410866;5233.869153271199;5290.894182116259;6417.2299324081405;6475.805725828232;6501.674916254263;4813.542713442217;5351.59863010268;6321.652309123042;6374.203224596926;6381.425671689571;5535.446455354432;6470.126532917996;6707.5975441473665;6723.125993620798;6769.289224631343;4112.136451345251;4267.402563641155;4278.197780453807;4287.356896465555;4386.495553011445;5807.245058142415;5846.473817356288;6406.647634380154;6412.729507951072;6431.099646049463;6191.597546159485;6192.717859534274;6232.719527621359;6235.688215589294;6253.187085678833;5654.519365853948;5724.341700704473;6199.783007594712;6227.941249987603;6229.937700623117;6021.969890150521;6038.396160383926;6045.425684559959;6074.817755467343;6106.5114790817515;5176.741162941763;6174.969125154345;6223.688815559294;6245.313015568375;6245.937540624717;5005.549500544968;5672;5986.431289027535;6090.969545152246;6118.357572454828 23:03:16 INFO - PROCESS | 1659 | |5;yui.html;681.7443458702986;682.7303215229981;734.4528326396834;745.2175216023178;764.3503022430934;14668.853311466883;14734.778978315333;14875.851241487582;14894.329755161016;14895.255237238149;13728.176309421442;13837;13849.653758656013;13862.722745545083;13864.237466939314;726.6439444672098;747.8766003609398;915.8305713443001;928.921041711452;933.3140141995627;899.5007770687266;939.3049143633696;940.0458534587399;942.0250041207352;945.6973768394116;983.8376667849797;992.8560358747977;995.3032876986102;997.9650712225074;1001.1740314240781;765.1048273519995;953.3660116022836;986.9457179855106;987.7728122531822;990.8166989107002;736.7310931510013;737.0197637142587;737.049206523584;737.6643627149634;738.0774032459426;14706.779398309034;14760.188189649565;14762.261886905719;14791.778123328158;14795.852041479555;12390.938045309806;12453.003759699202;12514.499420023189;12769.87230127696;12805.615831525069;9956.701298961043;9982.850257246148;10108.342957707726;10111.544980475823;10543.683689489328;3492.2142517933517;3504.299140171976;3549.929001419983;3578.35589593873;3598.8740394086344;81451;82382.35235295321;83102.58448707718;83280.58359708224;84569.15430845674;9098.681546145854;9432.103950124727;9467.715968520955;9715.805683886425;9818.950905245429;21713.45716357072;21917.232896848534;21918.45203869916;21927.451813704465;22072.668909966364;7329.706811727578;7342.045534080642;7376.336129748348;7513.511621744569;7624.42816788743;3934.075492259317;3984.362501999666;3985.322495175827;4045.554988951213;4267.722598031118;3562.5190599269285;3620.619834917296;3636.636336366395;3642.5446819147605;3642.763220390666;688.1226436293765;706.9575825450489;720.3732752505354;728.3117454006019;734.698773502872;426.5989969428727;515.6751246714546;565.4147956864625;575.3038823024104;585.2128886647453;7111.679974401164;7115.501914865909;7417.480776345604;7627.847443051219;7848.372130229568;536.4742552298769;549.5878091431426;566.283651181256;569.4959960435045;579.7333226715674;3650.543682039745;3750.83121259544;3783.4703141560203;3808.9809550952623;3823.1780167264174;76960.61519692365;78894.2110578887;78937;78957;79029.60485197537;50859;65424.34575654183;65959.68080638457;66068.66965665141;66445.66777166084;63126;64549.354506455275;64742.67628661827;65219;67744;7814.57019863913;7895.210478952059;8002;8030.307550040252;8178.713745019134;41368;42206;42889.35665964951;42998.35502467403;43166.56833431751;62105;62711.74576508353;62815.37184628278;63182.36817631765;63287.73424531391;10663.786724265316;10948.34309941406;10979;11073.667789966314;11220.2145849793;11399.373034483184;11553.075753939496;11898.643040708792;11925.224860384047;12364.876351236373;10059.597616095567;10361.222908281758;10674.73313167177;11161.497732602214;11226.158038147008;10806.297590656583;11097.33415995043;11210.831837522606;11266;11369.829452558053;10854.457277135954;11041.889581104408;11085;11098.445077746243;11685.182037257311;9293.25653947669;9479.47862867549;9629;9840.45877476717;9927.751806204902;7240.52936559122;9226.261899048195;9263.16209038945;9278.95360523193;9394.342396032485 23:03:16 INFO - PROCESS | 1659 | __end_tp_report 23:03:16 INFO - PROCESS | 1659 | __start_cc_report 23:03:16 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,1277 23:03:16 INFO - PROCESS | 1659 | __end_cc_report 23:03:16 INFO - PROCESS | 1659 | __startTimestamp1461218596849__endTimestamp 23:03:16 INFO - PROCESS | 1659 | 23:03:16 INFO - PROCESS | 1659 | ------- Summary: start ------- 23:03:16 INFO - PROCESS | 1659 | Number of tests: 6 23:03:16 INFO - PROCESS | 1659 | 23:03:16 INFO - PROCESS | 1659 | [#0] dojo.html Cycles:165 Average:5653.82 Median:5904.35 stddev:686.17 (11.6%) stddev-sans-first:664.00 23:03:16 INFO - PROCESS | 1659 | Values: 3347.6 3394.8 3423.6 3427.2 3462.8 5562.5 5567.0 5594.5 5612.8 5626.9 5594.7 5628.9 5674.1 5727.7 5741.5 5095.5 5103.3 5104.0 5134.7 5253.7 5264.3 5279.7 5283.9 5292.9 5323.1 3784.0 4221.7 4457.4 5069.9 5186.8 5148.2 5151.9 5161.5 5172.3 5172.7 5126.8 5162.5 5195.0 5216.9 5220.2 5454.9 5480.7 5569.0 5595.4 5625.0 6149.7 6181.8 6182.8 6196.3 6202.4 6143.5 6222.8 6247.9 6257.3 6261.4 5620.9 5693.8 5723.9 5802.5 5809.5 6231.8 6239.1 6244.9 6250.9 6252.2 6076.8 6084.3 6098.4 6105.6 6123.9 6077.9 6082.6 6120.7 6159.4 6176.8 6095.5 6137.4 6151.1 6153.0 6167.6 6159.9 6166.3 6169.1 6171.2 6172.0 3837.7 4189.0 4783.6 4820.1 4855.3 4205.6 4408.1 4409.6 4434.9 4444.2 5555.2 5564.2 5581.2 5602.9 5617.6 6093.0 6157.4 6174.8 6175.1 6186.2 4302.4 5490.4 5550.3 5570.6 5584.7 4871.7 5350.0 5356.6 5364.1 5390.5 6281.2 6308.7 6310.7 6311.2 6322.5 6120.8 6167.4 6211.8 6218.1 6268.9 6263.6 6287.1 6302.8 6311.6 6316.4 6120.1 6141.0 6150.8 6157.2 6183.2 6061.7 6138.8 6162.9 6200.5 6205.2 5933.3 5991.3 6020.5 6039.6 6063.6 6013.2 6041.0 6043.3 6051.2 6072.8 5746.0 5833.3 5885.7 5899.3 5904.4 5927.7 6030.8 6037.6 6048.4 6054.3 5868.2 5892.6 5900.6 5917.1 5952.6 23:03:16 INFO - PROCESS | 1659 | 23:03:16 INFO - PROCESS | 1659 | [#1] ext.html Cycles:180 Average:62275.85 Median:34498.83 stddev:129625.57 (375.7%) stddev-sans-first:129906.21 23:03:16 INFO - PROCESS | 1659 | Values: 497.9 501.4 520.1 534.1 555.1 36806.0 37438.0 38194.9 38236.0 38425.8 40613.0 41151.6 41416.0 41475.4 41504.8 8495.4 9626.1 9711.8 9749.8 11239.8 11424.0 11588.1 11590.0 11596.7 11600.7 11548.4 11644.5 11648.1 11657.8 11670.1 11520.7 11598.6 11614.2 11623.5 11626.7 11369.8 11580.1 11607.4 11612.0 11616.3 32925.0 39689.0 40076.4 40185.6 40215.4 2926.6 3487.2 3623.8 3642.9 3705.5 22972.7 27008.2 28917.0 29781.9 30092.0 2718.7 2737.5 2751.8 2786.3 2804.7 450919.0 453244.0 453884.0 455173.7 456421.0 60844.0 61242.4 61284.4 61296.7 61341.0 64530.0 64778.0 64804.0 64804.0 64933.0 15933.9 16373.3 16397.2 16400.5 16409.5 12673.4 12731.6 12740.8 12753.0 12754.7 16433.4 16734.7 16849.9 16900.6 16941.0 6730.4 6740.8 6774.8 6823.9 6857.7 957.4 962.0 1077.0 1090.1 1106.8 26928.9 29264.7 29695.7 29788.3 29942.0 988.4 1077.4 1101.8 1123.8 1211.6 13818.1 14203.8 14489.3 14684.6 15127.8 584077.0 589807.0 590087.0 590579.0 590634.0 57518.4 59938.0 59966.7 59968.4 60116.1 1369.8 1372.3 1413.4 1493.1 1495.9 19435.2 26500.0 29928.3 30354.8 30470.1 50050.0 50353.5 50606.5 50887.5 50919.0 394332.0 396889.0 397481.0 397676.0 398381.0 25796.6 26134.5 26735.5 26752.2 36154.5 42076.8 42584.6 42610.8 42929.4 43149.6 33625.0 35016.0 35572.0 35719.0 36036.1 34340.8 34656.8 35517.8 35545.5 35986.8 36410.6 36682.3 36799.3 36922.0 37351.4 26636.7 30347.1 30713.8 30735.4 30808.4 20378.8 21888.2 23186.9 23536.6 23641.9 23:03:16 INFO - PROCESS | 1659 | 23:03:16 INFO - PROCESS | 1659 | [#2] jquery.html Cycles:180 Average:34649.09 Median:13232.53 stddev:103312.53 (780.7%) stddev-sans-first:103570.34 23:03:16 INFO - PROCESS | 1659 | Values: 397.6 397.9 400.2 405.9 411.8 19382.9 19636.4 20355.3 20358.9 20510.5 19827.6 19961.4 21536.2 21753.5 21765.7 5377.3 5987.3 5992.0 6001.6 6026.0 5652.9 5787.5 5944.4 5954.9 5966.4 5869.4 5874.6 5889.5 5904.8 5913.7 5616.2 5864.2 5885.2 5887.8 5889.9 5131.6 5134.1 5170.8 5175.4 5177.3 24140.4 25457.7 25997.4 26022.7 26148.5 2058.4 2115.7 2154.4 2194.6 2324.7 14373.8 15024.4 15066.0 15147.5 15402.5 1679.2 1711.2 1732.3 1739.3 1752.7 60586.0 61215.0 102257.0 114550.0 121205.4 10928.4 11344.9 11347.7 11352.5 11461.3 14457.9 14564.7 14567.6 14578.3 14584.5 6707.7 6903.3 7605.3 8308.4 8749.3 6253.7 8386.2 8434.4 8515.7 8610.3 6782.2 6849.2 6927.4 6929.0 6972.2 1853.3 1866.4 1874.4 1878.3 1929.0 944.4 974.8 1044.7 1053.3 1079.7 18462.7 20571.0 20876.9 21624.5 21768.9 949.6 982.6 1002.8 1007.1 1063.6 11233.4 11563.0 11593.7 11624.9 11634.4 606238.0 608569.0 621858.0 640019.0 641710.8 118895.0 119328.4 119948.4 120906.0 122591.9 451.6 474.2 474.8 476.9 998.3 10315.0 10659.3 11141.4 11261.8 11376.3 49542.3 51580.7 51917.7 52785.7 53544.7 75500.9 76955.0 77051.2 77598.4 79276.0 8129.0 8176.6 8177.9 8398.8 8506.1 9158.1 10107.5 11944.4 12861.8 12941.4 9587.8 10956.4 11019.8 11247.2 11276.4 10818.9 10964.1 11108.1 11145.4 11181.9 11160.7 11302.8 11541.4 11543.7 11707.4 7103.5 7210.2 8191.5 8373.2 8398.2 9097.2 11069.8 11318.2 11405.5 11446.4 23:03:16 INFO - PROCESS | 1659 | 23:03:16 INFO - PROCESS | 1659 | [#3] mootools.html Cycles:180 Average:5610.05 Median:4881.38 stddev:3132.90 (64.2%) stddev-sans-first:3125.98 23:03:16 INFO - PROCESS | 1659 | Values: 1434.3 1611.8 1628.4 1922.2 2048.9 7871.7 8520.3 8769.5 8772.3 8781.5 8400.5 8611.9 8622.2 8624.2 8624.7 2455.5 2489.4 2492.2 2492.3 2493.4 2400.2 2484.3 2485.4 2488.6 2492.5 2231.9 2298.9 2418.2 2444.9 2450.5 2002.9 2442.5 2445.0 2446.8 2448.9 2346.0 2417.6 2425.4 2429.1 2430.5 7452.4 7558.8 7931.0 8496.8 8652.8 5187.5 5391.5 5392.0 5393.1 5403.0 3386.7 3414.3 3481.6 3491.7 3493.4 981.7 999.1 1007.1 1008.5 1010.2 11241.3 11362.7 11379.8 11575.7 11593.0 4745.3 4809.3 4826.7 4922.2 4941.4 8762.8 10034.8 10054.1 10064.0 10070.1 5315.9 5429.8 5432.8 5439.4 5459.1 4861.6 4862.2 4932.3 4933.5 4964.6 3759.7 3788.4 3876.0 3893.4 3899.9 1296.8 1305.5 1307.3 1319.7 1321.4 755.7 780.6 887.6 916.5 938.7 5564.7 5618.7 5668.1 5734.3 5869.2 439.3 452.9 461.6 462.9 468.8 3750.7 3851.7 3872.9 3878.2 3891.1 10138.3 10507.3 10728.7 10730.8 10822.0 10654.6 10654.8 10661.0 10672.8 10679.2 7974.9 7994.8 8006.4 8059.7 8127.1 5595.3 5605.7 5685.4 5698.6 5818.5 9413.5 9743.8 9770.0 9898.4 9934.9 9364.2 9897.2 9942.7 9987.9 10032.0 7299.2 7341.4 7365.3 7378.5 7438.3 8015.6 8090.9 8120.0 8126.9 8170.6 6768.4 6988.3 7298.8 7346.5 7483.3 7094.5 7104.2 7262.9 7314.2 7359.3 6660.4 6829.5 6932.1 6937.3 6943.9 4202.7 4685.9 4730.2 4815.5 4937.1 4762.2 4765.8 4815.9 4845.8 4900.6 23:03:16 INFO - PROCESS | 1659 | 23:03:16 INFO - PROCESS | 1659 | [#4] prototype.html Cycles:180 Average:4949.33 Median:5579.43 stddev:1574.01 (28.2%) stddev-sans-first:1538.48 23:03:16 INFO - PROCESS | 1659 | Values: 254.3 308.5 322.6 329.6 330.4 5591.6 5677.1 5790.5 5804.3 5864.1 4561.9 5108.8 5767.2 5825.2 5831.0 3596.2 4391.0 4393.1 4422.2 4425.0 3558.8 3807.6 4167.9 4293.3 4475.7 3944.9 4319.8 4371.0 4393.1 4401.1 3669.9 4153.3 4361.3 4378.9 4401.8 3309.4 3759.6 3816.5 3834.7 3851.7 4531.7 4973.6 5022.3 5384.7 5615.8 4161.0 5002.7 5012.8 5029.6 5040.8 5510.4 5809.5 6107.5 6113.6 6225.5 3657.7 3952.8 4077.7 4134.6 4145.2 5308.5 5347.7 5372.7 6517.9 6541.8 3682.4 4026.6 4047.9 4158.9 4179.7 4193.6 4223.3 4231.6 4237.2 4248.1 3903.5 4538.7 4675.1 4959.5 4974.5 5814.6 6206.9 6238.2 6251.3 6271.6 1244.9 1308.3 1420.5 1454.7 1455.1 938.4 941.5 957.7 960.9 965.5 5567.2 5670.1 5675.5 5680.8 5701.5 5952.3 6273.0 6290.0 6290.7 6295.3 4949.9 5504.9 5658.4 5791.5 5840.0 2961.5 3387.1 3409.0 3412.5 3414.9 6479.7 6628.0 6777.8 6788.4 6848.3 5992.3 6271.9 6585.7 6657.7 6668.2 5558.8 6542.1 6719.3 6738.4 6749.3 5233.9 5290.9 6417.2 6475.8 6501.7 4813.5 5351.6 6321.7 6374.2 6381.4 5535.4 6470.1 6707.6 6723.1 6769.3 4112.1 4267.4 4278.2 4287.4 4386.5 5807.2 5846.5 6406.6 6412.7 6431.1 6191.6 6192.7 6232.7 6235.7 6253.2 5654.5 5724.3 6199.8 6227.9 6229.9 6022.0 6038.4 6045.4 6074.8 6106.5 5176.7 6175.0 6223.7 6245.3 6245.9 5005.5 5672.0 5986.4 6091.0 6118.4 23:03:16 INFO - PROCESS | 1659 | 23:03:16 INFO - PROCESS | 1659 | [#5] yui.html Cycles:180 Average:16987.70 Median:62408.37 stddev:23128.77 (37.1%) stddev-sans-first:23161.24 23:03:16 INFO - PROCESS | 1659 | Values: 681.7 682.7 734.5 745.2 764.4 14668.9 14734.8 14875.9 14894.3 14895.3 13728.2 13837.0 13849.7 13862.7 13864.2 726.6 747.9 915.8 928.9 933.3 899.5 939.3 940.0 942.0 945.7 983.8 992.9 995.3 998.0 1001.2 765.1 953.4 986.9 987.8 990.8 736.7 737.0 737.0 737.7 738.1 14706.8 14760.2 14762.3 14791.8 14795.9 12390.9 12453.0 12514.5 12769.9 12805.6 9956.7 9982.9 10108.3 10111.5 10543.7 3492.2 3504.3 3549.9 3578.4 3598.9 81451.0 82382.4 83102.6 83280.6 84569.2 9098.7 9432.1 9467.7 9715.8 9819.0 21713.5 21917.2 21918.5 21927.5 22072.7 7329.7 7342.0 7376.3 7513.5 7624.4 3934.1 3984.4 3985.3 4045.6 4267.7 3562.5 3620.6 3636.6 3642.5 3642.8 688.1 707.0 720.4 728.3 734.7 426.6 515.7 565.4 575.3 585.2 7111.7 7115.5 7417.5 7627.8 7848.4 536.5 549.6 566.3 569.5 579.7 3650.5 3750.8 3783.5 3809.0 3823.2 76960.6 78894.2 78937.0 78957.0 79029.6 50859.0 65424.3 65959.7 66068.7 66445.7 63126.0 64549.4 64742.7 65219.0 67744.0 7814.6 7895.2 8002.0 8030.3 8178.7 41368.0 42206.0 42889.4 42998.4 43166.6 62105.0 62711.7 62815.4 63182.4 63287.7 10663.8 10948.3 10979.0 11073.7 11220.2 11399.4 11553.1 11898.6 11925.2 12364.9 10059.6 10361.2 10674.7 11161.5 11226.2 10806.3 11097.3 11210.8 11266.0 11369.8 10854.5 11041.9 11085.0 11098.4 11685.2 9293.3 9479.5 9629.0 9840.5 9927.8 7240.5 9226.3 9263.2 9279.0 9394.3 23:03:16 INFO - PROCESS | 1659 | -------- Summary: end -------- 23:03:16 INFO - PROCESS | 1659 | 23:03:17 INFO - TEST-INFO | 1659: exit 0 23:03:17 INFO - TEST-OK | dromaeo_css | took 1114536ms 23:03:17 INFO - TEST-START | kraken 23:03:17 INFO - operating with platform_type : mac_ 23:03:17 INFO - Initialising browser for kraken test... 23:03:17 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/b2/qr0y6yxn2s32nywswzqqd0bc00000w/T/tmpgNPrmm/profile http://localhost:49278/getInfo.html) 23:03:19 INFO - PROCESS | 1668 | __metrics Screen width/height:1600/1200 23:03:19 INFO - PROCESS | 1668 | colorDepth:24 23:03:19 INFO - PROCESS | 1668 | Browser inner width/height: 1024/689 23:03:19 INFO - PROCESS | 1668 | __metrics 23:03:19 INFO - PROCESS | 1668 | 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" 23:03:19 ERROR - PROCESS | 1668 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 23:03:20 INFO - TEST-INFO | 1668: exit 0 23:03:20 INFO - Browser initialized. 23:03:20 INFO - Running cycle 1/1 for kraken test... 23:03:20 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/b2/qr0y6yxn2s32nywswzqqd0bc00000w/T/tmpgNPrmm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:03:32 INFO - PROCESS | 1670 | RSS: Main: 238923776 23:03:32 INFO - PROCESS | 1670 | 23:03:35 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection) 23:03:36 INFO - PROCESS | 1670 | RSS: Main: 285577216 23:03:36 INFO - PROCESS | 1670 | 23:03:38 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-dft) 23:03:39 INFO - PROCESS | 1670 | RSS: Main: 299261952 23:03:39 INFO - PROCESS | 1670 | 23:03:42 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-fft) 23:03:43 INFO - PROCESS | 1670 | RSS: Main: 386265088 23:03:43 INFO - PROCESS | 1670 | 23:03:45 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator) 23:03:46 INFO - PROCESS | 1670 | RSS: Main: 286740480 23:03:46 INFO - PROCESS | 1670 | 23:03:48 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:03:48 INFO - PROCESS | 1670 | RSS: Main: 291872768 23:03:48 INFO - PROCESS | 1670 | 23:03:51 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom) 23:03:52 INFO - PROCESS | 1670 | RSS: Main: 329265152 23:03:52 INFO - PROCESS | 1670 | 23:03:54 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate) 23:03:55 INFO - PROCESS | 1670 | RSS: Main: 332144640 23:03:55 INFO - PROCESS | 1670 | 23:03:58 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial) 23:03:59 INFO - PROCESS | 1670 | RSS: Main: 328466432 23:03:59 INFO - PROCESS | 1670 | 23:04:00 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:04:01 INFO - PROCESS | 1670 | RSS: Main: 319729664 23:04:01 INFO - PROCESS | 1670 | 23:04:03 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:04:03 INFO - PROCESS | 1670 | RSS: Main: 326684672 23:04:03 INFO - PROCESS | 1670 | 23:04:05 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:04:06 INFO - PROCESS | 1670 | RSS: Main: 329437184 23:04:06 INFO - PROCESS | 1670 | 23:04:09 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:04:10 INFO - PROCESS | 1670 | RSS: Main: 331010048 23:04:10 INFO - PROCESS | 1670 | 23:04:12 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:04:13 INFO - PROCESS | 1670 | RSS: Main: 326414336 23:04:13 INFO - PROCESS | 1670 | 23:04:15 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49278/tests/kraken/driver.html?testName=ai-astar) 23:04:15 INFO - PROCESS | 1670 | RSS: Main: 390705152 23:04:15 INFO - PROCESS | 1670 | 23:04:15 INFO - PROCESS | 1670 | __start_tp_report 23:04:15 INFO - PROCESS | 1670 | _x_x_mozilla_page_load 23:04:15 INFO - PROCESS | 1670 | _x_x_mozilla_page_load_details 23:04:15 INFO - PROCESS | 1670 | |i|pagename|runs| 23:04:15 INFO - PROCESS | 1670 | |0;ai-astar;112;110;113;112;112;118;175;165;113;111 23:04:15 INFO - PROCESS | 1670 | |1;audio-beat-detection;160;123;145;168;120;141;139;122;172;113 23:04:15 INFO - PROCESS | 1670 | |2;audio-dft;186;186;184;184;183;185;184;180;185;183 23:04:15 INFO - PROCESS | 1670 | |3;audio-fft;73;74;87;91;57;58;58;77;90;81 23:04:15 INFO - PROCESS | 1670 | |4;audio-oscillator;89;102;136;78;78;77;126;81;77;128 23:04:15 INFO - PROCESS | 1670 | |5;imaging-gaussian-blur;87;86;88;86;87;83;87;84;86;84 23:04:15 INFO - PROCESS | 1670 | |6;imaging-darkroom;93;94;94;93;92;93;93;94;94;94 23:04:15 INFO - PROCESS | 1670 | |7;imaging-desaturate;86;85;84;85;85;84;84;84;100;138 23:04:15 INFO - PROCESS | 1670 | |8;json-parse-financial;60;62;58;59;58;58;59;72;60;61 23:04:15 INFO - PROCESS | 1670 | |9;json-stringify-tinderbox;67;72;79;77;72;72;68;68;68;67 23:04:15 INFO - PROCESS | 1670 | |10;stanford-crypto-aes;96;96;107;82;82;81;79;80;82;84 23:04:15 INFO - PROCESS | 1670 | |11;stanford-crypto-ccm;152;142;137;136;138;138;132;162;198;156 23:04:15 INFO - PROCESS | 1670 | |12;stanford-crypto-pbkdf2;173;170;171;169;173;169;169;168;168;167 23:04:15 INFO - PROCESS | 1670 | |13;stanford-crypto-sha256-iterative;69;70;77;97;98;95;96;75;67;67 23:04:15 INFO - PROCESS | 1670 | __end_tp_report 23:04:15 INFO - PROCESS | 1670 | __start_cc_report 23:04:15 INFO - PROCESS | 1670 | _x_x_mozilla_cycle_collect,1971 23:04:15 INFO - PROCESS | 1670 | __end_cc_report 23:04:15 INFO - PROCESS | 1670 | __startTimestamp1461218655670__endTimestamp 23:04:15 INFO - PROCESS | 1670 | 23:04:15 INFO - PROCESS | 1670 | ------- Summary: start ------- 23:04:15 INFO - PROCESS | 1670 | Number of tests: 14 23:04:15 INFO - PROCESS | 1670 | 23:04:15 INFO - PROCESS | 1670 | [#0] ai-astar Cycles:10 Average:124.10 Median:113.00 stddev:24.40 (21.6%) stddev-sans-first:25.48 23:04:15 INFO - PROCESS | 1670 | Values: 112.0 110.0 113.0 112.0 112.0 118.0 175.0 165.0 113.0 111.0 23:04:15 INFO - PROCESS | 1670 | 23:04:15 INFO - PROCESS | 1670 | [#1] audio-beat-detection Cycles:10 Average:140.30 Median:143.00 stddev:21.02 (14.7%) stddev-sans-first:21.05 23:04:15 INFO - PROCESS | 1670 | Values: 160.0 123.0 145.0 168.0 120.0 141.0 139.0 122.0 172.0 113.0 23:04:15 INFO - PROCESS | 1670 | 23:04:15 INFO - PROCESS | 1670 | [#2] audio-dft Cycles:10 Average:184.00 Median:184.50 stddev:1.76 (1.0%) stddev-sans-first:1.72 23:04:15 INFO - PROCESS | 1670 | Values: 186.0 186.0 184.0 184.0 183.0 185.0 184.0 180.0 185.0 183.0 23:04:15 INFO - PROCESS | 1670 | 23:04:15 INFO - PROCESS | 1670 | [#3] audio-fft Cycles:10 Average:74.60 Median:79.00 stddev:13.21 (16.7%) stddev-sans-first:14.00 23:04:15 INFO - PROCESS | 1670 | Values: 73.0 74.0 87.0 91.0 57.0 58.0 58.0 77.0 90.0 81.0 23:04:15 INFO - PROCESS | 1670 | 23:04:15 INFO - PROCESS | 1670 | [#4] audio-oscillator Cycles:10 Average:97.20 Median:77.50 stddev:24.01 (31.0%) stddev-sans-first:25.29 23:04:15 INFO - PROCESS | 1670 | Values: 89.0 102.0 136.0 78.0 78.0 77.0 126.0 81.0 77.0 128.0 23:04:15 INFO - PROCESS | 1670 | 23:04:15 INFO - PROCESS | 1670 | [#5] imaging-gaussian-blur Cycles:10 Average:85.80 Median:86.50 stddev:1.62 (1.9%) stddev-sans-first:1.66 23:04:15 INFO - PROCESS | 1670 | Values: 87.0 86.0 88.0 86.0 87.0 83.0 87.0 84.0 86.0 84.0 23:04:15 INFO - PROCESS | 1670 | 23:04:15 INFO - PROCESS | 1670 | [#6] imaging-darkroom Cycles:10 Average:93.40 Median:94.00 stddev:0.70 (0.7%) stddev-sans-first:0.73 23:04:15 INFO - PROCESS | 1670 | Values: 93.0 94.0 94.0 93.0 92.0 93.0 93.0 94.0 94.0 94.0 23:04:15 INFO - PROCESS | 1670 | 23:04:15 INFO - PROCESS | 1670 | [#7] imaging-desaturate Cycles:10 Average:91.50 Median:84.50 stddev:17.05 (20.2%) stddev-sans-first:17.97 23:04:15 INFO - PROCESS | 1670 | Values: 86.0 85.0 84.0 85.0 85.0 84.0 84.0 84.0 100.0 138.0 23:04:15 INFO - PROCESS | 1670 | 23:04:15 INFO - PROCESS | 1670 | [#8] json-parse-financial Cycles:10 Average:60.70 Median:60.00 stddev:4.19 (7.0%) stddev-sans-first:4.44 23:04:15 INFO - PROCESS | 1670 | Values: 60.0 62.0 58.0 59.0 58.0 58.0 59.0 72.0 60.0 61.0 23:04:15 INFO - PROCESS | 1670 | 23:04:15 INFO - PROCESS | 1670 | [#9] json-stringify-tinderbox Cycles:10 Average:71.00 Median:72.00 stddev:4.24 (5.9%) stddev-sans-first:4.25 23:04:15 INFO - PROCESS | 1670 | Values: 67.0 72.0 79.0 77.0 72.0 72.0 68.0 68.0 68.0 67.0 23:04:15 INFO - PROCESS | 1670 | 23:04:15 INFO - PROCESS | 1670 | [#10] stanford-crypto-aes Cycles:10 Average:86.90 Median:82.00 stddev:9.40 (11.5%) stddev-sans-first:9.37 23:04:15 INFO - PROCESS | 1670 | Values: 96.0 96.0 107.0 82.0 82.0 81.0 79.0 80.0 82.0 84.0 23:04:15 INFO - PROCESS | 1670 | 23:04:15 INFO - PROCESS | 1670 | [#11] stanford-crypto-ccm Cycles:10 Average:149.10 Median:147.00 stddev:19.78 (13.5%) stddev-sans-first:20.95 23:04:15 INFO - PROCESS | 1670 | Values: 152.0 142.0 137.0 136.0 138.0 138.0 132.0 162.0 198.0 156.0 23:04:15 INFO - PROCESS | 1670 | 23:04:15 INFO - PROCESS | 1670 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.70 Median:169.50 stddev:2.06 (1.2%) stddev-sans-first:1.80 23:04:15 INFO - PROCESS | 1670 | Values: 173.0 170.0 171.0 169.0 173.0 169.0 169.0 168.0 168.0 167.0 23:04:15 INFO - PROCESS | 1670 | 23:04:15 INFO - PROCESS | 1670 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:81.10 Median:86.00 stddev:13.64 (15.9%) stddev-sans-first:13.75 23:04:15 INFO - PROCESS | 1670 | Values: 69.0 70.0 77.0 97.0 98.0 95.0 96.0 75.0 67.0 67.0 23:04:15 INFO - PROCESS | 1670 | -------- Summary: end -------- 23:04:15 INFO - PROCESS | 1670 | 23:04:16 INFO - TEST-INFO | 1670: exit 0 23:04:16 INFO - TEST-OK | kraken | took 58986ms 23:04:16 INFO - SUITE-END | took 1173s 23:04:16 INFO - Completed test suite (00:19:33) 23:04:16 INFO - javascript benchmark 23:04:16 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3347.5815523059614, 3394.779339342941, 3423.640517745638, 3427.2288735034613, 3462.8268586570666, 5562.5271851892585, 5566.972165139179, 5594.468525490082, 5612.803551875685, 5626.943730562703, 5594.720263986805, 5628.943710562893, 5674.120511320742, 5727.684977326246, 5741.540676745861, 5095.464976177504, 5103.311053007834, 5104.030234255489, 5134.691918484903, 5253.684778913258, 5264.315638966929, 5279.683219006853, 5283.947160528384, 5292.92060619091, 5323.068463018975, 3784.0350710568905, 4221.725587836811, 4457.375967364572, 5069.898602027975, 5186.844394668166, 5148.202028685522, 5151.871203219949, 5161.483851614846, 5172.27588137661, 5172.741362931876, 5126.846194614168, 5162.5095615916425, 5194.961007798418, 5216.947830521722, 5220.2430647556275, 5454.945450545483, 5480.698561579112, 5568.972155139199, 5595.412481689405, 5624.971875140598, 6149.661768602725, 6181.845453863644, 6182.814515564541, 6196.318404975449, 6202.41077097675, 6143.539234557381, 6222.782202622931, 6247.9062814057825, 6257.280412752546, 6261.405166509175, 5620.9437905620825, 5693.829185124411, 5723.942760572382, 5802.535797136304, 5809.477147056785, 6231.781887633911, 6239.064140378889, 6244.937550624527, 6250.937490625036, 6252.218472690914, 6076.756929722762, 6084.330723620398, 6098.420650038239, 6105.603135796159, 6123.908141377883, 6077.9392206078255, 6082.574219804571, 6120.66336351496, 6159.4456498915315, 6176.814695559051, 6095.543686224553, 6137.41694539018, 6151.108089327023, 6152.969235153795, 6167.599106058092, 6159.938400615936, 6166.290876549165, 6169.136320915076, 6171.1668924694595, 6172.043333283348, 3837.6737977272246, 4189.015581338383, 4783.6412269079265, 4820.132376172322, 4855.344528488608, 4205.579442055831, 4408.096340250241, 4409.603135717801, 4434.933475997863, 4444.2444784386735, 5555.194496798023, 5564.221009058735, 5581.246531718247, 5602.943970560324, 5617.578681598897, 6092.969535152296, 6157.353477884847, 6174.783882564088, 6175.104609831639, 6186.226721659793, 4302.354646802942, 5490.423505531941, 5550.27846379968, 5570.582206334541, 5584.692841893735, 4871.683340582851, 5349.973250133802, 5356.625036247426, 5364.061289274361, 5390.51485366319, 6281.2462504498835, 6308.684565771785, 6310.747570097236, 6311.211098612674, 6322.4942004639515, 6120.846978825565, 6167.414095660905, 6211.782587609593, 6218.06728990655, 6268.874622507616, 6263.592866463665, 6287.119803227643, 6302.779402721067, 6311.621302721851, 6316.368363163647, 6120.081987701702, 6141.0, 6150.7847225346895, 6157.199564056644, 6183.196184495987, 6061.727222275096, 6138.815835524942, 6162.938370616236, 6200.472959798287, 6205.193324867739, 5933.2880054393745, 5991.310999235144, 6020.548458865515, 6039.607425517327, 6063.605865618897, 6013.188219590299, 6041.0, 6043.335233124265, 6051.152838602511, 6072.757089716539, 5745.971270143623, 5833.270841144857, 5885.705714714161, 5899.262592175978, 5904.350521442724, 5927.7036148191555, 6030.761264109715, 6037.607555508878, 6048.425399587032, 6054.273487181566, 5868.2077919482035, 5892.587518873809, 5900.616459930091, 5917.082852157922, 5952.553558483218], "value": 5608.207058368779, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [497.85313332566886, 501.3582614253752, 520.0742678033102, 534.0787142179739, 555.1256770586326, 36806.0, 37438.0, 38194.854154375345, 38236.04409889754, 38425.80787096068, 40612.98467538313, 41151.58848411515, 41416.0, 41475.37786933191, 41504.792476037575, 8495.447795893278, 9626.085521875428, 9711.805763884719, 9749.805003899917, 11239.775204495905, 11424.0, 11588.072954163647, 11590.014848737868, 11596.652100436959, 11600.709982250426, 11548.422578871065, 11644.475998580041, 11648.126390520703, 11657.766844663101, 11670.066394688447, 11520.711982200428, 11598.594049208237, 11614.18700690952, 11623.535058597647, 11626.709332266677, 11369.829452558219, 11580.131490138274, 11607.4196290186, 11612.0, 11616.303021818718, 32925.0, 39689.0, 40076.398854017214, 40185.59814401877, 40215.396769048486, 2926.590277361171, 3487.163080860575, 3623.7825730455993, 3642.8907132786057, 3705.481232627434, 22972.65541016886, 27008.1897543074, 28917.0, 29781.851090744407, 30092.0, 2718.7417195366406, 2737.4525094980886, 2751.8073734838567, 2786.275568352243, 2804.677462091865, 450919.0, 453244.0, 453884.03637799906, 455173.72413138056, 456421.0, 60844.0, 61242.38757612367, 61284.38715612876, 61296.69351653302, 61341.0, 64530.032049519294, 64778.02832957509, 64804.0, 64804.0, 64933.0, 15933.920330398274, 16373.345066197457, 16397.180140992903, 16400.507984760476, 16409.507714768577, 12673.429695663717, 12731.55439559611, 12740.80888786669, 12753.043521735906, 12754.744905101847, 16433.424830130887, 16734.74897876533, 16849.915750421416, 16900.577485562713, 16941.0, 6730.399236408498, 6740.764073257413, 6774.762883299061, 6823.931760682429, 6857.7256909723055, 957.4351132831663, 961.9615215391445, 1076.9730756730987, 1090.1224514265934, 1106.7509810292781, 26928.865355673097, 29264.707352926624, 29695.703042969726, 29788.255293617833, 29941.963638544923, 988.4316518002178, 1077.380506208933, 1101.8347247912716, 1123.7696272264166, 1211.6304527119314, 13818.101823381448, 14203.78694319586, 14489.275536223147, 14684.632884177981, 15127.773083403757, 584077.0, 589807.0, 590087.0, 590579.0, 590634.0, 57518.42481575214, 59938.0, 59966.700166498886, 59968.400315997154, 60116.09825852616, 1369.760291948925, 1372.34813463604, 1413.3993052952453, 1493.1489051240906, 1495.9177245251615, 19435.227533156038, 26500.0, 29928.25179370533, 30354.84822575961, 30470.085897423112, 50050.0, 50353.49646503488, 50606.49393506018, 50887.49112508827, 50919.0, 394332.0283398565, 396889.0, 397481.0, 397676.0, 398381.0, 25796.61305080463, 26134.477310453305, 26735.46529069369, 26752.19743407701, 36154.4576831353, 42076.78961605173, 42584.5741542593, 42610.78694606631, 42929.35605965975, 43149.56850431581, 33624.99125026253, 35016.0, 35572.0, 35719.0, 36036.09909752277, 34340.82829585836, 34656.82671586727, 35517.82241088778, 35545.466817997236, 35986.820065899505, 36410.63589364073, 36682.26635467329, 36799.264014720095, 36922.0, 37351.43972840464, 26636.7336326642, 30347.089587311533, 30713.846430767702, 30735.385292294475, 30808.383832323678, 20378.79621203769, 21888.233911813648, 23186.884065579565, 23536.646950295417, 23641.881790590938], "value": 19560.058769848565, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [397.6143141153082, 397.85876014015025, 400.1716446954804, 405.8518640696143, 411.83938264076994, 19382.93393863337, 19636.410907672776, 20355.28756493523, 20358.89820550897, 20510.487237819023, 19827.603447931033, 19961.401157965247, 21536.246231381865, 21753.456163595918, 21765.67351489725, 5377.300950876391, 5987.251593550806, 5992.0, 6001.60989535681, 6026.035834266519, 5652.943470565303, 5787.50806181475, 5944.405559444064, 5954.880902381949, 5966.37353077927, 5869.354371019184, 5874.618149820248, 5889.469947704706, 5904.822855314347, 5913.733881975321, 5616.185653080322, 5864.179014937912, 5885.176075349433, 5887.82150604277, 5889.911651325234, 5131.589472842164, 5134.127198376285, 5170.844874653766, 5175.404828444739, 5177.301064356301, 24140.396490087714, 25457.745422545908, 25997.350066248306, 26022.73977260222, 26148.477030459286, 2058.423641380401, 2115.6509175985943, 2154.3859999899955, 2194.6488561830192, 2324.6629238760456, 14373.78439323411, 15024.399024039025, 15066.0, 15147.469838555598, 15402.46091386796, 1679.2191630891573, 1711.2384988680042, 1732.2984191402502, 1739.339051160551, 1752.7049147602554, 60586.0, 61215.0, 102257.0, 114550.0, 121205.39397302957, 10928.398938058324, 11344.886551134383, 11347.659570212907, 11352.545898164146, 11461.312321260752, 14457.92771036138, 14564.708705825824, 14567.635809104857, 14578.343974521173, 14584.48954286595, 6707.692000060039, 6903.275156108536, 7605.315521603082, 8308.418410711192, 8749.343799215085, 6253.7185826637915, 8386.245237928615, 8434.409591328671, 8515.701950431705, 8610.268127209258, 6782.2200446948245, 6849.24658287598, 6927.445804335641, 6928.954721926812, 6972.163340399185, 1853.2657735822768, 1866.4214093631017, 1874.4282993687057, 1878.3050271399397, 1928.9807101929082, 944.4003058058182, 974.8001659659752, 1044.6865940217904, 1053.3048188195753, 1079.6760971708457, 18462.723059154123, 20571.0, 20876.895615521826, 21624.459388515414, 21768.89115554444, 949.5584553182803, 982.6020461712875, 1002.7593377589471, 1007.1238022920106, 1063.6064656077301, 11233.38216398106, 11563.0, 11593.6521904343, 11624.88375116238, 11634.418279086181, 606238.0, 608569.0, 621858.0, 640019.0, 641710.7914460398, 118895.0, 119328.40335798265, 119948.40025799815, 120906.0, 122591.8704064811, 451.60484575996, 474.18203598792496, 474.8029567729354, 476.8879313361427, 998.3061772068488, 10315.0, 10659.25385223027, 11141.38722370245, 11261.774764504828, 11376.317420954769, 49542.256866146316, 51580.74209628928, 51917.740411299215, 52785.7360713194, 53544.73227633837, 75500.86748698664, 76955.0, 77051.2294877044, 77598.44803104019, 79276.0, 8128.9593552031865, 8176.550289734122, 8177.918220817715, 8398.790030249293, 8506.106858779738, 9158.084191581054, 10107.545160467944, 11944.402779860799, 12861.807072894102, 12941.417636206204, 9587.808243835223, 10956.411320358662, 11019.779604407706, 11247.156463265124, 11276.436178190894, 10818.891811081789, 10964.122870170208, 11108.111351091731, 11145.442727863412, 11181.888181118085, 11160.720981975192, 11302.773944520899, 11541.422928853692, 11543.653690389301, 11707.356095414834, 7103.467239956921, 7210.206877243604, 8191.508509489449, 8373.246407823324, 8398.20217079401, 9097.226735727538, 11069.778604427705, 11318.151138664798, 11405.486753095965, 11446.37044962535], "value": 9356.168929529822, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1434.340203506387, 1611.838816118388, 1628.4463282483957, 1922.2311075569778, 2048.8975551222425, 7871.724489642864, 8520.318374530038, 8769.473831570094, 8772.254358379529, 8781.473111613308, 8400.453970491913, 8611.87082193766, 8622.180892815188, 8624.223819856212, 8624.655013799456, 2455.496623192246, 2489.390099425634, 2492.239866840613, 2492.264781889338, 2493.4140476987895, 2400.159944019588, 2484.3416494628937, 2485.3538080099124, 2488.552060629086, 2492.464120214154, 2231.877246751428, 2298.908043678251, 2418.177819541356, 2444.8533088014688, 2450.4976479821685, 2002.9499262518407, 2442.487077713682, 2445.0342114864616, 2446.8287219894523, 2448.902043918241, 2345.9765402346097, 2417.601095819188, 2425.4300239443714, 2429.149797570854, 2430.5260474207535, 7452.441066920002, 7558.811029724246, 7931.0, 8496.78758031048, 8652.783680407978, 5187.481251874782, 5391.487808658171, 5392.0294347017525, 5393.1370980643305, 5403.027455058088, 3386.6613338666184, 3414.2830005698834, 3481.56480439945, 3491.720662347006, 3493.441049432104, 981.6760469045198, 999.0558921818885, 1007.0936157458273, 1008.4671299624603, 1010.2322235101302, 11241.325520468798, 11362.71593210168, 11379.829302560469, 11575.652730418102, 11592.999999999916, 4745.311929770164, 4809.278608208797, 4826.710397376169, 4922.237053256751, 4941.431735350408, 8762.77321175036, 10034.845992710785, 10054.145397641283, 10064.0, 10070.144037756872, 5315.920261196085, 5429.755660995264, 5432.809851655173, 5439.428859969726, 5459.1265397537, 4861.562459378673, 4862.197737373364, 4932.260160975882, 4933.50664933511, 4964.60283177345, 3759.7180211484247, 3788.3749181384765, 3876.0, 3893.357595996629, 3899.902502437462, 1296.8314119164447, 1305.477808876457, 1307.2744626732065, 1319.683276013769, 1321.4053675845907, 755.7014979083232, 780.6284805091556, 887.573964497045, 916.5279880861361, 938.6667732954703, 5564.693941833238, 5618.747156377891, 5668.121441176624, 5734.283214598176, 5869.178315035899, 439.2620397731777, 452.85055931542513, 461.60302140159433, 462.8703962890396, 468.76480473951386, 3750.681192098702, 3851.730378873508, 3872.934942890671, 3878.166194268246, 3891.1439483313625, 10138.290319677551, 10507.264491485676, 10728.731781705364, 10730.839037414446, 10822.0, 10654.62708805188, 10654.840177397346, 10661.040506354466, 10672.78654426907, 10679.199060070527, 7974.920250797534, 7994.800129996796, 8006.439549231498, 8059.717909873244, 8127.146649601825, 5595.272614560081, 5605.663660180402, 5685.374608792947, 5698.629589076697, 5818.526179380366, 9413.52932353366, 9743.805123897624, 9770.0, 9898.356606820533, 9934.900650993397, 9364.20404265645, 9897.208223341971, 9942.701718948443, 9987.900120998696, 10032.0, 7299.197088320174, 7341.375983041287, 7365.2634736525915, 7378.483506154517, 7438.293362130589, 8015.6392962318005, 8090.919090809017, 8119.959400202961, 8126.918730812853, 8170.591470426574, 6768.424683901923, 6988.301169882971, 7298.781036568911, 7346.485745997943, 7483.251674832473, 7094.53885497441, 7104.218535961144, 7262.9273707262255, 7314.195438501867, 7359.300866417681, 6660.400563949074, 6829.453643708392, 6932.064171336805, 6937.271586483548, 6943.930560694328, 4202.726822756511, 4685.882852928704, 4730.172219861442, 4815.542523460265, 4937.086638971802, 4762.166620841408, 4765.8093676252565, 4815.879603009813, 4845.781939812788, 4900.558949694545], "value": 4455.80536966514, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [254.30194117148432, 308.4555759085214, 322.5790343601599, 329.5995365630758, 330.3673465313924, 5591.608587398884, 5677.120046392815, 5790.536757059435, 5804.2744656917885, 5864.1203819427055, 4561.863144105674, 5108.8211912583065, 5767.192593036978, 5825.213596164516, 5831.008728516152, 3596.1549035976523, 4390.968122491212, 4393.143337049277, 4422.248217802965, 4424.955750442503, 3558.8255875561003, 3807.562130355003, 4167.895507690464, 4293.334533147352, 4475.664325175625, 3944.895429279792, 4319.805608747598, 4371.0, 4393.07745373472, 4401.141777353418, 3669.853205871762, 4153.317906247959, 4361.323994780814, 4378.934315985262, 4401.757903315301, 3309.3712194682935, 3759.6052414496357, 3816.5229346331707, 3834.654881060718, 3851.73037887348, 4531.728096314198, 4973.632251130968, 5022.347094877679, 5384.703841288728, 5615.831525054255, 4161.0, 5002.6748261363255, 5012.8496145115705, 5029.647924645276, 5040.798368065273, 5510.401983424802, 5809.51857276396, 6107.541934354941, 6113.602615829956, 6225.470834979033, 3657.7439579229463, 3952.8179815248714, 4077.7145599808027, 4134.627883490471, 4145.150244199932, 5308.495692909168, 5347.679139251657, 5372.677639341613, 6517.859374609472, 6541.803745887631, 3682.3924052531033, 4026.5973402660084, 4047.9070650924086, 4158.9376159357635, 4179.665626749853, 4193.580641935782, 4223.260929337353, 4231.597998190167, 4237.216115018731, 4248.107897341592, 3903.4535165076913, 4538.727676339364, 4675.064987002613, 4959.454460009396, 4974.452810190876, 5814.592978491465, 6206.937930620636, 6238.1890354254565, 6251.343608921089, 6271.560990730605, 1244.9128561000823, 1308.3065975033248, 1420.5383250443647, 1454.7090581883665, 1455.1196526101767, 938.3859761210707, 941.4539567051094, 957.6887511558771, 960.8798900137483, 965.4593427680521, 5567.248421463131, 5670.149477578314, 5675.517581005578, 5680.829575112753, 5701.515371193412, 5952.285725712855, 6273.0, 6290.0, 6290.716917738713, 6295.307516173218, 4949.935763810798, 5504.862378440571, 5658.434156584391, 5791.536677065825, 5839.970800145972, 2961.4965455872552, 3387.0516255448515, 3408.9716618611887, 3412.505186747908, 3414.9487757683655, 6479.740810367627, 6628.0, 6777.830554236085, 6788.389044985975, 6848.280930502226, 5992.280926288873, 6271.937280627227, 6585.73657053722, 6657.700403481855, 6668.233153187349, 5558.832645144484, 6542.051402546699, 6719.328067193339, 6738.350538128676, 6749.291324410866, 5233.869153271199, 5290.894182116259, 6417.2299324081405, 6475.805725828232, 6501.674916254263, 4813.542713442217, 5351.59863010268, 6321.652309123042, 6374.203224596926, 6381.425671689571, 5535.446455354432, 6470.126532917996, 6707.5975441473665, 6723.125993620798, 6769.289224631343, 4112.136451345251, 4267.402563641155, 4278.197780453807, 4287.356896465555, 4386.495553011445, 5807.245058142415, 5846.473817356288, 6406.647634380154, 6412.729507951072, 6431.099646049463, 6191.597546159485, 6192.717859534274, 6232.719527621359, 6235.688215589294, 6253.187085678833, 5654.519365853948, 5724.341700704473, 6199.783007594712, 6227.941249987603, 6229.937700623117, 6021.969890150521, 6038.396160383926, 6045.425684559959, 6074.817755467343, 6106.5114790817515, 5176.741162941763, 6174.969125154345, 6223.688815559294, 6245.313015568375, 6245.937540624717, 5005.549500544968, 5672.0, 5986.431289027535, 6090.969545152246, 6118.357572454828], "value": 4440.543569340862, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [681.7443458702986, 682.7303215229981, 734.4528326396834, 745.2175216023178, 764.3503022430934, 14668.853311466883, 14734.778978315333, 14875.851241487582, 14894.329755161016, 14895.255237238149, 13728.176309421442, 13837.0, 13849.653758656013, 13862.722745545083, 13864.237466939314, 726.6439444672098, 747.8766003609398, 915.8305713443001, 928.921041711452, 933.3140141995627, 899.5007770687266, 939.3049143633696, 940.0458534587399, 942.0250041207352, 945.6973768394116, 983.8376667849797, 992.8560358747977, 995.3032876986102, 997.9650712225074, 1001.1740314240781, 765.1048273519995, 953.3660116022836, 986.9457179855106, 987.7728122531822, 990.8166989107002, 736.7310931510013, 737.0197637142587, 737.049206523584, 737.6643627149634, 738.0774032459426, 14706.779398309034, 14760.188189649565, 14762.261886905719, 14791.778123328158, 14795.852041479555, 12390.938045309806, 12453.003759699202, 12514.499420023189, 12769.87230127696, 12805.615831525069, 9956.701298961043, 9982.850257246148, 10108.342957707726, 10111.544980475823, 10543.683689489328, 3492.2142517933517, 3504.299140171976, 3549.929001419983, 3578.35589593873, 3598.8740394086344, 81451.0, 82382.35235295321, 83102.58448707718, 83280.58359708224, 84569.15430845674, 9098.681546145854, 9432.103950124727, 9467.715968520955, 9715.805683886425, 9818.950905245429, 21713.45716357072, 21917.232896848534, 21918.45203869916, 21927.451813704465, 22072.668909966364, 7329.706811727578, 7342.045534080642, 7376.336129748348, 7513.511621744569, 7624.42816788743, 3934.075492259317, 3984.362501999666, 3985.322495175827, 4045.554988951213, 4267.722598031118, 3562.5190599269285, 3620.619834917296, 3636.636336366395, 3642.5446819147605, 3642.763220390666, 688.1226436293765, 706.9575825450489, 720.3732752505354, 728.3117454006019, 734.698773502872, 426.5989969428727, 515.6751246714546, 565.4147956864625, 575.3038823024104, 585.2128886647453, 7111.679974401164, 7115.501914865909, 7417.480776345604, 7627.847443051219, 7848.372130229568, 536.4742552298769, 549.5878091431426, 566.283651181256, 569.4959960435045, 579.7333226715674, 3650.543682039745, 3750.83121259544, 3783.4703141560203, 3808.9809550952623, 3823.1780167264174, 76960.61519692365, 78894.2110578887, 78937.0, 78957.0, 79029.60485197537, 50859.0, 65424.34575654183, 65959.68080638457, 66068.66965665141, 66445.66777166084, 63126.0, 64549.354506455275, 64742.67628661827, 65219.0, 67744.0, 7814.57019863913, 7895.210478952059, 8002.0, 8030.307550040252, 8178.713745019134, 41368.0, 42206.0, 42889.35665964951, 42998.35502467403, 43166.56833431751, 62105.0, 62711.74576508353, 62815.37184628278, 63182.36817631765, 63287.73424531391, 10663.786724265316, 10948.34309941406, 10979.0, 11073.667789966314, 11220.2145849793, 11399.373034483184, 11553.075753939496, 11898.643040708792, 11925.224860384047, 12364.876351236373, 10059.597616095567, 10361.222908281758, 10674.73313167177, 11161.497732602214, 11226.158038147008, 10806.297590656583, 11097.33415995043, 11210.831837522606, 11266.0, 11369.829452558053, 10854.457277135954, 11041.889581104408, 11085.0, 11098.445077746243, 11685.182037257311, 9293.25653947669, 9479.47862867549, 9629.0, 9840.45877476717, 9927.751806204902, 7240.52936559122, 9226.261899048195, 9263.16209038945, 9278.95360523193, 9394.342396032485], "value": 6723.08639466035, "unit": "score"}], "name": "dromaeo_css", "value": 7175.921510908311, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 110.0, 113.0, 112.0, 112.0, 118.0, 175.0, 165.0, 113.0, 111.0], "value": 124.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 123.0, 145.0, 168.0, 120.0, 141.0, 139.0, 122.0, 172.0, 113.0], "value": 140.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 186.0, 184.0, 184.0, 183.0, 185.0, 184.0, 180.0, 185.0, 183.0], "value": 184.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 87.0, 91.0, 57.0, 58.0, 58.0, 77.0, 90.0, 81.0], "value": 74.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 102.0, 136.0, 78.0, 78.0, 77.0, 126.0, 81.0, 77.0, 128.0], "value": 97.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 88.0, 86.0, 87.0, 83.0, 87.0, 84.0, 86.0, 84.0], "value": 85.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 94.0, 93.0, 92.0, 93.0, 93.0, 94.0, 94.0, 94.0], "value": 93.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 84.0, 85.0, 85.0, 84.0, 84.0, 84.0, 100.0, 138.0], "value": 91.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 62.0, 58.0, 59.0, 58.0, 58.0, 59.0, 72.0, 60.0, 61.0], "value": 60.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 72.0, 79.0, 77.0, 72.0, 72.0, 68.0, 68.0, 68.0, 67.0], "value": 71.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 107.0, 82.0, 82.0, 81.0, 79.0, 80.0, 82.0, 84.0], "value": 86.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 142.0, 137.0, 136.0, 138.0, 138.0, 132.0, 162.0, 198.0, 156.0], "value": 149.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 170.0, 171.0, 169.0, 173.0, 169.0, 169.0, 168.0, 168.0, 167.0], "value": 169.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 77.0, 97.0, 98.0, 95.0, 96.0, 75.0, 67.0, 67.0], "value": 81.1, "unit": "score"}], "name": "kraken", "value": 1509.3999999999999, "alertThreshold": 2.0}]} 23:04:16 INFO - Return code: 0 23:04:16 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 23:04:17 ERROR - # TBPL SUCCESS # 23:04:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:04:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:04:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:04:17 INFO - 2016-04-20 23:04:17.016 screenresolution[1674:9968] starting screenresolution argv=screenresolution get 23:04:17 INFO - 2016-04-20 23:04:17.019 screenresolution[1674:9968] Display 0: 1600x1200x32@60 23:04:17 INFO - 2016-04-20 23:04:17.024 screenresolution[1675:9971] starting screenresolution argv=screenresolution list 23:04:17 INFO - Available Modes on Display 0 23:04:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:04:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:04:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:04:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:04:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:04:17 INFO - Intel Iris: 23:04:17 INFO - Chipset Model: Intel Iris 23:04:17 INFO - Type: GPU 23:04:17 INFO - Bus: Built-In 23:04:17 INFO - VRAM (Dynamic, Max): 1536 MB 23:04:17 INFO - Vendor: Intel (0x8086) 23:04:17 INFO - Device ID: 0x0a2e 23:04:17 INFO - Revision ID: 0x0009 23:04:17 INFO - Displays: 23:04:17 INFO - Display: 23:04:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:04:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:04:17 INFO - Display Serial Number: 1600x1200 60 23:04:17 INFO - Main Display: Yes 23:04:17 INFO - Mirror: Off 23:04:17 INFO - Online: Yes 23:04:17 INFO - Rotation: Supported 23:04:17 INFO - Return code: 0 23:04:17 INFO - Running post-action listener: _resource_record_post_action 23:04:17 INFO - Running post-run listener: _resource_record_post_run 23:04:18 INFO - Total resource usage - Wall time: 1209s; CPU: 53.0%; Read bytes: 37688832; Write bytes: 693352960; Read time: 395; Write time: 2070 23:04:18 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 395659776; Write bytes: 396957184; Read time: 29998; Write time: 744 23:04:18 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 35862016; Write bytes: 295547904; Read time: 372; Write time: 1323 23:04:18 INFO - Running post-run listener: _upload_blobber_files 23:04:18 INFO - Blob upload gear active. 23:04:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:04:18 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 23:04:18 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'] 23:04:18 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 23:04:19 INFO - (blobuploader) - INFO - Open directory for files ... 23:04:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:04:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:04:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:04:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:04:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:04:20 INFO - (blobuploader) - INFO - Done attempting. 23:04:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:04:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:04:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:04:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:04:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:04:22 INFO - (blobuploader) - INFO - Done attempting. 23:04:22 INFO - (blobuploader) - INFO - Iteration through files over. 23:04:22 INFO - Return code: 0 23:04:22 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:04:22 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:04:22 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/929f19da57d38c35b43f3f7565afc2ff21f7c6248cbf2fc489fb46ea530ed5e8455e9e65359e926f1496db3f2e46913519efe10de54202cfb547a8f51414d833", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f7f4c9c5ca7b1ae0e84c10477751d54539bc059c7a6ad9e3864fc233d9a39f1e702474907243d1b2a2d215ae06164d924a7ce59dac3addafa95eb593e371210b"} 23:04:22 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:04:22 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:04:22 INFO - Contents: 23:04:22 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/929f19da57d38c35b43f3f7565afc2ff21f7c6248cbf2fc489fb46ea530ed5e8455e9e65359e926f1496db3f2e46913519efe10de54202cfb547a8f51414d833", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f7f4c9c5ca7b1ae0e84c10477751d54539bc059c7a6ad9e3864fc233d9a39f1e702474907243d1b2a2d215ae06164d924a7ce59dac3addafa95eb593e371210b"} 23:04:22 INFO - Running post-run listener: copy_logs_to_upload_dir 23:04:22 INFO - Copying logs to upload dir... 23:04:22 INFO - mkdir: /builds/slave/test/build/upload/logs 23:04:22 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.348353 ========= master_lag: 127.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 23 mins, 15 secs) (at 2016-04-20 23:06:29.819072) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 11 secs) (at 2016-04-20 23:06:29.822061) ========= 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.fEGx2oPaql/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.lmVuL14aRz/Listeners TMPDIR=/var/folders/b2/qr0y6yxn2s32nywswzqqd0bc00000w/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/929f19da57d38c35b43f3f7565afc2ff21f7c6248cbf2fc489fb46ea530ed5e8455e9e65359e926f1496db3f2e46913519efe10de54202cfb547a8f51414d833", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f7f4c9c5ca7b1ae0e84c10477751d54539bc059c7a6ad9e3864fc233d9a39f1e702474907243d1b2a2d215ae06164d924a7ce59dac3addafa95eb593e371210b"} build_url:https://queue.taskcluster.net/v1/task/f--P_RvtTeqg9fmWAJp0DQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011171 build_url: 'https://queue.taskcluster.net/v1/task/f--P_RvtTeqg9fmWAJp0DQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/929f19da57d38c35b43f3f7565afc2ff21f7c6248cbf2fc489fb46ea530ed5e8455e9e65359e926f1496db3f2e46913519efe10de54202cfb547a8f51414d833", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f7f4c9c5ca7b1ae0e84c10477751d54539bc059c7a6ad9e3864fc233d9a39f1e702474907243d1b2a2d215ae06164d924a7ce59dac3addafa95eb593e371210b"}' ========= master_lag: 11.04 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 11 secs) (at 2016-04-20 23:06:40.873730) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 5 secs) (at 2016-04-20 23:06:40.874054) ========= 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.fEGx2oPaql/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.lmVuL14aRz/Listeners TMPDIR=/var/folders/b2/qr0y6yxn2s32nywswzqqd0bc00000w/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.005231 ========= master_lag: 5.79 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 5 secs) (at 2016-04-20 23:06:46.671857) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-20 23:06:46.672356) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-20 23:06:47.036278) ========= ========= Total master_lag: 144.03 =========