builder: larch_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0138 starttime: 1463766911.34 results: success (0) buildid: 20160520085604 builduid: 0f49ef73fb8e4dbb88fad2afa312b45a revision: eaeb86dd4a1980cc1b758440186438a43e801622 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-20 10:55:11.337471) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-20 10:55:11.337929) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-20 10:55:11.338219) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-20 10:55:11.359903) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 10:55:11.360308) ========= 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.lL1OKoRNi4/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.HOYZx6maSE/Listeners TMPDIR=/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/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-05-20 10:55:11-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 159M=0s 2016-05-20 10:55:11 (159 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.111020 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 10:55:11.490474) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 10:55:11.490789) ========= 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.lL1OKoRNi4/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.HOYZx6maSE/Listeners TMPDIR=/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/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.026740 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 10:55:11.539976) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 10:55:11.540343) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev eaeb86dd4a1980cc1b758440186438a43e801622 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/larch --rev eaeb86dd4a1980cc1b758440186438a43e801622 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.lL1OKoRNi4/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.HOYZx6maSE/Listeners TMPDIR=/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/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-05-20 10:55:11,615 truncating revision to first 12 chars 2016-05-20 10:55:11,615 Setting DEBUG logging. 2016-05-20 10:55:11,615 attempt 1/10 2016-05-20 10:55:11,615 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/eaeb86dd4a19?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-20 10:55:12,193 unpacking tar archive at: larch-eaeb86dd4a19/testing/mozharness/ program finished with exit code 0 elapsedTime=0.823045 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 10:55:12.381566) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-20 10:55:12.381874) ========= script_repo_revision: eaeb86dd4a1980cc1b758440186438a43e801622 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-20 10:55:12.382263) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-20 10:55:12.382525) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-20 10:55:12.397501) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-05-20 10:55:12.397891) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 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', 'Larch', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Larch'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.lL1OKoRNi4/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.HOYZx6maSE/Listeners TMPDIR=/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/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 10:55:12 INFO - MultiFileLogger online at 20160520 10:55:12 in /builds/slave/test 10:55:12 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 10:55:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:55:12 INFO - {'append_to_log': False, 10:55:12 INFO - 'base_work_dir': '/builds/slave/test', 10:55:12 INFO - 'blob_upload_branch': 'Larch', 10:55:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:55:12 INFO - 'branch': 'Larch', 10:55:12 INFO - 'buildbot_json_path': 'buildprops.json', 10:55:12 INFO - 'config_files': ('talos/mac_config.py',), 10:55:12 INFO - 'default_actions': ('clobber', 10:55:12 INFO - 'read-buildbot-config', 10:55:12 INFO - 'download-and-extract', 10:55:12 INFO - 'populate-webroot', 10:55:12 INFO - 'create-virtualenv', 10:55:12 INFO - 'install', 10:55:12 INFO - 'run-tests'), 10:55:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:55:12 INFO - 'download_minidump_stackwalk': True, 10:55:12 INFO - 'download_symbols': 'ondemand', 10:55:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:55:12 INFO - 'tooltool.py': '/tools/tooltool.py', 10:55:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:55:12 INFO - '/tools/misc-python/virtualenv.py')}, 10:55:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:55:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:55:12 INFO - 'installer_path': 'installer.exe', 10:55:12 INFO - 'log_level': 'info', 10:55:12 INFO - 'log_name': 'talos', 10:55:12 INFO - 'log_to_console': True, 10:55:12 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:55:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:55:12 INFO - 'opt_config_files': (), 10:55:12 INFO - 'pip_index': False, 10:55:12 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:55:12 INFO - 'cmd': ('bash', 10:55:12 INFO - '-c', 10:55:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:55:12 INFO - 'enabled': True, 10:55:12 INFO - 'halt_on_failure': False, 10:55:12 INFO - 'name': 'check_screen_resolution'},), 10:55:12 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:55:12 INFO - 'cmd': ('bash', 10:55:12 INFO - '-c', 10:55:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:55:12 INFO - 'enabled': True, 10:55:12 INFO - 'halt_on_failure': False, 10:55:12 INFO - 'name': 'check_screen_resolution'},), 10:55:12 INFO - 'run_cmd_checks_enabled': True, 10:55:12 INFO - 'sps_profile': False, 10:55:12 INFO - 'sps_profile_interval': 0, 10:55:12 INFO - 'suite': 'dromaeojs', 10:55:12 INFO - 'system_bits': '32', 10:55:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:55:12 INFO - 'title': 't-yosemite-r7-0138', 10:55:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:55:12 INFO - 'use_talos_json': True, 10:55:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:55:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:55:12 INFO - 'work_dir': 'build'} 10:55:12 INFO - ##### 10:55:12 INFO - ##### Running clobber step. 10:55:12 INFO - ##### 10:55:12 INFO - Running pre-action listener: _resource_record_pre_action 10:55:12 INFO - Running main action method: clobber 10:55:12 INFO - rmtree: /builds/slave/test/build 10:55:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:55:14 INFO - Running post-action listener: _resource_record_post_action 10:55:14 INFO - ##### 10:55:14 INFO - ##### Finished clobber step (success) 10:55:14 INFO - ##### 10:55:14 INFO - ##### 10:55:14 INFO - ##### Running read-buildbot-config step. 10:55:14 INFO - ##### 10:55:14 INFO - Running pre-action listener: _resource_record_pre_action 10:55:14 INFO - Running main action method: read_buildbot_config 10:55:14 INFO - Using buildbot properties: 10:55:14 INFO - { 10:55:14 INFO - "project": "", 10:55:14 INFO - "product": "firefox", 10:55:14 INFO - "script_repo_revision": "production", 10:55:14 INFO - "builddir": "larch_yosemite_r7_test-dromaeojs", 10:55:14 INFO - "repository": "", 10:55:14 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 larch talos dromaeojs", 10:55:14 INFO - "buildid": "20160520085604", 10:55:14 INFO - "pgo_build": "False", 10:55:14 INFO - "basedir": "/builds/slave/test", 10:55:14 INFO - "buildnumber": 0, 10:55:14 INFO - "slavename": "t-yosemite-r7-0138", 10:55:14 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 10:55:14 INFO - "platform": "yosemite_r7", 10:55:14 INFO - "scheduler": "tests-larch-macosx64-talos", 10:55:14 INFO - "branch": "larch", 10:55:14 INFO - "repo_path": "projects/larch", 10:55:14 INFO - "revision": "eaeb86dd4a1980cc1b758440186438a43e801622", 10:55:14 INFO - "stage_platform": "macosx64", 10:55:14 INFO - "builduid": "0f49ef73fb8e4dbb88fad2afa312b45a", 10:55:14 INFO - "slavebuilddir": "test" 10:55:14 INFO - } 10:55:14 INFO - Found installer url https://queue.taskcluster.net/v1/task/OhgntsRSQO-kvm-B3-LpYQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 10:55:14 INFO - Running post-action listener: _resource_record_post_action 10:55:14 INFO - ##### 10:55:14 INFO - ##### Finished read-buildbot-config step (success) 10:55:14 INFO - ##### 10:55:14 INFO - ##### 10:55:14 INFO - ##### Running download-and-extract step. 10:55:14 INFO - ##### 10:55:14 INFO - Running pre-action listener: _resource_record_pre_action 10:55:14 INFO - Running main action method: download_and_extract 10:55:14 INFO - mkdir: /builds/slave/test/build/tests 10:55:14 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')]} 10:55:14 INFO - https://queue.taskcluster.net/v1/task/OhgntsRSQO-kvm-B3-LpYQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 10:55:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhgntsRSQO-kvm-B3-LpYQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 10:55:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhgntsRSQO-kvm-B3-LpYQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 10:55:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhgntsRSQO-kvm-B3-LpYQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 10:55:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhgntsRSQO-kvm-B3-LpYQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 10:55:16 INFO - Downloaded 1199 bytes. 10:55:16 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 10:55:16 INFO - Using the following test package requirements: 10:55:16 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 10:55:16 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:55:16 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 10:55:16 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:55:16 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 10:55:16 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:55:16 INFO - u'jsshell-mac.zip'], 10:55:16 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:55:16 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 10:55:16 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 10:55:16 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:55:16 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 10:55:16 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:55:16 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 10:55:16 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:55:16 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 10:55:16 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:55:16 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 10:55:16 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:55: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')]} 10:55:16 INFO - https://queue.taskcluster.net/v1/task/OhgntsRSQO-kvm-B3-LpYQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 10:55:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhgntsRSQO-kvm-B3-LpYQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 10:55:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhgntsRSQO-kvm-B3-LpYQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 10:55:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhgntsRSQO-kvm-B3-LpYQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 10:55:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhgntsRSQO-kvm-B3-LpYQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:55:18 INFO - Downloaded 18150530 bytes. 10:55:18 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:55:18 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 10:55:21 INFO - Return code: 0 10:55:21 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 10:55: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')]} 10:55:21 INFO - https://queue.taskcluster.net/v1/task/OhgntsRSQO-kvm-B3-LpYQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 10:55:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhgntsRSQO-kvm-B3-LpYQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 10:55:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhgntsRSQO-kvm-B3-LpYQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 10:55:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhgntsRSQO-kvm-B3-LpYQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 10:55:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhgntsRSQO-kvm-B3-LpYQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:55:24 INFO - Downloaded 18150530 bytes. 10:55:24 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:55:24 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 10:55:27 INFO - Return code: 0 10:55: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')]} 10:55:27 INFO - https://queue.taskcluster.net/v1/task/OhgntsRSQO-kvm-B3-LpYQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 10:55:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhgntsRSQO-kvm-B3-LpYQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 10:55:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhgntsRSQO-kvm-B3-LpYQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 10:55:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhgntsRSQO-kvm-B3-LpYQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 10:55:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhgntsRSQO-kvm-B3-LpYQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 10:55:29 INFO - Downloaded 10840160 bytes. 10:55:29 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:55:29 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 10:55:29 INFO - Return code: 0 10:55:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:55:29 INFO - https://queue.taskcluster.net/v1/task/OhgntsRSQO-kvm-B3-LpYQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 10:55:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhgntsRSQO-kvm-B3-LpYQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 10:55:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhgntsRSQO-kvm-B3-LpYQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 10:55:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhgntsRSQO-kvm-B3-LpYQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 10:55:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhgntsRSQO-kvm-B3-LpYQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 10:55:33 INFO - Downloaded 110060022 bytes. 10:55:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OhgntsRSQO-kvm-B3-LpYQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 10:55:33 INFO - mkdir: /builds/slave/test/properties 10:55:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:55:33 INFO - Writing to file /builds/slave/test/properties/build_url 10:55:33 INFO - Contents: 10:55:33 INFO - build_url:https://queue.taskcluster.net/v1/task/OhgntsRSQO-kvm-B3-LpYQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 10:55:34 INFO - Running post-action listener: _resource_record_post_action 10:55:34 INFO - Running post-action listener: set_extra_try_arguments 10:55:34 INFO - ##### 10:55:34 INFO - ##### Finished download-and-extract step (success) 10:55:34 INFO - ##### 10:55:34 INFO - ##### 10:55:34 INFO - ##### Running populate-webroot step. 10:55:34 INFO - ##### 10:55:34 INFO - Running pre-action listener: _resource_record_pre_action 10:55:34 INFO - Running main action method: populate_webroot 10:55:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:55:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:55:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:55:34 INFO - '--tppagecycles', 10:55:34 INFO - '7'], 10:55:34 INFO - 'tests': ['tsvgm']}}, 10:55:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:55:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:55:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:55:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:55:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:55:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:55:34 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:55:34 INFO - 'talos_options': ['--disable-e10s'], 10:55:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:55:34 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:55:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:55:34 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:55:34 INFO - 'talos_options': ['--disable-e10s'], 10:55:34 INFO - 'tests': ['damp', 'tps']}, 10:55:34 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:55:34 INFO - 'tests': ['damp', 'tps']}, 10:55:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:55:34 INFO - 'tests': ['dromaeo_dom']}, 10:55:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:55:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:55:34 INFO - 'tests': ['a11yr', 10:55:34 INFO - 'ts_paint', 10:55:34 INFO - 'tpaint', 10:55:34 INFO - 'sessionrestore', 10:55:34 INFO - 'sessionrestore_no_auto_restore', 10:55:34 INFO - 'tabpaint']}, 10:55:34 INFO - 'other-e10s': {'tests': ['a11yr', 10:55:34 INFO - 'ts_paint', 10:55:34 INFO - 'tpaint', 10:55:34 INFO - 'sessionrestore', 10:55:34 INFO - 'sessionrestore_no_auto_restore', 10:55:34 INFO - 'tabpaint']}, 10:55:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:55:34 INFO - 'ts_paint', 10:55:34 INFO - 'tpaint', 10:55:34 INFO - 'sessionrestore', 10:55:34 INFO - 'sessionrestore_no_auto_restore', 10:55:34 INFO - 'tabpaint']}, 10:55:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:55:34 INFO - 'ts_paint', 10:55:34 INFO - 'tpaint', 10:55:34 INFO - 'sessionrestore', 10:55:34 INFO - 'sessionrestore_no_auto_restore', 10:55:34 INFO - 'tabpaint']}, 10:55:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:55:34 INFO - 'tests': ['a11yr', 10:55:34 INFO - 'ts_paint', 10:55:34 INFO - 'tpaint', 10:55:34 INFO - 'sessionrestore', 10:55:34 INFO - 'sessionrestore_no_auto_restore', 10:55:34 INFO - 'tabpaint']}, 10:55:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:55:34 INFO - 'tests': ['a11yr', 10:55:34 INFO - 'ts_paint', 10:55:34 INFO - 'tpaint', 10:55:34 INFO - 'sessionrestore', 10:55:34 INFO - 'sessionrestore_no_auto_restore', 10:55:34 INFO - 'tabpaint']}, 10:55:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:55:34 INFO - 'tests': ['tsvgx', 10:55:34 INFO - 'tsvgr_opacity', 10:55:34 INFO - 'tart', 10:55:34 INFO - 'tscrollx', 10:55:34 INFO - 'cart']}, 10:55:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:55:34 INFO - 'tsvgr_opacity', 10:55:34 INFO - 'tart', 10:55:34 INFO - 'tscrollx', 10:55:34 INFO - 'cart']}, 10:55:34 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:55:34 INFO - 'talos_options': ['--disable-e10s'], 10:55:34 INFO - 'tests': ['tp5o']}, 10:55:34 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:55:34 INFO - 'tests': ['tp5o']}, 10:55:34 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:55:34 INFO - 'talos_options': ['--disable-e10s', 10:55:34 INFO - '--xperf_path', 10:55:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:55:34 INFO - 'tests': ['tp5n']}, 10:55:34 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:55:34 INFO - 'talos_options': ['--xperf_path', 10:55:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:55:34 INFO - 'tests': ['tp5n']}}, 10:55:34 INFO - 'talos.zip': {'path': '', 10:55:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:55:34 INFO - Running post-action listener: _resource_record_post_action 10:55:34 INFO - ##### 10:55:34 INFO - ##### Finished populate-webroot step (success) 10:55:34 INFO - ##### 10:55:34 INFO - ##### 10:55:34 INFO - ##### Running create-virtualenv step. 10:55:34 INFO - ##### 10:55:34 INFO - Running pre-action listener: _resource_record_pre_action 10:55:34 INFO - Running main action method: create_virtualenv 10:55:34 INFO - Creating virtualenv /builds/slave/test/build/venv 10:55:34 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 10:55:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:55:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:55:34 INFO - Using real prefix '/tools/python27' 10:55:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:55:35 INFO - Installing distribute.............................................................................................................................................................................................done. 10:55:37 INFO - Installing pip.................done. 10:55:37 INFO - Return code: 0 10:55:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:55:37 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')]} 10:55:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:55:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:55:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:55:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:55:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:55:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101b78a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b7e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4eb4a65a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a1cd30>, '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 0x101840db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a13b20>, '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.lL1OKoRNi4/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.HOYZx6maSE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/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 10:55:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 10:55:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:55:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lL1OKoRNi4/Render', 10:55:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:55:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:55:38 INFO - 'HOME': '/Users/cltbld', 10:55:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:55:38 INFO - 'LOGNAME': 'cltbld', 10:55:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:55:38 INFO - 'MOZ_NO_REMOTE': '1', 10:55:38 INFO - 'NO_EM_RESTART': '1', 10:55:38 INFO - 'PAGER': '/bin/cat', 10:55:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:55:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:55:38 INFO - 'PWD': '/builds/slave/test', 10:55:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:55:38 INFO - 'SHELL': '/bin/bash', 10:55:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HOYZx6maSE/Listeners', 10:55:38 INFO - 'TMPDIR': '/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/', 10:55:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:55:38 INFO - 'USER': 'cltbld', 10:55:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:55:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:55:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:55:38 INFO - 'XPC_FLAGS': '0x0', 10:55:38 INFO - 'XPC_SERVICE_NAME': '0', 10:55:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:55:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:55:38 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 10:55:38 INFO - Cleaning up... 10:55:38 INFO - Return code: 0 10:55:38 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 10:55:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:55:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:55:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:55:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:55:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:55:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:55:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x101b78a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b7e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4eb4a65a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a1cd30>, '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 0x101840db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a13b20>, '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.lL1OKoRNi4/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.HOYZx6maSE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/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 10:55:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 10:55:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 10:55:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lL1OKoRNi4/Render', 10:55:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:55:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:55:38 INFO - 'HOME': '/Users/cltbld', 10:55:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:55:38 INFO - 'LOGNAME': 'cltbld', 10:55:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:55:38 INFO - 'MOZ_NO_REMOTE': '1', 10:55:38 INFO - 'NO_EM_RESTART': '1', 10:55:38 INFO - 'PAGER': '/bin/cat', 10:55:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:55:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:55:38 INFO - 'PWD': '/builds/slave/test', 10:55:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:55:38 INFO - 'SHELL': '/bin/bash', 10:55:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HOYZx6maSE/Listeners', 10:55:38 INFO - 'TMPDIR': '/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/', 10:55:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:55:38 INFO - 'USER': 'cltbld', 10:55:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:55:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:55:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:55:38 INFO - 'XPC_FLAGS': '0x0', 10:55:38 INFO - 'XPC_SERVICE_NAME': '0', 10:55:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:55:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:55:38 INFO - Downloading/unpacking psutil>=0.7.1 10:55:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:55:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:55:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:55:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:55:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:55:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:55:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:55:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:55:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:55:41 INFO - Installing collected packages: psutil 10:55:41 INFO - Running setup.py install for psutil 10:55:41 INFO - building 'psutil._psutil_osx' extension 10:55:41 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 10:55:42 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 10:55:42 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 10:55:42 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 10:55:42 INFO - building 'psutil._psutil_posix' extension 10:55:42 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 10:55:42 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:55:42 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:55:42 INFO - ^ 10:55:42 INFO - 1 warning generated. 10:55:42 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 10:55:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:55:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:55:42 INFO - Successfully installed psutil 10:55:42 INFO - Cleaning up... 10:55:42 INFO - Return code: 0 10:55:42 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 10:55:42 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')]} 10:55:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:55:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:55:42 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')]} 10:55:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:55:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:55:42 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 0x101b78a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b7e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4eb4a65a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a1cd30>, '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 0x101840db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a13b20>, '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.lL1OKoRNi4/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.HOYZx6maSE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/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 10:55:42 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 10:55:42 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 10:55:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lL1OKoRNi4/Render', 10:55:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:55:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:55:42 INFO - 'HOME': '/Users/cltbld', 10:55:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:55:42 INFO - 'LOGNAME': 'cltbld', 10:55:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:55:42 INFO - 'MOZ_NO_REMOTE': '1', 10:55:42 INFO - 'NO_EM_RESTART': '1', 10:55:42 INFO - 'PAGER': '/bin/cat', 10:55:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:55:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:55:42 INFO - 'PWD': '/builds/slave/test', 10:55:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:55:42 INFO - 'SHELL': '/bin/bash', 10:55:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HOYZx6maSE/Listeners', 10:55:42 INFO - 'TMPDIR': '/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/', 10:55:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:55:42 INFO - 'USER': 'cltbld', 10:55:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:55:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:55:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:55:42 INFO - 'XPC_FLAGS': '0x0', 10:55:42 INFO - 'XPC_SERVICE_NAME': '0', 10:55:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:55:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:55:42 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 10:55:42 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 10:55:42 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 10:55:42 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 10:55:42 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 10:55:42 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 10:55:42 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 10:55:45 INFO - Downloading mozsystemmonitor-0.0.tar.gz 10:55:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:55:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 10:55:45 INFO - Installing collected packages: mozsystemmonitor 10:55:45 INFO - Running setup.py install for mozsystemmonitor 10:55:45 INFO - Successfully installed mozsystemmonitor 10:55:45 INFO - Cleaning up... 10:55:45 INFO - Return code: 0 10:55:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:55:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:55:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:55:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:55:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:55:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:55:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:55:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x101b78a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b7e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4eb4a65a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a1cd30>, '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 0x101840db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a13b20>, '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.lL1OKoRNi4/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.HOYZx6maSE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/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 10:55:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:55:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:55:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lL1OKoRNi4/Render', 10:55:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:55:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:55:45 INFO - 'HOME': '/Users/cltbld', 10:55:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:55:45 INFO - 'LOGNAME': 'cltbld', 10:55:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:55:45 INFO - 'MOZ_NO_REMOTE': '1', 10:55:45 INFO - 'NO_EM_RESTART': '1', 10:55:45 INFO - 'PAGER': '/bin/cat', 10:55:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:55:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:55:45 INFO - 'PWD': '/builds/slave/test', 10:55:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:55:45 INFO - 'SHELL': '/bin/bash', 10:55:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HOYZx6maSE/Listeners', 10:55:45 INFO - 'TMPDIR': '/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/', 10:55:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:55:45 INFO - 'USER': 'cltbld', 10:55:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:55:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:55:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:55:45 INFO - 'XPC_FLAGS': '0x0', 10:55:45 INFO - 'XPC_SERVICE_NAME': '0', 10:55:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:55:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:55:45 INFO - Downloading/unpacking blobuploader==1.2.4 10:55:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:55:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:55:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:55:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:55:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:55:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:55:48 INFO - Downloading blobuploader-1.2.4.tar.gz 10:55:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:55:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:55:48 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 10:55:48 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 10:55:48 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 10:55:48 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 10:55:48 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 10:55:48 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 10:55:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:55:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:55:49 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 10:55:49 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 10:55:49 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 10:55:49 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 10:55:49 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 10:55:49 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 10:55:49 INFO - Downloading docopt-0.6.1.tar.gz 10:55:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:55:49 INFO - Installing collected packages: blobuploader, requests, docopt 10:55:49 INFO - Running setup.py install for blobuploader 10:55:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:55:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:55:49 INFO - Running setup.py install for requests 10:55:50 INFO - Running setup.py install for docopt 10:55:50 INFO - Successfully installed blobuploader requests docopt 10:55:50 INFO - Cleaning up... 10:55:50 INFO - Return code: 0 10:55:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:55:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:55:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:55:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:55:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:55:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:55:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:55:50 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 0x101b78a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b7e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4eb4a65a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a1cd30>, '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 0x101840db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a13b20>, '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.lL1OKoRNi4/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.HOYZx6maSE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/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 10:55:50 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 10:55:50 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 10:55:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lL1OKoRNi4/Render', 10:55:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:55:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:55:50 INFO - 'HOME': '/Users/cltbld', 10:55:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:55:50 INFO - 'LOGNAME': 'cltbld', 10:55:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:55:50 INFO - 'MOZ_NO_REMOTE': '1', 10:55:50 INFO - 'NO_EM_RESTART': '1', 10:55:50 INFO - 'PAGER': '/bin/cat', 10:55:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:55:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:55:50 INFO - 'PWD': '/builds/slave/test', 10:55:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:55:50 INFO - 'SHELL': '/bin/bash', 10:55:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HOYZx6maSE/Listeners', 10:55:50 INFO - 'TMPDIR': '/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/', 10:55:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:55:50 INFO - 'USER': 'cltbld', 10:55:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:55:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:55:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:55:50 INFO - 'XPC_FLAGS': '0x0', 10:55:50 INFO - 'XPC_SERVICE_NAME': '0', 10:55:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:55:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:55:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:55:50 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-6jzX26-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:55:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:55:50 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-_eTJu0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:55:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:55:50 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-BGlGfq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:55:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:55:51 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-nwLifA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:55:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:55:51 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-XqTxdM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:55:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:55:51 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-F8qruu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:55:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:55:51 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-boJYRZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:55:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:55:51 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-IXabEj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:55:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:55:51 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-uHxAwh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:55:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:55:51 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-p_13hD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:55:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:55:51 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-J1c9UF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:55:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:55:51 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-23YTXe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:55:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:55:51 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-diMeW0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:55:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:55:52 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-rScb5z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:55:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:55:52 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-50gGBl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:55:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:55:52 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-NPLHwy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:55:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:55:52 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-_7lRMi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:55:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:55:52 INFO - Running setup.py install for manifestparser 10:55:52 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:55:52 INFO - Running setup.py install for mozcrash 10:55:52 INFO - Running setup.py install for mozdebug 10:55:52 INFO - Running setup.py install for mozdevice 10:55:53 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:55:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:55:53 INFO - Running setup.py install for mozfile 10:55:53 INFO - Running setup.py install for mozhttpd 10:55:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:55:53 INFO - Running setup.py install for mozinfo 10:55:53 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:55:53 INFO - Running setup.py install for mozInstall 10:55:53 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:55:53 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:55:53 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:55:53 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:55:53 INFO - Running setup.py install for mozleak 10:55:53 INFO - Running setup.py install for mozlog 10:55:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:55:53 INFO - Running setup.py install for moznetwork 10:55:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:55:54 INFO - Running setup.py install for mozprocess 10:55:54 INFO - Running setup.py install for mozprofile 10:55:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:55:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:55:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:55:54 INFO - Running setup.py install for mozrunner 10:55:54 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:55:54 INFO - Running setup.py install for mozscreenshot 10:55:54 INFO - Running setup.py install for moztest 10:55:54 INFO - Running setup.py install for mozversion 10:55:54 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:55:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:55:55 INFO - Cleaning up... 10:55:55 INFO - Return code: 0 10:55:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:55:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:55:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:55:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:55:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:55:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:55:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:55:55 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 0x101b78a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b7e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4eb4a65a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a1cd30>, '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 0x101840db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a13b20>, '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.lL1OKoRNi4/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.HOYZx6maSE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/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 10:55:55 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 10:55:55 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 10:55:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lL1OKoRNi4/Render', 10:55:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:55:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:55:55 INFO - 'HOME': '/Users/cltbld', 10:55:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:55:55 INFO - 'LOGNAME': 'cltbld', 10:55:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:55:55 INFO - 'MOZ_NO_REMOTE': '1', 10:55:55 INFO - 'NO_EM_RESTART': '1', 10:55:55 INFO - 'PAGER': '/bin/cat', 10:55:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:55:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:55:55 INFO - 'PWD': '/builds/slave/test', 10:55:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:55:55 INFO - 'SHELL': '/bin/bash', 10:55:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HOYZx6maSE/Listeners', 10:55:55 INFO - 'TMPDIR': '/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/', 10:55:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:55:55 INFO - 'USER': 'cltbld', 10:55:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:55:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:55:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:55:55 INFO - 'XPC_FLAGS': '0x0', 10:55:55 INFO - 'XPC_SERVICE_NAME': '0', 10:55:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:55:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:55:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:55:55 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-XaJ2WB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:55:55 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)) 10:55:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:55:55 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-kyjYhd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:55:55 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)) 10:55:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:55:55 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-AMSCT1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:55:55 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)) 10:55:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:55:55 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-CwPcUJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:55:55 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)) 10:55:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:55:55 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-JDlgku-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:55:55 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)) 10:55:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:55:55 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-nQ9mgO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:55:55 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)) 10:55:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:55:55 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-VcQTL5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:55:56 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)) 10:55:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:55:56 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-JoYFnw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:55:56 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)) 10:55:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:55:56 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-csb0qH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:55:56 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)) 10:55:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:55:56 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-HGnFfl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:55:56 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)) 10:55:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:55:56 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-RjmSdD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:55:56 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)) 10:55:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:55:56 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-DpVWoN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:55:56 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)) 10:55:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:55:56 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-08uP6b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:55:56 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)) 10:55:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:55:56 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-sn1uue-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:55:56 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)) 10:55:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:55:56 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-0vNxBg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:55:56 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)) 10:55:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:55:56 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-V7VJke-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:55:57 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)) 10:55:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:55:57 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-IYwKoN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:55:57 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)) 10:55:57 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)) 10:55:57 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)) 10:55:57 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)) 10:55:57 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)) 10:55:57 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)) 10:55:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:55:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:55:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:55:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:55:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:55:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:55:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:55:59 INFO - Downloading blessings-1.6.tar.gz 10:55:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:55:59 INFO - Installing collected packages: blessings 10:55:59 INFO - Running setup.py install for blessings 10:55:59 INFO - Successfully installed blessings 10:55:59 INFO - Cleaning up... 10:56:00 INFO - Return code: 0 10:56:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:56:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:56:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:56:00 INFO - Reading from file tmpfile_stdout 10:56:00 INFO - Current package versions: 10:56:00 INFO - blessings == 1.6 10:56:00 INFO - blobuploader == 1.2.4 10:56:00 INFO - docopt == 0.6.1 10:56:00 INFO - manifestparser == 1.1 10:56:00 INFO - mozInstall == 1.12 10:56:00 INFO - mozcrash == 0.17 10:56:00 INFO - mozdebug == 0.1 10:56:00 INFO - mozdevice == 0.48 10:56:00 INFO - mozfile == 1.2 10:56:00 INFO - mozhttpd == 0.7 10:56:00 INFO - mozinfo == 0.9 10:56:00 INFO - mozleak == 0.1 10:56:00 INFO - mozlog == 3.1 10:56:00 INFO - moznetwork == 0.27 10:56:00 INFO - mozprocess == 0.22 10:56:00 INFO - mozprofile == 0.28 10:56:00 INFO - mozrunner == 6.11 10:56:00 INFO - mozscreenshot == 0.1 10:56:00 INFO - mozsystemmonitor == 0.0 10:56:00 INFO - moztest == 0.7 10:56:00 INFO - mozversion == 1.4 10:56:00 INFO - psutil == 3.1.1 10:56:00 INFO - requests == 1.2.3 10:56:00 INFO - wsgiref == 0.1.2 10:56:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:56:00 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')]} 10:56:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:56:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:56:00 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')]} 10:56:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:56:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:56:00 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 0x101b78a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b7e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4eb4a65a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a1cd30>, '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 0x101840db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a13b20>, '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.lL1OKoRNi4/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.HOYZx6maSE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/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 10:56:00 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 10:56:00 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 10:56:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lL1OKoRNi4/Render', 10:56:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:56:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:56:00 INFO - 'HOME': '/Users/cltbld', 10:56:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:56:00 INFO - 'LOGNAME': 'cltbld', 10:56:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:56:00 INFO - 'MOZ_NO_REMOTE': '1', 10:56:00 INFO - 'NO_EM_RESTART': '1', 10:56:00 INFO - 'PAGER': '/bin/cat', 10:56:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:56:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:56:00 INFO - 'PWD': '/builds/slave/test', 10:56:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:56:00 INFO - 'SHELL': '/bin/bash', 10:56:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HOYZx6maSE/Listeners', 10:56:00 INFO - 'TMPDIR': '/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/', 10:56:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:56:00 INFO - 'USER': 'cltbld', 10:56:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:56:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:56:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:56:00 INFO - 'XPC_FLAGS': '0x0', 10:56:00 INFO - 'XPC_SERVICE_NAME': '0', 10:56:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:56:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:56:00 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)) 10:56:00 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)) 10:56:00 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)) 10:56:00 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)) 10:56:00 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)) 10:56:00 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)) 10:56:00 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)) 10:56:00 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)) 10:56:00 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)) 10:56:00 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)) 10:56:00 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)) 10:56:00 INFO - Cleaning up... 10:56:00 INFO - Return code: 0 10:56:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:56:00 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')]} 10:56:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:56:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:56:00 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')]} 10:56:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:56:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:56:00 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 0x101b78a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b7e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4eb4a65a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a1cd30>, '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 0x101840db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a13b20>, '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.lL1OKoRNi4/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.HOYZx6maSE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/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 10:56:00 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 10:56:00 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 10:56:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lL1OKoRNi4/Render', 10:56:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:56:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:56:00 INFO - 'HOME': '/Users/cltbld', 10:56:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:56:00 INFO - 'LOGNAME': 'cltbld', 10:56:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:56:00 INFO - 'MOZ_NO_REMOTE': '1', 10:56:00 INFO - 'NO_EM_RESTART': '1', 10:56:00 INFO - 'PAGER': '/bin/cat', 10:56:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:56:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:56:00 INFO - 'PWD': '/builds/slave/test', 10:56:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:56:00 INFO - 'SHELL': '/bin/bash', 10:56:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HOYZx6maSE/Listeners', 10:56:00 INFO - 'TMPDIR': '/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/', 10:56:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:56:00 INFO - 'USER': 'cltbld', 10:56:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:56:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:56:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:56:00 INFO - 'XPC_FLAGS': '0x0', 10:56:00 INFO - 'XPC_SERVICE_NAME': '0', 10:56:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:56:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:56:00 INFO - Downloading/unpacking jsonschema 10:56:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:56:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:56:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:56:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:56:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:56:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:56:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:56:03 INFO - Downloading/unpacking functools32 (from jsonschema) 10:56:03 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 10:56:03 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 10:56:03 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 10:56:03 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 10:56:03 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 10:56:03 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 10:56:03 INFO - Downloading functools32-3.2.3-2.tar.gz 10:56:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:56:03 INFO - warning: no files found matching '*.txt' 10:56:03 INFO - no previously-included directories found matching 'build' 10:56:03 INFO - no previously-included directories found matching 'dist' 10:56:03 INFO - no previously-included directories found matching '.git*' 10:56:03 INFO - Installing collected packages: jsonschema, functools32 10:56:03 INFO - Running setup.py install for functools32 10:56:03 INFO - warning: no files found matching '*.txt' 10:56:03 INFO - no previously-included directories found matching 'build' 10:56:03 INFO - no previously-included directories found matching 'dist' 10:56:03 INFO - no previously-included directories found matching '.git*' 10:56:03 INFO - Successfully installed jsonschema functools32 10:56:03 INFO - Cleaning up... 10:56:03 INFO - Return code: 0 10:56:03 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 10:56:03 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')]} 10:56:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:56:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:56:03 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')]} 10:56:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:56:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:56:03 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 0x101b78a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b7e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4eb4a65a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a1cd30>, '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 0x101840db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a13b20>, '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.lL1OKoRNi4/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.HOYZx6maSE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/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 10:56:03 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 10:56:03 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 10:56:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lL1OKoRNi4/Render', 10:56:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:56:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:56:03 INFO - 'HOME': '/Users/cltbld', 10:56:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:56:03 INFO - 'LOGNAME': 'cltbld', 10:56:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:56:03 INFO - 'MOZ_NO_REMOTE': '1', 10:56:03 INFO - 'NO_EM_RESTART': '1', 10:56:03 INFO - 'PAGER': '/bin/cat', 10:56:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:56:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:56:03 INFO - 'PWD': '/builds/slave/test', 10:56:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:56:03 INFO - 'SHELL': '/bin/bash', 10:56:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HOYZx6maSE/Listeners', 10:56:03 INFO - 'TMPDIR': '/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/', 10:56:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:56:03 INFO - 'USER': 'cltbld', 10:56:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:56:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:56:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:56:03 INFO - 'XPC_FLAGS': '0x0', 10:56:03 INFO - 'XPC_SERVICE_NAME': '0', 10:56:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:56:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:56:04 INFO - Downloading/unpacking flake8 10:56:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:56:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:56:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:56:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:56:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:56:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:56:06 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 10:56:06 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 10:56: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 10:56: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 10:56: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 10:56: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 10:56: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 10:56: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 10:56:07 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 10:56:07 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 10:56:07 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 10:56:07 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 10:56:07 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 10:56:07 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 10:56:07 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 10:56:07 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 10:56:07 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 10:56:07 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 10:56:07 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 10:56:07 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 10:56:07 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 10:56:07 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 10:56:07 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 10:56:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 10:56:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:56:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:56:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:56:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:56:07 INFO - no previously-included directories found matching 'docs/_build' 10:56:07 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 10:56:08 INFO - Running setup.py install for pep8 10:56:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:56:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:56:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:56:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:56:08 INFO - no previously-included directories found matching 'docs/_build' 10:56:08 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 10:56:08 INFO - Successfully installed flake8 mccabe pyflakes pep8 10:56:08 INFO - Cleaning up... 10:56:08 INFO - Return code: 0 10:56:08 INFO - Running post-action listener: _resource_record_post_action 10:56:08 INFO - Running post-action listener: _start_resource_monitoring 10:56:08 INFO - Starting resource monitoring. 10:56:08 INFO - ##### 10:56:08 INFO - ##### Finished create-virtualenv step (success) 10:56:08 INFO - ##### 10:56:08 INFO - ##### 10:56:08 INFO - ##### Running install step. 10:56:08 INFO - ##### 10:56:08 INFO - Running pre-action listener: _resource_record_pre_action 10:56:08 INFO - Running main action method: install 10:56:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:56:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:56:08 INFO - Reading from file tmpfile_stdout 10:56:08 INFO - Detecting whether we're running mozinstall >=1.0... 10:56:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:56:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:56:08 INFO - Reading from file tmpfile_stdout 10:56:08 INFO - Output received: 10:56:08 INFO - Usage: mozinstall [options] installer 10:56:08 INFO - Options: 10:56:08 INFO - -h, --help show this help message and exit 10:56:08 INFO - -d DEST, --destination=DEST 10:56:08 INFO - Directory to install application into. [default: 10:56:08 INFO - "/builds/slave/test"] 10:56:08 INFO - --app=APP Application being installed. [default: firefox] 10:56:08 INFO - mkdir: /builds/slave/test/build/application 10:56:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 10:56:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 10:56:42 INFO - Reading from file tmpfile_stdout 10:56:42 INFO - Output received: 10:56:42 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:56:42 INFO - Running post-action listener: _resource_record_post_action 10:56:42 INFO - ##### 10:56:42 INFO - ##### Finished install step (success) 10:56:42 INFO - ##### 10:56:42 INFO - ##### 10:56:42 INFO - ##### Running run-tests step. 10:56:42 INFO - ##### 10:56:42 INFO - Running pre-action listener: _resource_record_pre_action 10:56:42 INFO - Running main action method: run_tests 10:56:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:56:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:56:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:56:42 INFO - 2016-05-20 10:56:42.227 screenresolution[1649:7740] starting screenresolution argv=screenresolution get 10:56:42 INFO - 2016-05-20 10:56:42.229 screenresolution[1649:7740] Display 0: 1600x1200x32@60 10:56:42 INFO - 2016-05-20 10:56:42.235 screenresolution[1650:7742] starting screenresolution argv=screenresolution list 10:56:42 INFO - Available Modes on Display 0 10:56:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:56:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:56:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:56:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:56:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:56:42 INFO - Intel Iris: 10:56:42 INFO - Chipset Model: Intel Iris 10:56:42 INFO - Type: GPU 10:56:42 INFO - Bus: Built-In 10:56:42 INFO - VRAM (Dynamic, Max): 1536 MB 10:56:42 INFO - Vendor: Intel (0x8086) 10:56:42 INFO - Device ID: 0x0a2e 10:56:42 INFO - Revision ID: 0x0009 10:56:42 INFO - Displays: 10:56:42 INFO - Display: 10:56:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:56:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:56:42 INFO - Display Serial Number: 1600x1200 60 10:56:42 INFO - Main Display: Yes 10:56:42 INFO - Mirror: Off 10:56:42 INFO - Online: Yes 10:56:42 INFO - Rotation: Supported 10:56:42 INFO - Return code: 0 10:56:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:56:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:56:42 INFO - Python 2.7.3 10:56:42 INFO - Return code: 0 10:56:42 INFO - grabbing minidump binary from tooltool 10:56:42 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')]} 10:56:42 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a1cd30>, '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 0x101840db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a13b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:56:42 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 10:56:42 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 10:56:42 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 10:56:42 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:56:42 INFO - Return code: 0 10:56:42 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:56:42 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:56:42 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:56:42 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:56:42 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:56:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 10:56:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 10:56:43 INFO - Return code: 0 10:56:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OhgntsRSQO-kvm-B3-LpYQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0138', '--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 10:56:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/OhgntsRSQO-kvm-B3-LpYQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0138 --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 10:56:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lL1OKoRNi4/Render', 10:56:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:56:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:56:43 INFO - 'HOME': '/Users/cltbld', 10:56:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:56:43 INFO - 'LOGNAME': 'cltbld', 10:56:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:56:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:56:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:56:43 INFO - 'MOZ_NO_REMOTE': '1', 10:56:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:56:43 INFO - 'NO_EM_RESTART': '1', 10:56:43 INFO - 'PAGER': '/bin/cat', 10:56:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:56:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:56:43 INFO - 'PWD': '/builds/slave/test', 10:56:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:56:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:56:43 INFO - 'SHELL': '/bin/bash', 10:56:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HOYZx6maSE/Listeners', 10:56:43 INFO - 'TMPDIR': '/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/', 10:56:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:56:43 INFO - 'USER': 'cltbld', 10:56:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:56:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:56:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:56:43 INFO - 'XPC_FLAGS': '0x0', 10:56:43 INFO - 'XPC_SERVICE_NAME': '0', 10:56:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:56:43 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OhgntsRSQO-kvm-B3-LpYQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0138', '--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 10:56:43 INFO - mozversion application_buildid: 20160520085604 10:56:43 INFO - mozversion application_changeset: eaeb86dd4a1980cc1b758440186438a43e801622 10:56:43 INFO - mozversion application_display_name: Nightly 10:56:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:56:43 INFO - mozversion application_name: Firefox 10:56:43 INFO - mozversion application_remotingname: firefox 10:56:43 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 10:56:43 INFO - mozversion application_vendor: Mozilla 10:56:43 INFO - mozversion application_version: 49.0a1 10:56:43 INFO - mozversion platform_buildid: 20160520085604 10:56:43 INFO - mozversion platform_changeset: eaeb86dd4a1980cc1b758440186438a43e801622 10:56:43 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 10:56:43 INFO - mozversion platform_version: 49.0a1 10:56:43 INFO - using testdate: 1463767003 10:56:43 INFO - actual date: 1463767003 10:56:43 INFO - starting webserver on 'localhost:49281' 10:56:43 INFO - SUITE-START | Running 2 tests 10:56:43 INFO - TEST-START | dromaeo_css 10:56:43 INFO - operating with platform_type : mac_ 10:56:43 INFO - Initialising browser for dromaeo_css test... 10:56:43 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/tmp2zVDYT/profile http://localhost:49281/getInfo.html) 10:56:46 INFO - PROCESS | 1668 | __metrics Screen width/height:1600/1200 10:56:46 INFO - PROCESS | 1668 | colorDepth:24 10:56:46 INFO - PROCESS | 1668 | Browser inner width/height: 1024/689 10:56:46 INFO - PROCESS | 1668 | __metrics 10:56:46 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" 10:56:46 ERROR - PROCESS | 1668 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 10:56:47 INFO - TEST-INFO | 1668: exit 0 10:56:47 INFO - Browser initialized. 10:56:47 INFO - Running cycle 1/1 for dromaeo_css test... 10:56:47 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/tmp2zVDYT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:56:59 INFO - PROCESS | 1670 | RSS: Main: 239071232 10:56:59 INFO - PROCESS | 1670 | 10:57:50 INFO - PROCESS | 1670 | 1463767070553 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] 11:00:00 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49281/tests/dromaeo/cssquery-ext.html) 11:00:02 INFO - PROCESS | 1670 | RSS: Main: 368132096 11:00:02 INFO - PROCESS | 1670 | 11:03:05 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-ext.html (next: http://localhost:49281/tests/dromaeo/cssquery-jquery.html) 11:03:05 INFO - PROCESS | 1670 | RSS: Main: 372203520 11:03:05 INFO - PROCESS | 1670 | 11:06:07 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49281/tests/dromaeo/cssquery-mootools.html) 11:06:08 INFO - PROCESS | 1670 | RSS: Main: 366440448 11:06:08 INFO - PROCESS | 1670 | 11:09:11 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49281/tests/dromaeo/cssquery-prototype.html) 11:09:11 INFO - PROCESS | 1670 | RSS: Main: 368242688 11:09:11 INFO - PROCESS | 1670 | 11:12:15 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49281/tests/dromaeo/cssquery-yui.html) 11:12:15 INFO - PROCESS | 1670 | RSS: Main: 335831040 11:12:15 INFO - PROCESS | 1670 | 11:15:18 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-yui.html (next: http://localhost:49281/tests/dromaeo/cssquery-dojo.html) 11:15:19 INFO - PROCESS | 1670 | RSS: Main: 376901632 11:15:19 INFO - PROCESS | 1670 | 11:15:19 INFO - PROCESS | 1670 | __start_tp_report 11:15:19 INFO - PROCESS | 1670 | _x_x_mozilla_page_load 11:15:19 INFO - PROCESS | 1670 | _x_x_mozilla_page_load_details 11:15:19 INFO - PROCESS | 1670 | |i|pagename|runs| 11:15:19 INFO - PROCESS | 1670 | |0;dojo.html;3480.321337339218;3489.6684814942587;3493.0743353011458;3512.051746028574;3571.7321200909923;5787.276590426197;5807.128930660403;5817.301923769153;5883.23517942668;5887.087501437279;5837.5913686041995;5871.970640146804;5881;5980.491658209057;5994.730237139328;5256.080185967459;5272.314599102111;5321.813736519222;5340.732963351837;5435.918461223085;5424.91862622059;5458.972705136469;5462.644928079662;5470.781168753245;5498.147787092987;4410.889727756799;5268.104422248208;5295.152775555931;5366.1682439221595;5368.62419630626;4457.955420445786;5328.813491527778;5334.973325133388;5350.785968561253;5376.596755243372;5293.86765330866;5349.491798279158;5358.142697168473;5360.3299587551555;5400.351957765093;5668.943310566924;5749.8275051748515;5810.82567522975;5822.097574875901;5833.912491312633;5791.855203619901;5950.928832810093;6296.748130074791;6303.842403939938;6312.652804095773;6272.184615999937;6319.304876463585;6342.238931328224;6342.841428964267;6362.045693146065;5241.24002019705;5879.265091863517;5951.434613711734;5970.1343305220935;6047.939520604826;6418.422341989243;6426.839329016719;6435.356464353527;6440.355964403522;6447.484201263887;6265.342139075423;6283.591566548251;6300.495960323163;6308;6308.337624549356;6224.190855188887;6235.501159907197;6260.373962603703;6269.373062693694;6330.240371155491;5784.814113106803;5848.152017957457;6213.068039794067;6275.309715931244;6278.937210627927;6206.968965155145;6213.130161777355;6283.717232724538;6305.558610897285;6307.085472606446;5010.549050585465;5051.444341122474;5075.137226671474;5077.5176358246645;5108.872278193075;4566.908661826745;4567.086582683477;4567.429071366079;4589.10512450069;4606.378138951199;5677.914831277534;5769.740361683735;5954.345022047572;5991.970040149771;5997.490213331828;5251.212318152231;6213.254409470893;6228.813135605939;6270.7805226816845;6289.685515724287;5874.32445268791;5900.045239645896;5935.080062590306;5940.554458415636;5945.286565612154;5426.972865135728;5551.916721249185;5557.138643510262;5559.9444005560235;5561.860953476114;6273.278572964076;6372.235331760218;6463.095166676669;6475.222973243241;6560.44236239925;6262.3111457738705;6282.1519094921605;6286.748530058747;6288.119663247057;6289.088082228142;6238.6880655967925;6551.836204094936;6565;6575.539712220098;6582.045603387578;6166.167567378347;6206.379362063757;6237.937620623736;6265.686715664107;6269.404406581368;6221.9377806221355;6264.68676566179;6274.811755647338;6276.905846412399;6277.905831412441;6116.153172518083;6121.7578389577775;6191.102290167811;6288.402601752826;6312.368763123651;4689;6223.875522489616;6234.844128896814;6262.624242545462;6269.529785266214;6177.81466556004;6180.938190618036;6183.783567575113;6184.257889053342;6184.721687523982;6094.177286066324;6133.326672827528;6156.969215153896;6245.96877015612;6248.281447633581;6154.599951003171;6183.288921773874;6184.783532576338;6194.039923811816;6211.3478084800445 11:15:19 INFO - PROCESS | 1670 | |1;ext.html;526.8656492594388;528.6986417741889;535.5153586004662;542.3545980283461;562.57525568196;30434.84782576087;31014.689853101463;32052;42357.78821105898;43682.34476482855;43668.344974825406;44034.77982610082;44211.55788442115;44244.778776106075;44268;9642.421454712721;9834.31159818813;9838.409695418262;9841.803163936716;9848.310618256724;9530.52347382628;9652.75868103296;9658.372205806636;9661.516924153799;9669.613215471372;8937.240334571568;9382.202512786422;9689;9743.902560974371;9745.074217949319;8388.37087218458;9685.612575497042;9689.176420004309;9703.029697030312;9738.464384458852;9444.48055356955;9527.142557169818;9534.237261019101;9539.90460095397;9542;40935;41239.58760412387;41318.38022429666;41351.58648413508;41400.792996035125;3095.1024202981107;3124.5157000664935;3163.1775738307974;3350.1959529712935;3922.980385098056;28437;29084;29109.854450727824;29395.265118371997;29713.55429668557;2630.5396555602692;2689.2335684329873;2700.9189724308303;2709.1195361507393;2781.3741908070724;475125;478514;479124;480213;484072;51874.74062629738;60202.39797601968;60392.69803650954;60483.69758151181;60500;52384.738076309375;55080.17379739307;55165;55202.44797552053;55213.17180242299;15417.920745547928;16013;16498.257578409;17249.482515524553;17299.394521191698;12987.090903636843;13120.475180992844;13129.474821007052;13131;13258.933705331412;15971.600709982344;15991.840081599268;16009.27958241882;16014.359425622844;16048.839511604734;5875.618084824473;7241.384482319062;7347.338739513362;7380.9630951845975;7467.925320746832;855.4311382930406;930.0234753508789;1064.3241541621126;1075.429573814927;1137.4142316706902;29370.82516699351;29549.852250738608;29949.951751688586;30274.394512109633;30336.393272134435;1061.8566493523276;1123.3035517978906;1127.9718007049726;1138.248755821154;1243.347242697591;15219.467318644016;15237.466688665843;15239.847601524065;15344.462943796914;15975.840241597667;181107.09446452948;183329;183356;183464;183625;58580.70709646425;59950.80098398008;59989.40010599925;60121.69939150276;60237;1342.335543905773;1384.0173476831362;1468.845771194052;1490.6646004648867;1507.5402002389158;29426.705732943254;30224.84887575548;30350.241243969078;30568.425642829465;30704;49673.75163124161;50188.49811501838;50243.497565023885;50249.74875125601;50336;389969;402067;402601;402827;404601;32588.022359329258;38652.03369915662;38847.41728874013;39907.80046099751;41791.7910410446;45704;45808.312875306234;46329.76835115938;46577;47134.29298560456;35779.642203578675;37329.62670373262;37666;38087.857364279116;38329.80835095918;32437.18907027343;36998.930117687574;37162.07094822651;37870.43194352032;38490.845274641804;31257;37748.05629859275;38042.809785952;38273.04317392087;38438.039049023995;31757.682423175473;33438.49842252417;33548.496772547944;35000.47499287462;35210.8239458801;24531;24793.504129917663;25212.24363269105;25358.55434863677;25641.743582563937 11:15:19 INFO - PROCESS | 1670 | |2;jquery.html;357.54056037991177;379.45737595238813;402.017895650986;404.4176386004154;424.7939749221629;13847.584572462816;20086.6986995195;20978;21501.462463438424;21525.24661636843;15786.84213157868;19596.51008724779;20057.498562535908;20512.179512819508;21014.57970840586;5821.621594596349;5966.910496342537;6017.608855424383;6037.879242415171;6055.545834062441;5881.382454842244;5889.499392551638;5928.822135335925;5953.523718102563;5975.581709280352;5764.711764411783;5862.355140934494;5913.911291330612;5935;5974.074018527113;5543.279373681416;5872.119182122716;5877.294724633029;5888.998870192099;5936.079907614305;5137.101007323741;5146.485351464861;5175.870603234912;5177.171652535614;5182.663126896778;20320.593588128155;20970.895145524326;25382.49235015308;26032.479350413076;27174.728252717614;2185.27885797688;2191.079746506455;2586.610603509268;2878.553824157259;2948.86730097144;14850.777238341434;14905.701885962328;15177.317020734094;15305.928584999054;15627.296771645304;1710.469754376135;1734.2456031626157;1735.5053809664312;1744.842964133234;1767.301915743274;128165.35917320447;128395.35802320928;132136;132493.33753331268;133757.99999999904;10210.387376757419;11462.083033357312;11785.407210413696;11826.467808948619;11905.404729763477;15182.392704291926;15279.847201528064;15302.770458443132;15327.693446131016;15357.308921098578;7584.772456826194;7601.695932162762;8766.95616521926;8926.955365223263;8980;7452.403807695479;8852.513111778786;8857.3799834011;8949.463032218087;8991.910080899239;4641.721496710141;4653.162430762428;4654.674172807939;4658.277966915066;4688.859334219979;1815.2738904438065;1871.5482717639936;2014.8791072535694;2019.515316324101;2025.8176764091008;920.59953920045;936.9718908432758;964.7539877331376;977.6947774720827;985.3890587835588;19511.902440487705;20771.68842467364;20817.375478735663;20887.895560522404;21033.68449473259;975.1711045611315;975.9219262458986;1007.2697294461603;1026.2610920137488;1028.2699283508643;10510.894891050992;10930.180236482296;11007;11011.61459348935;11021.338719676685;492791;505400;508154;604919;608504;115591;143654;143725;147187.52812471954;150597.24701376422;984.5077461269366;1009.7677534167036;1023.5445226874118;1026.3328836256344;1031.2265800649513;18270.26918923228;18522.722159167894;18585.34951276699;18591.721124182877;18612.906935465235;56107.15839262496;56328.71835640795;56484.30547083594;56550.586235344446;57264.713676431355;75485.49029019279;79866.6006669963;81244;83563.5821820887;85532;8005.9599702001115;8213.260806527202;8276.29651479631;8329.833403331779;8580.313574914117;11775.705607359885;12318.938405307916;12597.685057873627;12650.810237846625;12884.35578221104;10938.289011214245;11042.33745975244;11275.32348059119;11305.265157765049;11382.829257560978;10565.52455139536;10584.629537966443;10696.679099627023;11090.057345125755;11192.048675862643;9894.50527473609;10999.790023097294;11153.776924461303;11309.264897781617;11486.023687986615;6479.611223326616;6971.2331643518155;7310.780676579711;7416.0729908761405;7751.844963100819;10371.533281002525;10879.891201087888;11014.39420831862;11321.547138114713;11347.773044539228 11:15:19 INFO - PROCESS | 1670 | |3;mootools.html;1437.0730878583308;1598.8800839937007;1620.1170362152625;1858.9163487643063;1983.266191509142;7853.293203611674;8698.39111262212;8977.640894364218;8985.550722463868;9058.184763371311;8445.493270403766;8675.958884933802;8681.652733890653;8691.91308086919;8692.087330830267;2110.2297661353573;2281.2015794471968;2361.622140457527;2412.3245491262433;2468.6420469031978;2427.4659574893494;2449.216250799745;2450.0567281596623;2452.460458699083;2454.410941374066;2373.76262373763;2390.043982407042;2411.855288682676;2413.650020746991;2413.81896357773;2103.3584756649216;2330.7436182019965;2376.58409778289;2432.1122790181457;2433.2821817563863;2413.095089341497;2438.6220135878966;2441.182203961675;2442.255112190781;2442.9144979925795;8763.51800650964;8833.381663283573;8834.55827208637;8856.291496680251;8865.556722163867;5111.309973153651;5417.241586177938;5434.510894019518;5441.863953401157;5452.754626041838;3173.952390714141;3502.614712381636;3579.94630080549;3663.3955397359405;3686.2627474505207;1045.6758404894506;1058.3544038136731;1058.867641544807;1062.4156713807374;1064.6965614799744;11902.58340958071;11923.224990375598;11927.880721192763;11930.343831089287;11934.104942129288;4744.833930812405;4803.186854226316;4882.902341953141;4898.093852637202;4914.410270767531;10090.596376145018;10115.848262276071;10116.595336186618;10118.392896426238;10125.898741012643;5411.269478620415;5441;5445.564354851603;5450.836474905759;5456.781728730806;4262.488501379854;4393.099414620059;4583.106294272651;4598;4599.931001034987;4028.2144981728866;4112.876613701594;4123.113530590937;4137.958620413818;4141.64795992344;1167.3346192670267;1314.4742103158621;1349.7772867476756;1350.9392077356542;1360.5170164591625;649.4252586461016;679.7315060551055;835.2733122183739;932.7528205025674;977.9462129582942;5644.491995720405;5646.237757902631;5870.383609720918;5951.464368206882;6158.507319414436;933.332667142996;944.1974321826392;981.601218263971;987.086944576264;1025.6410256410345;3809.7523660961947;3948.348522493814;4040.878773636796;4078.2455245779624;4216.325387937976;10491.895081049244;10718.035376815968;10842.132629389629;10872.891271087346;10880.347179169117;10754.78490430187;11000.559977600966;11019.944900275448;11037.503312350964;11042.282251653616;7214.567125972353;7315.890261646079;7964.283214510722;8227.13615070409;8365.456245344034;5951.791687290924;6042.969785151046;6083.574149809471;6106.389361063858;6151.231096112986;9877.654282100091;10213.234007449619;10286.897131028594;10334.793304133726;10354;9560.378575392577;10008.899911000797;10113.241506886865;10195.7451063724;10296.17630589536;6735;6851.800934836483;6952;6964.582125072512;7573.2048134945035;7471.9626401867645;7687.2697093776005;7993.680252790056;8146.75559733209;8236.752897413087;7185.461090418135;7301.707931682886;7392.949848415822;7409.962950185431;7438.218987006287;7162.247963963709;7166.677499512431;7180.60506672138;7272.672729727281;7319.048523691886;6899;6938.514303998873;7027.754028608974;7060.752873649398;7064.505484616027;4762.8499687373005;4850.53919877611;4985.925211121908;5017.347744793299;5116.360454943132;4973.502649735142;5014.899702005867;5026.447090819934;5107.668001579886;5312.282841816305 11:15:19 INFO - PROCESS | 1670 | |4;prototype.html;307.0894796927111;307.9430305393504;320.09413360190655;327.1314659578819;329.0835024456887;4990.30135780991;5046.300229377284;5273.235380869772;5447.891042179154;5480.917786233204;5095.210242412422;5095.490450954902;5194.480551944804;5330.626856120073;5340.973295133528;3587.964120358789;4102.487189101363;4114.876553703393;4161.459010328653;4171.478565179353;3812.866549670762;3861.575226725058;4143.544210136897;4154.480689913761;4155.563665815092;3414.504896789964;3786.3941769316916;3891.571927088032;3911.5892831252736;4136.255474014676;4060.776657283848;4104.179164167179;4113.691473139526;4132.276851550967;4143.523494798092;3106.437189318821;3454.274602333513;3609.530761001053;3610.494530765695;3611.277744451094;4483.718049262946;4515.096980603892;4848.660593758438;5125.462361291635;5132.255822905695;4313.762743049131;4357.586029327209;4623.491415944277;4693.178693728584;4739.834105806315;5357.303550538444;5563.582731295129;5606.290081525133;5622.91565626516;5638;3536.3634545781747;3586.8206589670413;3608.4767708682093;3982.5463705747484;4000.5399379071196;4570.977145114253;5590.288709975659;5699.694177377111;5780;5876.177335173079;3902.1220225449615;3912.256671232514;3914.451976723261;3917.334053210961;3921.509811273591;3933.4689816874356;3937.586553411908;3961.3661814110183;3974.1058261890844;3986.063275130342;3891.4551962724677;3972.245273398045;4537.455505339314;4598.494165641777;4606.193916064675;4890.0928637831275;4979;5659.057290209151;5663;5683.658980461186;1268.0299570828324;1325.7746183148695;1341.684704094537;1358.1579420759192;1360.5102412857905;761.5126319155745;867.7657032601162;908.9000209976897;915.46445329483;926.6710317837205;5127.461616530285;5135.974320128375;5165.405978312467;5176.249443830623;5271.65734227274;4626.653001024936;5179.741012949337;5437.537809286255;5704.942950570524;5719.7702493964;4638.443386793539;4690.413698287714;4772.95227047732;5224.973875130676;5234.555062819627;2828.618136551539;2880.1215629233284;2886.0043285066617;2965.481040817848;2999.3851260491547;5609.831705048855;5656.830295091154;5730.193483192823;5876.206712093898;5904.468597826216;4965.095419179748;5263.526282634582;5791.623544469596;5812.912806307909;5852.678102704307;5304.416514183436;5391.865203369947;5399.352077750616;5650.208970744181;5883.470487656132;5134.691918484903;5431.9728401359325;5697.715114244188;5828.358880523224;5897.970510147422;4967.428745694291;5197.8440646780655;5797.76808927655;5814.738336774771;5826.563007774349;5002.649814512949;5424.674519528683;5620.269364982526;5851.268591426072;6127.142200091902;3564.7326450516834;3809.561900381381;3937.3503371943307;3993.0416699992375;3995.6403923647013;5177.378714554277;5204.34945631796;5869.970650146722;5888;5897.616654917417;4507.932381014353;5110.821121260738;5635.267415235993;5736.569757268138;5779.826605201851;5089.898202036013;5656.765577596909;5746.626469279484;5748.827535173951;5765;4879.682820616648;4915.631327650369;5210.687358758487;5566.888662226814;5572.665640061609;5039.370078740158;5317.415084340642;5665.348484924121;5683.431656834284;5760.366359700513;5513.807016754395;5541.445855414588;5561.304836895388;5578.86052848682;5586.944130558805 11:15:19 INFO - PROCESS | 1670 | |5;yui.html;651.5764752910608;687.2405991379526;718.1920339617926;719.1621760648844;740.522363075816;15235.162066086361;15507.224638768059;15696.372145114181;15747.606309842246;15770.84229157708;14264.429422823074;14467.493637722682;14505.27473626317;14511.564653060399;14791.186484743335;904.267543289934;919.3334832246635;934.5841100710187;936.3632729743772;938.132227689388;733.6368497593699;769.4190885881152;908.3459908865606;934.4953724988499;945.9716208513755;1002.9648962286321;1003.2325271167563;1003.6336737090983;1004.2518323848743;1005.783756492354;984.8276551603476;988.1551273661024;991.375433476905;992.4045572656348;992.6426486464903;570.7916610437202;711.1111111111111;738.4092725819323;742.9932441541687;746.4700062955309;13020.479180832755;15182.164980926154;15243.771343429857;15270.618234544114;15293.235338233044;11671.824922626167;11804.291742495392;12367.690807729789;12419.875801242053;12655.746885062246;10797.190210734148;10864.945675271652;10877.564897404094;11308.491117899714;11699;3945.6054394560315;4050.351943689025;4170.186813571355;4237.025484138635;4281.165172791307;75527;78168.43663126706;78222.21777782206;78264;78651.82022269671;9147;9213.262938964866;9299.302552308603;9362.485063321583;9465.952670236606;20358.18567257323;20378.898105509674;20388.694169587172;20392.388228352876;20421.489462763253;6970.605342042873;7009.754658586925;7043.330883566053;7085.822854428578;7261.751875152989;3957.4855268815445;4008.819603117867;4012.177503611811;4012.839486420569;4015.8393664253103;3409.2670075933793;3488.9476657849646;3492.6553276988543;3495.405781017233;3496.3356962177613;629.6568370238231;644.452215616722;645.1580687203245;653.3042309939899;653.4739534674633;508.8043098717983;523.5602094240855;537.8815968359906;567.7558649780551;581.7702007706932;7706.614669266514;7711.877882016179;7748.147703752583;7825;7851.136374998746;499.9800007999712;549.2996429552273;552.0890530624501;567.9914801277985;580.6893312078103;3109.5646609474693;3536.8054756988613;3605.94591081134;3640.18095928414;3693.9630603693618;61291.08063379053;77857.221427785;81479;84273;84607.57696211479;66845.66577167083;70801.93797093048;74872.62563687256;75039;75478;66133.00800487997;66503.66748166228;66819;67010.66494667495;67037.66481167563;7072.3634872861685;7123.180834204133;7194.676239569337;7264.963675181802;7277.3086556777025;38785.03037424087;41079.58920410877;42490.51283205073;42693;42862.14275714406;54505.72747136239;54775.90448191093;56264;56302.310930672145;56855.43144568501;8246.299064579578;9413.576389062371;9564.330496865152;9682.304052745758;10026.799464010825;10626.946865265625;10773;10886.346819190874;11017.944910275397;11052.889471105185;8615.923009623797;10103.191744660557;10111.241656875616;10345.741356465847;10388.636397726043;10022.899771002196;10204.948975255373;10338.896611033793;10341.63804266847;10590.152787777113;9314.161725444743;9592.472414017297;9649.855252171083;9848.359856609297;11040.889591104307;9535;9625.56684949165;9739.074787895139;9918.355306905029;9981.950090249502;9521.333506654468;9716.222702183666;9754.365966212174;9786.021397860442;9835.409875407498 11:15:19 INFO - PROCESS | 1670 | __end_tp_report 11:15:19 INFO - PROCESS | 1670 | __start_cc_report 11:15:19 INFO - PROCESS | 1670 | _x_x_mozilla_cycle_collect,1425 11:15:19 INFO - PROCESS | 1670 | __end_cc_report 11:15:19 INFO - PROCESS | 1670 | __startTimestamp1463768119058__endTimestamp 11:15:19 INFO - PROCESS | 1670 | 11:15:19 INFO - PROCESS | 1670 | ------- Summary: start ------- 11:15:19 INFO - PROCESS | 1670 | Number of tests: 6 11:15:19 INFO - PROCESS | 1670 | 11:15:19 INFO - PROCESS | 1670 | [#0] dojo.html Cycles:165 Average:5833.77 Median:6116.15 stddev:642.39 (10.5%) stddev-sans-first:617.26 11:15:19 INFO - PROCESS | 1670 | Values: 3480.3 3489.7 3493.1 3512.1 3571.7 5787.3 5807.1 5817.3 5883.2 5887.1 5837.6 5872.0 5881.0 5980.5 5994.7 5256.1 5272.3 5321.8 5340.7 5435.9 5424.9 5459.0 5462.6 5470.8 5498.1 4410.9 5268.1 5295.2 5366.2 5368.6 4458.0 5328.8 5335.0 5350.8 5376.6 5293.9 5349.5 5358.1 5360.3 5400.4 5668.9 5749.8 5810.8 5822.1 5833.9 5791.9 5950.9 6296.7 6303.8 6312.7 6272.2 6319.3 6342.2 6342.8 6362.0 5241.2 5879.3 5951.4 5970.1 6047.9 6418.4 6426.8 6435.4 6440.4 6447.5 6265.3 6283.6 6300.5 6308.0 6308.3 6224.2 6235.5 6260.4 6269.4 6330.2 5784.8 5848.2 6213.1 6275.3 6278.9 6207.0 6213.1 6283.7 6305.6 6307.1 5010.5 5051.4 5075.1 5077.5 5108.9 4566.9 4567.1 4567.4 4589.1 4606.4 5677.9 5769.7 5954.3 5992.0 5997.5 5251.2 6213.3 6228.8 6270.8 6289.7 5874.3 5900.0 5935.1 5940.6 5945.3 5427.0 5551.9 5557.1 5559.9 5561.9 6273.3 6372.2 6463.1 6475.2 6560.4 6262.3 6282.2 6286.7 6288.1 6289.1 6238.7 6551.8 6565.0 6575.5 6582.0 6166.2 6206.4 6237.9 6265.7 6269.4 6221.9 6264.7 6274.8 6276.9 6277.9 6116.2 6121.8 6191.1 6288.4 6312.4 4689.0 6223.9 6234.8 6262.6 6269.5 6177.8 6180.9 6183.8 6184.3 6184.7 6094.2 6133.3 6157.0 6246.0 6248.3 6154.6 6183.3 6184.8 6194.0 6211.3 11:15:19 INFO - PROCESS | 1670 | 11:15:19 INFO - PROCESS | 1670 | [#1] ext.html Cycles:180 Average:52140.40 Median:38571.44 stddev:99852.05 (258.9%) stddev-sans-first:100056.96 11:15:19 INFO - PROCESS | 1670 | Values: 526.9 528.7 535.5 542.4 562.6 30434.8 31014.7 32052.0 42357.8 43682.3 43668.3 44034.8 44211.6 44244.8 44268.0 9642.4 9834.3 9838.4 9841.8 9848.3 9530.5 9652.8 9658.4 9661.5 9669.6 8937.2 9382.2 9689.0 9743.9 9745.1 8388.4 9685.6 9689.2 9703.0 9738.5 9444.5 9527.1 9534.2 9539.9 9542.0 40935.0 41239.6 41318.4 41351.6 41400.8 3095.1 3124.5 3163.2 3350.2 3923.0 28437.0 29084.0 29109.9 29395.3 29713.6 2630.5 2689.2 2700.9 2709.1 2781.4 475125.0 478514.0 479124.0 480213.0 484072.0 51874.7 60202.4 60392.7 60483.7 60500.0 52384.7 55080.2 55165.0 55202.4 55213.2 15417.9 16013.0 16498.3 17249.5 17299.4 12987.1 13120.5 13129.5 13131.0 13258.9 15971.6 15991.8 16009.3 16014.4 16048.8 5875.6 7241.4 7347.3 7381.0 7467.9 855.4 930.0 1064.3 1075.4 1137.4 29370.8 29549.9 29950.0 30274.4 30336.4 1061.9 1123.3 1128.0 1138.2 1243.3 15219.5 15237.5 15239.8 15344.5 15975.8 181107.1 183329.0 183356.0 183464.0 183625.0 58580.7 59950.8 59989.4 60121.7 60237.0 1342.3 1384.0 1468.8 1490.7 1507.5 29426.7 30224.8 30350.2 30568.4 30704.0 49673.8 50188.5 50243.5 50249.7 50336.0 389969.0 402067.0 402601.0 402827.0 404601.0 32588.0 38652.0 38847.4 39907.8 41791.8 45704.0 45808.3 46329.8 46577.0 47134.3 35779.6 37329.6 37666.0 38087.9 38329.8 32437.2 36998.9 37162.1 37870.4 38490.8 31257.0 37748.1 38042.8 38273.0 38438.0 31757.7 33438.5 33548.5 35000.5 35210.8 24531.0 24793.5 25212.2 25358.6 25641.7 11:15:19 INFO - PROCESS | 1670 | 11:15:19 INFO - PROCESS | 1670 | [#2] jquery.html Cycles:180 Average:34433.01 Median:21267.57 stddev:92536.86 (435.1%) stddev-sans-first:92761.08 11:15:19 INFO - PROCESS | 1670 | Values: 357.5 379.5 402.0 404.4 424.8 13847.6 20086.7 20978.0 21501.5 21525.2 15786.8 19596.5 20057.5 20512.2 21014.6 5821.6 5966.9 6017.6 6037.9 6055.5 5881.4 5889.5 5928.8 5953.5 5975.6 5764.7 5862.4 5913.9 5935.0 5974.1 5543.3 5872.1 5877.3 5889.0 5936.1 5137.1 5146.5 5175.9 5177.2 5182.7 20320.6 20970.9 25382.5 26032.5 27174.7 2185.3 2191.1 2586.6 2878.6 2948.9 14850.8 14905.7 15177.3 15305.9 15627.3 1710.5 1734.2 1735.5 1744.8 1767.3 128165.4 128395.4 132136.0 132493.3 133758.0 10210.4 11462.1 11785.4 11826.5 11905.4 15182.4 15279.8 15302.8 15327.7 15357.3 7584.8 7601.7 8767.0 8927.0 8980.0 7452.4 8852.5 8857.4 8949.5 8991.9 4641.7 4653.2 4654.7 4658.3 4688.9 1815.3 1871.5 2014.9 2019.5 2025.8 920.6 937.0 964.8 977.7 985.4 19511.9 20771.7 20817.4 20887.9 21033.7 975.2 975.9 1007.3 1026.3 1028.3 10510.9 10930.2 11007.0 11011.6 11021.3 492791.0 505400.0 508154.0 604919.0 608504.0 115591.0 143654.0 143725.0 147187.5 150597.2 984.5 1009.8 1023.5 1026.3 1031.2 18270.3 18522.7 18585.3 18591.7 18612.9 56107.2 56328.7 56484.3 56550.6 57264.7 75485.5 79866.6 81244.0 83563.6 85532.0 8006.0 8213.3 8276.3 8329.8 8580.3 11775.7 12318.9 12597.7 12650.8 12884.4 10938.3 11042.3 11275.3 11305.3 11382.8 10565.5 10584.6 10696.7 11090.1 11192.0 9894.5 10999.8 11153.8 11309.3 11486.0 6479.6 6971.2 7310.8 7416.1 7751.8 10371.5 10879.9 11014.4 11321.5 11347.8 11:15:19 INFO - PROCESS | 1670 | 11:15:19 INFO - PROCESS | 1670 | [#3] mootools.html Cycles:180 Average:5723.81 Median:4783.02 stddev:3208.31 (67.1%) stddev-sans-first:3201.13 11:15:19 INFO - PROCESS | 1670 | Values: 1437.1 1598.9 1620.1 1858.9 1983.3 7853.3 8698.4 8977.6 8985.6 9058.2 8445.5 8676.0 8681.7 8691.9 8692.1 2110.2 2281.2 2361.6 2412.3 2468.6 2427.5 2449.2 2450.1 2452.5 2454.4 2373.8 2390.0 2411.9 2413.7 2413.8 2103.4 2330.7 2376.6 2432.1 2433.3 2413.1 2438.6 2441.2 2442.3 2442.9 8763.5 8833.4 8834.6 8856.3 8865.6 5111.3 5417.2 5434.5 5441.9 5452.8 3174.0 3502.6 3579.9 3663.4 3686.3 1045.7 1058.4 1058.9 1062.4 1064.7 11902.6 11923.2 11927.9 11930.3 11934.1 4744.8 4803.2 4882.9 4898.1 4914.4 10090.6 10115.8 10116.6 10118.4 10125.9 5411.3 5441.0 5445.6 5450.8 5456.8 4262.5 4393.1 4583.1 4598.0 4599.9 4028.2 4112.9 4123.1 4138.0 4141.6 1167.3 1314.5 1349.8 1350.9 1360.5 649.4 679.7 835.3 932.8 977.9 5644.5 5646.2 5870.4 5951.5 6158.5 933.3 944.2 981.6 987.1 1025.6 3809.8 3948.3 4040.9 4078.2 4216.3 10491.9 10718.0 10842.1 10872.9 10880.3 10754.8 11000.6 11019.9 11037.5 11042.3 7214.6 7315.9 7964.3 8227.1 8365.5 5951.8 6043.0 6083.6 6106.4 6151.2 9877.7 10213.2 10286.9 10334.8 10354.0 9560.4 10008.9 10113.2 10195.7 10296.2 6735.0 6851.8 6952.0 6964.6 7573.2 7472.0 7687.3 7993.7 8146.8 8236.8 7185.5 7301.7 7392.9 7410.0 7438.2 7162.2 7166.7 7180.6 7272.7 7319.0 6899.0 6938.5 7027.8 7060.8 7064.5 4762.8 4850.5 4985.9 5017.3 5116.4 4973.5 5014.9 5026.4 5107.7 5312.3 11:15:19 INFO - PROCESS | 1670 | 11:15:19 INFO - PROCESS | 1670 | [#4] prototype.html Cycles:180 Average:4521.43 Median:5095.35 stddev:1373.32 (27.0%) stddev-sans-first:1340.24 11:15:19 INFO - PROCESS | 1670 | Values: 307.1 307.9 320.1 327.1 329.1 4990.3 5046.3 5273.2 5447.9 5480.9 5095.2 5095.5 5194.5 5330.6 5341.0 3588.0 4102.5 4114.9 4161.5 4171.5 3812.9 3861.6 4143.5 4154.5 4155.6 3414.5 3786.4 3891.6 3911.6 4136.3 4060.8 4104.2 4113.7 4132.3 4143.5 3106.4 3454.3 3609.5 3610.5 3611.3 4483.7 4515.1 4848.7 5125.5 5132.3 4313.8 4357.6 4623.5 4693.2 4739.8 5357.3 5563.6 5606.3 5622.9 5638.0 3536.4 3586.8 3608.5 3982.5 4000.5 4571.0 5590.3 5699.7 5780.0 5876.2 3902.1 3912.3 3914.5 3917.3 3921.5 3933.5 3937.6 3961.4 3974.1 3986.1 3891.5 3972.2 4537.5 4598.5 4606.2 4890.1 4979.0 5659.1 5663.0 5683.7 1268.0 1325.8 1341.7 1358.2 1360.5 761.5 867.8 908.9 915.5 926.7 5127.5 5136.0 5165.4 5176.2 5271.7 4626.7 5179.7 5437.5 5704.9 5719.8 4638.4 4690.4 4773.0 5225.0 5234.6 2828.6 2880.1 2886.0 2965.5 2999.4 5609.8 5656.8 5730.2 5876.2 5904.5 4965.1 5263.5 5791.6 5812.9 5852.7 5304.4 5391.9 5399.4 5650.2 5883.5 5134.7 5432.0 5697.7 5828.4 5898.0 4967.4 5197.8 5797.8 5814.7 5826.6 5002.6 5424.7 5620.3 5851.3 6127.1 3564.7 3809.6 3937.4 3993.0 3995.6 5177.4 5204.3 5870.0 5888.0 5897.6 4507.9 5110.8 5635.3 5736.6 5779.8 5089.9 5656.8 5746.6 5748.8 5765.0 4879.7 4915.6 5210.7 5566.9 5572.7 5039.4 5317.4 5665.3 5683.4 5760.4 5513.8 5541.4 5561.3 5578.9 5586.9 11:15:19 INFO - PROCESS | 1670 | 11:15:19 INFO - PROCESS | 1670 | [#5] yui.html Cycles:180 Average:16780.74 Median:581.23 stddev:23036.94 (3963.5%) stddev-sans-first:23069.73 11:15:19 INFO - PROCESS | 1670 | Values: 651.6 687.2 718.2 719.2 740.5 15235.2 15507.2 15696.4 15747.6 15770.8 14264.4 14467.5 14505.3 14511.6 14791.2 904.3 919.3 934.6 936.4 938.1 733.6 769.4 908.3 934.5 946.0 1003.0 1003.2 1003.6 1004.3 1005.8 984.8 988.2 991.4 992.4 992.6 570.8 711.1 738.4 743.0 746.5 13020.5 15182.2 15243.8 15270.6 15293.2 11671.8 11804.3 12367.7 12419.9 12655.7 10797.2 10864.9 10877.6 11308.5 11699.0 3945.6 4050.4 4170.2 4237.0 4281.2 75527.0 78168.4 78222.2 78264.0 78651.8 9147.0 9213.3 9299.3 9362.5 9466.0 20358.2 20378.9 20388.7 20392.4 20421.5 6970.6 7009.8 7043.3 7085.8 7261.8 3957.5 4008.8 4012.2 4012.8 4015.8 3409.3 3488.9 3492.7 3495.4 3496.3 629.7 644.5 645.2 653.3 653.5 508.8 523.6 537.9 567.8 581.8 7706.6 7711.9 7748.1 7825.0 7851.1 500.0 549.3 552.1 568.0 580.7 3109.6 3536.8 3605.9 3640.2 3694.0 61291.1 77857.2 81479.0 84273.0 84607.6 66845.7 70801.9 74872.6 75039.0 75478.0 66133.0 66503.7 66819.0 67010.7 67037.7 7072.4 7123.2 7194.7 7265.0 7277.3 38785.0 41079.6 42490.5 42693.0 42862.1 54505.7 54775.9 56264.0 56302.3 56855.4 8246.3 9413.6 9564.3 9682.3 10026.8 10626.9 10773.0 10886.3 11017.9 11052.9 8615.9 10103.2 10111.2 10345.7 10388.6 10022.9 10204.9 10338.9 10341.6 10590.2 9314.2 9592.5 9649.9 9848.4 11040.9 9535.0 9625.6 9739.1 9918.4 9982.0 9521.3 9716.2 9754.4 9786.0 9835.4 11:15:19 INFO - PROCESS | 1670 | -------- Summary: end -------- 11:15:19 INFO - PROCESS | 1670 | 11:15:19 INFO - TEST-INFO | 1670: exit 0 11:15:19 INFO - TEST-OK | dromaeo_css | took 1115689ms 11:15:19 INFO - TEST-START | kraken 11:15:19 INFO - operating with platform_type : mac_ 11:15:19 INFO - Initialising browser for kraken test... 11:15:19 INFO - TEST-INFO | started process 1678 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/tmpuadlp1/profile http://localhost:49281/getInfo.html) 11:15:21 INFO - PROCESS | 1678 | __metrics Screen width/height:1600/1200 11:15:21 INFO - PROCESS | 1678 | colorDepth:24 11:15:21 INFO - PROCESS | 1678 | Browser inner width/height: 1024/689 11:15:21 INFO - PROCESS | 1678 | __metrics 11:15:21 INFO - PROCESS | 1678 | 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" 11:15:21 ERROR - PROCESS | 1678 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 11:15:22 INFO - TEST-INFO | 1678: exit 0 11:15:22 INFO - Browser initialized. 11:15:22 INFO - Running cycle 1/1 for kraken test... 11:15:22 INFO - TEST-INFO | started process 1680 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/tmpuadlp1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:15:34 INFO - PROCESS | 1680 | RSS: Main: 238796800 11:15:34 INFO - PROCESS | 1680 | 11:15:36 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49281/tests/kraken/driver.html?testName=audio-beat-detection) 11:15:37 INFO - PROCESS | 1680 | RSS: Main: 287031296 11:15:37 INFO - PROCESS | 1680 | 11:15:40 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49281/tests/kraken/driver.html?testName=audio-dft) 11:15:41 INFO - PROCESS | 1680 | RSS: Main: 296439808 11:15:41 INFO - PROCESS | 1680 | 11:15:44 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49281/tests/kraken/driver.html?testName=audio-fft) 11:15:45 INFO - PROCESS | 1680 | RSS: Main: 384946176 11:15:45 INFO - PROCESS | 1680 | 11:15:47 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49281/tests/kraken/driver.html?testName=audio-oscillator) 11:15:48 INFO - PROCESS | 1680 | RSS: Main: 284200960 11:15:48 INFO - PROCESS | 1680 | 11:15:50 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49281/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:15:51 INFO - PROCESS | 1680 | RSS: Main: 289361920 11:15:51 INFO - PROCESS | 1680 | 11:15:53 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49281/tests/kraken/driver.html?testName=imaging-darkroom) 11:15:54 INFO - PROCESS | 1680 | RSS: Main: 328364032 11:15:54 INFO - PROCESS | 1680 | 11:15:57 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49281/tests/kraken/driver.html?testName=imaging-desaturate) 11:15:58 INFO - PROCESS | 1680 | RSS: Main: 331034624 11:15:58 INFO - PROCESS | 1680 | 11:16:01 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49281/tests/kraken/driver.html?testName=json-parse-financial) 11:16:02 INFO - PROCESS | 1680 | RSS: Main: 328867840 11:16:02 INFO - PROCESS | 1680 | 11:16:03 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49281/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:16:04 INFO - PROCESS | 1680 | RSS: Main: 316350464 11:16:04 INFO - PROCESS | 1680 | 11:16:06 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:16:06 INFO - PROCESS | 1680 | RSS: Main: 323063808 11:16:06 INFO - PROCESS | 1680 | 11:16:09 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:16:09 INFO - PROCESS | 1680 | RSS: Main: 324780032 11:16:09 INFO - PROCESS | 1680 | 11:16:12 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:16:13 INFO - PROCESS | 1680 | RSS: Main: 329400320 11:16:13 INFO - PROCESS | 1680 | 11:16:16 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:16:17 INFO - PROCESS | 1680 | RSS: Main: 327172096 11:16:17 INFO - PROCESS | 1680 | 11:16:18 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49281/tests/kraken/driver.html?testName=ai-astar) 11:16:18 INFO - PROCESS | 1680 | RSS: Main: 372965376 11:16:18 INFO - PROCESS | 1680 | 11:16:18 INFO - PROCESS | 1680 | __start_tp_report 11:16:18 INFO - PROCESS | 1680 | _x_x_mozilla_page_load 11:16:18 INFO - PROCESS | 1680 | _x_x_mozilla_page_load_details 11:16:18 INFO - PROCESS | 1680 | |i|pagename|runs| 11:16:18 INFO - PROCESS | 1680 | |0;ai-astar;113;104;112;110;113;112;112;112;111;109 11:16:18 INFO - PROCESS | 1680 | |1;audio-beat-detection;153;117;139;195;117;139;134;116;167;116 11:16:18 INFO - PROCESS | 1680 | |2;audio-dft;187;189;186;183;186;183;182;180;189;187 11:16:18 INFO - PROCESS | 1680 | |3;audio-fft;78;75;57;124;57;61;57;60;112;55 11:16:18 INFO - PROCESS | 1680 | |4;audio-oscillator;90;100;133;79;79;78;102;81;79;106 11:16:18 INFO - PROCESS | 1680 | |5;imaging-gaussian-blur;87;85;87;85;88;84;88;84;88;84 11:16:18 INFO - PROCESS | 1680 | |6;imaging-darkroom;93;93;93;93;92;92;93;93;93;134 11:16:18 INFO - PROCESS | 1680 | |7;imaging-desaturate;85;86;83;89;88;136;86;87;86;85 11:16:18 INFO - PROCESS | 1680 | |8;json-parse-financial;60;60;60;60;60;60;59;120;56;56 11:16:18 INFO - PROCESS | 1680 | |9;json-stringify-tinderbox;62;78;64;64;71;69;66;68;67;67 11:16:18 INFO - PROCESS | 1680 | |10;stanford-crypto-aes;85;85;86;87;86;86;82;85;86;86 11:16:18 INFO - PROCESS | 1680 | |11;stanford-crypto-ccm;153;150;138;135;137;139;135;138;212;140 11:16:18 INFO - PROCESS | 1680 | |12;stanford-crypto-pbkdf2;168;168;165;162;162;165;167;165;167;165 11:16:18 INFO - PROCESS | 1680 | |13;stanford-crypto-sha256-iterative;67;67;65;67;67;64;65;66;67;67 11:16:18 INFO - PROCESS | 1680 | __end_tp_report 11:16:18 INFO - PROCESS | 1680 | __start_cc_report 11:16:18 INFO - PROCESS | 1680 | _x_x_mozilla_cycle_collect,2316 11:16:18 INFO - PROCESS | 1680 | __end_cc_report 11:16:18 INFO - PROCESS | 1680 | __startTimestamp1463768178987__endTimestamp 11:16:18 INFO - PROCESS | 1680 | 11:16:18 INFO - PROCESS | 1680 | ------- Summary: start ------- 11:16:18 INFO - PROCESS | 1680 | Number of tests: 14 11:16:18 INFO - PROCESS | 1680 | 11:16:18 INFO - PROCESS | 1680 | [#0] ai-astar Cycles:10 Average:110.80 Median:112.00 stddev:2.70 (2.4%) stddev-sans-first:2.74 11:16:18 INFO - PROCESS | 1680 | Values: 113.0 104.0 112.0 110.0 113.0 112.0 112.0 112.0 111.0 109.0 11:16:18 INFO - PROCESS | 1680 | 11:16:18 INFO - PROCESS | 1680 | [#1] audio-beat-detection Cycles:10 Average:139.30 Median:139.00 stddev:26.17 (18.8%) stddev-sans-first:27.29 11:16:18 INFO - PROCESS | 1680 | Values: 153.0 117.0 139.0 195.0 117.0 139.0 134.0 116.0 167.0 116.0 11:16:18 INFO - PROCESS | 1680 | 11:16:18 INFO - PROCESS | 1680 | [#2] audio-dft Cycles:10 Average:185.20 Median:186.50 stddev:3.05 (1.6%) stddev-sans-first:3.16 11:16:18 INFO - PROCESS | 1680 | Values: 187.0 189.0 186.0 183.0 186.0 183.0 182.0 180.0 189.0 187.0 11:16:18 INFO - PROCESS | 1680 | 11:16:18 INFO - PROCESS | 1680 | [#3] audio-fft Cycles:10 Average:73.60 Median:58.50 stddev:24.84 (42.5%) stddev-sans-first:26.29 11:16:18 INFO - PROCESS | 1680 | Values: 78.0 75.0 57.0 124.0 57.0 61.0 57.0 60.0 112.0 55.0 11:16:18 INFO - PROCESS | 1680 | 11:16:18 INFO - PROCESS | 1680 | [#4] audio-oscillator Cycles:10 Average:92.70 Median:79.00 stddev:17.84 (22.6%) stddev-sans-first:18.89 11:16:18 INFO - PROCESS | 1680 | Values: 90.0 100.0 133.0 79.0 79.0 78.0 102.0 81.0 79.0 106.0 11:16:18 INFO - PROCESS | 1680 | 11:16:18 INFO - PROCESS | 1680 | [#5] imaging-gaussian-blur Cycles:10 Average:86.00 Median:87.00 stddev:1.76 (2.0%) stddev-sans-first:1.83 11:16:18 INFO - PROCESS | 1680 | Values: 87.0 85.0 87.0 85.0 88.0 84.0 88.0 84.0 88.0 84.0 11:16:18 INFO - PROCESS | 1680 | 11:16:18 INFO - PROCESS | 1680 | [#6] imaging-darkroom Cycles:10 Average:96.90 Median:93.00 stddev:13.04 (14.0%) stddev-sans-first:13.76 11:16:18 INFO - PROCESS | 1680 | Values: 93.0 93.0 93.0 93.0 92.0 92.0 93.0 93.0 93.0 134.0 11:16:18 INFO - PROCESS | 1680 | 11:16:18 INFO - PROCESS | 1680 | [#7] imaging-desaturate Cycles:10 Average:91.10 Median:86.00 stddev:15.86 (18.4%) stddev-sans-first:16.67 11:16:18 INFO - PROCESS | 1680 | Values: 85.0 86.0 83.0 89.0 88.0 136.0 86.0 87.0 86.0 85.0 11:16:18 INFO - PROCESS | 1680 | 11:16:18 INFO - PROCESS | 1680 | [#8] json-parse-financial Cycles:10 Average:65.10 Median:60.00 stddev:19.36 (32.3%) stddev-sans-first:20.45 11:16:18 INFO - PROCESS | 1680 | Values: 60.0 60.0 60.0 60.0 60.0 60.0 59.0 120.0 56.0 56.0 11:16:18 INFO - PROCESS | 1680 | 11:16:18 INFO - PROCESS | 1680 | [#9] json-stringify-tinderbox Cycles:10 Average:67.60 Median:67.50 stddev:4.50 (6.7%) stddev-sans-first:4.29 11:16:19 INFO - PROCESS | 1680 | Values: 62.0 78.0 64.0 64.0 71.0 69.0 66.0 68.0 67.0 67.0 11:16:19 INFO - PROCESS | 1680 | 11:16:19 INFO - PROCESS | 1680 | [#10] stanford-crypto-aes Cycles:10 Average:85.40 Median:86.00 stddev:1.35 (1.6%) stddev-sans-first:1.42 11:16:19 INFO - PROCESS | 1680 | Values: 85.0 85.0 86.0 87.0 86.0 86.0 82.0 85.0 86.0 86.0 11:16:19 INFO - PROCESS | 1680 | 11:16:19 INFO - PROCESS | 1680 | [#11] stanford-crypto-ccm Cycles:10 Average:147.70 Median:139.50 stddev:23.40 (16.8%) stddev-sans-first:24.74 11:16:19 INFO - PROCESS | 1680 | Values: 153.0 150.0 138.0 135.0 137.0 139.0 135.0 138.0 212.0 140.0 11:16:19 INFO - PROCESS | 1680 | 11:16:19 INFO - PROCESS | 1680 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.40 Median:166.00 stddev:2.17 (1.3%) stddev-sans-first:2.09 11:16:19 INFO - PROCESS | 1680 | Values: 168.0 168.0 165.0 162.0 162.0 165.0 167.0 165.0 167.0 165.0 11:16:19 INFO - PROCESS | 1680 | 11:16:19 INFO - PROCESS | 1680 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.20 Median:67.00 stddev:1.14 (1.7%) stddev-sans-first:1.17 11:16:19 INFO - PROCESS | 1680 | Values: 67.0 67.0 65.0 67.0 67.0 64.0 65.0 66.0 67.0 67.0 11:16:19 INFO - PROCESS | 1680 | -------- Summary: end -------- 11:16:19 INFO - PROCESS | 1680 | 11:16:19 INFO - TEST-INFO | 1680: exit 0 11:16:19 INFO - TEST-OK | kraken | took 60190ms 11:16:19 INFO - SUITE-END | took 1175s 11:16:20 INFO - Completed test suite (00:19:36) 11:16:20 INFO - javascript benchmark 11:16:20 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3480.321337339218, 3489.6684814942587, 3493.0743353011458, 3512.051746028574, 3571.7321200909923, 5787.276590426197, 5807.128930660403, 5817.301923769153, 5883.23517942668, 5887.087501437279, 5837.5913686041995, 5871.970640146804, 5881.0, 5980.491658209057, 5994.730237139328, 5256.080185967459, 5272.314599102111, 5321.813736519222, 5340.732963351837, 5435.918461223085, 5424.91862622059, 5458.972705136469, 5462.644928079662, 5470.781168753245, 5498.147787092987, 4410.889727756799, 5268.104422248208, 5295.152775555931, 5366.1682439221595, 5368.62419630626, 4457.955420445786, 5328.813491527778, 5334.973325133388, 5350.785968561253, 5376.596755243372, 5293.86765330866, 5349.491798279158, 5358.142697168473, 5360.3299587551555, 5400.351957765093, 5668.943310566924, 5749.8275051748515, 5810.82567522975, 5822.097574875901, 5833.912491312633, 5791.855203619901, 5950.928832810093, 6296.748130074791, 6303.842403939938, 6312.652804095773, 6272.184615999937, 6319.304876463585, 6342.238931328224, 6342.841428964267, 6362.045693146065, 5241.24002019705, 5879.265091863517, 5951.434613711734, 5970.1343305220935, 6047.939520604826, 6418.422341989243, 6426.839329016719, 6435.356464353527, 6440.355964403522, 6447.484201263887, 6265.342139075423, 6283.591566548251, 6300.495960323163, 6308.0, 6308.337624549356, 6224.190855188887, 6235.501159907197, 6260.373962603703, 6269.373062693694, 6330.240371155491, 5784.814113106803, 5848.152017957457, 6213.068039794067, 6275.309715931244, 6278.937210627927, 6206.968965155145, 6213.130161777355, 6283.717232724538, 6305.558610897285, 6307.085472606446, 5010.549050585465, 5051.444341122474, 5075.137226671474, 5077.5176358246645, 5108.872278193075, 4566.908661826745, 4567.086582683477, 4567.429071366079, 4589.10512450069, 4606.378138951199, 5677.914831277534, 5769.740361683735, 5954.345022047572, 5991.970040149771, 5997.490213331828, 5251.212318152231, 6213.254409470893, 6228.813135605939, 6270.7805226816845, 6289.685515724287, 5874.32445268791, 5900.045239645896, 5935.080062590306, 5940.554458415636, 5945.286565612154, 5426.972865135728, 5551.916721249185, 5557.138643510262, 5559.9444005560235, 5561.860953476114, 6273.278572964076, 6372.235331760218, 6463.095166676669, 6475.222973243241, 6560.44236239925, 6262.3111457738705, 6282.1519094921605, 6286.748530058747, 6288.119663247057, 6289.088082228142, 6238.6880655967925, 6551.836204094936, 6565.0, 6575.539712220098, 6582.045603387578, 6166.167567378347, 6206.379362063757, 6237.937620623736, 6265.686715664107, 6269.404406581368, 6221.9377806221355, 6264.68676566179, 6274.811755647338, 6276.905846412399, 6277.905831412441, 6116.153172518083, 6121.7578389577775, 6191.102290167811, 6288.402601752826, 6312.368763123651, 4689.0, 6223.875522489616, 6234.844128896814, 6262.624242545462, 6269.529785266214, 6177.81466556004, 6180.938190618036, 6183.783567575113, 6184.257889053342, 6184.721687523982, 6094.177286066324, 6133.326672827528, 6156.969215153896, 6245.96877015612, 6248.281447633581, 6154.599951003171, 6183.288921773874, 6184.783532576338, 6194.039923811816, 6211.3478084800445], "value": 5795.450718164775, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [526.8656492594388, 528.6986417741889, 535.5153586004662, 542.3545980283461, 562.57525568196, 30434.84782576087, 31014.689853101463, 32052.0, 42357.78821105898, 43682.34476482855, 43668.344974825406, 44034.77982610082, 44211.55788442115, 44244.778776106075, 44268.0, 9642.421454712721, 9834.31159818813, 9838.409695418262, 9841.803163936716, 9848.310618256724, 9530.52347382628, 9652.75868103296, 9658.372205806636, 9661.516924153799, 9669.613215471372, 8937.240334571568, 9382.202512786422, 9689.0, 9743.902560974371, 9745.074217949319, 8388.37087218458, 9685.612575497042, 9689.176420004309, 9703.029697030312, 9738.464384458852, 9444.48055356955, 9527.142557169818, 9534.237261019101, 9539.90460095397, 9542.0, 40935.0, 41239.58760412387, 41318.38022429666, 41351.58648413508, 41400.792996035125, 3095.1024202981107, 3124.5157000664935, 3163.1775738307974, 3350.1959529712935, 3922.980385098056, 28437.0, 29084.0, 29109.854450727824, 29395.265118371997, 29713.55429668557, 2630.5396555602692, 2689.2335684329873, 2700.9189724308303, 2709.1195361507393, 2781.3741908070724, 475125.0, 478514.0, 479124.0, 480213.0, 484072.0, 51874.74062629738, 60202.39797601968, 60392.69803650954, 60483.69758151181, 60500.0, 52384.738076309375, 55080.17379739307, 55165.0, 55202.44797552053, 55213.17180242299, 15417.920745547928, 16013.0, 16498.257578409, 17249.482515524553, 17299.394521191698, 12987.090903636843, 13120.475180992844, 13129.474821007052, 13131.0, 13258.933705331412, 15971.600709982344, 15991.840081599268, 16009.27958241882, 16014.359425622844, 16048.839511604734, 5875.618084824473, 7241.384482319062, 7347.338739513362, 7380.9630951845975, 7467.925320746832, 855.4311382930406, 930.0234753508789, 1064.3241541621126, 1075.429573814927, 1137.4142316706902, 29370.82516699351, 29549.852250738608, 29949.951751688586, 30274.394512109633, 30336.393272134435, 1061.8566493523276, 1123.3035517978906, 1127.9718007049726, 1138.248755821154, 1243.347242697591, 15219.467318644016, 15237.466688665843, 15239.847601524065, 15344.462943796914, 15975.840241597667, 181107.09446452948, 183329.0, 183356.0, 183464.0, 183625.0, 58580.70709646425, 59950.80098398008, 59989.40010599925, 60121.69939150276, 60237.0, 1342.335543905773, 1384.0173476831362, 1468.845771194052, 1490.6646004648867, 1507.5402002389158, 29426.705732943254, 30224.84887575548, 30350.241243969078, 30568.425642829465, 30704.0, 49673.75163124161, 50188.49811501838, 50243.497565023885, 50249.74875125601, 50336.0, 389969.0, 402067.0, 402601.0, 402827.0, 404601.0, 32588.022359329258, 38652.03369915662, 38847.41728874013, 39907.80046099751, 41791.7910410446, 45704.0, 45808.312875306234, 46329.76835115938, 46577.0, 47134.29298560456, 35779.642203578675, 37329.62670373262, 37666.0, 38087.857364279116, 38329.80835095918, 32437.18907027343, 36998.930117687574, 37162.07094822651, 37870.43194352032, 38490.845274641804, 31257.0, 37748.05629859275, 38042.809785952, 38273.04317392087, 38438.039049023995, 31757.682423175473, 33438.49842252417, 33548.496772547944, 35000.47499287462, 35210.8239458801, 24531.0, 24793.504129917663, 25212.24363269105, 25358.55434863677, 25641.743582563937], "value": 18999.649953268636, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [357.54056037991177, 379.45737595238813, 402.017895650986, 404.4176386004154, 424.7939749221629, 13847.584572462816, 20086.6986995195, 20978.0, 21501.462463438424, 21525.24661636843, 15786.84213157868, 19596.51008724779, 20057.498562535908, 20512.179512819508, 21014.57970840586, 5821.621594596349, 5966.910496342537, 6017.608855424383, 6037.879242415171, 6055.545834062441, 5881.382454842244, 5889.499392551638, 5928.822135335925, 5953.523718102563, 5975.581709280352, 5764.711764411783, 5862.355140934494, 5913.911291330612, 5935.0, 5974.074018527113, 5543.279373681416, 5872.119182122716, 5877.294724633029, 5888.998870192099, 5936.079907614305, 5137.101007323741, 5146.485351464861, 5175.870603234912, 5177.171652535614, 5182.663126896778, 20320.593588128155, 20970.895145524326, 25382.49235015308, 26032.479350413076, 27174.728252717614, 2185.27885797688, 2191.079746506455, 2586.610603509268, 2878.553824157259, 2948.86730097144, 14850.777238341434, 14905.701885962328, 15177.317020734094, 15305.928584999054, 15627.296771645304, 1710.469754376135, 1734.2456031626157, 1735.5053809664312, 1744.842964133234, 1767.301915743274, 128165.35917320447, 128395.35802320928, 132136.0, 132493.33753331268, 133757.99999999904, 10210.387376757419, 11462.083033357312, 11785.407210413696, 11826.467808948619, 11905.404729763477, 15182.392704291926, 15279.847201528064, 15302.770458443132, 15327.693446131016, 15357.308921098578, 7584.772456826194, 7601.695932162762, 8766.95616521926, 8926.955365223263, 8980.0, 7452.403807695479, 8852.513111778786, 8857.3799834011, 8949.463032218087, 8991.910080899239, 4641.721496710141, 4653.162430762428, 4654.674172807939, 4658.277966915066, 4688.859334219979, 1815.2738904438065, 1871.5482717639936, 2014.8791072535694, 2019.515316324101, 2025.8176764091008, 920.59953920045, 936.9718908432758, 964.7539877331376, 977.6947774720827, 985.3890587835588, 19511.902440487705, 20771.68842467364, 20817.375478735663, 20887.895560522404, 21033.68449473259, 975.1711045611315, 975.9219262458986, 1007.2697294461603, 1026.2610920137488, 1028.2699283508643, 10510.894891050992, 10930.180236482296, 11007.0, 11011.61459348935, 11021.338719676685, 492791.0, 505400.0, 508154.0, 604919.0, 608504.0, 115591.0, 143654.0, 143725.0, 147187.52812471954, 150597.24701376422, 984.5077461269366, 1009.7677534167036, 1023.5445226874118, 1026.3328836256344, 1031.2265800649513, 18270.26918923228, 18522.722159167894, 18585.34951276699, 18591.721124182877, 18612.906935465235, 56107.15839262496, 56328.71835640795, 56484.30547083594, 56550.586235344446, 57264.713676431355, 75485.49029019279, 79866.6006669963, 81244.0, 83563.5821820887, 85532.0, 8005.9599702001115, 8213.260806527202, 8276.29651479631, 8329.833403331779, 8580.313574914117, 11775.705607359885, 12318.938405307916, 12597.685057873627, 12650.810237846625, 12884.35578221104, 10938.289011214245, 11042.33745975244, 11275.32348059119, 11305.265157765049, 11382.829257560978, 10565.52455139536, 10584.629537966443, 10696.679099627023, 11090.057345125755, 11192.048675862643, 9894.50527473609, 10999.790023097294, 11153.776924461303, 11309.264897781617, 11486.023687986615, 6479.611223326616, 6971.2331643518155, 7310.780676579711, 7416.0729908761405, 7751.844963100819, 10371.533281002525, 10879.891201087888, 11014.39420831862, 11321.547138114713, 11347.773044539228], "value": 9713.557220896058, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1437.0730878583308, 1598.8800839937007, 1620.1170362152625, 1858.9163487643063, 1983.266191509142, 7853.293203611674, 8698.39111262212, 8977.640894364218, 8985.550722463868, 9058.184763371311, 8445.493270403766, 8675.958884933802, 8681.652733890653, 8691.91308086919, 8692.087330830267, 2110.2297661353573, 2281.2015794471968, 2361.622140457527, 2412.3245491262433, 2468.6420469031978, 2427.4659574893494, 2449.216250799745, 2450.0567281596623, 2452.460458699083, 2454.410941374066, 2373.76262373763, 2390.043982407042, 2411.855288682676, 2413.650020746991, 2413.81896357773, 2103.3584756649216, 2330.7436182019965, 2376.58409778289, 2432.1122790181457, 2433.2821817563863, 2413.095089341497, 2438.6220135878966, 2441.182203961675, 2442.255112190781, 2442.9144979925795, 8763.51800650964, 8833.381663283573, 8834.55827208637, 8856.291496680251, 8865.556722163867, 5111.309973153651, 5417.241586177938, 5434.510894019518, 5441.863953401157, 5452.754626041838, 3173.952390714141, 3502.614712381636, 3579.94630080549, 3663.3955397359405, 3686.2627474505207, 1045.6758404894506, 1058.3544038136731, 1058.867641544807, 1062.4156713807374, 1064.6965614799744, 11902.58340958071, 11923.224990375598, 11927.880721192763, 11930.343831089287, 11934.104942129288, 4744.833930812405, 4803.186854226316, 4882.902341953141, 4898.093852637202, 4914.410270767531, 10090.596376145018, 10115.848262276071, 10116.595336186618, 10118.392896426238, 10125.898741012643, 5411.269478620415, 5441.0, 5445.564354851603, 5450.836474905759, 5456.781728730806, 4262.488501379854, 4393.099414620059, 4583.106294272651, 4598.0, 4599.931001034987, 4028.2144981728866, 4112.876613701594, 4123.113530590937, 4137.958620413818, 4141.64795992344, 1167.3346192670267, 1314.4742103158621, 1349.7772867476756, 1350.9392077356542, 1360.5170164591625, 649.4252586461016, 679.7315060551055, 835.2733122183739, 932.7528205025674, 977.9462129582942, 5644.491995720405, 5646.237757902631, 5870.383609720918, 5951.464368206882, 6158.507319414436, 933.332667142996, 944.1974321826392, 981.601218263971, 987.086944576264, 1025.6410256410345, 3809.7523660961947, 3948.348522493814, 4040.878773636796, 4078.2455245779624, 4216.325387937976, 10491.895081049244, 10718.035376815968, 10842.132629389629, 10872.891271087346, 10880.347179169117, 10754.78490430187, 11000.559977600966, 11019.944900275448, 11037.503312350964, 11042.282251653616, 7214.567125972353, 7315.890261646079, 7964.283214510722, 8227.13615070409, 8365.456245344034, 5951.791687290924, 6042.969785151046, 6083.574149809471, 6106.389361063858, 6151.231096112986, 9877.654282100091, 10213.234007449619, 10286.897131028594, 10334.793304133726, 10354.0, 9560.378575392577, 10008.899911000797, 10113.241506886865, 10195.7451063724, 10296.17630589536, 6735.0, 6851.800934836483, 6952.0, 6964.582125072512, 7573.2048134945035, 7471.9626401867645, 7687.2697093776005, 7993.680252790056, 8146.75559733209, 8236.752897413087, 7185.461090418135, 7301.707931682886, 7392.949848415822, 7409.962950185431, 7438.218987006287, 7162.247963963709, 7166.677499512431, 7180.60506672138, 7272.672729727281, 7319.048523691886, 6899.0, 6938.514303998873, 7027.754028608974, 7060.752873649398, 7064.505484616027, 4762.8499687373005, 4850.53919877611, 4985.925211121908, 5017.347744793299, 5116.360454943132, 4973.502649735142, 5014.899702005867, 5026.447090819934, 5107.668001579886, 5312.282841816305], "value": 4604.899962728164, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [307.0894796927111, 307.9430305393504, 320.09413360190655, 327.1314659578819, 329.0835024456887, 4990.30135780991, 5046.300229377284, 5273.235380869772, 5447.891042179154, 5480.917786233204, 5095.210242412422, 5095.490450954902, 5194.480551944804, 5330.626856120073, 5340.973295133528, 3587.964120358789, 4102.487189101363, 4114.876553703393, 4161.459010328653, 4171.478565179353, 3812.866549670762, 3861.575226725058, 4143.544210136897, 4154.480689913761, 4155.563665815092, 3414.504896789964, 3786.3941769316916, 3891.571927088032, 3911.5892831252736, 4136.255474014676, 4060.776657283848, 4104.179164167179, 4113.691473139526, 4132.276851550967, 4143.523494798092, 3106.437189318821, 3454.274602333513, 3609.530761001053, 3610.494530765695, 3611.277744451094, 4483.718049262946, 4515.096980603892, 4848.660593758438, 5125.462361291635, 5132.255822905695, 4313.762743049131, 4357.586029327209, 4623.491415944277, 4693.178693728584, 4739.834105806315, 5357.303550538444, 5563.582731295129, 5606.290081525133, 5622.91565626516, 5638.0, 3536.3634545781747, 3586.8206589670413, 3608.4767708682093, 3982.5463705747484, 4000.5399379071196, 4570.977145114253, 5590.288709975659, 5699.694177377111, 5780.0, 5876.177335173079, 3902.1220225449615, 3912.256671232514, 3914.451976723261, 3917.334053210961, 3921.509811273591, 3933.4689816874356, 3937.586553411908, 3961.3661814110183, 3974.1058261890844, 3986.063275130342, 3891.4551962724677, 3972.245273398045, 4537.455505339314, 4598.494165641777, 4606.193916064675, 4890.0928637831275, 4979.0, 5659.057290209151, 5663.0, 5683.658980461186, 1268.0299570828324, 1325.7746183148695, 1341.684704094537, 1358.1579420759192, 1360.5102412857905, 761.5126319155745, 867.7657032601162, 908.9000209976897, 915.46445329483, 926.6710317837205, 5127.461616530285, 5135.974320128375, 5165.405978312467, 5176.249443830623, 5271.65734227274, 4626.653001024936, 5179.741012949337, 5437.537809286255, 5704.942950570524, 5719.7702493964, 4638.443386793539, 4690.413698287714, 4772.95227047732, 5224.973875130676, 5234.555062819627, 2828.618136551539, 2880.1215629233284, 2886.0043285066617, 2965.481040817848, 2999.3851260491547, 5609.831705048855, 5656.830295091154, 5730.193483192823, 5876.206712093898, 5904.468597826216, 4965.095419179748, 5263.526282634582, 5791.623544469596, 5812.912806307909, 5852.678102704307, 5304.416514183436, 5391.865203369947, 5399.352077750616, 5650.208970744181, 5883.470487656132, 5134.691918484903, 5431.9728401359325, 5697.715114244188, 5828.358880523224, 5897.970510147422, 4967.428745694291, 5197.8440646780655, 5797.76808927655, 5814.738336774771, 5826.563007774349, 5002.649814512949, 5424.674519528683, 5620.269364982526, 5851.268591426072, 6127.142200091902, 3564.7326450516834, 3809.561900381381, 3937.3503371943307, 3993.0416699992375, 3995.6403923647013, 5177.378714554277, 5204.34945631796, 5869.970650146722, 5888.0, 5897.616654917417, 4507.932381014353, 5110.821121260738, 5635.267415235993, 5736.569757268138, 5779.826605201851, 5089.898202036013, 5656.765577596909, 5746.626469279484, 5748.827535173951, 5765.0, 4879.682820616648, 4915.631327650369, 5210.687358758487, 5566.888662226814, 5572.665640061609, 5039.370078740158, 5317.415084340642, 5665.348484924121, 5683.431656834284, 5760.366359700513, 5513.807016754395, 5541.445855414588, 5561.304836895388, 5578.86052848682, 5586.944130558805], "value": 4086.2611065910387, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [651.5764752910608, 687.2405991379526, 718.1920339617926, 719.1621760648844, 740.522363075816, 15235.162066086361, 15507.224638768059, 15696.372145114181, 15747.606309842246, 15770.84229157708, 14264.429422823074, 14467.493637722682, 14505.27473626317, 14511.564653060399, 14791.186484743335, 904.267543289934, 919.3334832246635, 934.5841100710187, 936.3632729743772, 938.132227689388, 733.6368497593699, 769.4190885881152, 908.3459908865606, 934.4953724988499, 945.9716208513755, 1002.9648962286321, 1003.2325271167563, 1003.6336737090983, 1004.2518323848743, 1005.783756492354, 984.8276551603476, 988.1551273661024, 991.375433476905, 992.4045572656348, 992.6426486464903, 570.7916610437202, 711.1111111111111, 738.4092725819323, 742.9932441541687, 746.4700062955309, 13020.479180832755, 15182.164980926154, 15243.771343429857, 15270.618234544114, 15293.235338233044, 11671.824922626167, 11804.291742495392, 12367.690807729789, 12419.875801242053, 12655.746885062246, 10797.190210734148, 10864.945675271652, 10877.564897404094, 11308.491117899714, 11699.0, 3945.6054394560315, 4050.351943689025, 4170.186813571355, 4237.025484138635, 4281.165172791307, 75527.0, 78168.43663126706, 78222.21777782206, 78264.0, 78651.82022269671, 9147.0, 9213.262938964866, 9299.302552308603, 9362.485063321583, 9465.952670236606, 20358.18567257323, 20378.898105509674, 20388.694169587172, 20392.388228352876, 20421.489462763253, 6970.605342042873, 7009.754658586925, 7043.330883566053, 7085.822854428578, 7261.751875152989, 3957.4855268815445, 4008.819603117867, 4012.177503611811, 4012.839486420569, 4015.8393664253103, 3409.2670075933793, 3488.9476657849646, 3492.6553276988543, 3495.405781017233, 3496.3356962177613, 629.6568370238231, 644.452215616722, 645.1580687203245, 653.3042309939899, 653.4739534674633, 508.8043098717983, 523.5602094240855, 537.8815968359906, 567.7558649780551, 581.7702007706932, 7706.614669266514, 7711.877882016179, 7748.147703752583, 7825.0, 7851.136374998746, 499.9800007999712, 549.2996429552273, 552.0890530624501, 567.9914801277985, 580.6893312078103, 3109.5646609474693, 3536.8054756988613, 3605.94591081134, 3640.18095928414, 3693.9630603693618, 61291.08063379053, 77857.221427785, 81479.0, 84273.0, 84607.57696211479, 66845.66577167083, 70801.93797093048, 74872.62563687256, 75039.0, 75478.0, 66133.00800487997, 66503.66748166228, 66819.0, 67010.66494667495, 67037.66481167563, 7072.3634872861685, 7123.180834204133, 7194.676239569337, 7264.963675181802, 7277.3086556777025, 38785.03037424087, 41079.58920410877, 42490.51283205073, 42693.0, 42862.14275714406, 54505.72747136239, 54775.90448191093, 56264.0, 56302.310930672145, 56855.43144568501, 8246.299064579578, 9413.576389062371, 9564.330496865152, 9682.304052745758, 10026.799464010825, 10626.946865265625, 10773.0, 10886.346819190874, 11017.944910275397, 11052.889471105185, 8615.923009623797, 10103.191744660557, 10111.241656875616, 10345.741356465847, 10388.636397726043, 10022.899771002196, 10204.948975255373, 10338.896611033793, 10341.63804266847, 10590.152787777113, 9314.161725444743, 9592.472414017297, 9649.855252171083, 9848.359856609297, 11040.889591104307, 9535.0, 9625.56684949165, 9739.074787895139, 9918.355306905029, 9981.950090249502, 9521.333506654468, 9716.222702183666, 9754.365966212174, 9786.021397860442, 9835.409875407498], "value": 6615.691916441856, "unit": "score"}], "name": "dromaeo_css", "value": 7145.996490469833, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 104.0, 112.0, 110.0, 113.0, 112.0, 112.0, 112.0, 111.0, 109.0], "value": 110.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 117.0, 139.0, 195.0, 117.0, 139.0, 134.0, 116.0, 167.0, 116.0], "value": 139.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 189.0, 186.0, 183.0, 186.0, 183.0, 182.0, 180.0, 189.0, 187.0], "value": 185.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 75.0, 57.0, 124.0, 57.0, 61.0, 57.0, 60.0, 112.0, 55.0], "value": 73.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 100.0, 133.0, 79.0, 79.0, 78.0, 102.0, 81.0, 79.0, 106.0], "value": 92.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 87.0, 85.0, 88.0, 84.0, 88.0, 84.0, 88.0, 84.0], "value": 86.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 93.0, 92.0, 92.0, 93.0, 93.0, 93.0, 134.0], "value": 96.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 83.0, 89.0, 88.0, 136.0, 86.0, 87.0, 86.0, 85.0], "value": 91.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 60.0, 60.0, 60.0, 60.0, 59.0, 120.0, 56.0, 56.0], "value": 65.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 78.0, 64.0, 64.0, 71.0, 69.0, 66.0, 68.0, 67.0, 67.0], "value": 67.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 86.0, 87.0, 86.0, 86.0, 82.0, 85.0, 86.0, 86.0], "value": 85.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 150.0, 138.0, 135.0, 137.0, 139.0, 135.0, 138.0, 212.0, 140.0], "value": 147.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 168.0, 165.0, 162.0, 162.0, 165.0, 167.0, 165.0, 167.0, 165.0], "value": 165.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 65.0, 67.0, 67.0, 64.0, 65.0, 66.0, 67.0, 67.0], "value": 66.2, "unit": "score"}], "name": "kraken", "value": 1473.0000000000002, "alertThreshold": 2.0}]} 11:16:20 INFO - Return code: 0 11:16:20 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 11:16:20 ERROR - # TBPL SUCCESS # 11:16:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:16:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:16:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:16:20 INFO - 2016-05-20 11:16:20.327 screenresolution[1684:9906] starting screenresolution argv=screenresolution get 11:16:20 INFO - 2016-05-20 11:16:20.330 screenresolution[1684:9906] Display 0: 1600x1200x32@60 11:16:20 INFO - 2016-05-20 11:16:20.336 screenresolution[1685:9909] starting screenresolution argv=screenresolution list 11:16:20 INFO - Available Modes on Display 0 11:16:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:16:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:16:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:16:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:16:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:16:20 INFO - Intel Iris: 11:16:20 INFO - Chipset Model: Intel Iris 11:16:20 INFO - Type: GPU 11:16:20 INFO - Bus: Built-In 11:16:20 INFO - VRAM (Dynamic, Max): 1536 MB 11:16:20 INFO - Vendor: Intel (0x8086) 11:16:20 INFO - Device ID: 0x0a2e 11:16:20 INFO - Revision ID: 0x0009 11:16:20 INFO - Displays: 11:16:20 INFO - Display: 11:16:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:16:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:16:20 INFO - Display Serial Number: 1600x1200 60 11:16:20 INFO - Main Display: Yes 11:16:20 INFO - Mirror: Off 11:16:20 INFO - Online: Yes 11:16:20 INFO - Rotation: Supported 11:16:20 INFO - Return code: 0 11:16:20 INFO - Running post-action listener: _resource_record_post_action 11:16:20 INFO - ##### 11:16:20 INFO - ##### Finished run-tests step (success) 11:16:20 INFO - ##### 11:16:20 INFO - Running post-run listener: _resource_record_post_run 11:16:21 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 38041088; Write bytes: 693598208; Read time: 428; Write time: 1850 11:16:21 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 381518336; Write bytes: 370267648; Read time: 28341; Write time: 721 11:16:21 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 31788544; Write bytes: 296382976; Read time: 346; Write time: 1080 11:16:21 INFO - Running post-run listener: _upload_blobber_files 11:16:21 INFO - Blob upload gear active. 11:16:21 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:16:21 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 11:16:21 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', 'Larch', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:16:21 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 Larch -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:16:22 INFO - (blobuploader) - INFO - Open directory for files ... 11:16:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:16:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:16:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:16:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:16:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:16:22 INFO - (blobuploader) - INFO - Done attempting. 11:16:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:16:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:16:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:16:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:16:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:16:23 INFO - (blobuploader) - INFO - Done attempting. 11:16:23 INFO - (blobuploader) - INFO - Iteration through files over. 11:16:23 INFO - Return code: 0 11:16:23 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:16:23 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:16:23 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/3e71f9c68dc9219a3fbb23e839df6451aad33ee6672d434f09bc59d3751f9c4bfaf66417373d4c88ca2280736af3caa450acbc98cb98d8c5b2472c42042fee79", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/8c8a3d309c51e6801a31bd227637e01cba165c866982f3e66883df2775699ea1e51ba979f0bc5a64a5e78f8edbfd8a84d7ebdf8d7cd2644600dc4e7729ee4914"} 11:16:23 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:16:23 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:16:23 INFO - Contents: 11:16:23 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/3e71f9c68dc9219a3fbb23e839df6451aad33ee6672d434f09bc59d3751f9c4bfaf66417373d4c88ca2280736af3caa450acbc98cb98d8c5b2472c42042fee79", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/8c8a3d309c51e6801a31bd227637e01cba165c866982f3e66883df2775699ea1e51ba979f0bc5a64a5e78f8edbfd8a84d7ebdf8d7cd2644600dc4e7729ee4914"} 11:16:23 INFO - Running post-run listener: copy_logs_to_upload_dir 11:16:23 INFO - Copying logs to upload dir... 11:16:23 INFO - mkdir: /builds/slave/test/build/upload/logs 11:16:23 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.286149 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-05-20 11:16:23.717592) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-20 11:16:23.721470) ========= 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.lL1OKoRNi4/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.HOYZx6maSE/Listeners TMPDIR=/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/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/Larch/sha512/3e71f9c68dc9219a3fbb23e839df6451aad33ee6672d434f09bc59d3751f9c4bfaf66417373d4c88ca2280736af3caa450acbc98cb98d8c5b2472c42042fee79", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/8c8a3d309c51e6801a31bd227637e01cba165c866982f3e66883df2775699ea1e51ba979f0bc5a64a5e78f8edbfd8a84d7ebdf8d7cd2644600dc4e7729ee4914"} build_url:https://queue.taskcluster.net/v1/task/OhgntsRSQO-kvm-B3-LpYQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011179 build_url: 'https://queue.taskcluster.net/v1/task/OhgntsRSQO-kvm-B3-LpYQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/3e71f9c68dc9219a3fbb23e839df6451aad33ee6672d434f09bc59d3751f9c4bfaf66417373d4c88ca2280736af3caa450acbc98cb98d8c5b2472c42042fee79", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/8c8a3d309c51e6801a31bd227637e01cba165c866982f3e66883df2775699ea1e51ba979f0bc5a64a5e78f8edbfd8a84d7ebdf8d7cd2644600dc4e7729ee4914"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-20 11:16:23.749965) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 11:16:23.750276) ========= 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.lL1OKoRNi4/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.HOYZx6maSE/Listeners TMPDIR=/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/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.005055 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 11:16:23.797675) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-20 11:16:23.797975) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-20 11:16:23.798300) ========= ========= Total master_lag: 0.15 =========