builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0353 starttime: 1464893249.2 results: success (0) buildid: 20160601060845 builduid: e5f657fc98d04bb385d07f09e2a9e0fb revision: 111970c738234569c8c180319155327316335deb ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-02 11:47:29.201011) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-02 11:47:29.201427) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-02 11:47:29.201701) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-02 11:47:29.274711) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 11:47:29.275145) ========= 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.nozkZArR78/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.UozXDb2UW6/Listeners TMPDIR=/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-06-02 11:47:29-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.9M=0.001s 2016-06-02 11:47:29 (18.9 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.152029 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 11:47:29.451268) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 11:47:29.451548) ========= 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.nozkZArR78/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.UozXDb2UW6/Listeners TMPDIR=/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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.024771 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 11:47:29.511210) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 11:47:29.511527) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 111970c738234569c8c180319155327316335deb --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 111970c738234569c8c180319155327316335deb --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.nozkZArR78/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.UozXDb2UW6/Listeners TMPDIR=/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-06-02 11:47:29,596 truncating revision to first 12 chars 2016-06-02 11:47:29,596 Setting DEBUG logging. 2016-06-02 11:47:29,596 attempt 1/10 2016-06-02 11:47:29,596 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/111970c73823?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-02 11:47:29,942 unpacking tar archive at: mozilla-central-111970c73823/testing/mozharness/ program finished with exit code 0 elapsedTime=0.613943 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 11:47:30.154143) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-02 11:47:30.155040) ========= script_repo_revision: 111970c738234569c8c180319155327316335deb ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-02 11:47:30.155969) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-02 11:47:30.156657) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-02 11:47:30.175713) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-06-02 11:47:30.176566) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.nozkZArR78/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.UozXDb2UW6/Listeners TMPDIR=/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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 11:47:30 INFO - MultiFileLogger online at 20160602 11:47:30 in /builds/slave/test 11:47:30 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 11:47:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:47:30 INFO - {'append_to_log': False, 11:47:30 INFO - 'base_work_dir': '/builds/slave/test', 11:47:30 INFO - 'blob_upload_branch': 'Firefox', 11:47:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:47:30 INFO - 'branch': 'Firefox', 11:47:30 INFO - 'buildbot_json_path': 'buildprops.json', 11:47:30 INFO - 'config_files': ('talos/mac_config.py',), 11:47:30 INFO - 'default_actions': ('clobber', 11:47:30 INFO - 'read-buildbot-config', 11:47:30 INFO - 'download-and-extract', 11:47:30 INFO - 'populate-webroot', 11:47:30 INFO - 'create-virtualenv', 11:47:30 INFO - 'install', 11:47:30 INFO - 'run-tests'), 11:47:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:47:30 INFO - 'download_minidump_stackwalk': True, 11:47:30 INFO - 'download_symbols': 'ondemand', 11:47:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:47:30 INFO - 'tooltool.py': '/tools/tooltool.py', 11:47:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:47:30 INFO - '/tools/misc-python/virtualenv.py')}, 11:47:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:47:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:47:30 INFO - 'installer_path': 'installer.exe', 11:47:30 INFO - 'log_level': 'info', 11:47:30 INFO - 'log_name': 'talos', 11:47:30 INFO - 'log_to_console': True, 11:47:30 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:47:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:47:30 INFO - 'opt_config_files': (), 11:47:30 INFO - 'pip_index': False, 11:47:30 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:47:30 INFO - 'cmd': ('bash', 11:47:30 INFO - '-c', 11:47:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:47:30 INFO - 'enabled': True, 11:47:30 INFO - 'halt_on_failure': False, 11:47:30 INFO - 'name': 'check_screen_resolution'},), 11:47:30 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:47:30 INFO - 'cmd': ('bash', 11:47:30 INFO - '-c', 11:47:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:47:30 INFO - 'enabled': True, 11:47:30 INFO - 'halt_on_failure': False, 11:47:30 INFO - 'name': 'check_screen_resolution'},), 11:47:30 INFO - 'run_cmd_checks_enabled': True, 11:47:30 INFO - 'sps_profile': False, 11:47:30 INFO - 'sps_profile_interval': 0, 11:47:30 INFO - 'suite': 'dromaeojs-e10s', 11:47:30 INFO - 'system_bits': '32', 11:47:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:47:30 INFO - 'title': 't-yosemite-r7-0353', 11:47:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:47:30 INFO - 'use_talos_json': True, 11:47:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:47:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:47:30 INFO - 'work_dir': 'build'} 11:47:30 INFO - ##### 11:47:30 INFO - ##### Running clobber step. 11:47:30 INFO - ##### 11:47:30 INFO - Running pre-action listener: _resource_record_pre_action 11:47:30 INFO - Running main action method: clobber 11:47:30 INFO - rmtree: /builds/slave/test/build 11:47:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:47:32 INFO - Running post-action listener: _resource_record_post_action 11:47:32 INFO - ##### 11:47:32 INFO - ##### Finished clobber step (success) 11:47:32 INFO - ##### 11:47:32 INFO - ##### 11:47:32 INFO - ##### Running read-buildbot-config step. 11:47:32 INFO - ##### 11:47:32 INFO - Running pre-action listener: _resource_record_pre_action 11:47:32 INFO - Running main action method: read_buildbot_config 11:47:32 INFO - Using buildbot properties: 11:47:32 INFO - { 11:47:32 INFO - "project": "", 11:47:32 INFO - "product": "firefox", 11:47:32 INFO - "script_repo_revision": "production", 11:47:32 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 11:47:32 INFO - "repository": "", 11:47:32 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 11:47:32 INFO - "buildid": "20160601060845", 11:47:32 INFO - "pgo_build": "False", 11:47:32 INFO - "basedir": "/builds/slave/test", 11:47:32 INFO - "buildnumber": 293, 11:47:32 INFO - "slavename": "t-yosemite-r7-0353", 11:47:32 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 11:47:32 INFO - "platform": "yosemite_r7", 11:47:32 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 11:47:32 INFO - "branch": "mozilla-central", 11:47:32 INFO - "repo_path": "mozilla-central", 11:47:32 INFO - "revision": "111970c738234569c8c180319155327316335deb", 11:47:32 INFO - "stage_platform": "macosx64", 11:47:32 INFO - "builduid": "e5f657fc98d04bb385d07f09e2a9e0fb", 11:47:32 INFO - "slavebuilddir": "test" 11:47:32 INFO - } 11:47:32 INFO - Found installer url https://queue.taskcluster.net/v1/task/NN_VMbPcR6K72Fpfp_HCKg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 11:47:32 INFO - Running post-action listener: _resource_record_post_action 11:47:32 INFO - ##### 11:47:32 INFO - ##### Finished read-buildbot-config step (success) 11:47:32 INFO - ##### 11:47:32 INFO - ##### 11:47:32 INFO - ##### Running download-and-extract step. 11:47:32 INFO - ##### 11:47:32 INFO - Running pre-action listener: _resource_record_pre_action 11:47:32 INFO - Running main action method: download_and_extract 11:47:32 INFO - mkdir: /builds/slave/test/build/tests 11:47:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:47:32 INFO - https://queue.taskcluster.net/v1/task/NN_VMbPcR6K72Fpfp_HCKg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 11:47:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NN_VMbPcR6K72Fpfp_HCKg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 11:47:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NN_VMbPcR6K72Fpfp_HCKg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 11:47:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NN_VMbPcR6K72Fpfp_HCKg/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 11:47:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NN_VMbPcR6K72Fpfp_HCKg/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 11:47:35 INFO - Downloaded 1199 bytes. 11:47:35 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 11:47:35 INFO - Using the following test package requirements: 11:47:35 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 11:47:35 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:47:35 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 11:47:35 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:47:35 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 11:47:35 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:47:35 INFO - u'jsshell-mac.zip'], 11:47:35 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:47:35 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 11:47:35 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 11:47:35 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:47:35 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 11:47:35 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:47:35 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 11:47:35 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:47:35 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 11:47:35 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:47:35 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 11:47:35 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 11:47: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')]} 11:47:35 INFO - https://queue.taskcluster.net/v1/task/NN_VMbPcR6K72Fpfp_HCKg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 11:47:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NN_VMbPcR6K72Fpfp_HCKg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 11:47:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NN_VMbPcR6K72Fpfp_HCKg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 11:47:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NN_VMbPcR6K72Fpfp_HCKg/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 11:47:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NN_VMbPcR6K72Fpfp_HCKg/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 11:47:37 INFO - Downloaded 18696507 bytes. 11:47:37 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'] 11:47:37 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 11:47:40 INFO - Return code: 0 11:47:40 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 11:47:40 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')]} 11:47:40 INFO - https://queue.taskcluster.net/v1/task/NN_VMbPcR6K72Fpfp_HCKg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 11:47:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NN_VMbPcR6K72Fpfp_HCKg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 11:47:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NN_VMbPcR6K72Fpfp_HCKg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 11:47:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NN_VMbPcR6K72Fpfp_HCKg/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 11:47:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NN_VMbPcR6K72Fpfp_HCKg/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 11:47:42 INFO - Downloaded 18696507 bytes. 11:47:42 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'] 11:47:42 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 11:47:46 INFO - Return code: 0 11:47: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')]} 11:47:46 INFO - https://queue.taskcluster.net/v1/task/NN_VMbPcR6K72Fpfp_HCKg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 11:47:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NN_VMbPcR6K72Fpfp_HCKg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 11:47:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NN_VMbPcR6K72Fpfp_HCKg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 11:47:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NN_VMbPcR6K72Fpfp_HCKg/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 11:47:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NN_VMbPcR6K72Fpfp_HCKg/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 11:47:48 INFO - Downloaded 10834055 bytes. 11:47:48 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'] 11:47:48 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 11:47:48 INFO - Return code: 0 11:47:48 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')]} 11:47:48 INFO - https://queue.taskcluster.net/v1/task/NN_VMbPcR6K72Fpfp_HCKg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 11:47:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NN_VMbPcR6K72Fpfp_HCKg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 11:47:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NN_VMbPcR6K72Fpfp_HCKg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 11:47:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NN_VMbPcR6K72Fpfp_HCKg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 11:47:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NN_VMbPcR6K72Fpfp_HCKg/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 11:47:51 INFO - Downloaded 111065392 bytes. 11:47:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NN_VMbPcR6K72Fpfp_HCKg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 11:47:51 INFO - mkdir: /builds/slave/test/properties 11:47:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:47:51 INFO - Writing to file /builds/slave/test/properties/build_url 11:47:51 INFO - Contents: 11:47:51 INFO - build_url:https://queue.taskcluster.net/v1/task/NN_VMbPcR6K72Fpfp_HCKg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 11:47:52 INFO - Running post-action listener: _resource_record_post_action 11:47:52 INFO - Running post-action listener: set_extra_try_arguments 11:47:52 INFO - ##### 11:47:52 INFO - ##### Finished download-and-extract step (success) 11:47:52 INFO - ##### 11:47:52 INFO - ##### 11:47:52 INFO - ##### Running populate-webroot step. 11:47:52 INFO - ##### 11:47:52 INFO - Running pre-action listener: _resource_record_pre_action 11:47:52 INFO - Running main action method: populate_webroot 11:47:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:47:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:47:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:47:52 INFO - '--tppagecycles', 11:47:52 INFO - '7'], 11:47:52 INFO - 'tests': ['tsvgm']}}, 11:47:52 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:47:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:47:52 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:47:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:47:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:47:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:47:52 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:47:52 INFO - 'talos_options': ['--disable-e10s'], 11:47:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:47:52 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:47:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:47:52 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:47:52 INFO - 'talos_options': ['--disable-e10s'], 11:47:52 INFO - 'tests': ['damp', 'tps']}, 11:47:52 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:47:52 INFO - 'tests': ['damp', 'tps']}, 11:47:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:47:52 INFO - 'tests': ['dromaeo_dom']}, 11:47:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:47:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:47:52 INFO - 'tests': ['a11yr', 11:47:52 INFO - 'ts_paint', 11:47:52 INFO - 'tpaint', 11:47:52 INFO - 'sessionrestore', 11:47:52 INFO - 'sessionrestore_no_auto_restore', 11:47:52 INFO - 'tabpaint']}, 11:47:52 INFO - 'other-e10s': {'tests': ['a11yr', 11:47:52 INFO - 'ts_paint', 11:47:52 INFO - 'tpaint', 11:47:52 INFO - 'sessionrestore', 11:47:52 INFO - 'sessionrestore_no_auto_restore', 11:47:52 INFO - 'tabpaint']}, 11:47:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:47:52 INFO - 'ts_paint', 11:47:52 INFO - 'tpaint', 11:47:52 INFO - 'sessionrestore', 11:47:52 INFO - 'sessionrestore_no_auto_restore', 11:47:52 INFO - 'tabpaint']}, 11:47:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:47:52 INFO - 'ts_paint', 11:47:52 INFO - 'tpaint', 11:47:52 INFO - 'sessionrestore', 11:47:52 INFO - 'sessionrestore_no_auto_restore', 11:47:52 INFO - 'tabpaint']}, 11:47:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:47:52 INFO - 'tests': ['a11yr', 11:47:52 INFO - 'ts_paint', 11:47:52 INFO - 'tpaint', 11:47:52 INFO - 'sessionrestore', 11:47:52 INFO - 'sessionrestore_no_auto_restore', 11:47:52 INFO - 'tabpaint']}, 11:47:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:47:52 INFO - 'tests': ['a11yr', 11:47:52 INFO - 'ts_paint', 11:47:52 INFO - 'tpaint', 11:47:52 INFO - 'sessionrestore', 11:47:52 INFO - 'sessionrestore_no_auto_restore', 11:47:52 INFO - 'tabpaint']}, 11:47:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:47:52 INFO - 'tests': ['tsvgx', 11:47:52 INFO - 'tsvgr_opacity', 11:47:52 INFO - 'tart', 11:47:52 INFO - 'tscrollx', 11:47:52 INFO - 'cart']}, 11:47:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:47:52 INFO - 'tsvgr_opacity', 11:47:52 INFO - 'tart', 11:47:52 INFO - 'tscrollx', 11:47:52 INFO - 'cart']}, 11:47:52 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:47:52 INFO - 'talos_options': ['--disable-e10s'], 11:47:52 INFO - 'tests': ['tp5o']}, 11:47:52 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:47:52 INFO - 'tests': ['tp5o']}, 11:47:52 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:47:52 INFO - 'talos_options': ['--disable-e10s', 11:47:52 INFO - '--xperf_path', 11:47:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:47:52 INFO - 'tests': ['tp5n']}, 11:47:52 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:47:52 INFO - 'talos_options': ['--xperf_path', 11:47:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:47:52 INFO - 'tests': ['tp5n']}}, 11:47:52 INFO - 'talos.zip': {'path': '', 11:47:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:47:52 INFO - Running post-action listener: _resource_record_post_action 11:47:52 INFO - ##### 11:47:52 INFO - ##### Finished populate-webroot step (success) 11:47:52 INFO - ##### 11:47:52 INFO - ##### 11:47:52 INFO - ##### Running create-virtualenv step. 11:47:52 INFO - ##### 11:47:52 INFO - Running pre-action listener: _resource_record_pre_action 11:47:52 INFO - Running main action method: create_virtualenv 11:47:52 INFO - Creating virtualenv /builds/slave/test/build/venv 11:47:52 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 11:47:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:47:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:47:52 INFO - Using real prefix '/tools/python27' 11:47:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:47:53 INFO - Installing distribute.............................................................................................................................................................................................done. 11:47:56 INFO - Installing pip.................done. 11:47:56 INFO - Return code: 0 11:47:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:47: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')]} 11:47:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:47:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:47: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')]} 11:47:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:47:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:47: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107033a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107039570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85194cdb90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ed7d30>, '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 0x106cfbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106eceb20>, '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.nozkZArR78/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.UozXDb2UW6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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 11:47: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', 'pip>=1.5'] in /builds/slave/test/build 11:47: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 pip>=1.5 11:47:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nozkZArR78/Render', 11:47:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:47:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:47:56 INFO - 'HOME': '/Users/cltbld', 11:47:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:47:56 INFO - 'LOGNAME': 'cltbld', 11:47:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:47:56 INFO - 'MOZ_NO_REMOTE': '1', 11:47:56 INFO - 'NO_EM_RESTART': '1', 11:47:56 INFO - 'PAGER': '/bin/cat', 11:47:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:47:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:47:56 INFO - 'PWD': '/builds/slave/test', 11:47:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:47:56 INFO - 'SHELL': '/bin/bash', 11:47:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UozXDb2UW6/Listeners', 11:47:56 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 11:47:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:47:56 INFO - 'USER': 'cltbld', 11:47:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:47:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:47:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:47:56 INFO - 'XPC_FLAGS': '0x0', 11:47:56 INFO - 'XPC_SERVICE_NAME': '0', 11:47:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:47:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:47:56 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 11:47:56 INFO - Cleaning up... 11:47:56 INFO - Return code: 0 11:47:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:47: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')]} 11:47:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:47:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:47: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')]} 11:47:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:47:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:47: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', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107033a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107039570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85194cdb90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ed7d30>, '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 0x106cfbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106eceb20>, '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.nozkZArR78/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.UozXDb2UW6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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 11:47: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', 'psutil>=3.1.1'] in /builds/slave/test/build 11:47: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 psutil>=3.1.1 11:47:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nozkZArR78/Render', 11:47:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:47:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:47:56 INFO - 'HOME': '/Users/cltbld', 11:47:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:47:56 INFO - 'LOGNAME': 'cltbld', 11:47:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:47:56 INFO - 'MOZ_NO_REMOTE': '1', 11:47:56 INFO - 'NO_EM_RESTART': '1', 11:47:56 INFO - 'PAGER': '/bin/cat', 11:47:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:47:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:47:56 INFO - 'PWD': '/builds/slave/test', 11:47:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:47:56 INFO - 'SHELL': '/bin/bash', 11:47:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UozXDb2UW6/Listeners', 11:47:56 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 11:47:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:47:56 INFO - 'USER': 'cltbld', 11:47:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:47:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:47:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:47:56 INFO - 'XPC_FLAGS': '0x0', 11:47:56 INFO - 'XPC_SERVICE_NAME': '0', 11:47:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:47:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:47:56 INFO - Downloading/unpacking psutil>=3.1.1 11:47: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 11:47: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 11:47: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 11:47: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 11:47: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 11:47: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 11:47:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:47:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:47:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:47:59 INFO - Installing collected packages: psutil 11:47:59 INFO - Running setup.py install for psutil 11:47:59 INFO - building 'psutil._psutil_osx' extension 11:47:59 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 11:48:00 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 11:48:00 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 11:48:00 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 11:48:00 INFO - building 'psutil._psutil_posix' extension 11:48:00 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 11:48:00 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:48:00 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:48:00 INFO - ^ 11:48:00 INFO - 1 warning generated. 11:48:00 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 11:48:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:48:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:48:00 INFO - Successfully installed psutil 11:48:00 INFO - Cleaning up... 11:48:00 INFO - Return code: 0 11:48:00 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:48:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:48:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:48:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:48:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:48:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:48:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:48:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107033a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107039570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85194cdb90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ed7d30>, '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 0x106cfbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106eceb20>, '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.nozkZArR78/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.UozXDb2UW6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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 11:48:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:48:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:48:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nozkZArR78/Render', 11:48:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:48:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:48:00 INFO - 'HOME': '/Users/cltbld', 11:48:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:48:00 INFO - 'LOGNAME': 'cltbld', 11:48:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:48:00 INFO - 'MOZ_NO_REMOTE': '1', 11:48:00 INFO - 'NO_EM_RESTART': '1', 11:48:00 INFO - 'PAGER': '/bin/cat', 11:48:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:48:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:48:00 INFO - 'PWD': '/builds/slave/test', 11:48:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:48:00 INFO - 'SHELL': '/bin/bash', 11:48:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UozXDb2UW6/Listeners', 11:48:00 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 11:48:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:48:00 INFO - 'USER': 'cltbld', 11:48:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:48:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:48:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:48:00 INFO - 'XPC_FLAGS': '0x0', 11:48:00 INFO - 'XPC_SERVICE_NAME': '0', 11:48:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:48:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:48:01 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:48:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:48:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:48:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:48:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:48:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:48:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:48:03 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:48:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:48:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:48:03 INFO - Installing collected packages: mozsystemmonitor 11:48:03 INFO - Running setup.py install for mozsystemmonitor 11:48:03 INFO - Successfully installed mozsystemmonitor 11:48:03 INFO - Cleaning up... 11:48:03 INFO - Return code: 0 11:48:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:48:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:48:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:48:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:48:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:48:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:48:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:48:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107033a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107039570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85194cdb90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ed7d30>, '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 0x106cfbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106eceb20>, '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.nozkZArR78/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.UozXDb2UW6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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 11:48:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:48:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 11:48:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nozkZArR78/Render', 11:48:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:48:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:48:03 INFO - 'HOME': '/Users/cltbld', 11:48:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:48:03 INFO - 'LOGNAME': 'cltbld', 11:48:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:48:03 INFO - 'MOZ_NO_REMOTE': '1', 11:48:03 INFO - 'NO_EM_RESTART': '1', 11:48:03 INFO - 'PAGER': '/bin/cat', 11:48:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:48:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:48:03 INFO - 'PWD': '/builds/slave/test', 11:48:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:48:03 INFO - 'SHELL': '/bin/bash', 11:48:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UozXDb2UW6/Listeners', 11:48:03 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 11:48:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:48:03 INFO - 'USER': 'cltbld', 11:48:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:48:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:48:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:48:03 INFO - 'XPC_FLAGS': '0x0', 11:48:03 INFO - 'XPC_SERVICE_NAME': '0', 11:48:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:48:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:48:04 INFO - Downloading/unpacking blobuploader==1.2.4 11:48:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:48:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:48:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:48:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:48:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:48:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:48:06 INFO - Downloading blobuploader-1.2.4.tar.gz 11:48:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:48:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:48:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:48:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:48:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:48:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:48:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:48:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:48:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:48:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:48:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:48:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:48:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:48:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:48:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:48:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:48:07 INFO - Downloading docopt-0.6.1.tar.gz 11:48:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:48:07 INFO - Installing collected packages: blobuploader, requests, docopt 11:48:07 INFO - Running setup.py install for blobuploader 11:48:08 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:48:08 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:48:08 INFO - Running setup.py install for requests 11:48:08 INFO - Running setup.py install for docopt 11:48:08 INFO - Successfully installed blobuploader requests docopt 11:48:08 INFO - Cleaning up... 11:48:08 INFO - Return code: 0 11:48:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:48:08 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')]} 11:48:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:48:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:48:08 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')]} 11:48:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:48:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:48:08 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 0x107033a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107039570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85194cdb90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ed7d30>, '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 0x106cfbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106eceb20>, '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.nozkZArR78/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.UozXDb2UW6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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 11:48:08 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 11:48:08 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 11:48:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nozkZArR78/Render', 11:48:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:48:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:48:08 INFO - 'HOME': '/Users/cltbld', 11:48:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:48:08 INFO - 'LOGNAME': 'cltbld', 11:48:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:48:08 INFO - 'MOZ_NO_REMOTE': '1', 11:48:08 INFO - 'NO_EM_RESTART': '1', 11:48:08 INFO - 'PAGER': '/bin/cat', 11:48:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:48:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:48:08 INFO - 'PWD': '/builds/slave/test', 11:48:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:48:08 INFO - 'SHELL': '/bin/bash', 11:48:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UozXDb2UW6/Listeners', 11:48:08 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 11:48:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:48:08 INFO - 'USER': 'cltbld', 11:48:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:48:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:48:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:48:08 INFO - 'XPC_FLAGS': '0x0', 11:48:08 INFO - 'XPC_SERVICE_NAME': '0', 11:48:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:48:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:48:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:48:09 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-ry3dCO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:48:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:48:09 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-UDcAtA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:48:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:48:09 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-X_VG6u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:48:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:48:09 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-x1fMK6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:48:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:48:09 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-yp2z35-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:48:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:48:09 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-EaB7Pe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:48:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:48:09 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-bueAmp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:48:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:48:09 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-HsDH_N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:48:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:48:09 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-ruM59d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:48:10 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-jNMyXl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:48:10 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-oY3H4z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:48:10 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-uZ5H3l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:48:10 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-GAQVFE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:48:10 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-cj16go-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:48:10 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-4d5rRU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:48:10 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-pg5wSu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:48:10 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-55D_MO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:48:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:48:10 INFO - Running setup.py install for manifestparser 11:48:10 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:48:11 INFO - Running setup.py install for mozcrash 11:48:11 INFO - Running setup.py install for mozdebug 11:48:11 INFO - Running setup.py install for mozdevice 11:48:11 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:48:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:48:11 INFO - Running setup.py install for mozfile 11:48:11 INFO - Running setup.py install for mozhttpd 11:48:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:48:11 INFO - Running setup.py install for mozinfo 11:48:11 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:48:11 INFO - Running setup.py install for mozInstall 11:48:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:48:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:48:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:48:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:48:12 INFO - Running setup.py install for mozleak 11:48:12 INFO - Running setup.py install for mozlog 11:48:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:48:12 INFO - Running setup.py install for moznetwork 11:48:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:48:12 INFO - Running setup.py install for mozprocess 11:48:12 INFO - Running setup.py install for mozprofile 11:48:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:48:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:48:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:48:12 INFO - Running setup.py install for mozrunner 11:48:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:48:12 INFO - Running setup.py install for mozscreenshot 11:48:13 INFO - Running setup.py install for moztest 11:48:13 INFO - Running setup.py install for mozversion 11:48:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:48:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:48:13 INFO - Cleaning up... 11:48:13 INFO - Return code: 0 11:48:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:48:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:48:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:48:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:48:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:48:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:48:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:48:13 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 0x107033a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107039570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85194cdb90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ed7d30>, '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 0x106cfbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106eceb20>, '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.nozkZArR78/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.UozXDb2UW6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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 11:48:13 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 11:48:13 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 11:48:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nozkZArR78/Render', 11:48:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:48:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:48:13 INFO - 'HOME': '/Users/cltbld', 11:48:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:48:13 INFO - 'LOGNAME': 'cltbld', 11:48:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:48:13 INFO - 'MOZ_NO_REMOTE': '1', 11:48:13 INFO - 'NO_EM_RESTART': '1', 11:48:13 INFO - 'PAGER': '/bin/cat', 11:48:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:48:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:48:13 INFO - 'PWD': '/builds/slave/test', 11:48:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:48:13 INFO - 'SHELL': '/bin/bash', 11:48:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UozXDb2UW6/Listeners', 11:48:13 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 11:48:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:48:13 INFO - 'USER': 'cltbld', 11:48:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:48:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:48:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:48:13 INFO - 'XPC_FLAGS': '0x0', 11:48:13 INFO - 'XPC_SERVICE_NAME': '0', 11:48:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:48:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:48:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:48:13 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-cfkIiM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:48:13 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)) 11:48:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:48:13 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-KXe1Ez-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:48:13 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)) 11:48:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:48:13 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-MZnazE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:48:14 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)) 11:48:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:48:14 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-j8I38i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:48:14 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)) 11:48:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:48:14 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-MduKb2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:48:14 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)) 11:48:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:48:14 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-zlYWsd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:48:14 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)) 11:48:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:48:14 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-sVwVF_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:48:14 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)) 11:48:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:48:14 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-HABu5o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:48:14 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)) 11:48:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:48:14 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-nepnsU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:48:14 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)) 11:48:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:48:14 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-8sykp_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:48:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:48:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:48:14 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-Vf9nm9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:48:14 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)) 11:48:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:48:14 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-Tm73YO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:48:14 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)) 11:48:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:48:14 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-DlI46P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:48:15 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)) 11:48:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:48:15 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-3LV_Lm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:48:15 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)) 11:48:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:48:15 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-PYRp6V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:48:15 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)) 11:48:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:48:15 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-SPhWl3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:48:15 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)) 11:48:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:48:15 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-rLzSU4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:48:15 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)) 11:48:15 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)) 11:48:15 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)) 11:48:15 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)) 11:48:15 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)) 11:48:15 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)) 11:48:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:48:15 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 11:48:15 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 11:48:15 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 11:48:15 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 11:48:15 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 11:48:15 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 11:48:17 INFO - Downloading blessings-1.6.tar.gz 11:48:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:48:18 INFO - Installing collected packages: blessings 11:48:18 INFO - Running setup.py install for blessings 11:48:18 INFO - Successfully installed blessings 11:48:18 INFO - Cleaning up... 11:48:18 INFO - Return code: 0 11:48:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:48:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:48:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:48:18 INFO - Reading from file tmpfile_stdout 11:48:18 INFO - Current package versions: 11:48:18 INFO - blessings == 1.6 11:48:18 INFO - blobuploader == 1.2.4 11:48:18 INFO - docopt == 0.6.1 11:48:18 INFO - manifestparser == 1.1 11:48:18 INFO - mozInstall == 1.12 11:48:18 INFO - mozcrash == 0.17 11:48:18 INFO - mozdebug == 0.1 11:48:18 INFO - mozdevice == 0.48 11:48:18 INFO - mozfile == 1.2 11:48:18 INFO - mozhttpd == 0.7 11:48:18 INFO - mozinfo == 0.9 11:48:18 INFO - mozleak == 0.1 11:48:18 INFO - mozlog == 3.2 11:48:18 INFO - moznetwork == 0.27 11:48:18 INFO - mozprocess == 0.22 11:48:18 INFO - mozprofile == 0.28 11:48:18 INFO - mozrunner == 6.11 11:48:18 INFO - mozscreenshot == 0.1 11:48:18 INFO - mozsystemmonitor == 0.3 11:48:18 INFO - moztest == 0.7 11:48:18 INFO - mozversion == 1.4 11:48:18 INFO - psutil == 3.1.1 11:48:18 INFO - requests == 1.2.3 11:48:18 INFO - wsgiref == 0.1.2 11:48:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:48:18 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')]} 11:48:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:48:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:48:18 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')]} 11:48:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:48:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:48:18 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 0x107033a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107039570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85194cdb90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ed7d30>, '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 0x106cfbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106eceb20>, '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.nozkZArR78/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.UozXDb2UW6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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 11:48:18 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 11:48:18 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 11:48:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nozkZArR78/Render', 11:48:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:48:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:48:18 INFO - 'HOME': '/Users/cltbld', 11:48:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:48:18 INFO - 'LOGNAME': 'cltbld', 11:48:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:48:18 INFO - 'MOZ_NO_REMOTE': '1', 11:48:18 INFO - 'NO_EM_RESTART': '1', 11:48:18 INFO - 'PAGER': '/bin/cat', 11:48:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:48:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:48:18 INFO - 'PWD': '/builds/slave/test', 11:48:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:48:18 INFO - 'SHELL': '/bin/bash', 11:48:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UozXDb2UW6/Listeners', 11:48:18 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 11:48:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:48:18 INFO - 'USER': 'cltbld', 11:48:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:48:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:48:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:48:18 INFO - 'XPC_FLAGS': '0x0', 11:48:18 INFO - 'XPC_SERVICE_NAME': '0', 11:48:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:48:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:48:18 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)) 11:48:18 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)) 11:48:18 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)) 11:48:18 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)) 11:48:18 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)) 11:48:18 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)) 11:48:18 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)) 11:48:18 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)) 11:48:18 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)) 11:48:18 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)) 11:48:18 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)) 11:48:18 INFO - Cleaning up... 11:48:18 INFO - Return code: 0 11:48:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:48:18 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')]} 11:48:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:48:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:48:18 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')]} 11:48:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:48:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:48:18 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 0x107033a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107039570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85194cdb90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ed7d30>, '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 0x106cfbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106eceb20>, '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.nozkZArR78/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.UozXDb2UW6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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 11:48:18 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 11:48:18 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 11:48:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nozkZArR78/Render', 11:48:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:48:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:48:18 INFO - 'HOME': '/Users/cltbld', 11:48:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:48:18 INFO - 'LOGNAME': 'cltbld', 11:48:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:48:18 INFO - 'MOZ_NO_REMOTE': '1', 11:48:18 INFO - 'NO_EM_RESTART': '1', 11:48:18 INFO - 'PAGER': '/bin/cat', 11:48:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:48:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:48:18 INFO - 'PWD': '/builds/slave/test', 11:48:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:48:18 INFO - 'SHELL': '/bin/bash', 11:48:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UozXDb2UW6/Listeners', 11:48:18 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 11:48:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:48:18 INFO - 'USER': 'cltbld', 11:48:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:48:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:48:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:48:18 INFO - 'XPC_FLAGS': '0x0', 11:48:18 INFO - 'XPC_SERVICE_NAME': '0', 11:48:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:48:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:48:19 INFO - Downloading/unpacking jsonschema 11:48:19 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 11:48:19 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 11:48:19 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 11:48:19 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 11:48:19 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 11:48:19 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 11:48:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:48:21 INFO - Downloading/unpacking functools32 (from jsonschema) 11:48:21 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 11:48:21 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 11:48:21 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 11:48:21 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 11:48:21 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 11:48:21 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 11:48:21 INFO - Downloading functools32-3.2.3-2.tar.gz 11:48:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:48:22 INFO - warning: no files found matching '*.txt' 11:48:22 INFO - no previously-included directories found matching 'build' 11:48:22 INFO - no previously-included directories found matching 'dist' 11:48:22 INFO - no previously-included directories found matching '.git*' 11:48:22 INFO - Installing collected packages: jsonschema, functools32 11:48:22 INFO - Running setup.py install for functools32 11:48:22 INFO - warning: no files found matching '*.txt' 11:48:22 INFO - no previously-included directories found matching 'build' 11:48:22 INFO - no previously-included directories found matching 'dist' 11:48:22 INFO - no previously-included directories found matching '.git*' 11:48:22 INFO - Successfully installed jsonschema functools32 11:48:22 INFO - Cleaning up... 11:48:22 INFO - Return code: 0 11:48:22 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 11:48: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')]} 11:48:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:48:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:48: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')]} 11:48:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:48:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:48:22 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 0x107033a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107039570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85194cdb90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ed7d30>, '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 0x106cfbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106eceb20>, '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.nozkZArR78/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.UozXDb2UW6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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 11:48:22 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 11:48:22 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 11:48:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nozkZArR78/Render', 11:48:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:48:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:48:22 INFO - 'HOME': '/Users/cltbld', 11:48:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:48:22 INFO - 'LOGNAME': 'cltbld', 11:48:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:48:22 INFO - 'MOZ_NO_REMOTE': '1', 11:48:22 INFO - 'NO_EM_RESTART': '1', 11:48:22 INFO - 'PAGER': '/bin/cat', 11:48:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:48:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:48:22 INFO - 'PWD': '/builds/slave/test', 11:48:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:48:22 INFO - 'SHELL': '/bin/bash', 11:48:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UozXDb2UW6/Listeners', 11:48:22 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 11:48:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:48:22 INFO - 'USER': 'cltbld', 11:48:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:48:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:48:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:48:22 INFO - 'XPC_FLAGS': '0x0', 11:48:22 INFO - 'XPC_SERVICE_NAME': '0', 11:48:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:48:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:48:22 INFO - Downloading/unpacking flake8 11:48:22 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 11:48:22 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 11:48:22 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 11:48:22 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 11:48:22 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 11:48:22 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 11:48:25 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 11:48:25 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 11:48:25 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 11:48:25 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 11:48:25 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 11:48:25 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 11:48:25 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 11:48:25 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 11:48:25 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 11:48:25 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 11:48:25 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 11:48:25 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 11:48:25 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 11:48:25 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 11:48:25 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 11:48:25 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 11:48:25 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 11:48:25 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 11:48:25 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 11:48:25 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 11:48:25 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 11:48:25 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 11:48:25 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 11:48:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 11:48:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 11:48:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 11:48:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 11:48:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 11:48:26 INFO - no previously-included directories found matching 'docs/_build' 11:48:26 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 11:48:26 INFO - Running setup.py install for pep8 11:48:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 11:48:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 11:48:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 11:48:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 11:48:26 INFO - no previously-included directories found matching 'docs/_build' 11:48:26 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 11:48:26 INFO - Successfully installed flake8 mccabe pyflakes pep8 11:48:26 INFO - Cleaning up... 11:48:26 INFO - Return code: 0 11:48:26 INFO - Running post-action listener: _resource_record_post_action 11:48:26 INFO - Running post-action listener: _start_resource_monitoring 11:48:26 INFO - Starting resource monitoring. 11:48:26 INFO - ##### 11:48:26 INFO - ##### Finished create-virtualenv step (success) 11:48:26 INFO - ##### 11:48:26 INFO - ##### 11:48:26 INFO - ##### Running install step. 11:48:26 INFO - ##### 11:48:26 INFO - Running pre-action listener: _resource_record_pre_action 11:48:26 INFO - Running main action method: install 11:48:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:48:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:48:27 INFO - Reading from file tmpfile_stdout 11:48:27 INFO - Detecting whether we're running mozinstall >=1.0... 11:48:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:48:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:48:27 INFO - Reading from file tmpfile_stdout 11:48:27 INFO - Output received: 11:48:27 INFO - Usage: mozinstall [options] installer 11:48:27 INFO - Options: 11:48:27 INFO - -h, --help show this help message and exit 11:48:27 INFO - -d DEST, --destination=DEST 11:48:27 INFO - Directory to install application into. [default: 11:48:27 INFO - "/builds/slave/test"] 11:48:27 INFO - --app=APP Application being installed. [default: firefox] 11:48:27 INFO - mkdir: /builds/slave/test/build/application 11:48:27 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'] 11:48:27 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 11:49:01 INFO - Reading from file tmpfile_stdout 11:49:01 INFO - Output received: 11:49:01 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 11:49:01 INFO - Running post-action listener: _resource_record_post_action 11:49:01 INFO - ##### 11:49:01 INFO - ##### Finished install step (success) 11:49:01 INFO - ##### 11:49:01 INFO - ##### 11:49:01 INFO - ##### Running run-tests step. 11:49:01 INFO - ##### 11:49:01 INFO - Running pre-action listener: _resource_record_pre_action 11:49:01 INFO - Running main action method: run_tests 11:49:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:49:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:49:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:49:01 INFO - 2016-06-02 11:49:01.718 screenresolution[1657:9705] starting screenresolution argv=screenresolution get 11:49:01 INFO - 2016-06-02 11:49:01.720 screenresolution[1657:9705] Display 0: 1600x1200x32@60 11:49:01 INFO - 2016-06-02 11:49:01.726 screenresolution[1658:9708] starting screenresolution argv=screenresolution list 11:49:01 INFO - Available Modes on Display 0 11:49:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:49:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:49:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:49:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:49:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:49:01 INFO - Intel Iris: 11:49:01 INFO - Chipset Model: Intel Iris 11:49:01 INFO - Type: GPU 11:49:01 INFO - Bus: Built-In 11:49:01 INFO - VRAM (Dynamic, Max): 1536 MB 11:49:01 INFO - Vendor: Intel (0x8086) 11:49:01 INFO - Device ID: 0x0a2e 11:49:01 INFO - Revision ID: 0x0009 11:49:01 INFO - Displays: 11:49:01 INFO - Display: 11:49:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:49:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:49:01 INFO - Display Serial Number: 1600x1200 60 11:49:01 INFO - Main Display: Yes 11:49:01 INFO - Mirror: Off 11:49:01 INFO - Online: Yes 11:49:01 INFO - Rotation: Supported 11:49:01 INFO - Return code: 0 11:49:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:49:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:49:01 INFO - Python 2.7.3 11:49:01 INFO - Return code: 0 11:49:01 INFO - grabbing minidump binary from tooltool 11:49:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:49:01 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 0x106ed7d30>, '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 0x106cfbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106eceb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:49:01 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 11:49:01 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 11:49:01 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 11:49:02 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:49:02 INFO - Return code: 0 11:49:02 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:49:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:49:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:49:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:49:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:49:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 11:49:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 11:49:03 INFO - Return code: 0 11:49:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NN_VMbPcR6K72Fpfp_HCKg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0353', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 11:49:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/NN_VMbPcR6K72Fpfp_HCKg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0353 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 11:49:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nozkZArR78/Render', 11:49:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:49:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:49:03 INFO - 'HOME': '/Users/cltbld', 11:49:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:49:03 INFO - 'LOGNAME': 'cltbld', 11:49:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:49:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:49:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:49:03 INFO - 'MOZ_NO_REMOTE': '1', 11:49:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:49:03 INFO - 'NO_EM_RESTART': '1', 11:49:03 INFO - 'PAGER': '/bin/cat', 11:49:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:49:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:49:03 INFO - 'PWD': '/builds/slave/test', 11:49:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:49:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:49:03 INFO - 'SHELL': '/bin/bash', 11:49:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UozXDb2UW6/Listeners', 11:49:03 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 11:49:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:49:03 INFO - 'USER': 'cltbld', 11:49:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:49:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:49:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:49:03 INFO - 'XPC_FLAGS': '0x0', 11:49:03 INFO - 'XPC_SERVICE_NAME': '0', 11:49:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:49:03 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NN_VMbPcR6K72Fpfp_HCKg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0353', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 11:49:03 INFO - mozversion application_buildid: 20160601060845 11:49:03 INFO - mozversion application_changeset: 111970c738234569c8c180319155327316335deb 11:49:03 INFO - mozversion application_display_name: Nightly 11:49:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:49:03 INFO - mozversion application_name: Firefox 11:49:03 INFO - mozversion application_remotingname: firefox 11:49:03 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 11:49:03 INFO - mozversion application_vendor: Mozilla 11:49:03 INFO - mozversion application_version: 49.0a1 11:49:03 INFO - mozversion platform_buildid: 20160601060845 11:49:03 INFO - mozversion platform_changeset: 111970c738234569c8c180319155327316335deb 11:49:03 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 11:49:03 INFO - mozversion platform_version: 49.0a1 11:49:03 INFO - using testdate: 1464893343 11:49:03 INFO - actual date: 1464893343 11:49:03 INFO - starting webserver on 'localhost:49272' 11:49:03 INFO - SUITE-START | Running 2 tests 11:49:03 INFO - TEST-START | dromaeo_css 11:49:03 INFO - operating with platform_type : mac_ 11:49:03 INFO - Initialising browser for dromaeo_css test... 11:49:03 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/tmppmX5ij/profile http://localhost:49272/getInfo.html) 11:49:06 INFO - PROCESS | 1676 | __metrics Screen width/height:1600/1200 11:49:06 INFO - PROCESS | 1676 | colorDepth:24 11:49:06 INFO - PROCESS | 1676 | Browser inner width/height: 1024/689 11:49:06 INFO - PROCESS | 1676 | __metrics 11:49:06 ERROR - PROCESS | 1676 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2938: TypeError: this.tabs is undefined 11:49:07 INFO - TEST-INFO | 1676: exit 0 11:49:07 INFO - Browser initialized. 11:49:07 INFO - Running cycle 1/1 for dromaeo_css test... 11:49:07 INFO - TEST-INFO | started process 1680 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/tmppmX5ij/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:49:19 INFO - PROCESS | 1680 | RSS: Main: 252719104 11:49:19 INFO - PROCESS | 1680 | 11:50:08 INFO - PROCESS | 1680 | 1464893408808 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 11:52:20 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49272/tests/dromaeo/cssquery-ext.html) 11:52:22 INFO - PROCESS | 1680 | RSS: Main: 265269248 11:52:22 INFO - PROCESS | 1680 | 11:55:24 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-ext.html (next: http://localhost:49272/tests/dromaeo/cssquery-jquery.html) 11:55:25 INFO - PROCESS | 1680 | RSS: Main: 276545536 11:55:25 INFO - PROCESS | 1680 | 11:58:26 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49272/tests/dromaeo/cssquery-mootools.html) 11:58:27 INFO - PROCESS | 1680 | RSS: Main: 278474752 11:58:27 INFO - PROCESS | 1680 | 12:01:29 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49272/tests/dromaeo/cssquery-prototype.html) 12:01:30 INFO - PROCESS | 1680 | RSS: Main: 279916544 12:01:30 INFO - PROCESS | 1680 | 12:04:32 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49272/tests/dromaeo/cssquery-yui.html) 12:04:33 INFO - PROCESS | 1680 | RSS: Main: 283578368 12:04:33 INFO - PROCESS | 1680 | 12:07:35 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-yui.html (next: http://localhost:49272/tests/dromaeo/cssquery-dojo.html) 12:07:36 INFO - PROCESS | 1680 | RSS: Main: 283779072 12:07:36 INFO - PROCESS | 1680 | 12:07:36 INFO - PROCESS | 1680 | __start_tp_report 12:07:36 INFO - PROCESS | 1680 | _x_x_mozilla_page_load 12:07:36 INFO - PROCESS | 1680 | _x_x_mozilla_page_load_details 12:07:36 INFO - PROCESS | 1680 | |i|pagename|runs| 12:07:36 INFO - PROCESS | 1680 | |0;dojo.html;3526.418141006734;3527.1534831640397;3541.4687796830476;3568.6788189062995;3586.8385922633497;5878.911816322753;5895.086261629449;5954.88090238196;5994.88084292139;5997.190379298793;4975.950240497603;5394.6493477923905;5441.972790136044;5828.708564571765;6044.758209671608;4134.028503301722;4627.329037289591;5193.039287731782;5228.947710522903;5244.239585260135;5242.842714718546;5425.159100339434;5454.209139674745;5456.863578410532;5473.972630136864;4451.931536431265;5218.060749065166;5390.19147127934;5411.918821217665;5417.431169727161;5232.790688372461;5324.600654950894;5325.121354976424;5330.413654498002;5356.598255130842;5256.579473642138;5267.183586544092;5273.789048438058;5297.629165958385;5349;5521.503064724154;5659.660420374749;5734.082546792493;5783.710814459302;5800.0719884818645;6247.187865577492;6254.21822272216;6298.02380631003;6319.14691516644;6326.398992095789;6286.151369565142;6304.527160462938;6322.020086886494;6328.8101356958905;6383.39357761012;5830.82507524775;5917.970410147965;5921.940780592225;5993.9400605993815;6013.939860601338;6375.330590288046;6393.296737358887;6406.775762848278;6407.231132264158;6554.672266386757;6310.148130002391;6311.274203466568;6321.146645202931;6329.367063293726;6333.714982825692;6222.097795819672;6227.844303892439;6230.688465576703;6240.625562466266;6316.557840951089;6122.581127188521;6226.719797609118;6300.558960872695;6307.337729538424;6317.1156038154695;6289.7169627366875;6305.842353941096;6308.495320374359;6329.461995730322;6351.936480635134;4864.610831133572;4896.902061958741;4923.458419573844;4928.778204980785;4991.026749783758;3699.389600715852;4430.889227769331;4434.84478043267;4452.465704115462;4488.147252022105;5878.971180043561;5883.676397798076;5901.17383566301;5903.0555111182;5907.232059832194;6223.875522489616;6223.937760622426;6253.437190652863;6262.6868656566985;6277.18396608438;5911.113333000085;5918.8816223675285;5929.229200203946;5929.347771745104;5950.4049595041015;5374.91937620936;5434.375046869661;5450.591205659592;5452.509274165344;5490.450954904478;6365.840853978688;6398.104265402847;6417.03744438338;6473.805785826433;6492.64290464029;5266.736663166903;6295.5907865988565;6301.369863013845;6322.304546499973;6343.9682801585695;6258.374162583706;6299.590526615755;6456.12842266288;6468.2561505427475;6482.448991835558;6220.626762394271;6253.187085678833;6311.463525600192;6314.905276420949;6330.620162790247;4920.438096837869;6317.557770955989;6317.989121740499;6324.873502530016;6328.493720502441;5699.173619825019;6088.36072212429;6205.348438414082;6250.343713909974;6263.091851681572;6116.938830611637;6147.016477363779;6197.81406557804;6215.192025036717;6222.751089956532;6011.098335249748;6066.484348830399;6124.223975047186;6145.262568491809;6167.876642467215;6112.50348092447;6122.459144589048;6202.658853762906;6257.78097766576;6268.341824108586;6160.9383906160365;6162.137300777805;6165.84585385369;6170.81487555374;6200.658963757037 12:07:36 INFO - PROCESS | 1680 | |1;ext.html;510.64765311934786;520.1599416941639;529.9533421492553;535.360244507813;560.8625886657769;38575.42136867949;41726;41788.58211417885;41852.65178785697;41892.79053604735;42196.94507637303;42494.57505424945;42880.785596071975;43354.13291734163;43543.56456435443;9369.67206147785;9580.80838383232;9621.08599683029;9622.711318660451;9654.324197306192;9385.95307023464;9414.811703765921;9463.337566370323;9542.475163865985;9557.761055973622;9523.19052880506;9530.475823829687;9535.332526723132;9592.280578956572;9632.855507167364;9476.952615236914;9553.761155971088;9567.425954442755;9599.856002159973;9628.807423851484;9326.580303886341;9413.717588472357;9442.29182811285;9445.811083778355;9457.668981585612;38684.22631547382;38857.417138742945;39141.02147446336;39212.411813822815;39406.605933940584;3370.174307294704;3397.456406974872;3482.3035392921515;3586.3185994661194;3977.6469414230173;31142.688573114207;31408.37183256322;31633.20916977071;32372.5144122836;32381;3094.9071527853976;3132.621646475566;3276.7542434317284;3282.720968717662;3336.43280642289;523545;529901;530449;530753;532136;30477.08568742941;30557.847210763804;61615.38384616186;62505.74988500204;62515.6874215626;34706.65293347085;34830.82584587061;35121.64878351235;35141.64858351435;35318.29363412769;11855.46650400734;13379.728925752039;13838.584842454742;13936.651583710489;13972.510962116277;11473.254238474472;11480.770384592428;11505.71235719097;11526.94236528812;11540.826887596693;7522.6614802334025;7526.6989320427665;7598.506097103781;7610.124835643861;7621;3079.5226739855357;3112.7042930921525;3115.9376812463624;3116.065180445857;3135.059482155344;940.703678341334;954.2223088183108;1004.989985065005;1024.3239461955075;1093.7156339351825;29249.268768280537;29315.706842931726;29594.704052959194;29680.967006836763;29773.851130744642;1022.8619136416637;1071.5927947379946;1111.1721767283425;1125.7692173104494;1152.3604399558326;15736.763948540782;15831.920840395724;15949.20253987296;16065.437709680338;16382.836171638131;702084;705262;711829;712136;716699;61839;61955.69022154951;62239.68880155661;62445;62446.751064978445;1389.2775756606509;1429.8784603308627;1438.5756201920667;1439.8128243328301;1440.51742666208;30243.848780755954;30406.63170157304;30525;30863.2284192897;30965.225869353446;46060.07879842451;50077.49922500728;52870.47129528655;53111;53298;608234;612718;612750;613110;613721;23373.532529349657;26968.460630787667;27047.594286085332;27743.30641733973;27801;45072.09855802931;45557.54442455533;45811.31283030824;45987.31019034784;46923.82690432767;40279.59720402758;40378.79810600928;40774.1845163101;40975.59024409718;41432;39426.2114757709;39650.2069958605;39892.2021559573;40097.59902400939;40195.39706904457;38710;38987;39052.02369940661;39701.80149099236;39878;32475.837620811744;34817;35115.59537618569;35325.646743532234;35398.29203415866;22389;22509.774902250767;23623.52752944897;24170;25009.74990250147 12:07:36 INFO - PROCESS | 1680 | |2;jquery.html;383.19911385204927;384.12611309271404;385.8591614060868;400.39340399295094;406.49391507573057;18615.4415367539;20866.37400877976;20933.790662093375;21246.787532124676;21247.15011399544;19943.9030853303;20486.078126484306;20629.793702062976;20849;20909.268175613855;5160.535551800337;5793.391693872146;5947.494462970652;5959.791407300723;5972.402759724015;5412.4858138476975;5678.801241956532;5915.142304365864;5928.911066334009;5998.610090344136;5626.746796394152;5780.739866706008;5882.5588080893895;5884.4703976642095;5886.087656413263;5722.513586345166;5737.540996720252;5771.740271687763;5831.5626328025355;5890.64656120632;5061.721605311706;5132.871678208037;5160.509751573594;5168.095583272912;5168.095583272912;24117.879410602836;25014.749852501423;25135.37161570957;25390.11134610298;25729.485410291876;2070.927517536879;2221.5001624634488;2319.3158018384615;2454.0797201049604;2642.0620679658637;15057.247137643184;15362.769558456632;15449.613759655986;16031.679366412724;16042.679146417006;1808.8372046515876;1820.8983564943283;1874.4751469588537;1928.6721257386278;1955.7066440033966;101721.49139254256;101918;112727.3090903637;114915;115389.42305288503;10672.413017284123;10680.092192163598;10875.619353322754;11147.665570032912;11196.552137914392;13305.201687898758;13524.188548686914;13533.188008719508;13723.245221512712;13752.862471375158;8274.213949674771;8326.70856520019;8332.291755200877;8404.915950840414;8427.494350338999;8044.1955804419085;8072.959635201903;8128.9187108129345;8238.670453181805;8355.582220888931;6173.9073913891325;6642.966785166043;6731.461483081342;6737.663116844138;6747.359000894907;1832.15720768448;1849.556106534422;1869.691500902336;1879.0792511669456;1911.9139638716292;853.4665833853842;913.4793167894369;1036.7978244242452;1045.273534893242;1100.741325788439;17571.385001524886;17616.29534818593;21446.142154313966;21471.46321341979;21884.671729924063;954.8663187153804;978.2858513285341;991.8115558043949;993.8608594796734;1053.2679787547734;9655.9034409655;11312.321260724382;11643.301401915913;11659.941700291443;12085.45615447307;587484;595589;602399;603798;604782;79565;116053;116370;120543;121797;895.4403497813867;1000.5797565022708;1039.604950118965;1046.6336782126195;1063.5320458998017;19048.238070477026;19080.8091919079;19320.613587728447;19412.708809368152;19466.513337166118;48530.5146948526;50395;51748.482515174364;52313;52459.73770131125;73067.63466182635;73493;73614.63192684003;74117;74538;7913.438952471526;7999.84000320002;8003.11965683786;8020.157883422156;8186.3860210485645;12544.937275313565;12672.809907851206;12703.174293670882;12939.935300323437;12955.805662915252;11057.004869561779;11069.446527673423;11189.72025699364;11325.15061370384;11420.94289528547;10328.483575821328;10834.945825270823;11100.833487497855;11191.38447385369;11347.092232621206;9932.453715045742;10856.503004308895;11314.490847911698;11451.828222576502;11795.587154449553;7976.282134607914;7984.640691169027;8008.15914328987;8024.956755621965;8041.115477297376;10653.467326633792;10690.57237710514;11317.71705707364;11408.94295528517;11463.942680286546 12:07:36 INFO - PROCESS | 1680 | |3;mootools.html;1564.5228205397357;1602.7435610302352;1747.912604369781;1813.3562585282225;1950.6586347389202;7998.600069996492;8578.871316930243;8642.265407440374;9102.226310763584;9109.089091090887;8698.695545655903;8782.736517904457;8865.423747456427;8887.466751994885;8893.64425422984;2364.586197415454;2369.763023697625;2401.939951501218;2402.8318017738766;2404.2907342333965;1986.354434808693;2194;2240.969154189067;2343.742188359279;2401.315625046862;2325.5232677301196;2372.5847976604027;2373.1100837186054;2374.943150298118;2376.6197408414655;2359.834811563191;2362.5511152881068;2367.041348253951;2394.6647469354302;2395.2095808383365;2370.277065495023;2370.813099240443;2371.146387300588;2391.8325717199805;2392.4736557957394;8772.692955746517;8840;8861.911380886173;8881;8970;5281.339832520935;5313.282706834567;5329.120695085307;5387.676739395649;5442.210879422501;3604.4140578145534;3642.6175251598465;3671.0638787109383;3718.219173973469;3761.5110035695184;1083.9620613278498;1093.3877028863863;1095.9890401096047;1096.1888202730001;1104.6354702948008;9013.414128081653;11676;11856.822147667792;11862.762744745143;11877.346745929057;4712.269598212282;4723.456802467692;4789.521047895182;4813.711177329302;4824.396950381202;9797.265205109645;9941.502924853872;9959.900400995897;9960.402375857471;10026.09765121128;5283.630145889828;5293.206019097166;5297.073012222845;5416.674999500043;5429.484199001088;4453.465584129925;4791.95208047922;4946.307516947631;4955.529224723646;4966.180580204225;3934.291827471083;3981.980090099531;3991.960080399217;4043.555208927016;4102.630763231324;1286.3118231746157;1292.5799115287568;1294.8834604885606;1302.2967597497245;1303.7327347893658;929.6746138851348;934.6681927915628;939.5067589515422;950.3442624589012;993.1210878372692;5888.175655408247;5906.940930590639;5943.3462319144855;6025.9096113558335;6036.01268220696;364.33327011568764;435.8343829344892;445.5277405949704;445.570024925948;446.4642429085046;3953.584873588289;3960.940585891214;3968.66266367362;4050.1292222172374;4058.330375488011;10390.116840068527;10742.24804263694;10846.969537893887;10919.508622112024;10932.125429965743;10319;10700.090492308085;10782.08352290064;10816.4050977197;10919.727006824734;7947.721829735931;8162.061362943337;8182.627038158987;8304.543250121302;8314.376421768513;5744.224529688438;5876.324222714443;5935.643861368331;5965.994132790558;6063.93881070802;8477.830443390974;9985.1512621428;10057.798844023226;10254.589816407559;10260.230482713976;8600.666896631032;9187.943386510637;10243.692689219335;10433.895661043292;10530.526126324487;7020.5787652741;7473.215312392338;7626.58053807046;7729.1884352142215;7762.495437796525;8458.027326857491;8492.70275540353;8506.276966457674;8517.787055323666;8626.008009079036;7451.664675089525;7549;7586.962065189638;7664.501807382502;7726.652300646372;7407.296306850841;7422.96288518554;7496.662650180646;7571.924280757122;7647.770566883002;7070.7525236616475;7077.504574679723;7143.535670181422;7144.214136445091;7173.677184526813;4203.7178660508835;4287.892802679958;4743.620510359232;4898.020395920759;4996.7001979881325;5015.322931404359;5078.5175408336145;5092.796288148581;5135.948640513697;5194.454582268959 12:07:36 INFO - PROCESS | 1680 | |4;prototype.html;302.78804836614364;310.6706890695862;317.7569159592912;319.1526497150065;325.3639135490117;5162.580290420132;5313.760880760366;5387.515123638872;5439.864003399917;5540.778368865245;5264.789408423659;5481.341549348563;5545.3622833374175;5549.889002219953;5554.86112847179;3508.561429821272;3802.4219948721316;3944.3689009758446;4194.035371864473;4198.097409057053;3305.3554556861422;3520.419130843408;3538.610752817188;3911.3155197840406;3970.5433875104445;3155.4635711929027;3731.3097077040834;4136.903720514066;4139.234241665286;4149.298787497127;3478.808665523395;3971.9871432784453;4158.043649960511;4161.396597493362;4184.895377615553;2910.359720861407;3248.610166779978;3311.873962852642;3617.8371973261005;3632.7093832493338;4495.955040449587;5153.651696488524;5181.611379146579;5324.653897496689;5406.972965135188;4754.904901901978;4779.928301075487;4796.25658023007;4811.350467686888;4826.951730482685;5472.72636368184;5804.997275939794;5814.5057670098095;5818.5926985111055;5821;3410.6077801052948;3661.578918424362;3915.5888631693547;3921.1961547882615;3970.285348637244;5544.532682956086;5711.086226203829;6129.509639228806;6178.320384757718;6195.690215489208;3243.789153705002;3939.3106206413763;3978.8607398741483;3981.681465482812;4023.8189281482405;3443.8278086095597;3902.648761611412;4048.332025215865;4056.0873803394;4059.2693315202955;4653.930191047137;4690.929636055462;4694.694844835075;4723.7874295656775;4737.786799594027;4706.964467817074;5180.792768289301;5466.371367292732;5550.750216240279;5907.932223721749;1241.8137279407981;1326.4694122351136;1432.9355179016761;1436.174199835099;1437.9856201437851;819.496009953874;838.9496630202208;873.5675840459013;901.7915992569857;905.2983937448398;4108.562003298822;4229.978850105791;4363.345498175299;4399.6040356368085;4716;4432.534583868712;4704.670673052853;5183.481651834786;5214;5225.451327610546;4035.9394609080887;4233.830646774095;4512.781548981757;4554.952360956999;4687.81248750053;2323.8954247058923;2421.152596591179;2550.4134049168797;2769.3630464993166;2774.9722502775116;4551.40831691878;4689.484156742756;5252.054630166606;5426.701531415731;5462.289902312674;4498.773107311901;4529.851753031678;4869.464358920516;5251.6061295403;5330.573554115662;4465.799039043316;4632.096741135513;4984.501549844987;5336.251830307395;5380.811671591554;4762.9285560715925;5018.8707540803025;5181.968548399229;5201.973990130025;5204.0372531081875;4516.412866327357;4705.364775755366;5082.186850103965;5120.462351453044;5143.630921746262;5384.300040994645;5633.408492108433;6326.841828954313;6471.741130354733;6478.967605162133;3957.8416863325147;3993.5607083221403;4004.2570838975394;4021.2158629067053;4036.6770658346672;5006.799728010985;5123.84713439488;5998;6017.338092809875;6179.073139029181;5895.58281104745;5931.911021334769;5954.46409823118;5957.523398128053;5957.553183511341;5675.943240567542;5856.677882716492;5859.589828711949;5943.940560594338;5987.191729116514;5353.982743278764;5505.889882202414;5627.409122042127;5802.303723553201;5821.476067153976;4983.327250821093;5470.671759694431;5870.354261031198;5929.644221346561;5985.221921150222;5032.949670503248;5773.364929857628;5795.188673585617;5812.738426770721;5842.7078646066675 12:07:36 INFO - PROCESS | 1680 | |5;yui.html;741.1809950005244;820.2617644120295;828.1000637380122;844.4131328726537;849.1423662101275;14534.98255122142;14813.407463701466;14914.403423863047;14923.552293431201;15068;10907.236493445462;13935.44258229672;13937;13962;13982.3707933143;688.5008368932534;864.1185990289932;866.9263112635418;875.4309698695835;884.0496466298755;856.6830272799073;856.8586183279751;872.655301156043;881.3742243007472;888.1695614600337;802.8314054048657;934.1825902335456;936.9484678342689;941.6327632223438;946.711253067814;931.6739141300559;936.166811537732;937.5968333616543;937.6765016069479;938.3900464697966;683.0744341417358;695.4922906278395;696.3941371007205;697.9860402791916;699.0283505926766;14025;14341.42634294627;14367.209803460806;14383.496577619733;14422.206778627171;13702.725945481134;13718.245496497688;13770.24263665498;13775.173489590657;13846.861531384659;11458.770824583462;11523.539058437653;11597.362145082017;11902.88097119035;11939.701507462296;3754.9812250938844;3837.175007373428;3845.557760857509;3866.2074274773604;3979.7612143271494;74112.25887741108;79917;81061;81106.78339824907;82221;9186;10553.261271710906;10673.199510036779;10827.945860270649;10849.457527123612;16575.502734917973;21120.366389008355;21361.46596335073;21451.14195432165;21548.13807447716;7538.170801211862;7808.492447990862;8028.197180281925;8103.837923241502;8219.54792486407;3598.604153543108;3923.352646813301;3952.4268980997595;3952.7430717003876;3959.1685745993664;3363.4618461046116;3460.013896039667;3468.098294443412;3476.37425263455;3476.721862251014;644.6712176789778;648.2545073165898;656.4846595422666;665.8834703926793;670.8457054877406;562.1174755633616;597.2265915639258;648.9875793761653;702.3994484715495;706.2795948132875;8782.560871956377;9055.728328150166;9401.341906066646;9520.571574279174;9538.713838584854;551.7020808763303;657.1850904877917;661.7716887673745;684.117488439907;691.1705952856491;4263.76549289792;4450.710703804242;4485.372047913294;4520.208963431386;4593.425821772278;90435;91159.54420227856;92552;93815;93942;78879.60560197162;81338.59330703308;81388.59305703553;81565;81927.77108343379;72171.63914180396;72601.54796904033;72656.91014634784;72756.63621681964;72895.27104728884;8371.706990255312;8386.37102217324;8501.914980850112;8528.74413767588;8549.957250213709;46128.538714613765;46664;47056.29415558838;47177;48241.552753417454;61552.46118847065;62180.378196219266;62603;63246.83765811783;63407;11484.540618375171;11794;11834;11835.822462662894;11912.98739607143;12359.814602781145;12443.315617641116;12469.747494073854;12623.558175463815;12698.04764642637;11773.35246561413;11907.642770716893;12044;12169.939150304192;12294.323812190058;11951.521939122338;12003.63989080329;12093.25545404637;12264.080193985665;12379.690507737017;10637.574497020032;11952.282863028246;13045.282509462071;13049.608511744664;13359.587248572756;10983.231173818076;11117.44412779374;11137.273722573014;11415.429228538374;11442.656720298404;9377.717798770376;10257.128144107834;10984.450777461256;11034.558617655204;11282.887171128512 12:07:36 INFO - PROCESS | 1680 | __end_tp_report 12:07:36 INFO - PROCESS | 1680 | __start_cc_report 12:07:36 INFO - PROCESS | 1680 | _x_x_mozilla_cycle_collect,420 12:07:36 INFO - PROCESS | 1680 | __end_cc_report 12:07:36 INFO - PROCESS | 1680 | __startTimestamp1464894456193__endTimestamp 12:07:36 INFO - PROCESS | 1680 | 12:07:36 INFO - PROCESS | 1680 | ------- Summary: start ------- 12:07:36 INFO - PROCESS | 1680 | Number of tests: 6 12:07:36 INFO - PROCESS | 1680 | 12:07:36 INFO - PROCESS | 1680 | [#0] dojo.html Cycles:165 Average:5808.03 Median:6116.94 stddev:680.71 (11.1%) stddev-sans-first:658.84 12:07:36 INFO - PROCESS | 1680 | Values: 3526.4 3527.2 3541.5 3568.7 3586.8 5878.9 5895.1 5954.9 5994.9 5997.2 4976.0 5394.6 5442.0 5828.7 6044.8 4134.0 4627.3 5193.0 5228.9 5244.2 5242.8 5425.2 5454.2 5456.9 5474.0 4451.9 5218.1 5390.2 5411.9 5417.4 5232.8 5324.6 5325.1 5330.4 5356.6 5256.6 5267.2 5273.8 5297.6 5349.0 5521.5 5659.7 5734.1 5783.7 5800.1 6247.2 6254.2 6298.0 6319.1 6326.4 6286.2 6304.5 6322.0 6328.8 6383.4 5830.8 5918.0 5921.9 5993.9 6013.9 6375.3 6393.3 6406.8 6407.2 6554.7 6310.1 6311.3 6321.1 6329.4 6333.7 6222.1 6227.8 6230.7 6240.6 6316.6 6122.6 6226.7 6300.6 6307.3 6317.1 6289.7 6305.8 6308.5 6329.5 6351.9 4864.6 4896.9 4923.5 4928.8 4991.0 3699.4 4430.9 4434.8 4452.5 4488.1 5879.0 5883.7 5901.2 5903.1 5907.2 6223.9 6223.9 6253.4 6262.7 6277.2 5911.1 5918.9 5929.2 5929.3 5950.4 5374.9 5434.4 5450.6 5452.5 5490.5 6365.8 6398.1 6417.0 6473.8 6492.6 5266.7 6295.6 6301.4 6322.3 6344.0 6258.4 6299.6 6456.1 6468.3 6482.4 6220.6 6253.2 6311.5 6314.9 6330.6 4920.4 6317.6 6318.0 6324.9 6328.5 5699.2 6088.4 6205.3 6250.3 6263.1 6116.9 6147.0 6197.8 6215.2 6222.8 6011.1 6066.5 6124.2 6145.3 6167.9 6112.5 6122.5 6202.7 6257.8 6268.3 6160.9 6162.1 6165.8 6170.8 6200.7 12:07:36 INFO - PROCESS | 1680 | 12:07:36 INFO - PROCESS | 1680 | [#1] ext.html Cycles:180 Average:72901.47 Median:21839.72 stddev:166789.17 (763.7%) stddev-sans-first:167168.50 12:07:36 INFO - PROCESS | 1680 | Values: 510.6 520.2 530.0 535.4 560.9 38575.4 41726.0 41788.6 41852.7 41892.8 42196.9 42494.6 42880.8 43354.1 43543.6 9369.7 9580.8 9621.1 9622.7 9654.3 9386.0 9414.8 9463.3 9542.5 9557.8 9523.2 9530.5 9535.3 9592.3 9632.9 9477.0 9553.8 9567.4 9599.9 9628.8 9326.6 9413.7 9442.3 9445.8 9457.7 38684.2 38857.4 39141.0 39212.4 39406.6 3370.2 3397.5 3482.3 3586.3 3977.6 31142.7 31408.4 31633.2 32372.5 32381.0 3094.9 3132.6 3276.8 3282.7 3336.4 523545.0 529901.0 530449.0 530753.0 532136.0 30477.1 30557.8 61615.4 62505.7 62515.7 34706.7 34830.8 35121.6 35141.6 35318.3 11855.5 13379.7 13838.6 13936.7 13972.5 11473.3 11480.8 11505.7 11526.9 11540.8 7522.7 7526.7 7598.5 7610.1 7621.0 3079.5 3112.7 3115.9 3116.1 3135.1 940.7 954.2 1005.0 1024.3 1093.7 29249.3 29315.7 29594.7 29681.0 29773.9 1022.9 1071.6 1111.2 1125.8 1152.4 15736.8 15831.9 15949.2 16065.4 16382.8 702084.0 705262.0 711829.0 712136.0 716699.0 61839.0 61955.7 62239.7 62445.0 62446.8 1389.3 1429.9 1438.6 1439.8 1440.5 30243.8 30406.6 30525.0 30863.2 30965.2 46060.1 50077.5 52870.5 53111.0 53298.0 608234.0 612718.0 612750.0 613110.0 613721.0 23373.5 26968.5 27047.6 27743.3 27801.0 45072.1 45557.5 45811.3 45987.3 46923.8 40279.6 40378.8 40774.2 40975.6 41432.0 39426.2 39650.2 39892.2 40097.6 40195.4 38710.0 38987.0 39052.0 39701.8 39878.0 32475.8 34817.0 35115.6 35325.6 35398.3 22389.0 22509.8 23623.5 24170.0 25009.7 12:07:36 INFO - PROCESS | 1680 | 12:07:36 INFO - PROCESS | 1680 | [#2] jquery.html Cycles:180 Average:34298.22 Median:12053.09 stddev:99288.12 (823.8%) stddev-sans-first:99533.99 12:07:36 INFO - PROCESS | 1680 | Values: 383.2 384.1 385.9 400.4 406.5 18615.4 20866.4 20933.8 21246.8 21247.2 19943.9 20486.1 20629.8 20849.0 20909.3 5160.5 5793.4 5947.5 5959.8 5972.4 5412.5 5678.8 5915.1 5928.9 5998.6 5626.7 5780.7 5882.6 5884.5 5886.1 5722.5 5737.5 5771.7 5831.6 5890.6 5061.7 5132.9 5160.5 5168.1 5168.1 24117.9 25014.7 25135.4 25390.1 25729.5 2070.9 2221.5 2319.3 2454.1 2642.1 15057.2 15362.8 15449.6 16031.7 16042.7 1808.8 1820.9 1874.5 1928.7 1955.7 101721.5 101918.0 112727.3 114915.0 115389.4 10672.4 10680.1 10875.6 11147.7 11196.6 13305.2 13524.2 13533.2 13723.2 13752.9 8274.2 8326.7 8332.3 8404.9 8427.5 8044.2 8073.0 8128.9 8238.7 8355.6 6173.9 6643.0 6731.5 6737.7 6747.4 1832.2 1849.6 1869.7 1879.1 1911.9 853.5 913.5 1036.8 1045.3 1100.7 17571.4 17616.3 21446.1 21471.5 21884.7 954.9 978.3 991.8 993.9 1053.3 9655.9 11312.3 11643.3 11659.9 12085.5 587484.0 595589.0 602399.0 603798.0 604782.0 79565.0 116053.0 116370.0 120543.0 121797.0 895.4 1000.6 1039.6 1046.6 1063.5 19048.2 19080.8 19320.6 19412.7 19466.5 48530.5 50395.0 51748.5 52313.0 52459.7 73067.6 73493.0 73614.6 74117.0 74538.0 7913.4 7999.8 8003.1 8020.2 8186.4 12544.9 12672.8 12703.2 12939.9 12955.8 11057.0 11069.4 11189.7 11325.2 11420.9 10328.5 10834.9 11100.8 11191.4 11347.1 9932.5 10856.5 11314.5 11451.8 11795.6 7976.3 7984.6 8008.2 8025.0 8041.1 10653.5 10690.6 11317.7 11408.9 11463.9 12:07:36 INFO - PROCESS | 1680 | 12:07:36 INFO - PROCESS | 1680 | [#3] mootools.html Cycles:180 Average:5720.39 Median:4861.21 stddev:3203.41 (65.9%) stddev-sans-first:3197.18 12:07:36 INFO - PROCESS | 1680 | Values: 1564.5 1602.7 1747.9 1813.4 1950.7 7998.6 8578.9 8642.3 9102.2 9109.1 8698.7 8782.7 8865.4 8887.5 8893.6 2364.6 2369.8 2401.9 2402.8 2404.3 1986.4 2194.0 2241.0 2343.7 2401.3 2325.5 2372.6 2373.1 2374.9 2376.6 2359.8 2362.6 2367.0 2394.7 2395.2 2370.3 2370.8 2371.1 2391.8 2392.5 8772.7 8840.0 8861.9 8881.0 8970.0 5281.3 5313.3 5329.1 5387.7 5442.2 3604.4 3642.6 3671.1 3718.2 3761.5 1084.0 1093.4 1096.0 1096.2 1104.6 9013.4 11676.0 11856.8 11862.8 11877.3 4712.3 4723.5 4789.5 4813.7 4824.4 9797.3 9941.5 9959.9 9960.4 10026.1 5283.6 5293.2 5297.1 5416.7 5429.5 4453.5 4792.0 4946.3 4955.5 4966.2 3934.3 3982.0 3992.0 4043.6 4102.6 1286.3 1292.6 1294.9 1302.3 1303.7 929.7 934.7 939.5 950.3 993.1 5888.2 5906.9 5943.3 6025.9 6036.0 364.3 435.8 445.5 445.6 446.5 3953.6 3960.9 3968.7 4050.1 4058.3 10390.1 10742.2 10847.0 10919.5 10932.1 10319.0 10700.1 10782.1 10816.4 10919.7 7947.7 8162.1 8182.6 8304.5 8314.4 5744.2 5876.3 5935.6 5966.0 6063.9 8477.8 9985.2 10057.8 10254.6 10260.2 8600.7 9187.9 10243.7 10433.9 10530.5 7020.6 7473.2 7626.6 7729.2 7762.5 8458.0 8492.7 8506.3 8517.8 8626.0 7451.7 7549.0 7587.0 7664.5 7726.7 7407.3 7423.0 7496.7 7571.9 7647.8 7070.8 7077.5 7143.5 7144.2 7173.7 4203.7 4287.9 4743.6 4898.0 4996.7 5015.3 5078.5 5092.8 5135.9 5194.5 12:07:36 INFO - PROCESS | 1680 | 12:07:36 INFO - PROCESS | 1680 | [#4] prototype.html Cycles:180 Average:4472.84 Median:4803.80 stddev:1407.85 (29.3%) stddev-sans-first:1376.57 12:07:36 INFO - PROCESS | 1680 | Values: 302.8 310.7 317.8 319.2 325.4 5162.6 5313.8 5387.5 5439.9 5540.8 5264.8 5481.3 5545.4 5549.9 5554.9 3508.6 3802.4 3944.4 4194.0 4198.1 3305.4 3520.4 3538.6 3911.3 3970.5 3155.5 3731.3 4136.9 4139.2 4149.3 3478.8 3972.0 4158.0 4161.4 4184.9 2910.4 3248.6 3311.9 3617.8 3632.7 4496.0 5153.7 5181.6 5324.7 5407.0 4754.9 4779.9 4796.3 4811.4 4827.0 5472.7 5805.0 5814.5 5818.6 5821.0 3410.6 3661.6 3915.6 3921.2 3970.3 5544.5 5711.1 6129.5 6178.3 6195.7 3243.8 3939.3 3978.9 3981.7 4023.8 3443.8 3902.6 4048.3 4056.1 4059.3 4653.9 4690.9 4694.7 4723.8 4737.8 4707.0 5180.8 5466.4 5550.8 5907.9 1241.8 1326.5 1432.9 1436.2 1438.0 819.5 838.9 873.6 901.8 905.3 4108.6 4230.0 4363.3 4399.6 4716.0 4432.5 4704.7 5183.5 5214.0 5225.5 4035.9 4233.8 4512.8 4555.0 4687.8 2323.9 2421.2 2550.4 2769.4 2775.0 4551.4 4689.5 5252.1 5426.7 5462.3 4498.8 4529.9 4869.5 5251.6 5330.6 4465.8 4632.1 4984.5 5336.3 5380.8 4762.9 5018.9 5182.0 5202.0 5204.0 4516.4 4705.4 5082.2 5120.5 5143.6 5384.3 5633.4 6326.8 6471.7 6479.0 3957.8 3993.6 4004.3 4021.2 4036.7 5006.8 5123.8 5998.0 6017.3 6179.1 5895.6 5931.9 5954.5 5957.5 5957.6 5675.9 5856.7 5859.6 5943.9 5987.2 5354.0 5505.9 5627.4 5802.3 5821.5 4983.3 5470.7 5870.4 5929.6 5985.2 5032.9 5773.4 5795.2 5812.7 5842.7 12:07:36 INFO - PROCESS | 1680 | 12:07:36 INFO - PROCESS | 1680 | [#5] yui.html Cycles:180 Average:18476.23 Median:47116.65 stddev:25514.76 (54.2%) stddev-sans-first:25551.58 12:07:36 INFO - PROCESS | 1680 | Values: 741.2 820.3 828.1 844.4 849.1 14535.0 14813.4 14914.4 14923.6 15068.0 10907.2 13935.4 13937.0 13962.0 13982.4 688.5 864.1 866.9 875.4 884.0 856.7 856.9 872.7 881.4 888.2 802.8 934.2 936.9 941.6 946.7 931.7 936.2 937.6 937.7 938.4 683.1 695.5 696.4 698.0 699.0 14025.0 14341.4 14367.2 14383.5 14422.2 13702.7 13718.2 13770.2 13775.2 13846.9 11458.8 11523.5 11597.4 11902.9 11939.7 3755.0 3837.2 3845.6 3866.2 3979.8 74112.3 79917.0 81061.0 81106.8 82221.0 9186.0 10553.3 10673.2 10827.9 10849.5 16575.5 21120.4 21361.5 21451.1 21548.1 7538.2 7808.5 8028.2 8103.8 8219.5 3598.6 3923.4 3952.4 3952.7 3959.2 3363.5 3460.0 3468.1 3476.4 3476.7 644.7 648.3 656.5 665.9 670.8 562.1 597.2 649.0 702.4 706.3 8782.6 9055.7 9401.3 9520.6 9538.7 551.7 657.2 661.8 684.1 691.2 4263.8 4450.7 4485.4 4520.2 4593.4 90435.0 91159.5 92552.0 93815.0 93942.0 78879.6 81338.6 81388.6 81565.0 81927.8 72171.6 72601.5 72656.9 72756.6 72895.3 8371.7 8386.4 8501.9 8528.7 8550.0 46128.5 46664.0 47056.3 47177.0 48241.6 61552.5 62180.4 62603.0 63246.8 63407.0 11484.5 11794.0 11834.0 11835.8 11913.0 12359.8 12443.3 12469.7 12623.6 12698.0 11773.4 11907.6 12044.0 12169.9 12294.3 11951.5 12003.6 12093.3 12264.1 12379.7 10637.6 11952.3 13045.3 13049.6 13359.6 10983.2 11117.4 11137.3 11415.4 11442.7 9377.7 10257.1 10984.5 11034.6 11282.9 12:07:36 INFO - PROCESS | 1680 | -------- Summary: end -------- 12:07:36 INFO - PROCESS | 1680 | 12:07:36 INFO - TEST-INFO | 1680: exit 0 12:07:36 INFO - TEST-OK | dromaeo_css | took 1113329ms 12:07:36 INFO - TEST-START | kraken 12:07:36 INFO - operating with platform_type : mac_ 12:07:36 INFO - Initialising browser for kraken test... 12:07:36 INFO - TEST-INFO | started process 1688 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/tmpN6k6vN/profile http://localhost:49272/getInfo.html) 12:07:39 INFO - PROCESS | 1688 | __metrics Screen width/height:1600/1200 12:07:39 INFO - PROCESS | 1688 | colorDepth:24 12:07:39 INFO - PROCESS | 1688 | Browser inner width/height: 1024/689 12:07:39 INFO - PROCESS | 1688 | __metrics 12:07:39 ERROR - PROCESS | 1688 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2938: TypeError: this.tabs is undefined 12:07:40 INFO - TEST-INFO | 1688: exit 0 12:07:40 INFO - Browser initialized. 12:07:40 INFO - Running cycle 1/1 for kraken test... 12:07:40 INFO - TEST-INFO | started process 1690 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/tmpN6k6vN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:07:52 INFO - PROCESS | 1690 | RSS: Main: 253095936 12:07:52 INFO - PROCESS | 1690 | 12:07:55 INFO - PROCESS | 1690 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection) 12:07:56 INFO - PROCESS | 1690 | RSS: Main: 265568256 12:07:56 INFO - PROCESS | 1690 | 12:07:58 INFO - PROCESS | 1690 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-dft) 12:07:59 INFO - PROCESS | 1690 | RSS: Main: 269692928 12:07:59 INFO - PROCESS | 1690 | 12:08:02 INFO - PROCESS | 1690 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-fft) 12:08:03 INFO - PROCESS | 1690 | RSS: Main: 270225408 12:08:03 INFO - PROCESS | 1690 | 12:08:05 INFO - PROCESS | 1690 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator) 12:08:05 INFO - PROCESS | 1690 | RSS: Main: 271249408 12:08:05 INFO - PROCESS | 1690 | 12:08:07 INFO - PROCESS | 1690 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:08:08 INFO - PROCESS | 1690 | RSS: Main: 271839232 12:08:08 INFO - PROCESS | 1690 | 12:08:11 INFO - PROCESS | 1690 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom) 12:08:11 INFO - PROCESS | 1690 | RSS: Main: 272429056 12:08:11 INFO - PROCESS | 1690 | 12:08:15 INFO - PROCESS | 1690 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate) 12:08:15 INFO - PROCESS | 1690 | RSS: Main: 272695296 12:08:15 INFO - PROCESS | 1690 | 12:08:19 INFO - PROCESS | 1690 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial) 12:08:19 INFO - PROCESS | 1690 | RSS: Main: 272633856 12:08:19 INFO - PROCESS | 1690 | 12:08:21 INFO - PROCESS | 1690 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:08:21 INFO - PROCESS | 1690 | RSS: Main: 272187392 12:08:21 INFO - PROCESS | 1690 | 12:08:23 INFO - PROCESS | 1690 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:08:24 INFO - PROCESS | 1690 | RSS: Main: 272232448 12:08:24 INFO - PROCESS | 1690 | 12:08:26 INFO - PROCESS | 1690 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:08:27 INFO - PROCESS | 1690 | RSS: Main: 272568320 12:08:27 INFO - PROCESS | 1690 | 12:08:30 INFO - PROCESS | 1690 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:08:30 INFO - PROCESS | 1690 | RSS: Main: 272678912 12:08:30 INFO - PROCESS | 1690 | 12:08:33 INFO - PROCESS | 1690 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:08:34 INFO - PROCESS | 1690 | RSS: Main: 272859136 12:08:34 INFO - PROCESS | 1690 | 12:08:36 INFO - PROCESS | 1690 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49272/tests/kraken/driver.html?testName=ai-astar) 12:08:36 INFO - PROCESS | 1690 | RSS: Main: 273387520 12:08:36 INFO - PROCESS | 1690 | 12:08:36 INFO - PROCESS | 1690 | __start_tp_report 12:08:36 INFO - PROCESS | 1690 | _x_x_mozilla_page_load 12:08:36 INFO - PROCESS | 1690 | _x_x_mozilla_page_load_details 12:08:36 INFO - PROCESS | 1690 | |i|pagename|runs| 12:08:36 INFO - PROCESS | 1690 | |0;ai-astar;148;96;169;172;174;117;115;116;116;116 12:08:36 INFO - PROCESS | 1690 | |1;audio-beat-detection;156;124;145;168;116;142;140;122;170;119 12:08:36 INFO - PROCESS | 1690 | |2;audio-dft;197;197;209;250;234;202;192;194;194;187 12:08:36 INFO - PROCESS | 1690 | |3;audio-fft;70;81;74;135;58;58;62;61;139;52 12:08:36 INFO - PROCESS | 1690 | |4;audio-oscillator;69;73;129;70;72;88;114;70;74;119 12:08:36 INFO - PROCESS | 1690 | |5;imaging-gaussian-blur;92;90;131;89;93;89;76;76;76;112 12:08:36 INFO - PROCESS | 1690 | |6;imaging-darkroom;100;128;152;133;108;153;105;96;153;123 12:08:36 INFO - PROCESS | 1690 | |7;imaging-desaturate;147;134;100;75;136;94;145;151;94;90 12:08:36 INFO - PROCESS | 1690 | |8;json-parse-financial;56;58;57;56;58;58;59;58;58;58 12:08:36 INFO - PROCESS | 1690 | |9;json-stringify-tinderbox;80;76;72;70;74;76;78;84;69;71 12:08:36 INFO - PROCESS | 1690 | |10;stanford-crypto-aes;87;87;88;85;88;88;89;88;132;86 12:08:36 INFO - PROCESS | 1690 | |11;stanford-crypto-ccm;132;152;140;146;144;159;143;153;165;161 12:08:36 INFO - PROCESS | 1690 | |12;stanford-crypto-pbkdf2;168;164;170;146;215;167;171;168;165;162 12:08:36 INFO - PROCESS | 1690 | |13;stanford-crypto-sha256-iterative;66;71;77;86;87;82;68;70;62;70 12:08:36 INFO - PROCESS | 1690 | __end_tp_report 12:08:36 INFO - PROCESS | 1690 | __start_cc_report 12:08:36 INFO - PROCESS | 1690 | _x_x_mozilla_cycle_collect,1077 12:08:36 INFO - PROCESS | 1690 | __end_cc_report 12:08:36 INFO - PROCESS | 1690 | __startTimestamp1464894516344__endTimestamp 12:08:36 INFO - PROCESS | 1690 | 12:08:36 INFO - PROCESS | 1690 | ------- Summary: start ------- 12:08:36 INFO - PROCESS | 1690 | Number of tests: 14 12:08:36 INFO - PROCESS | 1690 | 12:08:36 INFO - PROCESS | 1690 | [#0] ai-astar Cycles:10 Average:133.90 Median:158.50 stddev:28.93 (18.3%) stddev-sans-first:30.23 12:08:36 INFO - PROCESS | 1690 | Values: 148.0 96.0 169.0 172.0 174.0 117.0 115.0 116.0 116.0 116.0 12:08:36 INFO - PROCESS | 1690 | 12:08:36 INFO - PROCESS | 1690 | [#1] audio-beat-detection Cycles:10 Average:140.20 Median:143.50 stddev:19.90 (13.9%) stddev-sans-first:20.27 12:08:36 INFO - PROCESS | 1690 | Values: 156.0 124.0 145.0 168.0 116.0 142.0 140.0 122.0 170.0 119.0 12:08:36 INFO - PROCESS | 1690 | 12:08:36 INFO - PROCESS | 1690 | [#2] audio-dft Cycles:10 Average:205.60 Median:199.50 stddev:20.41 (10.2%) stddev-sans-first:21.41 12:08:36 INFO - PROCESS | 1690 | Values: 197.0 197.0 209.0 250.0 234.0 202.0 192.0 194.0 194.0 187.0 12:08:36 INFO - PROCESS | 1690 | 12:08:36 INFO - PROCESS | 1690 | [#3] audio-fft Cycles:10 Average:79.00 Median:61.50 stddev:31.75 (51.6%) stddev-sans-first:33.50 12:08:36 INFO - PROCESS | 1690 | Values: 70.0 81.0 74.0 135.0 58.0 58.0 62.0 61.0 139.0 52.0 12:08:36 INFO - PROCESS | 1690 | 12:08:36 INFO - PROCESS | 1690 | [#4] audio-oscillator Cycles:10 Average:87.80 Median:71.00 stddev:23.58 (33.2%) stddev-sans-first:24.01 12:08:36 INFO - PROCESS | 1690 | Values: 69.0 73.0 129.0 70.0 72.0 88.0 114.0 70.0 74.0 119.0 12:08:36 INFO - PROCESS | 1690 | 12:08:36 INFO - PROCESS | 1690 | [#5] imaging-gaussian-blur Cycles:10 Average:92.40 Median:89.00 stddev:17.35 (19.5%) stddev-sans-first:18.41 12:08:36 INFO - PROCESS | 1690 | Values: 92.0 90.0 131.0 89.0 93.0 89.0 76.0 76.0 76.0 112.0 12:08:36 INFO - PROCESS | 1690 | 12:08:36 INFO - PROCESS | 1690 | [#6] imaging-darkroom Cycles:10 Average:125.10 Median:142.50 stddev:22.43 (15.7%) stddev-sans-first:21.88 12:08:36 INFO - PROCESS | 1690 | Values: 100.0 128.0 152.0 133.0 108.0 153.0 105.0 96.0 153.0 123.0 12:08:36 INFO - PROCESS | 1690 | 12:08:36 INFO - PROCESS | 1690 | [#7] imaging-desaturate Cycles:10 Average:116.60 Median:113.00 stddev:28.54 (25.3%) stddev-sans-first:28.07 12:08:36 INFO - PROCESS | 1690 | Values: 147.0 134.0 100.0 75.0 136.0 94.0 145.0 151.0 94.0 90.0 12:08:36 INFO - PROCESS | 1690 | 12:08:36 INFO - PROCESS | 1690 | [#8] json-parse-financial Cycles:10 Average:57.60 Median:58.00 stddev:0.97 (1.7%) stddev-sans-first:0.83 12:08:36 INFO - PROCESS | 1690 | Values: 56.0 58.0 57.0 56.0 58.0 58.0 59.0 58.0 58.0 58.0 12:08:36 INFO - PROCESS | 1690 | 12:08:36 INFO - PROCESS | 1690 | [#9] json-stringify-tinderbox Cycles:10 Average:75.00 Median:76.00 stddev:4.76 (6.3%) stddev-sans-first:4.69 12:08:36 INFO - PROCESS | 1690 | Values: 80.0 76.0 72.0 70.0 74.0 76.0 78.0 84.0 69.0 71.0 12:08:36 INFO - PROCESS | 1690 | 12:08:36 INFO - PROCESS | 1690 | [#10] stanford-crypto-aes Cycles:10 Average:91.80 Median:88.00 stddev:14.17 (16.1%) stddev-sans-first:14.92 12:08:36 INFO - PROCESS | 1690 | Values: 87.0 87.0 88.0 85.0 88.0 88.0 89.0 88.0 132.0 86.0 12:08:36 INFO - PROCESS | 1690 | 12:08:36 INFO - PROCESS | 1690 | [#11] stanford-crypto-ccm Cycles:10 Average:149.50 Median:152.50 stddev:10.34 (6.8%) stddev-sans-first:8.82 12:08:36 INFO - PROCESS | 1690 | Values: 132.0 152.0 140.0 146.0 144.0 159.0 143.0 153.0 165.0 161.0 12:08:36 INFO - PROCESS | 1690 | 12:08:36 INFO - PROCESS | 1690 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.60 Median:168.00 stddev:17.46 (10.4%) stddev-sans-first:18.51 12:08:36 INFO - PROCESS | 1690 | Values: 168.0 164.0 170.0 146.0 215.0 167.0 171.0 168.0 165.0 162.0 12:08:36 INFO - PROCESS | 1690 | 12:08:36 INFO - PROCESS | 1690 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.90 Median:74.00 stddev:8.63 (11.7%) stddev-sans-first:8.67 12:08:36 INFO - PROCESS | 1690 | Values: 66.0 71.0 77.0 86.0 87.0 82.0 68.0 70.0 62.0 70.0 12:08:36 INFO - PROCESS | 1690 | -------- Summary: end -------- 12:08:36 INFO - PROCESS | 1690 | 12:08:37 INFO - TEST-INFO | 1690: exit 0 12:08:37 INFO - TEST-OK | kraken | took 60395ms 12:08:37 INFO - SUITE-END | took 1173s 12:08:37 INFO - Completed test suite (00:19:34) 12:08:37 INFO - javascript benchmark 12:08:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7176.156326906693, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3526.418141006734, 3527.1534831640397, 3541.4687796830476, 3568.6788189062995, 3586.8385922633497, 5878.911816322753, 5895.086261629449, 5954.88090238196, 5994.88084292139, 5997.190379298793, 4975.950240497603, 5394.6493477923905, 5441.972790136044, 5828.708564571765, 6044.758209671608, 4134.028503301722, 4627.329037289591, 5193.039287731782, 5228.947710522903, 5244.239585260135, 5242.842714718546, 5425.159100339434, 5454.209139674745, 5456.863578410532, 5473.972630136864, 4451.931536431265, 5218.060749065166, 5390.19147127934, 5411.918821217665, 5417.431169727161, 5232.790688372461, 5324.600654950894, 5325.121354976424, 5330.413654498002, 5356.598255130842, 5256.579473642138, 5267.183586544092, 5273.789048438058, 5297.629165958385, 5349.0, 5521.503064724154, 5659.660420374749, 5734.082546792493, 5783.710814459302, 5800.0719884818645, 6247.187865577492, 6254.21822272216, 6298.02380631003, 6319.14691516644, 6326.398992095789, 6286.151369565142, 6304.527160462938, 6322.020086886494, 6328.8101356958905, 6383.39357761012, 5830.82507524775, 5917.970410147965, 5921.940780592225, 5993.9400605993815, 6013.939860601338, 6375.330590288046, 6393.296737358887, 6406.775762848278, 6407.231132264158, 6554.672266386757, 6310.148130002391, 6311.274203466568, 6321.146645202931, 6329.367063293726, 6333.714982825692, 6222.097795819672, 6227.844303892439, 6230.688465576703, 6240.625562466266, 6316.557840951089, 6122.581127188521, 6226.719797609118, 6300.558960872695, 6307.337729538424, 6317.1156038154695, 6289.7169627366875, 6305.842353941096, 6308.495320374359, 6329.461995730322, 6351.936480635134, 4864.610831133572, 4896.902061958741, 4923.458419573844, 4928.778204980785, 4991.026749783758, 3699.389600715852, 4430.889227769331, 4434.84478043267, 4452.465704115462, 4488.147252022105, 5878.971180043561, 5883.676397798076, 5901.17383566301, 5903.0555111182, 5907.232059832194, 6223.875522489616, 6223.937760622426, 6253.437190652863, 6262.6868656566985, 6277.18396608438, 5911.113333000085, 5918.8816223675285, 5929.229200203946, 5929.347771745104, 5950.4049595041015, 5374.91937620936, 5434.375046869661, 5450.591205659592, 5452.509274165344, 5490.450954904478, 6365.840853978688, 6398.104265402847, 6417.03744438338, 6473.805785826433, 6492.64290464029, 5266.736663166903, 6295.5907865988565, 6301.369863013845, 6322.304546499973, 6343.9682801585695, 6258.374162583706, 6299.590526615755, 6456.12842266288, 6468.2561505427475, 6482.448991835558, 6220.626762394271, 6253.187085678833, 6311.463525600192, 6314.905276420949, 6330.620162790247, 4920.438096837869, 6317.557770955989, 6317.989121740499, 6324.873502530016, 6328.493720502441, 5699.173619825019, 6088.36072212429, 6205.348438414082, 6250.343713909974, 6263.091851681572, 6116.938830611637, 6147.016477363779, 6197.81406557804, 6215.192025036717, 6222.751089956532, 6011.098335249748, 6066.484348830399, 6124.223975047186, 6145.262568491809, 6167.876642467215, 6112.50348092447, 6122.459144589048, 6202.658853762906, 6257.78097766576, 6268.341824108586, 6160.9383906160365, 6162.137300777805, 6165.84585385369, 6170.81487555374, 6200.658963757037], "value": 5765.185350164563, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [510.64765311934786, 520.1599416941639, 529.9533421492553, 535.360244507813, 560.8625886657769, 38575.42136867949, 41726.0, 41788.58211417885, 41852.65178785697, 41892.79053604735, 42196.94507637303, 42494.57505424945, 42880.785596071975, 43354.13291734163, 43543.56456435443, 9369.67206147785, 9580.80838383232, 9621.08599683029, 9622.711318660451, 9654.324197306192, 9385.95307023464, 9414.811703765921, 9463.337566370323, 9542.475163865985, 9557.761055973622, 9523.19052880506, 9530.475823829687, 9535.332526723132, 9592.280578956572, 9632.855507167364, 9476.952615236914, 9553.761155971088, 9567.425954442755, 9599.856002159973, 9628.807423851484, 9326.580303886341, 9413.717588472357, 9442.29182811285, 9445.811083778355, 9457.668981585612, 38684.22631547382, 38857.417138742945, 39141.02147446336, 39212.411813822815, 39406.605933940584, 3370.174307294704, 3397.456406974872, 3482.3035392921515, 3586.3185994661194, 3977.6469414230173, 31142.688573114207, 31408.37183256322, 31633.20916977071, 32372.5144122836, 32381.0, 3094.9071527853976, 3132.621646475566, 3276.7542434317284, 3282.720968717662, 3336.43280642289, 523545.0, 529901.0, 530449.0, 530753.0, 532136.0, 30477.08568742941, 30557.847210763804, 61615.38384616186, 62505.74988500204, 62515.6874215626, 34706.65293347085, 34830.82584587061, 35121.64878351235, 35141.64858351435, 35318.29363412769, 11855.46650400734, 13379.728925752039, 13838.584842454742, 13936.651583710489, 13972.510962116277, 11473.254238474472, 11480.770384592428, 11505.71235719097, 11526.94236528812, 11540.826887596693, 7522.6614802334025, 7526.6989320427665, 7598.506097103781, 7610.124835643861, 7621.0, 3079.5226739855357, 3112.7042930921525, 3115.9376812463624, 3116.065180445857, 3135.059482155344, 940.703678341334, 954.2223088183108, 1004.989985065005, 1024.3239461955075, 1093.7156339351825, 29249.268768280537, 29315.706842931726, 29594.704052959194, 29680.967006836763, 29773.851130744642, 1022.8619136416637, 1071.5927947379946, 1111.1721767283425, 1125.7692173104494, 1152.3604399558326, 15736.763948540782, 15831.920840395724, 15949.20253987296, 16065.437709680338, 16382.836171638131, 702084.0, 705262.0, 711829.0, 712136.0, 716699.0, 61839.0, 61955.69022154951, 62239.68880155661, 62445.0, 62446.751064978445, 1389.2775756606509, 1429.8784603308627, 1438.5756201920667, 1439.8128243328301, 1440.51742666208, 30243.848780755954, 30406.63170157304, 30525.0, 30863.2284192897, 30965.225869353446, 46060.07879842451, 50077.49922500728, 52870.47129528655, 53111.0, 53298.0, 608234.0, 612718.0, 612750.0, 613110.0, 613721.0, 23373.532529349657, 26968.460630787667, 27047.594286085332, 27743.30641733973, 27801.0, 45072.09855802931, 45557.54442455533, 45811.31283030824, 45987.31019034784, 46923.82690432767, 40279.59720402758, 40378.79810600928, 40774.1845163101, 40975.59024409718, 41432.0, 39426.2114757709, 39650.2069958605, 39892.2021559573, 40097.59902400939, 40195.39706904457, 38710.0, 38987.0, 39052.02369940661, 39701.80149099236, 39878.0, 32475.837620811744, 34817.0, 35115.59537618569, 35325.646743532234, 35398.29203415866, 22389.0, 22509.774902250767, 23623.52752944897, 24170.0, 25009.74990250147], "value": 18767.714680614237, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [383.19911385204927, 384.12611309271404, 385.8591614060868, 400.39340399295094, 406.49391507573057, 18615.4415367539, 20866.37400877976, 20933.790662093375, 21246.787532124676, 21247.15011399544, 19943.9030853303, 20486.078126484306, 20629.793702062976, 20849.0, 20909.268175613855, 5160.535551800337, 5793.391693872146, 5947.494462970652, 5959.791407300723, 5972.402759724015, 5412.4858138476975, 5678.801241956532, 5915.142304365864, 5928.911066334009, 5998.610090344136, 5626.746796394152, 5780.739866706008, 5882.5588080893895, 5884.4703976642095, 5886.087656413263, 5722.513586345166, 5737.540996720252, 5771.740271687763, 5831.5626328025355, 5890.64656120632, 5061.721605311706, 5132.871678208037, 5160.509751573594, 5168.095583272912, 5168.095583272912, 24117.879410602836, 25014.749852501423, 25135.37161570957, 25390.11134610298, 25729.485410291876, 2070.927517536879, 2221.5001624634488, 2319.3158018384615, 2454.0797201049604, 2642.0620679658637, 15057.247137643184, 15362.769558456632, 15449.613759655986, 16031.679366412724, 16042.679146417006, 1808.8372046515876, 1820.8983564943283, 1874.4751469588537, 1928.6721257386278, 1955.7066440033966, 101721.49139254256, 101918.0, 112727.3090903637, 114915.0, 115389.42305288503, 10672.413017284123, 10680.092192163598, 10875.619353322754, 11147.665570032912, 11196.552137914392, 13305.201687898758, 13524.188548686914, 13533.188008719508, 13723.245221512712, 13752.862471375158, 8274.213949674771, 8326.70856520019, 8332.291755200877, 8404.915950840414, 8427.494350338999, 8044.1955804419085, 8072.959635201903, 8128.9187108129345, 8238.670453181805, 8355.582220888931, 6173.9073913891325, 6642.966785166043, 6731.461483081342, 6737.663116844138, 6747.359000894907, 1832.15720768448, 1849.556106534422, 1869.691500902336, 1879.0792511669456, 1911.9139638716292, 853.4665833853842, 913.4793167894369, 1036.7978244242452, 1045.273534893242, 1100.741325788439, 17571.385001524886, 17616.29534818593, 21446.142154313966, 21471.46321341979, 21884.671729924063, 954.8663187153804, 978.2858513285341, 991.8115558043949, 993.8608594796734, 1053.2679787547734, 9655.9034409655, 11312.321260724382, 11643.301401915913, 11659.941700291443, 12085.45615447307, 587484.0, 595589.0, 602399.0, 603798.0, 604782.0, 79565.0, 116053.0, 116370.0, 120543.0, 121797.0, 895.4403497813867, 1000.5797565022708, 1039.604950118965, 1046.6336782126195, 1063.5320458998017, 19048.238070477026, 19080.8091919079, 19320.613587728447, 19412.708809368152, 19466.513337166118, 48530.5146948526, 50395.0, 51748.482515174364, 52313.0, 52459.73770131125, 73067.63466182635, 73493.0, 73614.63192684003, 74117.0, 74538.0, 7913.438952471526, 7999.84000320002, 8003.11965683786, 8020.157883422156, 8186.3860210485645, 12544.937275313565, 12672.809907851206, 12703.174293670882, 12939.935300323437, 12955.805662915252, 11057.004869561779, 11069.446527673423, 11189.72025699364, 11325.15061370384, 11420.94289528547, 10328.483575821328, 10834.945825270823, 11100.833487497855, 11191.38447385369, 11347.092232621206, 9932.453715045742, 10856.503004308895, 11314.490847911698, 11451.828222576502, 11795.587154449553, 7976.282134607914, 7984.640691169027, 8008.15914328987, 8024.956755621965, 8041.115477297376, 10653.467326633792, 10690.57237710514, 11317.71705707364, 11408.94295528517, 11463.942680286546], "value": 9672.845857721215, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1564.5228205397357, 1602.7435610302352, 1747.912604369781, 1813.3562585282225, 1950.6586347389202, 7998.600069996492, 8578.871316930243, 8642.265407440374, 9102.226310763584, 9109.089091090887, 8698.695545655903, 8782.736517904457, 8865.423747456427, 8887.466751994885, 8893.64425422984, 2364.586197415454, 2369.763023697625, 2401.939951501218, 2402.8318017738766, 2404.2907342333965, 1986.354434808693, 2194.0, 2240.969154189067, 2343.742188359279, 2401.315625046862, 2325.5232677301196, 2372.5847976604027, 2373.1100837186054, 2374.943150298118, 2376.6197408414655, 2359.834811563191, 2362.5511152881068, 2367.041348253951, 2394.6647469354302, 2395.2095808383365, 2370.277065495023, 2370.813099240443, 2371.146387300588, 2391.8325717199805, 2392.4736557957394, 8772.692955746517, 8840.0, 8861.911380886173, 8881.0, 8970.0, 5281.339832520935, 5313.282706834567, 5329.120695085307, 5387.676739395649, 5442.210879422501, 3604.4140578145534, 3642.6175251598465, 3671.0638787109383, 3718.219173973469, 3761.5110035695184, 1083.9620613278498, 1093.3877028863863, 1095.9890401096047, 1096.1888202730001, 1104.6354702948008, 9013.414128081653, 11676.0, 11856.822147667792, 11862.762744745143, 11877.346745929057, 4712.269598212282, 4723.456802467692, 4789.521047895182, 4813.711177329302, 4824.396950381202, 9797.265205109645, 9941.502924853872, 9959.900400995897, 9960.402375857471, 10026.09765121128, 5283.630145889828, 5293.206019097166, 5297.073012222845, 5416.674999500043, 5429.484199001088, 4453.465584129925, 4791.95208047922, 4946.307516947631, 4955.529224723646, 4966.180580204225, 3934.291827471083, 3981.980090099531, 3991.960080399217, 4043.555208927016, 4102.630763231324, 1286.3118231746157, 1292.5799115287568, 1294.8834604885606, 1302.2967597497245, 1303.7327347893658, 929.6746138851348, 934.6681927915628, 939.5067589515422, 950.3442624589012, 993.1210878372692, 5888.175655408247, 5906.940930590639, 5943.3462319144855, 6025.9096113558335, 6036.01268220696, 364.33327011568764, 435.8343829344892, 445.5277405949704, 445.570024925948, 446.4642429085046, 3953.584873588289, 3960.940585891214, 3968.66266367362, 4050.1292222172374, 4058.330375488011, 10390.116840068527, 10742.24804263694, 10846.969537893887, 10919.508622112024, 10932.125429965743, 10319.0, 10700.090492308085, 10782.08352290064, 10816.4050977197, 10919.727006824734, 7947.721829735931, 8162.061362943337, 8182.627038158987, 8304.543250121302, 8314.376421768513, 5744.224529688438, 5876.324222714443, 5935.643861368331, 5965.994132790558, 6063.93881070802, 8477.830443390974, 9985.1512621428, 10057.798844023226, 10254.589816407559, 10260.230482713976, 8600.666896631032, 9187.943386510637, 10243.692689219335, 10433.895661043292, 10530.526126324487, 7020.5787652741, 7473.215312392338, 7626.58053807046, 7729.1884352142215, 7762.495437796525, 8458.027326857491, 8492.70275540353, 8506.276966457674, 8517.787055323666, 8626.008009079036, 7451.664675089525, 7549.0, 7586.962065189638, 7664.501807382502, 7726.652300646372, 7407.296306850841, 7422.96288518554, 7496.662650180646, 7571.924280757122, 7647.770566883002, 7070.7525236616475, 7077.504574679723, 7143.535670181422, 7144.214136445091, 7173.677184526813, 4203.7178660508835, 4287.892802679958, 4743.620510359232, 4898.020395920759, 4996.7001979881325, 5015.322931404359, 5078.5175408336145, 5092.796288148581, 5135.948640513697, 5194.454582268959], "value": 4529.632201332631, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [302.78804836614364, 310.6706890695862, 317.7569159592912, 319.1526497150065, 325.3639135490117, 5162.580290420132, 5313.760880760366, 5387.515123638872, 5439.864003399917, 5540.778368865245, 5264.789408423659, 5481.341549348563, 5545.3622833374175, 5549.889002219953, 5554.86112847179, 3508.561429821272, 3802.4219948721316, 3944.3689009758446, 4194.035371864473, 4198.097409057053, 3305.3554556861422, 3520.419130843408, 3538.610752817188, 3911.3155197840406, 3970.5433875104445, 3155.4635711929027, 3731.3097077040834, 4136.903720514066, 4139.234241665286, 4149.298787497127, 3478.808665523395, 3971.9871432784453, 4158.043649960511, 4161.396597493362, 4184.895377615553, 2910.359720861407, 3248.610166779978, 3311.873962852642, 3617.8371973261005, 3632.7093832493338, 4495.955040449587, 5153.651696488524, 5181.611379146579, 5324.653897496689, 5406.972965135188, 4754.904901901978, 4779.928301075487, 4796.25658023007, 4811.350467686888, 4826.951730482685, 5472.72636368184, 5804.997275939794, 5814.5057670098095, 5818.5926985111055, 5821.0, 3410.6077801052948, 3661.578918424362, 3915.5888631693547, 3921.1961547882615, 3970.285348637244, 5544.532682956086, 5711.086226203829, 6129.509639228806, 6178.320384757718, 6195.690215489208, 3243.789153705002, 3939.3106206413763, 3978.8607398741483, 3981.681465482812, 4023.8189281482405, 3443.8278086095597, 3902.648761611412, 4048.332025215865, 4056.0873803394, 4059.2693315202955, 4653.930191047137, 4690.929636055462, 4694.694844835075, 4723.7874295656775, 4737.786799594027, 4706.964467817074, 5180.792768289301, 5466.371367292732, 5550.750216240279, 5907.932223721749, 1241.8137279407981, 1326.4694122351136, 1432.9355179016761, 1436.174199835099, 1437.9856201437851, 819.496009953874, 838.9496630202208, 873.5675840459013, 901.7915992569857, 905.2983937448398, 4108.562003298822, 4229.978850105791, 4363.345498175299, 4399.6040356368085, 4716.0, 4432.534583868712, 4704.670673052853, 5183.481651834786, 5214.0, 5225.451327610546, 4035.9394609080887, 4233.830646774095, 4512.781548981757, 4554.952360956999, 4687.81248750053, 2323.8954247058923, 2421.152596591179, 2550.4134049168797, 2769.3630464993166, 2774.9722502775116, 4551.40831691878, 4689.484156742756, 5252.054630166606, 5426.701531415731, 5462.289902312674, 4498.773107311901, 4529.851753031678, 4869.464358920516, 5251.6061295403, 5330.573554115662, 4465.799039043316, 4632.096741135513, 4984.501549844987, 5336.251830307395, 5380.811671591554, 4762.9285560715925, 5018.8707540803025, 5181.968548399229, 5201.973990130025, 5204.0372531081875, 4516.412866327357, 4705.364775755366, 5082.186850103965, 5120.462351453044, 5143.630921746262, 5384.300040994645, 5633.408492108433, 6326.841828954313, 6471.741130354733, 6478.967605162133, 3957.8416863325147, 3993.5607083221403, 4004.2570838975394, 4021.2158629067053, 4036.6770658346672, 5006.799728010985, 5123.84713439488, 5998.0, 6017.338092809875, 6179.073139029181, 5895.58281104745, 5931.911021334769, 5954.46409823118, 5957.523398128053, 5957.553183511341, 5675.943240567542, 5856.677882716492, 5859.589828711949, 5943.940560594338, 5987.191729116514, 5353.982743278764, 5505.889882202414, 5627.409122042127, 5802.303723553201, 5821.476067153976, 4983.327250821093, 5470.671759694431, 5870.354261031198, 5929.644221346561, 5985.221921150222, 5032.949670503248, 5773.364929857628, 5795.188673585617, 5812.738426770721, 5842.7078646066675], "value": 4032.457940794284, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [741.1809950005244, 820.2617644120295, 828.1000637380122, 844.4131328726537, 849.1423662101275, 14534.98255122142, 14813.407463701466, 14914.403423863047, 14923.552293431201, 15068.0, 10907.236493445462, 13935.44258229672, 13937.0, 13962.0, 13982.3707933143, 688.5008368932534, 864.1185990289932, 866.9263112635418, 875.4309698695835, 884.0496466298755, 856.6830272799073, 856.8586183279751, 872.655301156043, 881.3742243007472, 888.1695614600337, 802.8314054048657, 934.1825902335456, 936.9484678342689, 941.6327632223438, 946.711253067814, 931.6739141300559, 936.166811537732, 937.5968333616543, 937.6765016069479, 938.3900464697966, 683.0744341417358, 695.4922906278395, 696.3941371007205, 697.9860402791916, 699.0283505926766, 14025.0, 14341.42634294627, 14367.209803460806, 14383.496577619733, 14422.206778627171, 13702.725945481134, 13718.245496497688, 13770.24263665498, 13775.173489590657, 13846.861531384659, 11458.770824583462, 11523.539058437653, 11597.362145082017, 11902.88097119035, 11939.701507462296, 3754.9812250938844, 3837.175007373428, 3845.557760857509, 3866.2074274773604, 3979.7612143271494, 74112.25887741108, 79917.0, 81061.0, 81106.78339824907, 82221.0, 9186.0, 10553.261271710906, 10673.199510036779, 10827.945860270649, 10849.457527123612, 16575.502734917973, 21120.366389008355, 21361.46596335073, 21451.14195432165, 21548.13807447716, 7538.170801211862, 7808.492447990862, 8028.197180281925, 8103.837923241502, 8219.54792486407, 3598.604153543108, 3923.352646813301, 3952.4268980997595, 3952.7430717003876, 3959.1685745993664, 3363.4618461046116, 3460.013896039667, 3468.098294443412, 3476.37425263455, 3476.721862251014, 644.6712176789778, 648.2545073165898, 656.4846595422666, 665.8834703926793, 670.8457054877406, 562.1174755633616, 597.2265915639258, 648.9875793761653, 702.3994484715495, 706.2795948132875, 8782.560871956377, 9055.728328150166, 9401.341906066646, 9520.571574279174, 9538.713838584854, 551.7020808763303, 657.1850904877917, 661.7716887673745, 684.117488439907, 691.1705952856491, 4263.76549289792, 4450.710703804242, 4485.372047913294, 4520.208963431386, 4593.425821772278, 90435.0, 91159.54420227856, 92552.0, 93815.0, 93942.0, 78879.60560197162, 81338.59330703308, 81388.59305703553, 81565.0, 81927.77108343379, 72171.63914180396, 72601.54796904033, 72656.91014634784, 72756.63621681964, 72895.27104728884, 8371.706990255312, 8386.37102217324, 8501.914980850112, 8528.74413767588, 8549.957250213709, 46128.538714613765, 46664.0, 47056.29415558838, 47177.0, 48241.552753417454, 61552.46118847065, 62180.378196219266, 62603.0, 63246.83765811783, 63407.0, 11484.540618375171, 11794.0, 11834.0, 11835.822462662894, 11912.98739607143, 12359.814602781145, 12443.315617641116, 12469.747494073854, 12623.558175463815, 12698.04764642637, 11773.35246561413, 11907.642770716893, 12044.0, 12169.939150304192, 12294.323812190058, 11951.521939122338, 12003.63989080329, 12093.25545404637, 12264.080193985665, 12379.690507737017, 10637.574497020032, 11952.282863028246, 13045.282509462071, 13049.608511744664, 13359.587248572756, 10983.231173818076, 11117.44412779374, 11137.273722573014, 11415.429228538374, 11442.656720298404, 9377.717798770376, 10257.128144107834, 10984.450777461256, 11034.558617655204, 11282.887171128512], "value": 7143.236138634863, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1598.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 96.0, 169.0, 172.0, 174.0, 117.0, 115.0, 116.0, 116.0, 116.0], "value": 133.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 124.0, 145.0, 168.0, 116.0, 142.0, 140.0, 122.0, 170.0, 119.0], "value": 140.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 197.0, 209.0, 250.0, 234.0, 202.0, 192.0, 194.0, 194.0, 187.0], "value": 205.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 81.0, 74.0, 135.0, 58.0, 58.0, 62.0, 61.0, 139.0, 52.0], "value": 79.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 73.0, 129.0, 70.0, 72.0, 88.0, 114.0, 70.0, 74.0, 119.0], "value": 87.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 90.0, 131.0, 89.0, 93.0, 89.0, 76.0, 76.0, 76.0, 112.0], "value": 92.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 128.0, 152.0, 133.0, 108.0, 153.0, 105.0, 96.0, 153.0, 123.0], "value": 125.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 134.0, 100.0, 75.0, 136.0, 94.0, 145.0, 151.0, 94.0, 90.0], "value": 116.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 58.0, 57.0, 56.0, 58.0, 58.0, 59.0, 58.0, 58.0, 58.0], "value": 57.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 76.0, 72.0, 70.0, 74.0, 76.0, 78.0, 84.0, 69.0, 71.0], "value": 75.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 88.0, 85.0, 88.0, 88.0, 89.0, 88.0, 132.0, 86.0], "value": 91.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 152.0, 140.0, 146.0, 144.0, 159.0, 143.0, 153.0, 165.0, 161.0], "value": 149.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 164.0, 170.0, 146.0, 215.0, 167.0, 171.0, 168.0, 165.0, 162.0], "value": 169.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 71.0, 77.0, 86.0, 87.0, 82.0, 68.0, 70.0, 62.0, 70.0], "value": 73.9, "unit": "score"}]}]} 12:08:37 INFO - Return code: 0 12:08:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 12:08:37 ERROR - # TBPL SUCCESS # 12:08:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:08:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:08:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:08:37 INFO - 2016-06-02 12:08:37.684 screenresolution[1695:12532] starting screenresolution argv=screenresolution get 12:08:37 INFO - 2016-06-02 12:08:37.687 screenresolution[1695:12532] Display 0: 1600x1200x32@60 12:08:37 INFO - 2016-06-02 12:08:37.692 screenresolution[1696:12535] starting screenresolution argv=screenresolution list 12:08:37 INFO - Available Modes on Display 0 12:08:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:08:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:08:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:08:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:08:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:08:37 INFO - Intel Iris: 12:08:37 INFO - Chipset Model: Intel Iris 12:08:37 INFO - Type: GPU 12:08:37 INFO - Bus: Built-In 12:08:37 INFO - VRAM (Dynamic, Max): 1536 MB 12:08:37 INFO - Vendor: Intel (0x8086) 12:08:37 INFO - Device ID: 0x0a2e 12:08:37 INFO - Revision ID: 0x0009 12:08:37 INFO - Displays: 12:08:37 INFO - Display: 12:08:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:08:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:08:37 INFO - Display Serial Number: 1600x1200 60 12:08:37 INFO - Main Display: Yes 12:08:37 INFO - Mirror: Off 12:08:37 INFO - Online: Yes 12:08:37 INFO - Rotation: Supported 12:08:37 INFO - Return code: 0 12:08:37 INFO - Running post-action listener: _resource_record_post_action 12:08:37 INFO - ##### 12:08:37 INFO - ##### Finished run-tests step (success) 12:08:37 INFO - ##### 12:08:37 INFO - Running post-run listener: _resource_record_post_run 12:08:38 INFO - Total resource usage - Wall time: 1210s; CPU: 55.0%; Read bytes: 31510016; Write bytes: 701832704; Read time: 196; Write time: 1942 12:08:38 INFO - TinderboxPrint: CPU usage
54.8% 12:08:38 INFO - TinderboxPrint: I/O read bytes / time
31,510,016 / 196 12:08:38 INFO - TinderboxPrint: I/O write bytes / time
701,832,704 / 1,942 12:08:38 INFO - TinderboxPrint: CPU idle
2,189.1 (45.2%) 12:08:38 INFO - TinderboxPrint: CPU system
979.8 (20.2%) 12:08:38 INFO - TinderboxPrint: CPU user
1,672.8 (34.6%) 12:08:38 INFO - TinderboxPrint: Swap in / out
427,008,000 / 0 12:08:38 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 378741248; Write bytes: 373687808; Read time: 29077; Write time: 752 12:08:38 INFO - run-tests - Wall time: 1176s; CPU: 55.0%; Read bytes: 30555648; Write bytes: 298797056; Read time: 183; Write time: 1122 12:08:38 INFO - Running post-run listener: _upload_blobber_files 12:08:38 INFO - Blob upload gear active. 12:08:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:08:38 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 12:08:38 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:08:38 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:08:38 INFO - (blobuploader) - INFO - Open directory for files ... 12:08:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:08:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:08:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:08:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:08:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:08:40 INFO - (blobuploader) - INFO - Done attempting. 12:08:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:08:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:08:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:08:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:08:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:08:41 INFO - (blobuploader) - INFO - Done attempting. 12:08:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:08:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:08:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:08:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:08:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:08:44 INFO - (blobuploader) - INFO - Done attempting. 12:08:44 INFO - (blobuploader) - INFO - Iteration through files over. 12:08:44 INFO - Return code: 0 12:08:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:08:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:08:44 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/82d228efa4e27ed175bdf5627c84a0932c434f1627c4d76bb7e3af58d5feb063db04cffff8b9c89b8a8383fd5d191a0d2120586a199d6cb51e9417d0982d4649", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e2132eafafd78636bb6ebfbaa7cc9f8c9b452be61c708c6b7c1f725667baed7e94d53ad7cbe088a907a334c2d20201c8f014978f1afe69ffc74027dc09a8faf", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7fac9a4dee7958d218371a32add34ecd42320ddc7842b1bc0bff82df4ad1b91945893d0213fb2ba9173a2ff0a97ce9776d52b8042740b0fd46d9d5a0949f7107"} 12:08:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:08:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:08:44 INFO - Contents: 12:08:44 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/82d228efa4e27ed175bdf5627c84a0932c434f1627c4d76bb7e3af58d5feb063db04cffff8b9c89b8a8383fd5d191a0d2120586a199d6cb51e9417d0982d4649", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e2132eafafd78636bb6ebfbaa7cc9f8c9b452be61c708c6b7c1f725667baed7e94d53ad7cbe088a907a334c2d20201c8f014978f1afe69ffc74027dc09a8faf", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7fac9a4dee7958d218371a32add34ecd42320ddc7842b1bc0bff82df4ad1b91945893d0213fb2ba9173a2ff0a97ce9776d52b8042740b0fd46d9d5a0949f7107"} 12:08:44 INFO - Running post-run listener: copy_logs_to_upload_dir 12:08:44 INFO - Copying logs to upload dir... 12:08:44 INFO - mkdir: /builds/slave/test/build/upload/logs 12:08:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.191112 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-06-02 12:08:44.409313) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-02 12:08:44.413267) ========= 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.nozkZArR78/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.UozXDb2UW6/Listeners TMPDIR=/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/82d228efa4e27ed175bdf5627c84a0932c434f1627c4d76bb7e3af58d5feb063db04cffff8b9c89b8a8383fd5d191a0d2120586a199d6cb51e9417d0982d4649", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e2132eafafd78636bb6ebfbaa7cc9f8c9b452be61c708c6b7c1f725667baed7e94d53ad7cbe088a907a334c2d20201c8f014978f1afe69ffc74027dc09a8faf", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7fac9a4dee7958d218371a32add34ecd42320ddc7842b1bc0bff82df4ad1b91945893d0213fb2ba9173a2ff0a97ce9776d52b8042740b0fd46d9d5a0949f7107"} build_url:https://queue.taskcluster.net/v1/task/NN_VMbPcR6K72Fpfp_HCKg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011056 build_url: 'https://queue.taskcluster.net/v1/task/NN_VMbPcR6K72Fpfp_HCKg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/82d228efa4e27ed175bdf5627c84a0932c434f1627c4d76bb7e3af58d5feb063db04cffff8b9c89b8a8383fd5d191a0d2120586a199d6cb51e9417d0982d4649", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e2132eafafd78636bb6ebfbaa7cc9f8c9b452be61c708c6b7c1f725667baed7e94d53ad7cbe088a907a334c2d20201c8f014978f1afe69ffc74027dc09a8faf", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7fac9a4dee7958d218371a32add34ecd42320ddc7842b1bc0bff82df4ad1b91945893d0213fb2ba9173a2ff0a97ce9776d52b8042740b0fd46d9d5a0949f7107"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-02 12:08:44.450668) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 12:08:44.451101) ========= 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.nozkZArR78/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.UozXDb2UW6/Listeners TMPDIR=/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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.005024 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 12:08:44.507304) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-02 12:08:44.507679) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-02 12:08:44.508076) ========= ========= Total master_lag: 0.21 =========