builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0130 starttime: 1462983546.89 results: success (0) buildid: 20160511073416 builduid: 01d3b6ca3381478b86e8e726f0c2d4d0 revision: a8f80b823c8d5434ae50bcec715846e854365f42 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-11 09:19:06.886628) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-11 09:19:06.887138) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-11 09:19:06.887419) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-11 09:19:06.905750) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 09:19:06.906121) ========= 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.WfOuxcAtG1/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.kdaAjT1HOK/Listeners TMPDIR=/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/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-11 09:19:06-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 12.2M=0.001s 2016-05-11 09:19:06 (12.2 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.078534 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 09:19:07.001401) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 09:19:07.001719) ========= 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.WfOuxcAtG1/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.kdaAjT1HOK/Listeners TMPDIR=/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/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.020707 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 09:19:07.050390) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-11 09:19:07.050762) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev a8f80b823c8d5434ae50bcec715846e854365f42 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev a8f80b823c8d5434ae50bcec715846e854365f42 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.WfOuxcAtG1/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.kdaAjT1HOK/Listeners TMPDIR=/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/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-11 09:19:07,126 truncating revision to first 12 chars 2016-05-11 09:19:07,126 Setting DEBUG logging. 2016-05-11 09:19:07,126 attempt 1/10 2016-05-11 09:19:07,126 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/a8f80b823c8d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-11 09:19:08,364 unpacking tar archive at: fx-team-a8f80b823c8d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.490740 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-11 09:19:08.558856) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-11 09:19:08.559190) ========= script_repo_revision: a8f80b823c8d5434ae50bcec715846e854365f42 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-11 09:19:08.559648) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-11 09:19:08.559918) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-11 09:19:08.573723) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-05-11 09:19:08.574114) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.WfOuxcAtG1/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.kdaAjT1HOK/Listeners TMPDIR=/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/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 09:19:08 INFO - MultiFileLogger online at 20160511 09:19:08 in /builds/slave/test 09:19:08 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 09:19:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:19:08 INFO - {'append_to_log': False, 09:19:08 INFO - 'base_work_dir': '/builds/slave/test', 09:19:08 INFO - 'blob_upload_branch': 'Fx-Team', 09:19:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:19:08 INFO - 'branch': 'Fx-Team', 09:19:08 INFO - 'buildbot_json_path': 'buildprops.json', 09:19:08 INFO - 'config_files': ('talos/mac_config.py',), 09:19:08 INFO - 'default_actions': ('clobber', 09:19:08 INFO - 'read-buildbot-config', 09:19:08 INFO - 'download-and-extract', 09:19:08 INFO - 'populate-webroot', 09:19:08 INFO - 'create-virtualenv', 09:19:08 INFO - 'install', 09:19:08 INFO - 'run-tests'), 09:19:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:19:08 INFO - 'download_minidump_stackwalk': True, 09:19:08 INFO - 'download_symbols': 'ondemand', 09:19:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:19:08 INFO - 'tooltool.py': '/tools/tooltool.py', 09:19:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:19:08 INFO - '/tools/misc-python/virtualenv.py')}, 09:19:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:19:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:19:08 INFO - 'installer_path': 'installer.exe', 09:19:08 INFO - 'log_level': 'info', 09:19:08 INFO - 'log_name': 'talos', 09:19:08 INFO - 'log_to_console': True, 09:19:08 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:19:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:19:08 INFO - 'opt_config_files': (), 09:19:08 INFO - 'pip_index': False, 09:19:08 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:19:08 INFO - 'cmd': ('bash', 09:19:08 INFO - '-c', 09:19:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:19:08 INFO - 'enabled': True, 09:19:08 INFO - 'halt_on_failure': False, 09:19:08 INFO - 'name': 'check_screen_resolution'},), 09:19:08 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:19:08 INFO - 'cmd': ('bash', 09:19:08 INFO - '-c', 09:19:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:19:08 INFO - 'enabled': True, 09:19:08 INFO - 'halt_on_failure': False, 09:19:08 INFO - 'name': 'check_screen_resolution'},), 09:19:08 INFO - 'run_cmd_checks_enabled': True, 09:19:08 INFO - 'sps_profile': False, 09:19:08 INFO - 'sps_profile_interval': 0, 09:19:08 INFO - 'suite': 'dromaeojs', 09:19:08 INFO - 'system_bits': '32', 09:19:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:19:08 INFO - 'title': 't-yosemite-r7-0130', 09:19:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:19:08 INFO - 'use_talos_json': True, 09:19:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:19:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:19:08 INFO - 'work_dir': 'build'} 09:19:08 INFO - ##### 09:19:08 INFO - ##### Running clobber step. 09:19:08 INFO - ##### 09:19:08 INFO - Running pre-action listener: _resource_record_pre_action 09:19:08 INFO - Running main action method: clobber 09:19:08 INFO - rmtree: /builds/slave/test/build 09:19:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:19:10 INFO - Running post-action listener: _resource_record_post_action 09:19:10 INFO - ##### 09:19:10 INFO - ##### Finished clobber step (success) 09:19:10 INFO - ##### 09:19:10 INFO - ##### 09:19:10 INFO - ##### Running read-buildbot-config step. 09:19:10 INFO - ##### 09:19:10 INFO - Running pre-action listener: _resource_record_pre_action 09:19:10 INFO - Running main action method: read_buildbot_config 09:19:10 INFO - Using buildbot properties: 09:19:10 INFO - { 09:19:10 INFO - "project": "", 09:19:10 INFO - "product": "firefox", 09:19:10 INFO - "script_repo_revision": "production", 09:19:10 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 09:19:10 INFO - "repository": "", 09:19:10 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 09:19:10 INFO - "buildid": "20160511073416", 09:19:10 INFO - "pgo_build": "False", 09:19:10 INFO - "basedir": "/builds/slave/test", 09:19:10 INFO - "buildnumber": 9, 09:19:10 INFO - "slavename": "t-yosemite-r7-0130", 09:19:10 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 09:19:10 INFO - "platform": "yosemite_r7", 09:19:10 INFO - "scheduler": "tests-fx-team-macosx64-talos", 09:19:10 INFO - "branch": "fx-team", 09:19:10 INFO - "repo_path": "integration/fx-team", 09:19:10 INFO - "revision": "a8f80b823c8d5434ae50bcec715846e854365f42", 09:19:10 INFO - "stage_platform": "macosx64", 09:19:10 INFO - "builduid": "01d3b6ca3381478b86e8e726f0c2d4d0", 09:19:10 INFO - "slavebuilddir": "test" 09:19:10 INFO - } 09:19:10 INFO - Found installer url https://queue.taskcluster.net/v1/task/NcfWqedxRr-BhSdcTJbSdw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 09:19:10 INFO - Running post-action listener: _resource_record_post_action 09:19:10 INFO - ##### 09:19:10 INFO - ##### Finished read-buildbot-config step (success) 09:19:10 INFO - ##### 09:19:10 INFO - ##### 09:19:10 INFO - ##### Running download-and-extract step. 09:19:10 INFO - ##### 09:19:10 INFO - Running pre-action listener: _resource_record_pre_action 09:19:10 INFO - Running main action method: download_and_extract 09:19:10 INFO - mkdir: /builds/slave/test/build/tests 09:19:10 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')]} 09:19:10 INFO - https://queue.taskcluster.net/v1/task/NcfWqedxRr-BhSdcTJbSdw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 09:19:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NcfWqedxRr-BhSdcTJbSdw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 09:19:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NcfWqedxRr-BhSdcTJbSdw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 09:19:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NcfWqedxRr-BhSdcTJbSdw/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 09:19:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NcfWqedxRr-BhSdcTJbSdw/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 09:19:11 INFO - Downloaded 1199 bytes. 09:19:11 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 09:19:11 INFO - Using the following test package requirements: 09:19:11 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 09:19:11 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:19:11 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 09:19:11 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:19:11 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 09:19:11 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:19:11 INFO - u'jsshell-mac.zip'], 09:19:11 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:19:11 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 09:19:11 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 09:19:11 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:19:11 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 09:19:11 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:19:11 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 09:19:11 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:19:11 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 09:19:11 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:19:11 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 09:19:11 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:19:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:19:11 INFO - https://queue.taskcluster.net/v1/task/NcfWqedxRr-BhSdcTJbSdw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:19:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NcfWqedxRr-BhSdcTJbSdw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:19:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NcfWqedxRr-BhSdcTJbSdw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:19:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NcfWqedxRr-BhSdcTJbSdw/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 09:19:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NcfWqedxRr-BhSdcTJbSdw/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 09:19:13 INFO - Downloaded 18150526 bytes. 09:19:13 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'] 09:19:13 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 09:19:16 INFO - Return code: 0 09:19:16 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 09:19: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')]} 09:19:16 INFO - https://queue.taskcluster.net/v1/task/NcfWqedxRr-BhSdcTJbSdw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:19:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NcfWqedxRr-BhSdcTJbSdw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:19:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NcfWqedxRr-BhSdcTJbSdw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:19:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NcfWqedxRr-BhSdcTJbSdw/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 09:19:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NcfWqedxRr-BhSdcTJbSdw/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 09:19:17 INFO - Downloaded 18150526 bytes. 09:19:17 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'] 09:19:17 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 09:19:20 INFO - Return code: 0 09:19:20 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')]} 09:19:20 INFO - https://queue.taskcluster.net/v1/task/NcfWqedxRr-BhSdcTJbSdw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 09:19:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NcfWqedxRr-BhSdcTJbSdw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 09:19:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NcfWqedxRr-BhSdcTJbSdw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 09:19:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NcfWqedxRr-BhSdcTJbSdw/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 09:19:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NcfWqedxRr-BhSdcTJbSdw/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 09:19:21 INFO - Downloaded 10840152 bytes. 09:19:21 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'] 09:19:21 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 09:19:22 INFO - Return code: 0 09:19:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:19:22 INFO - https://queue.taskcluster.net/v1/task/NcfWqedxRr-BhSdcTJbSdw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 09:19:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NcfWqedxRr-BhSdcTJbSdw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 09:19:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NcfWqedxRr-BhSdcTJbSdw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 09:19:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NcfWqedxRr-BhSdcTJbSdw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 09:19:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NcfWqedxRr-BhSdcTJbSdw/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 09:19:25 INFO - Downloaded 109798580 bytes. 09:19:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NcfWqedxRr-BhSdcTJbSdw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 09:19:25 INFO - mkdir: /builds/slave/test/properties 09:19:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:19:25 INFO - Writing to file /builds/slave/test/properties/build_url 09:19:25 INFO - Contents: 09:19:25 INFO - build_url:https://queue.taskcluster.net/v1/task/NcfWqedxRr-BhSdcTJbSdw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 09:19:26 INFO - Running post-action listener: _resource_record_post_action 09:19:26 INFO - Running post-action listener: set_extra_try_arguments 09:19:26 INFO - ##### 09:19:26 INFO - ##### Finished download-and-extract step (success) 09:19:26 INFO - ##### 09:19:26 INFO - ##### 09:19:26 INFO - ##### Running populate-webroot step. 09:19:26 INFO - ##### 09:19:26 INFO - Running pre-action listener: _resource_record_pre_action 09:19:26 INFO - Running main action method: populate_webroot 09:19:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:19:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:19:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:19:26 INFO - '--tppagecycles', 09:19:26 INFO - '7'], 09:19:26 INFO - 'tests': ['tsvgm']}}, 09:19:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:19:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:19:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:19:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:19:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:19:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:19:26 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:19:26 INFO - 'talos_options': ['--disable-e10s'], 09:19:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:19:26 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:19:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:19:26 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:19:26 INFO - 'talos_options': ['--disable-e10s'], 09:19:26 INFO - 'tests': ['damp', 'tps']}, 09:19:26 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:19:26 INFO - 'tests': ['damp', 'tps']}, 09:19:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:19:26 INFO - 'tests': ['dromaeo_dom']}, 09:19:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:19:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:19:26 INFO - 'tests': ['a11yr', 09:19:26 INFO - 'ts_paint', 09:19:26 INFO - 'tpaint', 09:19:26 INFO - 'sessionrestore', 09:19:26 INFO - 'sessionrestore_no_auto_restore', 09:19:26 INFO - 'tabpaint']}, 09:19:26 INFO - 'other-e10s': {'tests': ['a11yr', 09:19:26 INFO - 'ts_paint', 09:19:26 INFO - 'tpaint', 09:19:26 INFO - 'sessionrestore', 09:19:26 INFO - 'sessionrestore_no_auto_restore', 09:19:26 INFO - 'tabpaint']}, 09:19:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:19:26 INFO - 'ts_paint', 09:19:26 INFO - 'tpaint', 09:19:26 INFO - 'sessionrestore', 09:19:26 INFO - 'sessionrestore_no_auto_restore', 09:19:26 INFO - 'tabpaint']}, 09:19:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:19:26 INFO - 'ts_paint', 09:19:26 INFO - 'tpaint', 09:19:26 INFO - 'sessionrestore', 09:19:26 INFO - 'sessionrestore_no_auto_restore', 09:19:26 INFO - 'tabpaint']}, 09:19:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:19:26 INFO - 'tests': ['a11yr', 09:19:26 INFO - 'ts_paint', 09:19:26 INFO - 'tpaint', 09:19:26 INFO - 'sessionrestore', 09:19:26 INFO - 'sessionrestore_no_auto_restore', 09:19:26 INFO - 'tabpaint']}, 09:19:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:19:26 INFO - 'tests': ['a11yr', 09:19:26 INFO - 'ts_paint', 09:19:26 INFO - 'tpaint', 09:19:26 INFO - 'sessionrestore', 09:19:26 INFO - 'sessionrestore_no_auto_restore', 09:19:26 INFO - 'tabpaint']}, 09:19:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:19:26 INFO - 'tests': ['tsvgx', 09:19:26 INFO - 'tsvgr_opacity', 09:19:26 INFO - 'tart', 09:19:26 INFO - 'tscrollx', 09:19:26 INFO - 'cart']}, 09:19:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:19:26 INFO - 'tsvgr_opacity', 09:19:26 INFO - 'tart', 09:19:26 INFO - 'tscrollx', 09:19:26 INFO - 'cart']}, 09:19:26 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:19:26 INFO - 'talos_options': ['--disable-e10s'], 09:19:26 INFO - 'tests': ['tp5o']}, 09:19:26 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:19:26 INFO - 'tests': ['tp5o']}, 09:19:26 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:19:26 INFO - 'talos_options': ['--disable-e10s', 09:19:26 INFO - '--xperf_path', 09:19:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:19:26 INFO - 'tests': ['tp5n']}, 09:19:26 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:19:26 INFO - 'talos_options': ['--xperf_path', 09:19:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:19:26 INFO - 'tests': ['tp5n']}}, 09:19:26 INFO - 'talos.zip': {'path': '', 09:19:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:19:26 INFO - Running post-action listener: _resource_record_post_action 09:19:26 INFO - ##### 09:19:26 INFO - ##### Finished populate-webroot step (success) 09:19:26 INFO - ##### 09:19:26 INFO - ##### 09:19:26 INFO - ##### Running create-virtualenv step. 09:19:26 INFO - ##### 09:19:26 INFO - Running pre-action listener: _resource_record_pre_action 09:19:26 INFO - Running main action method: create_virtualenv 09:19:26 INFO - Creating virtualenv /builds/slave/test/build/venv 09:19:26 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:19:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:19:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:19:26 INFO - Using real prefix '/tools/python27' 09:19:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:19:27 INFO - Installing distribute.............................................................................................................................................................................................done. 09:19:30 INFO - Installing pip.................done. 09:19:30 INFO - Return code: 0 09:19:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:19:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:19:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:19:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:19:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:19:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cfa0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfa6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe7149afa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce44d30>, '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 0x10cc68db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ce3bb20>, '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.WfOuxcAtG1/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.kdaAjT1HOK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/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 09:19:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:19:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:19:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WfOuxcAtG1/Render', 09:19:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:19:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:19:30 INFO - 'HOME': '/Users/cltbld', 09:19:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:19:30 INFO - 'LOGNAME': 'cltbld', 09:19:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:19:30 INFO - 'MOZ_NO_REMOTE': '1', 09:19:30 INFO - 'NO_EM_RESTART': '1', 09:19:30 INFO - 'PAGER': '/bin/cat', 09:19:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:19:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:19:30 INFO - 'PWD': '/builds/slave/test', 09:19:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:19:30 INFO - 'SHELL': '/bin/bash', 09:19:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kdaAjT1HOK/Listeners', 09:19:30 INFO - 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/', 09:19:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:19:30 INFO - 'USER': 'cltbld', 09:19:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:19:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:19:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:19:30 INFO - 'XPC_FLAGS': '0x0', 09:19:30 INFO - 'XPC_SERVICE_NAME': '0', 09:19:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:19:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:19:30 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:19:30 INFO - Cleaning up... 09:19:30 INFO - Return code: 0 09:19:30 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 09:19:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:19:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:19:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:19:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:19:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cfa0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfa6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe7149afa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce44d30>, '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 0x10cc68db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ce3bb20>, '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.WfOuxcAtG1/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.kdaAjT1HOK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/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 09:19:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 09:19:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 09:19:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WfOuxcAtG1/Render', 09:19:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:19:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:19:30 INFO - 'HOME': '/Users/cltbld', 09:19:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:19:30 INFO - 'LOGNAME': 'cltbld', 09:19:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:19:30 INFO - 'MOZ_NO_REMOTE': '1', 09:19:30 INFO - 'NO_EM_RESTART': '1', 09:19:30 INFO - 'PAGER': '/bin/cat', 09:19:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:19:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:19:30 INFO - 'PWD': '/builds/slave/test', 09:19:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:19:30 INFO - 'SHELL': '/bin/bash', 09:19:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kdaAjT1HOK/Listeners', 09:19:30 INFO - 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/', 09:19:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:19:30 INFO - 'USER': 'cltbld', 09:19:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:19:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:19:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:19:30 INFO - 'XPC_FLAGS': '0x0', 09:19:30 INFO - 'XPC_SERVICE_NAME': '0', 09:19:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:19:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:19:30 INFO - Downloading/unpacking psutil>=0.7.1 09:19:30 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 09:19:30 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 09:19:30 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 09:19:30 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 09:19:30 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 09:19:30 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 09:19:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:19:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:19:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:19:33 INFO - Installing collected packages: psutil 09:19:33 INFO - Running setup.py install for psutil 09:19:33 INFO - building 'psutil._psutil_osx' extension 09:19:33 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 09:19:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:19:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:19:34 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:19:34 INFO - building 'psutil._psutil_posix' extension 09:19:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:19:34 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:19:34 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:19:34 INFO - ^ 09:19:34 INFO - 1 warning generated. 09:19:34 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 09:19:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:19:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:19:34 INFO - Successfully installed psutil 09:19:34 INFO - Cleaning up... 09:19:34 INFO - Return code: 0 09:19:34 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 09:19:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:19:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:19:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:19:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:19:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:34 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 0x10cfa0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfa6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe7149afa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce44d30>, '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 0x10cc68db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ce3bb20>, '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.WfOuxcAtG1/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.kdaAjT1HOK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/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 09:19:34 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 09:19:34 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 09:19:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WfOuxcAtG1/Render', 09:19:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:19:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:19:34 INFO - 'HOME': '/Users/cltbld', 09:19:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:19:34 INFO - 'LOGNAME': 'cltbld', 09:19:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:19:34 INFO - 'MOZ_NO_REMOTE': '1', 09:19:34 INFO - 'NO_EM_RESTART': '1', 09:19:34 INFO - 'PAGER': '/bin/cat', 09:19:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:19:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:19:34 INFO - 'PWD': '/builds/slave/test', 09:19:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:19:34 INFO - 'SHELL': '/bin/bash', 09:19:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kdaAjT1HOK/Listeners', 09:19:34 INFO - 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/', 09:19:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:19:34 INFO - 'USER': 'cltbld', 09:19:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:19:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:19:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:19:34 INFO - 'XPC_FLAGS': '0x0', 09:19:34 INFO - 'XPC_SERVICE_NAME': '0', 09:19:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:19:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:19:34 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:19:34 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 09:19:34 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 09:19:34 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 09:19:34 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 09:19:34 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 09:19:34 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 09:19:37 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:19:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:19:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:19:37 INFO - Installing collected packages: mozsystemmonitor 09:19:37 INFO - Running setup.py install for mozsystemmonitor 09:19:37 INFO - Successfully installed mozsystemmonitor 09:19:37 INFO - Cleaning up... 09:19:37 INFO - Return code: 0 09:19:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:19: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')]} 09:19:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:19:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19: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')]} 09:19:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:19:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:37 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 0x10cfa0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfa6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe7149afa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce44d30>, '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 0x10cc68db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ce3bb20>, '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.WfOuxcAtG1/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.kdaAjT1HOK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/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 09:19:37 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 09:19:37 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 09:19:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WfOuxcAtG1/Render', 09:19:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:19:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:19:37 INFO - 'HOME': '/Users/cltbld', 09:19:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:19:37 INFO - 'LOGNAME': 'cltbld', 09:19:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:19:37 INFO - 'MOZ_NO_REMOTE': '1', 09:19:37 INFO - 'NO_EM_RESTART': '1', 09:19:37 INFO - 'PAGER': '/bin/cat', 09:19:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:19:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:19:37 INFO - 'PWD': '/builds/slave/test', 09:19:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:19:37 INFO - 'SHELL': '/bin/bash', 09:19:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kdaAjT1HOK/Listeners', 09:19:37 INFO - 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/', 09:19:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:19:37 INFO - 'USER': 'cltbld', 09:19:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:19:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:19:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:19:37 INFO - 'XPC_FLAGS': '0x0', 09:19:37 INFO - 'XPC_SERVICE_NAME': '0', 09:19:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:19:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:19:37 INFO - Downloading/unpacking blobuploader==1.2.4 09:19:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:19:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:19:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:19:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:19:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:19:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:19:40 INFO - Downloading blobuploader-1.2.4.tar.gz 09:19:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:19:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:19:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:19:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:19:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:19:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:19:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:19:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:19:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:19:40 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:19:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:19:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:19:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:19:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:19:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:19:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:19:41 INFO - Downloading docopt-0.6.1.tar.gz 09:19:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:19:41 INFO - Installing collected packages: blobuploader, requests, docopt 09:19:41 INFO - Running setup.py install for blobuploader 09:19:41 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:19:41 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:19:41 INFO - Running setup.py install for requests 09:19:42 INFO - Running setup.py install for docopt 09:19:42 INFO - Successfully installed blobuploader requests docopt 09:19:42 INFO - Cleaning up... 09:19:42 INFO - Return code: 0 09:19:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:19: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')]} 09:19:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:19:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19: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')]} 09:19:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:19:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:42 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 0x10cfa0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfa6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe7149afa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce44d30>, '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 0x10cc68db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ce3bb20>, '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.WfOuxcAtG1/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.kdaAjT1HOK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/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 09:19:42 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 09:19:42 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 09:19:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WfOuxcAtG1/Render', 09:19:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:19:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:19:42 INFO - 'HOME': '/Users/cltbld', 09:19:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:19:42 INFO - 'LOGNAME': 'cltbld', 09:19:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:19:42 INFO - 'MOZ_NO_REMOTE': '1', 09:19:42 INFO - 'NO_EM_RESTART': '1', 09:19:42 INFO - 'PAGER': '/bin/cat', 09:19:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:19:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:19:42 INFO - 'PWD': '/builds/slave/test', 09:19:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:19:42 INFO - 'SHELL': '/bin/bash', 09:19:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kdaAjT1HOK/Listeners', 09:19:42 INFO - 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/', 09:19:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:19:42 INFO - 'USER': 'cltbld', 09:19:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:19:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:19:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:19:42 INFO - 'XPC_FLAGS': '0x0', 09:19:42 INFO - 'XPC_SERVICE_NAME': '0', 09:19:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:19:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:19:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:19:42 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-oeXFp0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:19:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:19:42 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-CqUPkW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:19:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:19:42 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-rfdqgJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:19:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:19:42 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-xbWaJ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:19:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:19:43 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-fJRoc1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:19:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:19:43 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-JCa_47-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:19:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:19:43 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-YqmIkz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:19:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:19:43 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-BwFeD1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:19:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:19:43 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-Y0ZEb9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:19:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:19:43 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-yvMgK5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:19:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:19:43 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-IUoBfI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:19:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:19:43 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-ubpnyA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:19:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:19:43 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-AMDg9G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:19:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:19:44 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-PtZ6DK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:19:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:19:44 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-fvye9B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:19:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:19:44 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-d7nC8w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:19:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:19:44 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-y47c8p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:19:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:19:44 INFO - Running setup.py install for manifestparser 09:19:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:19:44 INFO - Running setup.py install for mozcrash 09:19:44 INFO - Running setup.py install for mozdebug 09:19:44 INFO - Running setup.py install for mozdevice 09:19:44 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:19:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:19:45 INFO - Running setup.py install for mozfile 09:19:45 INFO - Running setup.py install for mozhttpd 09:19:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:19:45 INFO - Running setup.py install for mozinfo 09:19:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:19:45 INFO - Running setup.py install for mozInstall 09:19:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:19:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:19:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:19:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:19:45 INFO - Running setup.py install for mozleak 09:19:45 INFO - Running setup.py install for mozlog 09:19:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:19:45 INFO - Running setup.py install for moznetwork 09:19:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:19:46 INFO - Running setup.py install for mozprocess 09:19:46 INFO - Running setup.py install for mozprofile 09:19:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:19:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:19:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:19:46 INFO - Running setup.py install for mozrunner 09:19:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:19:46 INFO - Running setup.py install for mozscreenshot 09:19:46 INFO - Running setup.py install for moztest 09:19:46 INFO - Running setup.py install for mozversion 09:19:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:19:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:19:46 INFO - Cleaning up... 09:19:46 INFO - Return code: 0 09:19:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:19:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:19:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:19:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:19:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:19:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:46 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 0x10cfa0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfa6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe7149afa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce44d30>, '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 0x10cc68db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ce3bb20>, '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.WfOuxcAtG1/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.kdaAjT1HOK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/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 09:19:46 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 09:19:46 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 09:19:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WfOuxcAtG1/Render', 09:19:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:19:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:19:46 INFO - 'HOME': '/Users/cltbld', 09:19:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:19:46 INFO - 'LOGNAME': 'cltbld', 09:19:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:19:46 INFO - 'MOZ_NO_REMOTE': '1', 09:19:46 INFO - 'NO_EM_RESTART': '1', 09:19:46 INFO - 'PAGER': '/bin/cat', 09:19:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:19:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:19:46 INFO - 'PWD': '/builds/slave/test', 09:19:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:19:46 INFO - 'SHELL': '/bin/bash', 09:19:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kdaAjT1HOK/Listeners', 09:19:46 INFO - 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/', 09:19:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:19:46 INFO - 'USER': 'cltbld', 09:19:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:19:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:19:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:19:46 INFO - 'XPC_FLAGS': '0x0', 09:19:46 INFO - 'XPC_SERVICE_NAME': '0', 09:19:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:19:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:19:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:19:47 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-68bI4Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:19:47 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)) 09:19:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:19:47 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-gVV4VC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:19:47 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)) 09:19:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:19:47 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-Q3Pcc8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:19:47 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)) 09:19:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:19:47 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-32tCeh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:19:47 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)) 09:19:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:19:47 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-dTko14-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:19:47 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)) 09:19:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:19:47 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-Ihjk4t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:19:47 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)) 09:19:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:19:47 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-9HxqUs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:19:47 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)) 09:19:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:19:47 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-N8iPR0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:19:48 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)) 09:19:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:19:48 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-Rsb6lI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:19:48 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)) 09:19:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:19:48 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-cRLNgJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:19:48 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)) 09:19:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:19:48 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-qF8sR4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:19:48 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)) 09:19:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:19:48 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-zVPLBY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:19:48 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)) 09:19:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:19:48 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-0CgaXU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:19:48 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)) 09:19:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:19:48 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-CEr8Oo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:19:48 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)) 09:19:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:19:48 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-G1Nlqb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:19:48 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)) 09:19:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:19:48 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-UyYBqj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:19:48 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)) 09:19:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:19:48 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-CI0tBG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:19:49 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)) 09:19:49 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)) 09:19:49 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)) 09:19:49 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)) 09:19:49 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)) 09:19:49 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)) 09:19:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:19: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 09:19: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 09:19: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 09:19: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 09:19: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 09:19: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 09:19:51 INFO - Downloading blessings-1.6.tar.gz 09:19:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:19:51 INFO - Installing collected packages: blessings 09:19:51 INFO - Running setup.py install for blessings 09:19:51 INFO - Successfully installed blessings 09:19:51 INFO - Cleaning up... 09:19:51 INFO - Return code: 0 09:19:51 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:19:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:19:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:19:52 INFO - Reading from file tmpfile_stdout 09:19:52 INFO - Current package versions: 09:19:52 INFO - blessings == 1.6 09:19:52 INFO - blobuploader == 1.2.4 09:19:52 INFO - docopt == 0.6.1 09:19:52 INFO - manifestparser == 1.1 09:19:52 INFO - mozInstall == 1.12 09:19:52 INFO - mozcrash == 0.17 09:19:52 INFO - mozdebug == 0.1 09:19:52 INFO - mozdevice == 0.48 09:19:52 INFO - mozfile == 1.2 09:19:52 INFO - mozhttpd == 0.7 09:19:52 INFO - mozinfo == 0.9 09:19:52 INFO - mozleak == 0.1 09:19:52 INFO - mozlog == 3.1 09:19:52 INFO - moznetwork == 0.27 09:19:52 INFO - mozprocess == 0.22 09:19:52 INFO - mozprofile == 0.28 09:19:52 INFO - mozrunner == 6.11 09:19:52 INFO - mozscreenshot == 0.1 09:19:52 INFO - mozsystemmonitor == 0.0 09:19:52 INFO - moztest == 0.7 09:19:52 INFO - mozversion == 1.4 09:19:52 INFO - psutil == 3.1.1 09:19:52 INFO - requests == 1.2.3 09:19:52 INFO - wsgiref == 0.1.2 09:19:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:19:52 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')]} 09:19:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:19:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:52 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')]} 09:19:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:19:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:52 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 0x10cfa0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfa6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe7149afa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce44d30>, '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 0x10cc68db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ce3bb20>, '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.WfOuxcAtG1/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.kdaAjT1HOK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/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 09:19:52 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 09:19:52 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 09:19:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WfOuxcAtG1/Render', 09:19:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:19:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:19:52 INFO - 'HOME': '/Users/cltbld', 09:19:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:19:52 INFO - 'LOGNAME': 'cltbld', 09:19:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:19:52 INFO - 'MOZ_NO_REMOTE': '1', 09:19:52 INFO - 'NO_EM_RESTART': '1', 09:19:52 INFO - 'PAGER': '/bin/cat', 09:19:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:19:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:19:52 INFO - 'PWD': '/builds/slave/test', 09:19:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:19:52 INFO - 'SHELL': '/bin/bash', 09:19:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kdaAjT1HOK/Listeners', 09:19:52 INFO - 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/', 09:19:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:19:52 INFO - 'USER': 'cltbld', 09:19:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:19:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:19:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:19:52 INFO - 'XPC_FLAGS': '0x0', 09:19:52 INFO - 'XPC_SERVICE_NAME': '0', 09:19:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:19:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:19:52 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)) 09:19:52 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)) 09:19:52 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)) 09:19:52 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)) 09:19:52 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)) 09:19:52 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)) 09:19:52 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)) 09:19:52 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)) 09:19:52 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)) 09:19:52 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)) 09:19:52 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)) 09:19:52 INFO - Cleaning up... 09:19:52 INFO - Return code: 0 09:19:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:19:52 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')]} 09:19:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:19:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:52 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')]} 09:19:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:19:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:52 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 0x10cfa0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfa6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe7149afa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce44d30>, '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 0x10cc68db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ce3bb20>, '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.WfOuxcAtG1/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.kdaAjT1HOK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/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 09:19:52 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 09:19:52 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 09:19:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WfOuxcAtG1/Render', 09:19:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:19:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:19:52 INFO - 'HOME': '/Users/cltbld', 09:19:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:19:52 INFO - 'LOGNAME': 'cltbld', 09:19:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:19:52 INFO - 'MOZ_NO_REMOTE': '1', 09:19:52 INFO - 'NO_EM_RESTART': '1', 09:19:52 INFO - 'PAGER': '/bin/cat', 09:19:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:19:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:19:52 INFO - 'PWD': '/builds/slave/test', 09:19:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:19:52 INFO - 'SHELL': '/bin/bash', 09:19:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kdaAjT1HOK/Listeners', 09:19:52 INFO - 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/', 09:19:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:19:52 INFO - 'USER': 'cltbld', 09:19:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:19:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:19:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:19:52 INFO - 'XPC_FLAGS': '0x0', 09:19:52 INFO - 'XPC_SERVICE_NAME': '0', 09:19:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:19:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:19:52 INFO - Downloading/unpacking jsonschema 09:19:52 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 09:19:52 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 09:19:52 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 09:19:52 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 09:19:52 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 09:19:52 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 09:19:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:19:56 INFO - Downloading/unpacking functools32 (from jsonschema) 09:19:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:19:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:19:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:19:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:19:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:19:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:19:56 INFO - Downloading functools32-3.2.3-2.tar.gz 09:19:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:19:56 INFO - warning: no files found matching '*.txt' 09:19:56 INFO - no previously-included directories found matching 'build' 09:19:56 INFO - no previously-included directories found matching 'dist' 09:19:56 INFO - no previously-included directories found matching '.git*' 09:19:56 INFO - Installing collected packages: jsonschema, functools32 09:19:56 INFO - Running setup.py install for functools32 09:19:56 INFO - warning: no files found matching '*.txt' 09:19:56 INFO - no previously-included directories found matching 'build' 09:19:56 INFO - no previously-included directories found matching 'dist' 09:19:56 INFO - no previously-included directories found matching '.git*' 09:19:56 INFO - Successfully installed jsonschema functools32 09:19:56 INFO - Cleaning up... 09:19:56 INFO - Return code: 0 09:19:56 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:19:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:19:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:19:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:19:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:19:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cfa0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfa6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe7149afa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce44d30>, '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 0x10cc68db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ce3bb20>, '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.WfOuxcAtG1/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.kdaAjT1HOK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/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 09:19:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 09:19:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 09:19:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WfOuxcAtG1/Render', 09:19:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:19:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:19:56 INFO - 'HOME': '/Users/cltbld', 09:19:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:19:56 INFO - 'LOGNAME': 'cltbld', 09:19:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:19:56 INFO - 'MOZ_NO_REMOTE': '1', 09:19:56 INFO - 'NO_EM_RESTART': '1', 09:19:56 INFO - 'PAGER': '/bin/cat', 09:19:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:19:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:19:56 INFO - 'PWD': '/builds/slave/test', 09:19:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:19:56 INFO - 'SHELL': '/bin/bash', 09:19:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kdaAjT1HOK/Listeners', 09:19:56 INFO - 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/', 09:19:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:19:56 INFO - 'USER': 'cltbld', 09:19:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:19:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:19:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:19:56 INFO - 'XPC_FLAGS': '0x0', 09:19:56 INFO - 'XPC_SERVICE_NAME': '0', 09:19:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:19:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:19:57 INFO - Downloading/unpacking flake8 09:19: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 09:19: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 09:19: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 09:19: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 09:19: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 09:19: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 09:19:59 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:19:59 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:19:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:19:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:19:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:19:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:19:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:19:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:19:59 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:19:59 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:19:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:19:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:19:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:19:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:19:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:19:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:20:00 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:20: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 09:20: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 09:20: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 09:20: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 09:20: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 09:20: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 09:20:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:20:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:20:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:20:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:20:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:20:00 INFO - no previously-included directories found matching 'docs/_build' 09:20:00 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:20:00 INFO - Running setup.py install for pep8 09:20:01 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:20:01 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:20:01 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:20:01 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:20:01 INFO - no previously-included directories found matching 'docs/_build' 09:20:01 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:20:01 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:20:01 INFO - Cleaning up... 09:20:01 INFO - Return code: 0 09:20:01 INFO - Running post-action listener: _resource_record_post_action 09:20:01 INFO - Running post-action listener: _start_resource_monitoring 09:20:01 INFO - Starting resource monitoring. 09:20:01 INFO - ##### 09:20:01 INFO - ##### Finished create-virtualenv step (success) 09:20:01 INFO - ##### 09:20:01 INFO - ##### 09:20:01 INFO - ##### Running install step. 09:20:01 INFO - ##### 09:20:01 INFO - Running pre-action listener: _resource_record_pre_action 09:20:01 INFO - Running main action method: install 09:20:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:20:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:20:01 INFO - Reading from file tmpfile_stdout 09:20:01 INFO - Detecting whether we're running mozinstall >=1.0... 09:20:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:20:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:20:01 INFO - Reading from file tmpfile_stdout 09:20:01 INFO - Output received: 09:20:01 INFO - Usage: mozinstall [options] installer 09:20:01 INFO - Options: 09:20:01 INFO - -h, --help show this help message and exit 09:20:01 INFO - -d DEST, --destination=DEST 09:20:01 INFO - Directory to install application into. [default: 09:20:01 INFO - "/builds/slave/test"] 09:20:01 INFO - --app=APP Application being installed. [default: firefox] 09:20:01 INFO - mkdir: /builds/slave/test/build/application 09:20:01 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'] 09:20:01 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 09:20:34 INFO - Reading from file tmpfile_stdout 09:20:34 INFO - Output received: 09:20:34 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:20:34 INFO - Running post-action listener: _resource_record_post_action 09:20:34 INFO - ##### 09:20:34 INFO - ##### Finished install step (success) 09:20:34 INFO - ##### 09:20:34 INFO - ##### 09:20:34 INFO - ##### Running run-tests step. 09:20:34 INFO - ##### 09:20:34 INFO - Running pre-action listener: _resource_record_pre_action 09:20:34 INFO - Running main action method: run_tests 09:20:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:20:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:20:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:20:34 INFO - 2016-05-11 09:20:34.893 screenresolution[1919:10293] starting screenresolution argv=screenresolution get 09:20:34 INFO - 2016-05-11 09:20:34.895 screenresolution[1919:10293] Display 0: 1600x1200x32@60 09:20:34 INFO - 2016-05-11 09:20:34.902 screenresolution[1920:10295] starting screenresolution argv=screenresolution list 09:20:34 INFO - Available Modes on Display 0 09:20:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:20:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:20:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:20:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:20:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:20:35 INFO - Intel Iris: 09:20:35 INFO - Chipset Model: Intel Iris 09:20:35 INFO - Type: GPU 09:20:35 INFO - Bus: Built-In 09:20:35 INFO - VRAM (Dynamic, Max): 1536 MB 09:20:35 INFO - Vendor: Intel (0x8086) 09:20:35 INFO - Device ID: 0x0a2e 09:20:35 INFO - Revision ID: 0x0009 09:20:35 INFO - Displays: 09:20:35 INFO - Display: 09:20:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:20:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:20:35 INFO - Display Serial Number: 1600x1200 60 09:20:35 INFO - Main Display: Yes 09:20:35 INFO - Mirror: Off 09:20:35 INFO - Online: Yes 09:20:35 INFO - Rotation: Supported 09:20:35 INFO - Return code: 0 09:20:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:20:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:20:35 INFO - Python 2.7.3 09:20:35 INFO - Return code: 0 09:20:35 INFO - grabbing minidump binary from tooltool 09:20:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:20:35 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 0x10ce44d30>, '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 0x10cc68db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ce3bb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:20:35 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 09:20:35 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 09:20:35 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 09:20:35 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:20:35 INFO - Return code: 0 09:20:35 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:20:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:20:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:20:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:20:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:20:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:20:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:20:36 INFO - Return code: 0 09:20:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NcfWqedxRr-BhSdcTJbSdw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0130', '--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 09:20:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/NcfWqedxRr-BhSdcTJbSdw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0130 --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 09:20:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WfOuxcAtG1/Render', 09:20:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:20:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:20:36 INFO - 'HOME': '/Users/cltbld', 09:20:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:20:36 INFO - 'LOGNAME': 'cltbld', 09:20:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:20:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:20:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:20:36 INFO - 'MOZ_NO_REMOTE': '1', 09:20:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:20:36 INFO - 'NO_EM_RESTART': '1', 09:20:36 INFO - 'PAGER': '/bin/cat', 09:20:36 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:20:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:20:36 INFO - 'PWD': '/builds/slave/test', 09:20:36 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:20:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:20:36 INFO - 'SHELL': '/bin/bash', 09:20:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kdaAjT1HOK/Listeners', 09:20:36 INFO - 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/', 09:20:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:20:36 INFO - 'USER': 'cltbld', 09:20:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:20:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:20:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:20:36 INFO - 'XPC_FLAGS': '0x0', 09:20:36 INFO - 'XPC_SERVICE_NAME': '0', 09:20:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:20:36 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NcfWqedxRr-BhSdcTJbSdw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0130', '--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 09:20:36 INFO - mozversion application_buildid: 20160511073416 09:20:36 INFO - mozversion application_changeset: a8f80b823c8d5434ae50bcec715846e854365f42 09:20:36 INFO - mozversion application_display_name: Nightly 09:20:36 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:20:36 INFO - mozversion application_name: Firefox 09:20:36 INFO - mozversion application_remotingname: firefox 09:20:36 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:20:36 INFO - mozversion application_vendor: Mozilla 09:20:36 INFO - mozversion application_version: 49.0a1 09:20:36 INFO - mozversion platform_buildid: 20160511073416 09:20:36 INFO - mozversion platform_changeset: a8f80b823c8d5434ae50bcec715846e854365f42 09:20:36 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:20:36 INFO - mozversion platform_version: 49.0a1 09:20:36 INFO - using testdate: 1462983636 09:20:36 INFO - actual date: 1462983636 09:20:36 INFO - starting webserver on 'localhost:49525' 09:20:36 INFO - SUITE-START | Running 2 tests 09:20:36 INFO - TEST-START | dromaeo_css 09:20:36 INFO - operating with platform_type : mac_ 09:20:36 INFO - Initialising browser for dromaeo_css test... 09:20:36 INFO - TEST-INFO | started process 1938 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/tmpJZBnEW/profile http://localhost:49525/getInfo.html) 09:20:38 INFO - PROCESS | 1938 | __metrics Screen width/height:1600/1200 09:20:38 INFO - PROCESS | 1938 | colorDepth:24 09:20:38 INFO - PROCESS | 1938 | Browser inner width/height: 1024/689 09:20:38 INFO - PROCESS | 1938 | __metrics 09:20:38 INFO - PROCESS | 1938 | 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" 09:20:38 ERROR - PROCESS | 1938 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:20:39 INFO - TEST-INFO | 1938: exit 0 09:20:39 INFO - Browser initialized. 09:20:39 INFO - Running cycle 1/1 for dromaeo_css test... 09:20:39 INFO - TEST-INFO | started process 1940 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/tmpJZBnEW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:20:51 INFO - PROCESS | 1940 | RSS: Main: 238854144 09:20:51 INFO - PROCESS | 1940 | 09:21:43 INFO - PROCESS | 1940 | 1462983703154 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] 09:23:53 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49525/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49525/tests/dromaeo/cssquery-ext.html) 09:23:55 INFO - PROCESS | 1940 | RSS: Main: 343425024 09:23:55 INFO - PROCESS | 1940 | 09:26:58 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49525/tests/dromaeo/cssquery-ext.html (next: http://localhost:49525/tests/dromaeo/cssquery-jquery.html) 09:26:58 INFO - PROCESS | 1940 | RSS: Main: 368205824 09:26:58 INFO - PROCESS | 1940 | 09:30:01 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49525/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49525/tests/dromaeo/cssquery-mootools.html) 09:30:01 INFO - PROCESS | 1940 | RSS: Main: 366313472 09:30:01 INFO - PROCESS | 1940 | 09:33:03 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49525/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49525/tests/dromaeo/cssquery-prototype.html) 09:33:04 INFO - PROCESS | 1940 | RSS: Main: 368697344 09:33:04 INFO - PROCESS | 1940 | 09:36:07 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49525/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49525/tests/dromaeo/cssquery-yui.html) 09:36:08 INFO - PROCESS | 1940 | RSS: Main: 338214912 09:36:08 INFO - PROCESS | 1940 | 09:39:11 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49525/tests/dromaeo/cssquery-yui.html (next: http://localhost:49525/tests/dromaeo/cssquery-dojo.html) 09:39:11 INFO - PROCESS | 1940 | RSS: Main: 414691328 09:39:11 INFO - PROCESS | 1940 | 09:39:11 INFO - PROCESS | 1940 | __start_tp_report 09:39:11 INFO - PROCESS | 1940 | _x_x_mozilla_page_load 09:39:11 INFO - PROCESS | 1940 | _x_x_mozilla_page_load_details 09:39:11 INFO - PROCESS | 1940 | |i|pagename|runs| 09:39:11 INFO - PROCESS | 1940 | |0;dojo.html;3393.3552625001253;3398.490226466028;3423.537822393976;3436.3127374525084;3466.878659246927;5553.472420120087;5603.719814009303;5618;5619.943800561993;5668.319801623811;5617.269754931864;5647.887042259153;5670.4896559309655;5749.425057494249;5758.654480731148;5043.672161309503;5108.66793658411;5132.563732082777;5154.768035438415;5242.737863106849;5206.297149884755;5245.711485868275;5255.76349064293;5271.736413179345;5283.841484755463;5066.2907192992825;5114.053900028507;5120.615953803443;5132.358455193101;5168.457311982225;5073.46728593496;5084.949150508484;5113.360829896263;5128.974355128238;5232.084385232607;5084.466131056222;5084.745762711849;5113.258577506278;5149.742512874379;5187.870303242411;5457.208704737791;5550.972245138749;5607.495325420691;5611.663300202001;5618.803341883015;6183.227096612924;6228.252609686867;6258.436740693314;6265.968670156665;6278.246610406781;5077.669951453144;6265.060240963846;6286.591371560879;6311.684415779238;6317.684115794192;5671.631343962629;5750.511206547407;5801.3038435388025;5821.301443826683;5852.853678658025;6225.501959843202;6228.626282423069;6233.470155036782;6234.220722409699;6253.1245625612455;6136.907946380718;6150.569460137747;6172.290186628506;6173.074038894202;6177.1969643945995;6055.152278680988;6073.240844894388;6129.419919118773;6202.937970620236;6222.253329600477;6083.482903953125;6096.163860440966;6123.679189354777;6141.209856311994;6160.537959653044;6239.875202495925;6266.968665156645;6267.341929097471;6269.905951410732;6278.654673992883;4913.729744863996;4923.926141107886;4935.358403407534;4957.752112394366;4967.4784147664695;4370.147821174904;4381.992141807402;4459.687821852439;4463.598276155161;4479.59683628475;4626.791794369262;5630.296212973379;5663.575231857545;5669.121286200645;5703.058995265817;6262.937370626236;6288.9056664150075;6296.244450665858;6302.243730752339;6321.178246827975;5533.916991245134;5578.386377498472;5582.190582365534;5624.690642014647;5654.547636189177;5380.461953804588;5397.001554712391;5425.728713564385;5434.538064264581;5465.344158700981;6256.249250090018;6287.748490060347;6294.9685251574365;6327.240731112204;6334.493240540838;6100.762843515285;6164.106204600218;6230.43926046658;6298.244210694746;6305.464035557029;6274.811755647155;6292.433680968735;6311.842203944938;6320.367963203643;6321.1782468278825;5983.311919129356;6002.909956350745;6178.505719542516;6179.134921110958;6187.690615469299;6068.089786532056;6149.585595313014;6153.969230153821;6168.444839964424;6265.248170219603;5065.696058236517;6104.053871649901;6114.602550834182;6133.3866613338305;6170.969145154425;6118.785842495669;6119.35746746603;6124.081387791689;6139.416755408229;6141.109539116893;4572;5721.685307308138;5767.452092051249;5944.73248703818;5956.523478121827;6111.86523267538;6120.081455037822;6126.517673797367;6134.202553667995;6140.039674409892;5917.556183286357;5995.490383317292;6011.248593925759;6050.485708714808;6051.788187413419 09:39:11 INFO - PROCESS | 1940 | |1;ext.html;500.694576308452;517.9300794392756;525.7292494365403;535.469884814034;539.606087556084;24364.756352436492;31060.689393106062;32171.356572868528;32202;32259;32443.67556324442;43969;45519.86200344993;45799.62601121972;48209.79475513114;9430.52847357631;9583.904160958406;9591.66429174979;9591.712248632517;9595.088466595696;8570.18583234595;9479.43123412594;9596.616135354578;9606.423614583147;9616.691993921479;8609.440386374898;11707.356095414749;11896.04831613469;11926.46330915111;12005.979491743212;11771.941140294286;11926.52293908247;11933.284002959763;11936.58221962227;11942.283462992249;8528.275096616795;11274.887251127466;11579.53681852725;11677.474513646821;11816.29102253859;41521.1695766083;41912.580874191175;41940;41948;41957.741267762016;3234.214427123223;3241.9675803241903;3260.9021729348156;3278.327942771726;3465.896023119285;28400.573991390145;29443.70556294431;30146.397072058655;30178.09465716032;30334.938277160192;2534.759197876199;2651.9867400662874;2692.0981471206974;2723.536998710224;2751.793615478847;510852;515437;515697;515734.9999999963;516630;66983;67572.64854702879;67614;67628;67669.66165169241;71506.64246678837;71628;71665.2833471669;71710.28289717036;71769.64115179391;17111;17718.734218986727;17726.556836079202;17739.93560386381;17758.822411775716;13323.733525329633;13418.597442076752;13453.596392108253;13482.123661961941;13524.93237533806;17339.47981560555;17984;19686.507837304183;19892.717701579368;20600.27899023527;8173.918260817434;8194.877076843852;8201.343892488587;8220.506769593843;8283.716024016287;813.4468561378319;943.5470973932413;1016.1362841584742;1020.3061917895753;1037.3361048928691;27459;29991.850040749658;30136.698633013828;30559;30755.692443075284;1079.5627770752858;1098.417838545572;1158.5771193514306;1188.4057971014493;1262.2994238197705;13565.932170339085;13846.930765346311;15378.231088445533;15423;15841.128737919295;666867;670527;673320;674026;674194;62452;62831;63016.68491657513;63054.369456305765;63111.36888631055;1469.7207530569158;1482.7182835261267;1488.0104730354194;1505.787840788176;1509.939602415913;29142.70857291485;29339.559906601844;29382.853085734434;29944.700552994193;30027.69972300249;54544.72727636337;55159;55559.72220139035;55637.721811390686;55647.443525564224;415958;477519.6124019358;482776;483020.754688687;484454;24117.517649646557;24539.263822085366;27014.594781077907;27310.5903411445;27647.170584882486;46177;47481.81295467503;47741.283880742514;47819.52180478151;49054.26418603795;37130.628693712715;38593.03517412087;38884.80557597307;39580.208395832495;39854;37548.812255939636;37606.62393376031;37693.05767355728;38493;38501.03747406337;34099;37605.43591846179;37691;38864.80567597144;38883;33036.1740956478;34435.82782086074;34476.48285275673;34490.655093448746;39822;28582;30053.248668783457;30766.230844229074;31045.68954310428;31834.36331273408 09:39:11 INFO - PROCESS | 1940 | |2;jquery.html;384.2909831361139;390.3383764519139;393.0939185178163;396.91466334738027;416.6292000119894;12520;17722;20426.693599596;21747.78252217479;21760.564788704218;19953.501162470944;20256.696149557767;22125.557488850212;22274.331770046883;22287.554248915054;5748.655080695161;6070.57505974582;6072.51419886408;6077.817665470043;6079.20970273866;5704.258446401962;5950.761969521214;6004.429579189992;6095.603785753934;6608.372204640576;6581.111549940744;6605.636689982049;6607.867842643168;6616.338366163393;6617.172853393326;6411.070394792729;6481.773137940173;6575;6583.9012414813815;6584.6707664616815;5508.724563771835;5699.74351154199;5748.109043098327;5778.133280007991;5779.884402311972;26424;26780.794864231157;27052.59421108685;27065.052723154793;27310.726892731214;1950.0737149853849;1970.753655793026;2154.450615093157;2170.945726356838;2184.6067707812585;10251.128654064414;10258.333208341433;10403;14600.342984565614;15202.619934501616;1724.844763971236;1786.428342930263;1798.1009495252374;1818.7544681467964;1955.4231501707031;109089;131412;133475.50723846946;134633;137093;11759.412029398496;11817.054635629129;11854.525818967317;11869.643910682693;11982.88017119835;12457.439415226336;13137.934310328386;15327.846721532642;15410.845891541165;15428.38286468551;8411.45325553837;8474.88127968008;8773.473591584525;8940.68707595231;9064.682736104205;8893.199612034949;8893.955530222436;8910.509921954224;9014.278857691368;9268.814623707489;4677.134730074947;4707.905841883212;4709.1994360958715;4721.834735784231;4769.713817170981;1657.8590819780454;1934.9806501935082;1971.5859669469285;1972.7336809530816;1995.092233033966;902.7201567513961;933.3653115881131;966.642896759376;1016.425719468498;1030.6701855406197;17443.21505532254;20729.378118656463;20742.170313187304;21120.366389008355;21179.364619061453;972.4554249620236;975;1000.574181790945;1009.8838633557089;1045.811753884308;10974.561017559368;11190.38452885083;11206.607768728056;11233.71915702114;11257.155713321534;587610;589458;610578;611483;612068;117534;139659;141093.58906410803;142812;146667;1011.5195282241024;1015.2334987084852;1034.8706411698538;1087.1737479515466;1090.0788833435734;19034.809651903302;19341.903290483457;19429.417117486497;19490;19625.70561441608;39532.80233598813;42288;45869.85325366759;51154.20960266373;54022;60347.69826150841;68093.65953170203;78666.24668136588;79808.2019179824;81733.3653326942;7967.442279040411;8097.2712455879255;8183.386246031452;8240.258376746122;8509.404341696021;10645.308054976402;11559.595414160463;12470.314132722788;12624.305663188245;12785;11012.779744405227;11232.045276151619;11280.379579123228;11441.42792860337;11544.307341559534;10680.465976701289;10979.725506862393;11115.444227788417;11148.498317575892;11424.942875285571;10039.498025098861;10770.922907709166;10978.615748448767;11552.133589980616;11750.823737643772;7051.365377116084;8084.434089613671;8224.177582241728;8336.62485188156;8353.749387518385;8374.455660382057;10245.948770256102;10890.821835633064;11316.9434152832;11323.660290191307 09:39:11 INFO - PROCESS | 1940 | |3;mootools.html;1447.2329665277407;1629.7473891546815;1653.00819508295;1894.3559189875439;1994.850386221034;7894.605269736512;8272.338212942965;8284.047334556528;8285.668573257062;8293.04629967554;8392.202740739634;8702.216800487955;8712.390132690714;8841.469511829295;8852.203301702846;2365.7042869641336;2458.2748089313695;2461.8153638477097;2466.6053431450973;2470.1601455505124;2413.2036427978724;2433.4038160650666;2453.668754718108;2457.0663148003823;2461.5815311397014;2417.2748175547354;2437.8049756019564;2449.80401567875;2455.852648841066;2460.8646524441147;2407.57867373209;2408.7350391456926;2447.3881529617597;2449.9387515312083;2452.8405653632544;2428.9757102429103;2441.316431399211;2470.357706996176;2476.702795664532;2477.170148000422;8790.912090879074;8883.244924181452;8883.333749968713;8886.022537520868;8901.73294801157;5331.067063263913;5333.546648534879;5463.808766693147;5473.917891231635;5490.313710786152;3435.3129374125274;3581.3553560359123;3647.8905632831056;3662.9633703663153;3671.4492826076303;1049.9842502362471;1053.1995683280686;1055.4986381468818;1078.9161392405017;1081.0756802933536;11415.543378264943;11747.29516229029;11749.353785541793;12003.57987470443;12067.637970860977;4102.261592913304;4619.145458090264;4625.421822272216;4712.64655150865;4727.787249573708;9239.214666753267;9253.537323133816;9255.629774809067;9262.675806346746;9266.907330926739;5306.204069389545;5309.177077552986;5312.574994000471;5312.574994000471;5321.308229930084;4436.733795972187;4606.838760643362;4794.232922732346;4894.926576101361;4943.233798761198;3739.9439008414897;3751.981240093782;3826.827792749333;3861.864834730771;3946.901327466779;1094.7208461842338;1098.5122236897816;1167.0896700573567;1181.8640856301463;1199.7960346741074;647.4917190005823;789.6841263494648;837.4598384042258;892.4912799704107;957.9616815327308;4687.179743544866;5862.237909071793;5984.371640977722;6133.478654314434;6163.537734669918;903.8599017152352;944.2681921510884;948.6015873333216;955.4888134847823;983.9458829764432;3832.846686132579;3963.365861462152;4030.979845100756;4049.493813273341;4049.7367671101283;10592.682219533426;10719.78560428787;11108.666739997812;11262;11269.436528173559;10735.033846953811;11082.16883733707;11177.21759476845;11200.831987520194;11248.887511124947;8136.430449868571;8383.748487545383;8405.537695426689;8432.198941100585;8442.366822488339;5701.458361455655;5810.4189581041555;5843.415658434123;5899.734511947058;5907.675077870586;9994.350367226107;10163.491825408551;10225.539850706884;10344.948275258877;10353.327033742782;10068.949655251678;10381.584736610452;10414.635487758194;10431;10536.894631053592;7066.187388450188;7151.82120446993;7220.89168662481;7239.710411583477;7257.927420725937;8145.307648849915;8394.202550757669;8420.915790842013;8427;8437.734339848826;6111.5110791137495;7433.2195119513835;7479.512292624382;7691.15397306307;7779.883301750591;6338.619682819046;7334.486585938933;7394.482386232912;7447.404207663482;7526.285002924714;6605;6956.304369563206;7033.226345102137;7056.047433596399;7064.116985376828;4816.566509014206;4820.25286080658;4899.4365647950945;4973.229149481836;5118.41138269089;4882.5605695487575;4902.117618828645;4983.377077865267;5019.3976722793495;5203.453637368022 09:39:11 INFO - PROCESS | 1940 | |4;prototype.html;300.54317437495007;304.94206100840836;315.6370174299555;319.36287107221096;321.7024252566377;4393.362962370455;4853.762290615897;5131.255967884659;5135.5634771044415;5276.894462110756;4228.6256966485935;4283.571642835715;4526.932096018554;4924.361605961181;5177.9708870310915;3422.760406771527;3680.0983758979087;3700.3903302063554;4006.417465101283;4006.599340065999;3394.6605339466105;3636;3828.9617103829023;3982.1836523512757;4023;3512.2448673535187;3540.504329393887;3781.640744129303;3973.2450834341525;3985.322495175827;3504.404251277289;3952.6442620164034;3961.227560625679;3998.5601583825755;4030.3349947258666;3498.475228715713;3502.0038578010544;3504.211552400715;3507.7369197310304;3523.594786599548;4502.396516307644;4556.567126123013;4962.727050012248;4993.700377977297;5082.618803589746;3683;3962.960370396288;4350.347447882843;4557.951671115628;4564.475085365192;5148.768305426265;5339.492748188916;5400.621956463049;5416.345990464683;5457.808976685837;3366.5118557809224;3597.4244120940784;3674.816259187057;3714.275716235335;3907.5310962684375;4496.460424749019;4799.536141476849;5219.295395121648;5225.111731005738;5451.809186678447;3334.699877011081;3709.6104908984707;3723.627637236309;3743.7192210584317;3898.2788184186015;3602.8290805488323;3940.5271367435516;3951.9329780959556;3965.2466031453932;3992.5208974922616;4484.192845287815;4546.65900057497;4550.247100504147;4560.72635641856;4591.655625828076;4479.910401791946;4480.566218810015;4552.9772351138035;5240.318758561362;5540.260324281094;1170.8302296167008;1208.6615747590688;1221.8900298972958;1330.7405056014177;1338.1235290884485;808.6846130009301;835.8077642142341;845.2772879188328;846.7798372423091;847.6733911428595;3825.713071519647;4020.3567429211184;4103.097318589965;4253.234417804825;4260.91478170441;3929.8035098244973;4005.176350672769;4209.084866385787;4515.819367225275;4650.627949764011;4199.349100889367;4263.467066616673;4268.679849011337;4272.829086836554;4358.888483436766;2065.019115919926;2150.7203214087594;2328.0455013444407;2467.925962221136;2521.5037113257745;4165.125323682;4223.852165174204;4395.164918665443;4667.899445249651;4668.85993420198;4361.760103194291;4711.809008810919;4870;4873;4907.754612269372;4003.978985358773;4379.912401752011;4460.843870464518;4593.517680643551;4989.975050124726;3969.7618142910364;4403.273459879084;4683.447521101076;4758.4765675776325;4759.3574867393845;4261.765602891871;4388.2759344707765;4543.909121817611;4590.862274131781;4756.476787553435;4297.82808687649;4795.7388975495605;4799.9440123174245;4904.386951631046;4915.508449155056;3090.2119959410206;3611.850948685528;3905.5744653201946;3912.4545804407667;3942.5071866016747;4784.569388755029;5512.283403157564;5580.693061881473;5601.327840659147;5662.490375866192;4784.545468180518;4817.397825271841;5020.924686129784;5479.726013699379;5550.5282051026115;5018.598512118949;5304.443033481429;5318.069337865935;5375.08623534008;5383.035192154914;4292.892677683083;4480.551944805494;5220.243064755513;5223.503767142115;5344.465553444625;5024.341967150758;5175;5199.324087868553;5378.327709036371;5486.256871564218;4864.883775557644;5018.598512119095;5090.974545127251;5283.920741188808;5326.97336513315 09:39:11 INFO - PROCESS | 1940 | |5;yui.html;627.4666533446571;634.3263145840282;714.2464699741773;748.1695318196801;754.4492520460064;15031.248437578119;15507.767383489258;15615.219239038046;15773.842261577382;15955.883088183831;14501.564953051398;14564.41742330308;14636.4877229297;14651.706965860676;14675.412983480675;715.9248278930716;907.4782000349792;924.6994726713812;925.4632313258328;926.4996901673122;736.5433431272619;742.5136535569184;743.4275607782005;744.5383862005564;747.4132805747489;950.4724877692878;980.6665733650557;984.7193549838297;986.4229425785924;989.5893204320198;966.3766870368643;973.0366936732619;976.3995142987012;976.5995941663883;977.5112443778111;474.90976714424494;703.855709579535;722.8409749855024;723.0311382747144;723.1033518437152;14634.853651463456;14920.477783277533;14984.175870327128;14993.475228367064;15012.699746005019;12615.369231538387;12623.368831558386;12667.176633518791;12696.365181740875;12892.097553171281;10917.563297468092;10960.671179864617;10979.011888930034;11215.694045377952;11323.037541808957;4054.148628787958;4055.7972101395108;4060.756354618703;4071.450354202204;4204.600562946515;84728.57635711782;85757.14242857553;86532;86861;88860;8216.46592971455;9372.39079459833;9663.082007209305;9879.259055570861;9930;22502;22538.323850284516;22562.984665690085;22569.774302257094;22625.660615090786;7403.296686814744;7426.108866936002;7455.7017719290625;7528.774136775905;7614.048243969504;4004.4794176756836;4028.093678922219;4046.8785936421955;4046.988252936766;4047;3371.072954937412;3484.337975784593;3490.0402389343135;3490.267043920805;3496.5279687242405;602.108878859282;614.8770245950739;619.7830759234322;625.6652690810486;625.978090766821;471.61092099018447;521.7521677203374;536.2599612534677;572.9111987641922;581.16022347708;7348.889766653505;7504.699812007568;7876.409269304825;7965.322947549407;8047.758567242875;455.26020217147135;544.5262621519304;569.0780934885512;569.5301376364433;595.425414475025;3688.3360995020616;3715.64701353371;3770.472133901256;3944.9605503945168;3967.4247234150885;88186.67719984206;93160.53419732858;93894.53052734693;94775;95557;80424.98937526492;81238.59380703176;83513.58243208745;84033.99999999878;84675;56333.7183314089;69501.65249173823;69652.34282089851;71839.64080179566;72349;7377.926220737724;7440.73957411488;7476.514026588255;7509.887351689838;7522.774316770506;40142.99642508961;45545.54454455546;45989.310160346955;46523;46790;57819;61352;61665.07502387557;61871.38128618656;61940;10778.245522813328;11133.83299250528;11225.382603956861;11347;11582.976852037062;11821.704457388634;11842.46708898119;11843.949772824584;11871.881281187078;12992.935035324763;11396.259243149168;11531.308121512737;11926.880731192578;12057.758844823229;12310.938445307716;11332.263402878787;11394.202405831513;11559.075273977893;11776.941115294369;11884;11199.496022679163;11213.27113737637;11470.369129697612;11613.187076904536;11692.298462092302;9413.858792117986;9429.292803039662;9469;9482.478463684287;10111.595536178766;7318.31576844322;8760.561971901507;8851.778705532413;9557.665481708107;9603.567839447102 09:39:11 INFO - PROCESS | 1940 | __end_tp_report 09:39:11 INFO - PROCESS | 1940 | __start_cc_report 09:39:11 INFO - PROCESS | 1940 | _x_x_mozilla_cycle_collect,1190 09:39:11 INFO - PROCESS | 1940 | __end_cc_report 09:39:11 INFO - PROCESS | 1940 | __startTimestamp1462984751526__endTimestamp 09:39:11 INFO - PROCESS | 1940 | 09:39:11 INFO - PROCESS | 1940 | ------- Summary: start ------- 09:39:11 INFO - PROCESS | 1940 | Number of tests: 6 09:39:11 INFO - PROCESS | 1940 | 09:39:11 INFO - PROCESS | 1940 | [#0] dojo.html Cycles:165 Average:5698.44 Median:5956.52 stddev:649.39 (10.9%) stddev-sans-first:625.70 09:39:11 INFO - PROCESS | 1940 | Values: 3393.4 3398.5 3423.5 3436.3 3466.9 5553.5 5603.7 5618.0 5619.9 5668.3 5617.3 5647.9 5670.5 5749.4 5758.7 5043.7 5108.7 5132.6 5154.8 5242.7 5206.3 5245.7 5255.8 5271.7 5283.8 5066.3 5114.1 5120.6 5132.4 5168.5 5073.5 5084.9 5113.4 5129.0 5232.1 5084.5 5084.7 5113.3 5149.7 5187.9 5457.2 5551.0 5607.5 5611.7 5618.8 6183.2 6228.3 6258.4 6266.0 6278.2 5077.7 6265.1 6286.6 6311.7 6317.7 5671.6 5750.5 5801.3 5821.3 5852.9 6225.5 6228.6 6233.5 6234.2 6253.1 6136.9 6150.6 6172.3 6173.1 6177.2 6055.2 6073.2 6129.4 6202.9 6222.3 6083.5 6096.2 6123.7 6141.2 6160.5 6239.9 6267.0 6267.3 6269.9 6278.7 4913.7 4923.9 4935.4 4957.8 4967.5 4370.1 4382.0 4459.7 4463.6 4479.6 4626.8 5630.3 5663.6 5669.1 5703.1 6262.9 6288.9 6296.2 6302.2 6321.2 5533.9 5578.4 5582.2 5624.7 5654.5 5380.5 5397.0 5425.7 5434.5 5465.3 6256.2 6287.7 6295.0 6327.2 6334.5 6100.8 6164.1 6230.4 6298.2 6305.5 6274.8 6292.4 6311.8 6320.4 6321.2 5983.3 6002.9 6178.5 6179.1 6187.7 6068.1 6149.6 6154.0 6168.4 6265.2 5065.7 6104.1 6114.6 6133.4 6171.0 6118.8 6119.4 6124.1 6139.4 6141.1 4572.0 5721.7 5767.5 5944.7 5956.5 6111.9 6120.1 6126.5 6134.2 6140.0 5917.6 5995.5 6011.2 6050.5 6051.8 09:39:11 INFO - PROCESS | 1940 | 09:39:11 INFO - PROCESS | 1940 | [#1] ext.html Cycles:180 Average:69646.59 Median:18978.28 stddev:149240.12 (786.4%) stddev-sans-first:149568.48 09:39:11 INFO - PROCESS | 1940 | Values: 500.7 517.9 525.7 535.5 539.6 24364.8 31060.7 32171.4 32202.0 32259.0 32443.7 43969.0 45519.9 45799.6 48209.8 9430.5 9583.9 9591.7 9591.7 9595.1 8570.2 9479.4 9596.6 9606.4 9616.7 8609.4 11707.4 11896.0 11926.5 12006.0 11771.9 11926.5 11933.3 11936.6 11942.3 8528.3 11274.9 11579.5 11677.5 11816.3 41521.2 41912.6 41940.0 41948.0 41957.7 3234.2 3242.0 3260.9 3278.3 3465.9 28400.6 29443.7 30146.4 30178.1 30334.9 2534.8 2652.0 2692.1 2723.5 2751.8 510852.0 515437.0 515697.0 515735.0 516630.0 66983.0 67572.6 67614.0 67628.0 67669.7 71506.6 71628.0 71665.3 71710.3 71769.6 17111.0 17718.7 17726.6 17739.9 17758.8 13323.7 13418.6 13453.6 13482.1 13524.9 17339.5 17984.0 19686.5 19892.7 20600.3 8173.9 8194.9 8201.3 8220.5 8283.7 813.4 943.5 1016.1 1020.3 1037.3 27459.0 29991.9 30136.7 30559.0 30755.7 1079.6 1098.4 1158.6 1188.4 1262.3 13565.9 13846.9 15378.2 15423.0 15841.1 666867.0 670527.0 673320.0 674026.0 674194.0 62452.0 62831.0 63016.7 63054.4 63111.4 1469.7 1482.7 1488.0 1505.8 1509.9 29142.7 29339.6 29382.9 29944.7 30027.7 54544.7 55159.0 55559.7 55637.7 55647.4 415958.0 477519.6 482776.0 483020.8 484454.0 24117.5 24539.3 27014.6 27310.6 27647.2 46177.0 47481.8 47741.3 47819.5 49054.3 37130.6 38593.0 38884.8 39580.2 39854.0 37548.8 37606.6 37693.1 38493.0 38501.0 34099.0 37605.4 37691.0 38864.8 38883.0 33036.2 34435.8 34476.5 34490.7 39822.0 28582.0 30053.2 30766.2 31045.7 31834.4 09:39:11 INFO - PROCESS | 1940 | 09:39:11 INFO - PROCESS | 1940 | [#2] jquery.html Cycles:180 Average:35560.16 Median:11666.91 stddev:100960.82 (865.4%) stddev-sans-first:101209.49 09:39:11 INFO - PROCESS | 1940 | Values: 384.3 390.3 393.1 396.9 416.6 12520.0 17722.0 20426.7 21747.8 21760.6 19953.5 20256.7 22125.6 22274.3 22287.6 5748.7 6070.6 6072.5 6077.8 6079.2 5704.3 5950.8 6004.4 6095.6 6608.4 6581.1 6605.6 6607.9 6616.3 6617.2 6411.1 6481.8 6575.0 6583.9 6584.7 5508.7 5699.7 5748.1 5778.1 5779.9 26424.0 26780.8 27052.6 27065.1 27310.7 1950.1 1970.8 2154.5 2170.9 2184.6 10251.1 10258.3 10403.0 14600.3 15202.6 1724.8 1786.4 1798.1 1818.8 1955.4 109089.0 131412.0 133475.5 134633.0 137093.0 11759.4 11817.1 11854.5 11869.6 11982.9 12457.4 13137.9 15327.8 15410.8 15428.4 8411.5 8474.9 8773.5 8940.7 9064.7 8893.2 8894.0 8910.5 9014.3 9268.8 4677.1 4707.9 4709.2 4721.8 4769.7 1657.9 1935.0 1971.6 1972.7 1995.1 902.7 933.4 966.6 1016.4 1030.7 17443.2 20729.4 20742.2 21120.4 21179.4 972.5 975.0 1000.6 1009.9 1045.8 10974.6 11190.4 11206.6 11233.7 11257.2 587610.0 589458.0 610578.0 611483.0 612068.0 117534.0 139659.0 141093.6 142812.0 146667.0 1011.5 1015.2 1034.9 1087.2 1090.1 19034.8 19341.9 19429.4 19490.0 19625.7 39532.8 42288.0 45869.9 51154.2 54022.0 60347.7 68093.7 78666.2 79808.2 81733.4 7967.4 8097.3 8183.4 8240.3 8509.4 10645.3 11559.6 12470.3 12624.3 12785.0 11012.8 11232.0 11280.4 11441.4 11544.3 10680.5 10979.7 11115.4 11148.5 11424.9 10039.5 10770.9 10978.6 11552.1 11750.8 7051.4 8084.4 8224.2 8336.6 8353.7 8374.5 10245.9 10890.8 11316.9 11323.7 09:39:11 INFO - PROCESS | 1940 | 09:39:11 INFO - PROCESS | 1940 | [#3] mootools.html Cycles:180 Average:5715.05 Median:4888.74 stddev:3216.07 (65.8%) stddev-sans-first:3209.10 09:39:11 INFO - PROCESS | 1940 | Values: 1447.2 1629.7 1653.0 1894.4 1994.9 7894.6 8272.3 8284.0 8285.7 8293.0 8392.2 8702.2 8712.4 8841.5 8852.2 2365.7 2458.3 2461.8 2466.6 2470.2 2413.2 2433.4 2453.7 2457.1 2461.6 2417.3 2437.8 2449.8 2455.9 2460.9 2407.6 2408.7 2447.4 2449.9 2452.8 2429.0 2441.3 2470.4 2476.7 2477.2 8790.9 8883.2 8883.3 8886.0 8901.7 5331.1 5333.5 5463.8 5473.9 5490.3 3435.3 3581.4 3647.9 3663.0 3671.4 1050.0 1053.2 1055.5 1078.9 1081.1 11415.5 11747.3 11749.4 12003.6 12067.6 4102.3 4619.1 4625.4 4712.6 4727.8 9239.2 9253.5 9255.6 9262.7 9266.9 5306.2 5309.2 5312.6 5312.6 5321.3 4436.7 4606.8 4794.2 4894.9 4943.2 3739.9 3752.0 3826.8 3861.9 3946.9 1094.7 1098.5 1167.1 1181.9 1199.8 647.5 789.7 837.5 892.5 958.0 4687.2 5862.2 5984.4 6133.5 6163.5 903.9 944.3 948.6 955.5 983.9 3832.8 3963.4 4031.0 4049.5 4049.7 10592.7 10719.8 11108.7 11262.0 11269.4 10735.0 11082.2 11177.2 11200.8 11248.9 8136.4 8383.7 8405.5 8432.2 8442.4 5701.5 5810.4 5843.4 5899.7 5907.7 9994.4 10163.5 10225.5 10344.9 10353.3 10068.9 10381.6 10414.6 10431.0 10536.9 7066.2 7151.8 7220.9 7239.7 7257.9 8145.3 8394.2 8420.9 8427.0 8437.7 6111.5 7433.2 7479.5 7691.2 7779.9 6338.6 7334.5 7394.5 7447.4 7526.3 6605.0 6956.3 7033.2 7056.0 7064.1 4816.6 4820.3 4899.4 4973.2 5118.4 4882.6 4902.1 4983.4 5019.4 5203.5 09:39:11 INFO - PROCESS | 1940 | 09:39:11 INFO - PROCESS | 1940 | [#4] prototype.html Cycles:180 Average:4108.71 Median:4432.06 stddev:1219.18 (27.5%) stddev-sans-first:1188.62 09:39:11 INFO - PROCESS | 1940 | Values: 300.5 304.9 315.6 319.4 321.7 4393.4 4853.8 5131.3 5135.6 5276.9 4228.6 4283.6 4526.9 4924.4 5178.0 3422.8 3680.1 3700.4 4006.4 4006.6 3394.7 3636.0 3829.0 3982.2 4023.0 3512.2 3540.5 3781.6 3973.2 3985.3 3504.4 3952.6 3961.2 3998.6 4030.3 3498.5 3502.0 3504.2 3507.7 3523.6 4502.4 4556.6 4962.7 4993.7 5082.6 3683.0 3963.0 4350.3 4558.0 4564.5 5148.8 5339.5 5400.6 5416.3 5457.8 3366.5 3597.4 3674.8 3714.3 3907.5 4496.5 4799.5 5219.3 5225.1 5451.8 3334.7 3709.6 3723.6 3743.7 3898.3 3602.8 3940.5 3951.9 3965.2 3992.5 4484.2 4546.7 4550.2 4560.7 4591.7 4479.9 4480.6 4553.0 5240.3 5540.3 1170.8 1208.7 1221.9 1330.7 1338.1 808.7 835.8 845.3 846.8 847.7 3825.7 4020.4 4103.1 4253.2 4260.9 3929.8 4005.2 4209.1 4515.8 4650.6 4199.3 4263.5 4268.7 4272.8 4358.9 2065.0 2150.7 2328.0 2467.9 2521.5 4165.1 4223.9 4395.2 4667.9 4668.9 4361.8 4711.8 4870.0 4873.0 4907.8 4004.0 4379.9 4460.8 4593.5 4990.0 3969.8 4403.3 4683.4 4758.5 4759.4 4261.8 4388.3 4543.9 4590.9 4756.5 4297.8 4795.7 4799.9 4904.4 4915.5 3090.2 3611.9 3905.6 3912.5 3942.5 4784.6 5512.3 5580.7 5601.3 5662.5 4784.5 4817.4 5020.9 5479.7 5550.5 5018.6 5304.4 5318.1 5375.1 5383.0 4292.9 4480.6 5220.2 5223.5 5344.5 5024.3 5175.0 5199.3 5378.3 5486.3 4864.9 5018.6 5091.0 5283.9 5327.0 09:39:11 INFO - PROCESS | 1940 | 09:39:11 INFO - PROCESS | 1940 | [#5] yui.html Cycles:180 Average:18290.37 Median:29195.96 stddev:25903.56 (88.7%) stddev-sans-first:25942.28 09:39:11 INFO - PROCESS | 1940 | Values: 627.5 634.3 714.2 748.2 754.4 15031.2 15507.8 15615.2 15773.8 15955.9 14501.6 14564.4 14636.5 14651.7 14675.4 715.9 907.5 924.7 925.5 926.5 736.5 742.5 743.4 744.5 747.4 950.5 980.7 984.7 986.4 989.6 966.4 973.0 976.4 976.6 977.5 474.9 703.9 722.8 723.0 723.1 14634.9 14920.5 14984.2 14993.5 15012.7 12615.4 12623.4 12667.2 12696.4 12892.1 10917.6 10960.7 10979.0 11215.7 11323.0 4054.1 4055.8 4060.8 4071.5 4204.6 84728.6 85757.1 86532.0 86861.0 88860.0 8216.5 9372.4 9663.1 9879.3 9930.0 22502.0 22538.3 22563.0 22569.8 22625.7 7403.3 7426.1 7455.7 7528.8 7614.0 4004.5 4028.1 4046.9 4047.0 4047.0 3371.1 3484.3 3490.0 3490.3 3496.5 602.1 614.9 619.8 625.7 626.0 471.6 521.8 536.3 572.9 581.2 7348.9 7504.7 7876.4 7965.3 8047.8 455.3 544.5 569.1 569.5 595.4 3688.3 3715.6 3770.5 3945.0 3967.4 88186.7 93160.5 93894.5 94775.0 95557.0 80425.0 81238.6 83513.6 84034.0 84675.0 56333.7 69501.7 69652.3 71839.6 72349.0 7377.9 7440.7 7476.5 7509.9 7522.8 40143.0 45545.5 45989.3 46523.0 46790.0 57819.0 61352.0 61665.1 61871.4 61940.0 10778.2 11133.8 11225.4 11347.0 11583.0 11821.7 11842.5 11843.9 11871.9 12992.9 11396.3 11531.3 11926.9 12057.8 12310.9 11332.3 11394.2 11559.1 11776.9 11884.0 11199.5 11213.3 11470.4 11613.2 11692.3 9413.9 9429.3 9469.0 9482.5 10111.6 7318.3 8760.6 8851.8 9557.7 9603.6 09:39:11 INFO - PROCESS | 1940 | -------- Summary: end -------- 09:39:11 INFO - PROCESS | 1940 | 09:39:12 INFO - TEST-INFO | 1940: exit 0 09:39:12 INFO - TEST-OK | dromaeo_css | took 1115530ms 09:39:12 INFO - TEST-START | kraken 09:39:12 INFO - operating with platform_type : mac_ 09:39:12 INFO - Initialising browser for kraken test... 09:39:12 INFO - TEST-INFO | started process 1945 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/tmp0KA5lk/profile http://localhost:49525/getInfo.html) 09:39:14 INFO - PROCESS | 1945 | __metrics Screen width/height:1600/1200 09:39:14 INFO - PROCESS | 1945 | colorDepth:24 09:39:14 INFO - PROCESS | 1945 | Browser inner width/height: 1024/689 09:39:14 INFO - PROCESS | 1945 | __metrics 09:39:14 INFO - PROCESS | 1945 | 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" 09:39:14 ERROR - PROCESS | 1945 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:39:15 INFO - TEST-INFO | 1945: exit 0 09:39:15 INFO - Browser initialized. 09:39:15 INFO - Running cycle 1/1 for kraken test... 09:39:15 INFO - TEST-INFO | started process 1947 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/tmp0KA5lk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:39:27 INFO - PROCESS | 1947 | RSS: Main: 238796800 09:39:27 INFO - PROCESS | 1947 | 09:39:30 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:49525/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49525/tests/kraken/driver.html?testName=audio-beat-detection) 09:39:31 INFO - PROCESS | 1947 | RSS: Main: 287662080 09:39:31 INFO - PROCESS | 1947 | 09:39:33 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:49525/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49525/tests/kraken/driver.html?testName=audio-dft) 09:39:34 INFO - PROCESS | 1947 | RSS: Main: 295825408 09:39:34 INFO - PROCESS | 1947 | 09:39:38 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:49525/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49525/tests/kraken/driver.html?testName=audio-fft) 09:39:38 INFO - PROCESS | 1947 | RSS: Main: 383148032 09:39:38 INFO - PROCESS | 1947 | 09:39:40 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:49525/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49525/tests/kraken/driver.html?testName=audio-oscillator) 09:39:41 INFO - PROCESS | 1947 | RSS: Main: 283287552 09:39:41 INFO - PROCESS | 1947 | 09:39:43 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:49525/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49525/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:39:44 INFO - PROCESS | 1947 | RSS: Main: 286797824 09:39:44 INFO - PROCESS | 1947 | 09:39:47 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:49525/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49525/tests/kraken/driver.html?testName=imaging-darkroom) 09:39:47 INFO - PROCESS | 1947 | RSS: Main: 326574080 09:39:47 INFO - PROCESS | 1947 | 09:39:50 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:49525/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49525/tests/kraken/driver.html?testName=imaging-desaturate) 09:39:51 INFO - PROCESS | 1947 | RSS: Main: 330047488 09:39:51 INFO - PROCESS | 1947 | 09:39:54 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:49525/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49525/tests/kraken/driver.html?testName=json-parse-financial) 09:39:55 INFO - PROCESS | 1947 | RSS: Main: 328613888 09:39:55 INFO - PROCESS | 1947 | 09:39:56 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:49525/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49525/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:39:57 INFO - PROCESS | 1947 | RSS: Main: 316149760 09:39:57 INFO - PROCESS | 1947 | 09:39:59 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:49525/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49525/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:40:00 INFO - PROCESS | 1947 | RSS: Main: 322670592 09:40:00 INFO - PROCESS | 1947 | 09:40:02 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:49525/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49525/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:40:03 INFO - PROCESS | 1947 | RSS: Main: 324628480 09:40:03 INFO - PROCESS | 1947 | 09:40:06 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:49525/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49525/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:40:06 INFO - PROCESS | 1947 | RSS: Main: 326225920 09:40:06 INFO - PROCESS | 1947 | 09:40:09 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:49525/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49525/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:40:10 INFO - PROCESS | 1947 | RSS: Main: 321740800 09:40:10 INFO - PROCESS | 1947 | 09:40:12 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:49525/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49525/tests/kraken/driver.html?testName=ai-astar) 09:40:12 INFO - PROCESS | 1947 | RSS: Main: 368791552 09:40:12 INFO - PROCESS | 1947 | 09:40:12 INFO - PROCESS | 1947 | __start_tp_report 09:40:12 INFO - PROCESS | 1947 | _x_x_mozilla_page_load 09:40:12 INFO - PROCESS | 1947 | _x_x_mozilla_page_load_details 09:40:12 INFO - PROCESS | 1947 | |i|pagename|runs| 09:40:12 INFO - PROCESS | 1947 | |0;ai-astar;110;104;112;112;110;113;112;112;112;111 09:40:12 INFO - PROCESS | 1947 | |1;audio-beat-detection;156;120;141;211;103;156;165;144;194;137 09:40:12 INFO - PROCESS | 1947 | |2;audio-dft;186;184;185;184;185;186;184;183;185;184 09:40:12 INFO - PROCESS | 1947 | |3;audio-fft;77;72;57;126;57;61;57;59;113;56 09:40:12 INFO - PROCESS | 1947 | |4;audio-oscillator;90;96;135;80;79;77;103;81;80;108 09:40:12 INFO - PROCESS | 1947 | |5;imaging-gaussian-blur;88;83;87;84;86;83;87;84;87;84 09:40:12 INFO - PROCESS | 1947 | |6;imaging-darkroom;93;93;94;93;96;93;93;94;93;94 09:40:12 INFO - PROCESS | 1947 | |7;imaging-desaturate;83;86;84;86;86;84;84;85;85;89 09:40:12 INFO - PROCESS | 1947 | |8;json-parse-financial;60;60;61;60;59;59;61;120;60;57 09:40:12 INFO - PROCESS | 1947 | |9;json-stringify-tinderbox;63;62;71;75;71;68;66;66;67;67 09:40:12 INFO - PROCESS | 1947 | |10;stanford-crypto-aes;85;85;84;84;85;85;80;84;82;83 09:40:12 INFO - PROCESS | 1947 | |11;stanford-crypto-ccm;140;137;139;145;148;135;139;138;264;140 09:40:12 INFO - PROCESS | 1947 | |12;stanford-crypto-pbkdf2;168;170;170;168;150;205;227;169;167;167 09:40:12 INFO - PROCESS | 1947 | |13;stanford-crypto-sha256-iterative;68;68;68;67;66;68;67;67;66;66 09:40:12 INFO - PROCESS | 1947 | __end_tp_report 09:40:12 INFO - PROCESS | 1947 | __start_cc_report 09:40:12 INFO - PROCESS | 1947 | _x_x_mozilla_cycle_collect,2202 09:40:12 INFO - PROCESS | 1947 | __end_cc_report 09:40:12 INFO - PROCESS | 1947 | __startTimestamp1462984812352__endTimestamp 09:40:12 INFO - PROCESS | 1947 | 09:40:12 INFO - PROCESS | 1947 | ------- Summary: start ------- 09:40:12 INFO - PROCESS | 1947 | Number of tests: 14 09:40:12 INFO - PROCESS | 1947 | 09:40:12 INFO - PROCESS | 1947 | [#0] ai-astar Cycles:10 Average:110.80 Median:112.00 stddev:2.57 (2.3%) stddev-sans-first:2.71 09:40:12 INFO - PROCESS | 1947 | Values: 110.0 104.0 112.0 112.0 110.0 113.0 112.0 112.0 112.0 111.0 09:40:12 INFO - PROCESS | 1947 | 09:40:12 INFO - PROCESS | 1947 | [#1] audio-beat-detection Cycles:10 Average:152.70 Median:156.00 stddev:32.10 (20.6%) stddev-sans-first:34.03 09:40:12 INFO - PROCESS | 1947 | Values: 156.0 120.0 141.0 211.0 103.0 156.0 165.0 144.0 194.0 137.0 09:40:12 INFO - PROCESS | 1947 | 09:40:12 INFO - PROCESS | 1947 | [#2] audio-dft Cycles:10 Average:184.60 Median:185.00 stddev:0.97 (0.5%) stddev-sans-first:0.88 09:40:12 INFO - PROCESS | 1947 | Values: 186.0 184.0 185.0 184.0 185.0 186.0 184.0 183.0 185.0 184.0 09:40:12 INFO - PROCESS | 1947 | 09:40:12 INFO - PROCESS | 1947 | [#3] audio-fft Cycles:10 Average:73.50 Median:58.00 stddev:25.43 (43.8%) stddev-sans-first:26.94 09:40:12 INFO - PROCESS | 1947 | Values: 77.0 72.0 57.0 126.0 57.0 61.0 57.0 59.0 113.0 56.0 09:40:12 INFO - PROCESS | 1947 | 09:40:12 INFO - PROCESS | 1947 | [#4] audio-oscillator Cycles:10 Average:92.90 Median:80.00 stddev:18.38 (23.0%) stddev-sans-first:19.47 09:40:12 INFO - PROCESS | 1947 | Values: 90.0 96.0 135.0 80.0 79.0 77.0 103.0 81.0 80.0 108.0 09:40:12 INFO - PROCESS | 1947 | 09:40:12 INFO - PROCESS | 1947 | [#5] imaging-gaussian-blur Cycles:10 Average:85.30 Median:86.50 stddev:1.89 (2.2%) stddev-sans-first:1.73 09:40:12 INFO - PROCESS | 1947 | Values: 88.0 83.0 87.0 84.0 86.0 83.0 87.0 84.0 87.0 84.0 09:40:12 INFO - PROCESS | 1947 | 09:40:12 INFO - PROCESS | 1947 | [#6] imaging-darkroom Cycles:10 Average:93.60 Median:93.50 stddev:0.97 (1.0%) stddev-sans-first:1.00 09:40:12 INFO - PROCESS | 1947 | Values: 93.0 93.0 94.0 93.0 96.0 93.0 93.0 94.0 93.0 94.0 09:40:12 INFO - PROCESS | 1947 | 09:40:12 INFO - PROCESS | 1947 | [#7] imaging-desaturate Cycles:10 Average:85.20 Median:85.50 stddev:1.69 (2.0%) stddev-sans-first:1.59 09:40:12 INFO - PROCESS | 1947 | Values: 83.0 86.0 84.0 86.0 86.0 84.0 84.0 85.0 85.0 89.0 09:40:12 INFO - PROCESS | 1947 | 09:40:12 INFO - PROCESS | 1947 | [#8] json-parse-financial Cycles:10 Average:65.70 Median:60.00 stddev:19.11 (31.9%) stddev-sans-first:20.16 09:40:12 INFO - PROCESS | 1947 | Values: 60.0 60.0 61.0 60.0 59.0 59.0 61.0 120.0 60.0 57.0 09:40:12 INFO - PROCESS | 1947 | 09:40:12 INFO - PROCESS | 1947 | [#9] json-stringify-tinderbox Cycles:10 Average:67.60 Median:67.50 stddev:3.89 (5.8%) stddev-sans-first:3.76 09:40:12 INFO - PROCESS | 1947 | Values: 63.0 62.0 71.0 75.0 71.0 68.0 66.0 66.0 67.0 67.0 09:40:12 INFO - PROCESS | 1947 | 09:40:12 INFO - PROCESS | 1947 | [#10] stanford-crypto-aes Cycles:10 Average:83.70 Median:84.50 stddev:1.64 (1.9%) stddev-sans-first:1.67 09:40:12 INFO - PROCESS | 1947 | Values: 85.0 85.0 84.0 84.0 85.0 85.0 80.0 84.0 82.0 83.0 09:40:12 INFO - PROCESS | 1947 | 09:40:12 INFO - PROCESS | 1947 | [#11] stanford-crypto-ccm Cycles:10 Average:152.50 Median:140.00 stddev:39.36 (28.1%) stddev-sans-first:41.49 09:40:12 INFO - PROCESS | 1947 | Values: 140.0 137.0 139.0 145.0 148.0 135.0 139.0 138.0 264.0 140.0 09:40:12 INFO - PROCESS | 1947 | 09:40:12 INFO - PROCESS | 1947 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.10 Median:169.50 stddev:22.43 (13.2%) stddev-sans-first:23.60 09:40:12 INFO - PROCESS | 1947 | Values: 168.0 170.0 170.0 168.0 150.0 205.0 227.0 169.0 167.0 167.0 09:40:12 INFO - PROCESS | 1947 | 09:40:12 INFO - PROCESS | 1947 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.10 Median:67.50 stddev:0.88 (1.3%) stddev-sans-first:0.87 09:40:12 INFO - PROCESS | 1947 | Values: 68.0 68.0 68.0 67.0 66.0 68.0 67.0 67.0 66.0 66.0 09:40:12 INFO - PROCESS | 1947 | -------- Summary: end -------- 09:40:12 INFO - PROCESS | 1947 | 09:40:13 INFO - TEST-INFO | 1947: exit 0 09:40:13 INFO - TEST-OK | kraken | took 61145ms 09:40:13 INFO - SUITE-END | took 1176s 09:40:13 INFO - Completed test suite (00:19:36) 09:40:13 INFO - javascript benchmark 09:40:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3393.3552625001253, 3398.490226466028, 3423.537822393976, 3436.3127374525084, 3466.878659246927, 5553.472420120087, 5603.719814009303, 5618.0, 5619.943800561993, 5668.319801623811, 5617.269754931864, 5647.887042259153, 5670.4896559309655, 5749.425057494249, 5758.654480731148, 5043.672161309503, 5108.66793658411, 5132.563732082777, 5154.768035438415, 5242.737863106849, 5206.297149884755, 5245.711485868275, 5255.76349064293, 5271.736413179345, 5283.841484755463, 5066.2907192992825, 5114.053900028507, 5120.615953803443, 5132.358455193101, 5168.457311982225, 5073.46728593496, 5084.949150508484, 5113.360829896263, 5128.974355128238, 5232.084385232607, 5084.466131056222, 5084.745762711849, 5113.258577506278, 5149.742512874379, 5187.870303242411, 5457.208704737791, 5550.972245138749, 5607.495325420691, 5611.663300202001, 5618.803341883015, 6183.227096612924, 6228.252609686867, 6258.436740693314, 6265.968670156665, 6278.246610406781, 5077.669951453144, 6265.060240963846, 6286.591371560879, 6311.684415779238, 6317.684115794192, 5671.631343962629, 5750.511206547407, 5801.3038435388025, 5821.301443826683, 5852.853678658025, 6225.501959843202, 6228.626282423069, 6233.470155036782, 6234.220722409699, 6253.1245625612455, 6136.907946380718, 6150.569460137747, 6172.290186628506, 6173.074038894202, 6177.1969643945995, 6055.152278680988, 6073.240844894388, 6129.419919118773, 6202.937970620236, 6222.253329600477, 6083.482903953125, 6096.163860440966, 6123.679189354777, 6141.209856311994, 6160.537959653044, 6239.875202495925, 6266.968665156645, 6267.341929097471, 6269.905951410732, 6278.654673992883, 4913.729744863996, 4923.926141107886, 4935.358403407534, 4957.752112394366, 4967.4784147664695, 4370.147821174904, 4381.992141807402, 4459.687821852439, 4463.598276155161, 4479.59683628475, 4626.791794369262, 5630.296212973379, 5663.575231857545, 5669.121286200645, 5703.058995265817, 6262.937370626236, 6288.9056664150075, 6296.244450665858, 6302.243730752339, 6321.178246827975, 5533.916991245134, 5578.386377498472, 5582.190582365534, 5624.690642014647, 5654.547636189177, 5380.461953804588, 5397.001554712391, 5425.728713564385, 5434.538064264581, 5465.344158700981, 6256.249250090018, 6287.748490060347, 6294.9685251574365, 6327.240731112204, 6334.493240540838, 6100.762843515285, 6164.106204600218, 6230.43926046658, 6298.244210694746, 6305.464035557029, 6274.811755647155, 6292.433680968735, 6311.842203944938, 6320.367963203643, 6321.1782468278825, 5983.311919129356, 6002.909956350745, 6178.505719542516, 6179.134921110958, 6187.690615469299, 6068.089786532056, 6149.585595313014, 6153.969230153821, 6168.444839964424, 6265.248170219603, 5065.696058236517, 6104.053871649901, 6114.602550834182, 6133.3866613338305, 6170.969145154425, 6118.785842495669, 6119.35746746603, 6124.081387791689, 6139.416755408229, 6141.109539116893, 4572.0, 5721.685307308138, 5767.452092051249, 5944.73248703818, 5956.523478121827, 6111.86523267538, 6120.081455037822, 6126.517673797367, 6134.202553667995, 6140.039674409892, 5917.556183286357, 5995.490383317292, 6011.248593925759, 6050.485708714808, 6051.788187413419], "value": 5658.343248409108, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [500.694576308452, 517.9300794392756, 525.7292494365403, 535.469884814034, 539.606087556084, 24364.756352436492, 31060.689393106062, 32171.356572868528, 32202.0, 32259.0, 32443.67556324442, 43969.0, 45519.86200344993, 45799.62601121972, 48209.79475513114, 9430.52847357631, 9583.904160958406, 9591.66429174979, 9591.712248632517, 9595.088466595696, 8570.18583234595, 9479.43123412594, 9596.616135354578, 9606.423614583147, 9616.691993921479, 8609.440386374898, 11707.356095414749, 11896.04831613469, 11926.46330915111, 12005.979491743212, 11771.941140294286, 11926.52293908247, 11933.284002959763, 11936.58221962227, 11942.283462992249, 8528.275096616795, 11274.887251127466, 11579.53681852725, 11677.474513646821, 11816.29102253859, 41521.1695766083, 41912.580874191175, 41940.0, 41948.0, 41957.741267762016, 3234.214427123223, 3241.9675803241903, 3260.9021729348156, 3278.327942771726, 3465.896023119285, 28400.573991390145, 29443.70556294431, 30146.397072058655, 30178.09465716032, 30334.938277160192, 2534.759197876199, 2651.9867400662874, 2692.0981471206974, 2723.536998710224, 2751.793615478847, 510852.0, 515437.0, 515697.0, 515734.9999999963, 516630.0, 66983.0, 67572.64854702879, 67614.0, 67628.0, 67669.66165169241, 71506.64246678837, 71628.0, 71665.2833471669, 71710.28289717036, 71769.64115179391, 17111.0, 17718.734218986727, 17726.556836079202, 17739.93560386381, 17758.822411775716, 13323.733525329633, 13418.597442076752, 13453.596392108253, 13482.123661961941, 13524.93237533806, 17339.47981560555, 17984.0, 19686.507837304183, 19892.717701579368, 20600.27899023527, 8173.918260817434, 8194.877076843852, 8201.343892488587, 8220.506769593843, 8283.716024016287, 813.4468561378319, 943.5470973932413, 1016.1362841584742, 1020.3061917895753, 1037.3361048928691, 27459.0, 29991.850040749658, 30136.698633013828, 30559.0, 30755.692443075284, 1079.5627770752858, 1098.417838545572, 1158.5771193514306, 1188.4057971014493, 1262.2994238197705, 13565.932170339085, 13846.930765346311, 15378.231088445533, 15423.0, 15841.128737919295, 666867.0, 670527.0, 673320.0, 674026.0, 674194.0, 62452.0, 62831.0, 63016.68491657513, 63054.369456305765, 63111.36888631055, 1469.7207530569158, 1482.7182835261267, 1488.0104730354194, 1505.787840788176, 1509.939602415913, 29142.70857291485, 29339.559906601844, 29382.853085734434, 29944.700552994193, 30027.69972300249, 54544.72727636337, 55159.0, 55559.72220139035, 55637.721811390686, 55647.443525564224, 415958.0, 477519.6124019358, 482776.0, 483020.754688687, 484454.0, 24117.517649646557, 24539.263822085366, 27014.594781077907, 27310.5903411445, 27647.170584882486, 46177.0, 47481.81295467503, 47741.283880742514, 47819.52180478151, 49054.26418603795, 37130.628693712715, 38593.03517412087, 38884.80557597307, 39580.208395832495, 39854.0, 37548.812255939636, 37606.62393376031, 37693.05767355728, 38493.0, 38501.03747406337, 34099.0, 37605.43591846179, 37691.0, 38864.80567597144, 38883.0, 33036.1740956478, 34435.82782086074, 34476.48285275673, 34490.655093448746, 39822.0, 28582.0, 30053.248668783457, 30766.230844229074, 31045.68954310428, 31834.36331273408], "value": 20480.45737439458, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [384.2909831361139, 390.3383764519139, 393.0939185178163, 396.91466334738027, 416.6292000119894, 12520.0, 17722.0, 20426.693599596, 21747.78252217479, 21760.564788704218, 19953.501162470944, 20256.696149557767, 22125.557488850212, 22274.331770046883, 22287.554248915054, 5748.655080695161, 6070.57505974582, 6072.51419886408, 6077.817665470043, 6079.20970273866, 5704.258446401962, 5950.761969521214, 6004.429579189992, 6095.603785753934, 6608.372204640576, 6581.111549940744, 6605.636689982049, 6607.867842643168, 6616.338366163393, 6617.172853393326, 6411.070394792729, 6481.773137940173, 6575.0, 6583.9012414813815, 6584.6707664616815, 5508.724563771835, 5699.74351154199, 5748.109043098327, 5778.133280007991, 5779.884402311972, 26424.0, 26780.794864231157, 27052.59421108685, 27065.052723154793, 27310.726892731214, 1950.0737149853849, 1970.753655793026, 2154.450615093157, 2170.945726356838, 2184.6067707812585, 10251.128654064414, 10258.333208341433, 10403.0, 14600.342984565614, 15202.619934501616, 1724.844763971236, 1786.428342930263, 1798.1009495252374, 1818.7544681467964, 1955.4231501707031, 109089.0, 131412.0, 133475.50723846946, 134633.0, 137093.0, 11759.412029398496, 11817.054635629129, 11854.525818967317, 11869.643910682693, 11982.88017119835, 12457.439415226336, 13137.934310328386, 15327.846721532642, 15410.845891541165, 15428.38286468551, 8411.45325553837, 8474.88127968008, 8773.473591584525, 8940.68707595231, 9064.682736104205, 8893.199612034949, 8893.955530222436, 8910.509921954224, 9014.278857691368, 9268.814623707489, 4677.134730074947, 4707.905841883212, 4709.1994360958715, 4721.834735784231, 4769.713817170981, 1657.8590819780454, 1934.9806501935082, 1971.5859669469285, 1972.7336809530816, 1995.092233033966, 902.7201567513961, 933.3653115881131, 966.642896759376, 1016.425719468498, 1030.6701855406197, 17443.21505532254, 20729.378118656463, 20742.170313187304, 21120.366389008355, 21179.364619061453, 972.4554249620236, 975.0, 1000.574181790945, 1009.8838633557089, 1045.811753884308, 10974.561017559368, 11190.38452885083, 11206.607768728056, 11233.71915702114, 11257.155713321534, 587610.0, 589458.0, 610578.0, 611483.0, 612068.0, 117534.0, 139659.0, 141093.58906410803, 142812.0, 146667.0, 1011.5195282241024, 1015.2334987084852, 1034.8706411698538, 1087.1737479515466, 1090.0788833435734, 19034.809651903302, 19341.903290483457, 19429.417117486497, 19490.0, 19625.70561441608, 39532.80233598813, 42288.0, 45869.85325366759, 51154.20960266373, 54022.0, 60347.69826150841, 68093.65953170203, 78666.24668136588, 79808.2019179824, 81733.3653326942, 7967.442279040411, 8097.2712455879255, 8183.386246031452, 8240.258376746122, 8509.404341696021, 10645.308054976402, 11559.595414160463, 12470.314132722788, 12624.305663188245, 12785.0, 11012.779744405227, 11232.045276151619, 11280.379579123228, 11441.42792860337, 11544.307341559534, 10680.465976701289, 10979.725506862393, 11115.444227788417, 11148.498317575892, 11424.942875285571, 10039.498025098861, 10770.922907709166, 10978.615748448767, 11552.133589980616, 11750.823737643772, 7051.365377116084, 8084.434089613671, 8224.177582241728, 8336.62485188156, 8353.749387518385, 8374.455660382057, 10245.948770256102, 10890.821835633064, 11316.9434152832, 11323.660290191307], "value": 9747.969388065609, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1447.2329665277407, 1629.7473891546815, 1653.00819508295, 1894.3559189875439, 1994.850386221034, 7894.605269736512, 8272.338212942965, 8284.047334556528, 8285.668573257062, 8293.04629967554, 8392.202740739634, 8702.216800487955, 8712.390132690714, 8841.469511829295, 8852.203301702846, 2365.7042869641336, 2458.2748089313695, 2461.8153638477097, 2466.6053431450973, 2470.1601455505124, 2413.2036427978724, 2433.4038160650666, 2453.668754718108, 2457.0663148003823, 2461.5815311397014, 2417.2748175547354, 2437.8049756019564, 2449.80401567875, 2455.852648841066, 2460.8646524441147, 2407.57867373209, 2408.7350391456926, 2447.3881529617597, 2449.9387515312083, 2452.8405653632544, 2428.9757102429103, 2441.316431399211, 2470.357706996176, 2476.702795664532, 2477.170148000422, 8790.912090879074, 8883.244924181452, 8883.333749968713, 8886.022537520868, 8901.73294801157, 5331.067063263913, 5333.546648534879, 5463.808766693147, 5473.917891231635, 5490.313710786152, 3435.3129374125274, 3581.3553560359123, 3647.8905632831056, 3662.9633703663153, 3671.4492826076303, 1049.9842502362471, 1053.1995683280686, 1055.4986381468818, 1078.9161392405017, 1081.0756802933536, 11415.543378264943, 11747.29516229029, 11749.353785541793, 12003.57987470443, 12067.637970860977, 4102.261592913304, 4619.145458090264, 4625.421822272216, 4712.64655150865, 4727.787249573708, 9239.214666753267, 9253.537323133816, 9255.629774809067, 9262.675806346746, 9266.907330926739, 5306.204069389545, 5309.177077552986, 5312.574994000471, 5312.574994000471, 5321.308229930084, 4436.733795972187, 4606.838760643362, 4794.232922732346, 4894.926576101361, 4943.233798761198, 3739.9439008414897, 3751.981240093782, 3826.827792749333, 3861.864834730771, 3946.901327466779, 1094.7208461842338, 1098.5122236897816, 1167.0896700573567, 1181.8640856301463, 1199.7960346741074, 647.4917190005823, 789.6841263494648, 837.4598384042258, 892.4912799704107, 957.9616815327308, 4687.179743544866, 5862.237909071793, 5984.371640977722, 6133.478654314434, 6163.537734669918, 903.8599017152352, 944.2681921510884, 948.6015873333216, 955.4888134847823, 983.9458829764432, 3832.846686132579, 3963.365861462152, 4030.979845100756, 4049.493813273341, 4049.7367671101283, 10592.682219533426, 10719.78560428787, 11108.666739997812, 11262.0, 11269.436528173559, 10735.033846953811, 11082.16883733707, 11177.21759476845, 11200.831987520194, 11248.887511124947, 8136.430449868571, 8383.748487545383, 8405.537695426689, 8432.198941100585, 8442.366822488339, 5701.458361455655, 5810.4189581041555, 5843.415658434123, 5899.734511947058, 5907.675077870586, 9994.350367226107, 10163.491825408551, 10225.539850706884, 10344.948275258877, 10353.327033742782, 10068.949655251678, 10381.584736610452, 10414.635487758194, 10431.0, 10536.894631053592, 7066.187388450188, 7151.82120446993, 7220.89168662481, 7239.710411583477, 7257.927420725937, 8145.307648849915, 8394.202550757669, 8420.915790842013, 8427.0, 8437.734339848826, 6111.5110791137495, 7433.2195119513835, 7479.512292624382, 7691.15397306307, 7779.883301750591, 6338.619682819046, 7334.486585938933, 7394.482386232912, 7447.404207663482, 7526.285002924714, 6605.0, 6956.304369563206, 7033.226345102137, 7056.047433596399, 7064.116985376828, 4816.566509014206, 4820.25286080658, 4899.4365647950945, 4973.229149481836, 5118.41138269089, 4882.5605695487575, 4902.117618828645, 4983.377077865267, 5019.3976722793495, 5203.453637368022], "value": 4589.642681594673, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.54317437495007, 304.94206100840836, 315.6370174299555, 319.36287107221096, 321.7024252566377, 4393.362962370455, 4853.762290615897, 5131.255967884659, 5135.5634771044415, 5276.894462110756, 4228.6256966485935, 4283.571642835715, 4526.932096018554, 4924.361605961181, 5177.9708870310915, 3422.760406771527, 3680.0983758979087, 3700.3903302063554, 4006.417465101283, 4006.599340065999, 3394.6605339466105, 3636.0, 3828.9617103829023, 3982.1836523512757, 4023.0, 3512.2448673535187, 3540.504329393887, 3781.640744129303, 3973.2450834341525, 3985.322495175827, 3504.404251277289, 3952.6442620164034, 3961.227560625679, 3998.5601583825755, 4030.3349947258666, 3498.475228715713, 3502.0038578010544, 3504.211552400715, 3507.7369197310304, 3523.594786599548, 4502.396516307644, 4556.567126123013, 4962.727050012248, 4993.700377977297, 5082.618803589746, 3683.0, 3962.960370396288, 4350.347447882843, 4557.951671115628, 4564.475085365192, 5148.768305426265, 5339.492748188916, 5400.621956463049, 5416.345990464683, 5457.808976685837, 3366.5118557809224, 3597.4244120940784, 3674.816259187057, 3714.275716235335, 3907.5310962684375, 4496.460424749019, 4799.536141476849, 5219.295395121648, 5225.111731005738, 5451.809186678447, 3334.699877011081, 3709.6104908984707, 3723.627637236309, 3743.7192210584317, 3898.2788184186015, 3602.8290805488323, 3940.5271367435516, 3951.9329780959556, 3965.2466031453932, 3992.5208974922616, 4484.192845287815, 4546.65900057497, 4550.247100504147, 4560.72635641856, 4591.655625828076, 4479.910401791946, 4480.566218810015, 4552.9772351138035, 5240.318758561362, 5540.260324281094, 1170.8302296167008, 1208.6615747590688, 1221.8900298972958, 1330.7405056014177, 1338.1235290884485, 808.6846130009301, 835.8077642142341, 845.2772879188328, 846.7798372423091, 847.6733911428595, 3825.713071519647, 4020.3567429211184, 4103.097318589965, 4253.234417804825, 4260.91478170441, 3929.8035098244973, 4005.176350672769, 4209.084866385787, 4515.819367225275, 4650.627949764011, 4199.349100889367, 4263.467066616673, 4268.679849011337, 4272.829086836554, 4358.888483436766, 2065.019115919926, 2150.7203214087594, 2328.0455013444407, 2467.925962221136, 2521.5037113257745, 4165.125323682, 4223.852165174204, 4395.164918665443, 4667.899445249651, 4668.85993420198, 4361.760103194291, 4711.809008810919, 4870.0, 4873.0, 4907.754612269372, 4003.978985358773, 4379.912401752011, 4460.843870464518, 4593.517680643551, 4989.975050124726, 3969.7618142910364, 4403.273459879084, 4683.447521101076, 4758.4765675776325, 4759.3574867393845, 4261.765602891871, 4388.2759344707765, 4543.909121817611, 4590.862274131781, 4756.476787553435, 4297.82808687649, 4795.7388975495605, 4799.9440123174245, 4904.386951631046, 4915.508449155056, 3090.2119959410206, 3611.850948685528, 3905.5744653201946, 3912.4545804407667, 3942.5071866016747, 4784.569388755029, 5512.283403157564, 5580.693061881473, 5601.327840659147, 5662.490375866192, 4784.545468180518, 4817.397825271841, 5020.924686129784, 5479.726013699379, 5550.5282051026115, 5018.598512118949, 5304.443033481429, 5318.069337865935, 5375.08623534008, 5383.035192154914, 4292.892677683083, 4480.551944805494, 5220.243064755513, 5223.503767142115, 5344.465553444625, 5024.341967150758, 5175.0, 5199.324087868553, 5378.327709036371, 5486.256871564218, 4864.883775557644, 5018.598512119095, 5090.974545127251, 5283.920741188808, 5326.97336513315], "value": 3732.4448578274455, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [627.4666533446571, 634.3263145840282, 714.2464699741773, 748.1695318196801, 754.4492520460064, 15031.248437578119, 15507.767383489258, 15615.219239038046, 15773.842261577382, 15955.883088183831, 14501.564953051398, 14564.41742330308, 14636.4877229297, 14651.706965860676, 14675.412983480675, 715.9248278930716, 907.4782000349792, 924.6994726713812, 925.4632313258328, 926.4996901673122, 736.5433431272619, 742.5136535569184, 743.4275607782005, 744.5383862005564, 747.4132805747489, 950.4724877692878, 980.6665733650557, 984.7193549838297, 986.4229425785924, 989.5893204320198, 966.3766870368643, 973.0366936732619, 976.3995142987012, 976.5995941663883, 977.5112443778111, 474.90976714424494, 703.855709579535, 722.8409749855024, 723.0311382747144, 723.1033518437152, 14634.853651463456, 14920.477783277533, 14984.175870327128, 14993.475228367064, 15012.699746005019, 12615.369231538387, 12623.368831558386, 12667.176633518791, 12696.365181740875, 12892.097553171281, 10917.563297468092, 10960.671179864617, 10979.011888930034, 11215.694045377952, 11323.037541808957, 4054.148628787958, 4055.7972101395108, 4060.756354618703, 4071.450354202204, 4204.600562946515, 84728.57635711782, 85757.14242857553, 86532.0, 86861.0, 88860.0, 8216.46592971455, 9372.39079459833, 9663.082007209305, 9879.259055570861, 9930.0, 22502.0, 22538.323850284516, 22562.984665690085, 22569.774302257094, 22625.660615090786, 7403.296686814744, 7426.108866936002, 7455.7017719290625, 7528.774136775905, 7614.048243969504, 4004.4794176756836, 4028.093678922219, 4046.8785936421955, 4046.988252936766, 4047.0, 3371.072954937412, 3484.337975784593, 3490.0402389343135, 3490.267043920805, 3496.5279687242405, 602.108878859282, 614.8770245950739, 619.7830759234322, 625.6652690810486, 625.978090766821, 471.61092099018447, 521.7521677203374, 536.2599612534677, 572.9111987641922, 581.16022347708, 7348.889766653505, 7504.699812007568, 7876.409269304825, 7965.322947549407, 8047.758567242875, 455.26020217147135, 544.5262621519304, 569.0780934885512, 569.5301376364433, 595.425414475025, 3688.3360995020616, 3715.64701353371, 3770.472133901256, 3944.9605503945168, 3967.4247234150885, 88186.67719984206, 93160.53419732858, 93894.53052734693, 94775.0, 95557.0, 80424.98937526492, 81238.59380703176, 83513.58243208745, 84033.99999999878, 84675.0, 56333.7183314089, 69501.65249173823, 69652.34282089851, 71839.64080179566, 72349.0, 7377.926220737724, 7440.73957411488, 7476.514026588255, 7509.887351689838, 7522.774316770506, 40142.99642508961, 45545.54454455546, 45989.310160346955, 46523.0, 46790.0, 57819.0, 61352.0, 61665.07502387557, 61871.38128618656, 61940.0, 10778.245522813328, 11133.83299250528, 11225.382603956861, 11347.0, 11582.976852037062, 11821.704457388634, 11842.46708898119, 11843.949772824584, 11871.881281187078, 12992.935035324763, 11396.259243149168, 11531.308121512737, 11926.880731192578, 12057.758844823229, 12310.938445307716, 11332.263402878787, 11394.202405831513, 11559.075273977893, 11776.941115294369, 11884.0, 11199.496022679163, 11213.27113737637, 11470.369129697612, 11613.187076904536, 11692.298462092302, 9413.858792117986, 9429.292803039662, 9469.0, 9482.478463684287, 10111.595536178766, 7318.31576844322, 8760.561971901507, 8851.778705532413, 9557.665481708107, 9603.567839447102], "value": 6837.138901172817, "unit": "score"}], "name": "dromaeo_css", "value": 7138.499891054197, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 104.0, 112.0, 112.0, 110.0, 113.0, 112.0, 112.0, 112.0, 111.0], "value": 110.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 120.0, 141.0, 211.0, 103.0, 156.0, 165.0, 144.0, 194.0, 137.0], "value": 152.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 184.0, 185.0, 184.0, 185.0, 186.0, 184.0, 183.0, 185.0, 184.0], "value": 184.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 72.0, 57.0, 126.0, 57.0, 61.0, 57.0, 59.0, 113.0, 56.0], "value": 73.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 96.0, 135.0, 80.0, 79.0, 77.0, 103.0, 81.0, 80.0, 108.0], "value": 92.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 83.0, 87.0, 84.0, 86.0, 83.0, 87.0, 84.0, 87.0, 84.0], "value": 85.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 94.0, 93.0, 96.0, 93.0, 93.0, 94.0, 93.0, 94.0], "value": 93.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 86.0, 84.0, 86.0, 86.0, 84.0, 84.0, 85.0, 85.0, 89.0], "value": 85.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 61.0, 60.0, 59.0, 59.0, 61.0, 120.0, 60.0, 57.0], "value": 65.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 71.0, 75.0, 71.0, 68.0, 66.0, 66.0, 67.0, 67.0], "value": 67.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 84.0, 84.0, 85.0, 85.0, 80.0, 84.0, 82.0, 83.0], "value": 83.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 137.0, 139.0, 145.0, 148.0, 135.0, 139.0, 138.0, 264.0, 140.0], "value": 152.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 170.0, 170.0, 168.0, 150.0, 205.0, 227.0, 169.0, 167.0, 167.0], "value": 176.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 68.0, 67.0, 66.0, 68.0, 67.0, 67.0, 66.0, 66.0], "value": 67.1, "unit": "score"}], "name": "kraken", "value": 1491.3, "alertThreshold": 2.0}]} 09:40:13 INFO - Return code: 0 09:40:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 09:40:13 ERROR - # TBPL SUCCESS # 09:40:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:40:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:40:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:40:13 INFO - 2016-05-11 09:40:13.662 screenresolution[1951:12210] starting screenresolution argv=screenresolution get 09:40:13 INFO - 2016-05-11 09:40:13.665 screenresolution[1951:12210] Display 0: 1600x1200x32@60 09:40:13 INFO - 2016-05-11 09:40:13.672 screenresolution[1952:12213] starting screenresolution argv=screenresolution list 09:40:13 INFO - Available Modes on Display 0 09:40:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:40:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:40:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:40:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:40:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:40:13 INFO - Intel Iris: 09:40:13 INFO - Chipset Model: Intel Iris 09:40:13 INFO - Type: GPU 09:40:13 INFO - Bus: Built-In 09:40:13 INFO - VRAM (Dynamic, Max): 1536 MB 09:40:13 INFO - Vendor: Intel (0x8086) 09:40:13 INFO - Device ID: 0x0a2e 09:40:13 INFO - Revision ID: 0x0009 09:40:13 INFO - Displays: 09:40:13 INFO - Display: 09:40:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:40:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:40:13 INFO - Display Serial Number: 1600x1200 60 09:40:13 INFO - Main Display: Yes 09:40:13 INFO - Mirror: Off 09:40:13 INFO - Online: Yes 09:40:13 INFO - Rotation: Supported 09:40:13 INFO - Return code: 0 09:40:13 INFO - Running post-action listener: _resource_record_post_action 09:40:13 INFO - ##### 09:40:13 INFO - ##### Finished run-tests step (success) 09:40:13 INFO - ##### 09:40:13 INFO - Running post-run listener: _resource_record_post_run 09:40:14 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 425984; Write bytes: 693198336; Read time: 9; Write time: 1802 09:40:14 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 381643264; Write bytes: 376477696; Read time: 28347; Write time: 675 09:40:14 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 425984; Write bytes: 295233024; Read time: 9; Write time: 1076 09:40:14 INFO - Running post-run listener: _upload_blobber_files 09:40:14 INFO - Blob upload gear active. 09:40:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:40:14 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 09:40:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:40:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:40:15 INFO - (blobuploader) - INFO - Open directory for files ... 09:40:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:40:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:40:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:40:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:40:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:40:16 INFO - (blobuploader) - INFO - Done attempting. 09:40:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:40:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:40:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:40:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:40:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:40:16 INFO - (blobuploader) - INFO - Done attempting. 09:40:16 INFO - (blobuploader) - INFO - Iteration through files over. 09:40:17 INFO - Return code: 0 09:40:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:40:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:40:17 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5bf527bbf8f7f604a6d1bbd456b69e454daa4bae98bc683fa6a1809270673b41be0e73ffd88beedcd981b44574fe5a6c1f0426a43b922f987aacd1bfecc83db9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a0425bfe2229fcd6a81647124de2073fff2ccca59e1797bbebb34f822912fb7d68c1c3c683f7eccb1191144f2a3afc9f8aa6b1fc3b5418aeb1ec8ca5f48c487d"} 09:40:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:40:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:40:17 INFO - Contents: 09:40:17 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5bf527bbf8f7f604a6d1bbd456b69e454daa4bae98bc683fa6a1809270673b41be0e73ffd88beedcd981b44574fe5a6c1f0426a43b922f987aacd1bfecc83db9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a0425bfe2229fcd6a81647124de2073fff2ccca59e1797bbebb34f822912fb7d68c1c3c683f7eccb1191144f2a3afc9f8aa6b1fc3b5418aeb1ec8ca5f48c487d"} 09:40:17 INFO - Running post-run listener: copy_logs_to_upload_dir 09:40:17 INFO - Copying logs to upload dir... 09:40:17 INFO - mkdir: /builds/slave/test/build/upload/logs 09:40:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.454555 ========= master_lag: 1.28 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-05-11 09:40:18.313044) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-11 09:40:18.314202) ========= 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.WfOuxcAtG1/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.kdaAjT1HOK/Listeners TMPDIR=/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5bf527bbf8f7f604a6d1bbd456b69e454daa4bae98bc683fa6a1809270673b41be0e73ffd88beedcd981b44574fe5a6c1f0426a43b922f987aacd1bfecc83db9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a0425bfe2229fcd6a81647124de2073fff2ccca59e1797bbebb34f822912fb7d68c1c3c683f7eccb1191144f2a3afc9f8aa6b1fc3b5418aeb1ec8ca5f48c487d"} build_url:https://queue.taskcluster.net/v1/task/NcfWqedxRr-BhSdcTJbSdw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011026 build_url: 'https://queue.taskcluster.net/v1/task/NcfWqedxRr-BhSdcTJbSdw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5bf527bbf8f7f604a6d1bbd456b69e454daa4bae98bc683fa6a1809270673b41be0e73ffd88beedcd981b44574fe5a6c1f0426a43b922f987aacd1bfecc83db9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a0425bfe2229fcd6a81647124de2073fff2ccca59e1797bbebb34f822912fb7d68c1c3c683f7eccb1191144f2a3afc9f8aa6b1fc3b5418aeb1ec8ca5f48c487d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-11 09:40:18.345171) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 1 secs) (at 2016-05-11 09:40:18.345537) ========= 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.WfOuxcAtG1/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.kdaAjT1HOK/Listeners TMPDIR=/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/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.005312 ========= master_lag: 1.44 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 1 secs) (at 2016-05-11 09:40:19.787533) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-11 09:40:19.787894) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-11 09:40:19.792621) ========= ========= Total master_lag: 2.80 =========