builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0167 starttime: 1462941987.86 results: success (0) buildid: 20160510194316 builduid: acc7ff442c5a4c99ad2347ebff9a1829 revision: b8f01297e8fca2f828b996a8ddefc2009e4f35cf ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-10 21:46:27.863437) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-10 21:46:27.863900) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-10 21:46:27.864293) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-10 21:46:27.883392) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 21:46:27.883717) ========= 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.ZiE1QBBzh9/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.iLALYGnLw2/Listeners TMPDIR=/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-10 21:46:27-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 13.7M=0.001s 2016-05-10 21:46:28 (13.7 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.138769 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 21:46:28.039774) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 21:46:28.040102) ========= 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.ZiE1QBBzh9/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.iLALYGnLw2/Listeners TMPDIR=/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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.026445 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 21:46:28.088901) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-10 21:46:28.089265) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev b8f01297e8fca2f828b996a8ddefc2009e4f35cf --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev b8f01297e8fca2f828b996a8ddefc2009e4f35cf --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ZiE1QBBzh9/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.iLALYGnLw2/Listeners TMPDIR=/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-10 21:46:28,164 truncating revision to first 12 chars 2016-05-10 21:46:28,164 Setting DEBUG logging. 2016-05-10 21:46:28,164 attempt 1/10 2016-05-10 21:46:28,164 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/b8f01297e8fc?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-10 21:46:29,819 unpacking tar archive at: fx-team-b8f01297e8fc/testing/mozharness/ program finished with exit code 0 elapsedTime=2.079903 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-10 21:46:30.185834) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-10 21:46:30.186197) ========= script_repo_revision: b8f01297e8fca2f828b996a8ddefc2009e4f35cf ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-10 21:46:30.186605) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-10 21:46:30.186881) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-10 21:46:30.199732) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-05-10 21:46:30.200000) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ZiE1QBBzh9/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.iLALYGnLw2/Listeners TMPDIR=/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 21:46:30 INFO - MultiFileLogger online at 20160510 21:46:30 in /builds/slave/test 21:46:30 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 21:46:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:46:30 INFO - {'append_to_log': False, 21:46:30 INFO - 'base_work_dir': '/builds/slave/test', 21:46:30 INFO - 'blob_upload_branch': 'Fx-Team', 21:46:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:46:30 INFO - 'branch': 'Fx-Team', 21:46:30 INFO - 'buildbot_json_path': 'buildprops.json', 21:46:30 INFO - 'config_files': ('talos/mac_config.py',), 21:46:30 INFO - 'default_actions': ('clobber', 21:46:30 INFO - 'read-buildbot-config', 21:46:30 INFO - 'download-and-extract', 21:46:30 INFO - 'populate-webroot', 21:46:30 INFO - 'create-virtualenv', 21:46:30 INFO - 'install', 21:46:30 INFO - 'run-tests'), 21:46:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:46:30 INFO - 'download_minidump_stackwalk': True, 21:46:30 INFO - 'download_symbols': 'ondemand', 21:46:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:46:30 INFO - 'tooltool.py': '/tools/tooltool.py', 21:46:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:46:30 INFO - '/tools/misc-python/virtualenv.py')}, 21:46:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:46:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:46:30 INFO - 'installer_path': 'installer.exe', 21:46:30 INFO - 'log_level': 'info', 21:46:30 INFO - 'log_name': 'talos', 21:46:30 INFO - 'log_to_console': True, 21:46:30 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 21:46:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 21:46:30 INFO - 'opt_config_files': (), 21:46:30 INFO - 'pip_index': False, 21:46:30 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:46:30 INFO - 'cmd': ('bash', 21:46:30 INFO - '-c', 21:46:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:46:30 INFO - 'enabled': True, 21:46:30 INFO - 'halt_on_failure': False, 21:46:30 INFO - 'name': 'check_screen_resolution'},), 21:46:30 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:46:30 INFO - 'cmd': ('bash', 21:46:30 INFO - '-c', 21:46:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:46:30 INFO - 'enabled': True, 21:46:30 INFO - 'halt_on_failure': False, 21:46:30 INFO - 'name': 'check_screen_resolution'},), 21:46:30 INFO - 'run_cmd_checks_enabled': True, 21:46:30 INFO - 'sps_profile': False, 21:46:30 INFO - 'sps_profile_interval': 0, 21:46:30 INFO - 'suite': 'dromaeojs-e10s', 21:46:30 INFO - 'system_bits': '32', 21:46:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:46:30 INFO - 'title': 't-yosemite-r7-0167', 21:46:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:46:30 INFO - 'use_talos_json': True, 21:46:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:46:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:46:30 INFO - 'work_dir': 'build'} 21:46:30 INFO - ##### 21:46:30 INFO - ##### Running clobber step. 21:46:30 INFO - ##### 21:46:30 INFO - Running pre-action listener: _resource_record_pre_action 21:46:30 INFO - Running main action method: clobber 21:46:30 INFO - rmtree: /builds/slave/test/build 21:46:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:46:32 INFO - Running post-action listener: _resource_record_post_action 21:46:32 INFO - ##### 21:46:32 INFO - ##### Finished clobber step (success) 21:46:32 INFO - ##### 21:46:32 INFO - ##### 21:46:32 INFO - ##### Running read-buildbot-config step. 21:46:32 INFO - ##### 21:46:32 INFO - Running pre-action listener: _resource_record_pre_action 21:46:32 INFO - Running main action method: read_buildbot_config 21:46:32 INFO - Using buildbot properties: 21:46:32 INFO - { 21:46:32 INFO - "project": "", 21:46:32 INFO - "product": "firefox", 21:46:32 INFO - "script_repo_revision": "production", 21:46:32 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 21:46:32 INFO - "repository": "", 21:46:32 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 21:46:32 INFO - "buildid": "20160510194316", 21:46:32 INFO - "pgo_build": "False", 21:46:32 INFO - "basedir": "/builds/slave/test", 21:46:32 INFO - "buildnumber": 4, 21:46:32 INFO - "slavename": "t-yosemite-r7-0167", 21:46:32 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 21:46:32 INFO - "platform": "yosemite_r7", 21:46:32 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 21:46:32 INFO - "branch": "fx-team", 21:46:32 INFO - "repo_path": "integration/fx-team", 21:46:32 INFO - "revision": "b8f01297e8fca2f828b996a8ddefc2009e4f35cf", 21:46:32 INFO - "stage_platform": "macosx64", 21:46:32 INFO - "builduid": "acc7ff442c5a4c99ad2347ebff9a1829", 21:46:32 INFO - "slavebuilddir": "test" 21:46:32 INFO - } 21:46:32 INFO - Found installer url https://queue.taskcluster.net/v1/task/fmuKbuH2SMy0uOQUcJEXNw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 21:46:32 INFO - Running post-action listener: _resource_record_post_action 21:46:32 INFO - ##### 21:46:32 INFO - ##### Finished read-buildbot-config step (success) 21:46:32 INFO - ##### 21:46:32 INFO - ##### 21:46:32 INFO - ##### Running download-and-extract step. 21:46:32 INFO - ##### 21:46:32 INFO - Running pre-action listener: _resource_record_pre_action 21:46:32 INFO - Running main action method: download_and_extract 21:46:32 INFO - mkdir: /builds/slave/test/build/tests 21:46: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')]} 21:46:32 INFO - https://queue.taskcluster.net/v1/task/fmuKbuH2SMy0uOQUcJEXNw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 21:46:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fmuKbuH2SMy0uOQUcJEXNw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 21:46:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fmuKbuH2SMy0uOQUcJEXNw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 21:46:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fmuKbuH2SMy0uOQUcJEXNw/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 21:46:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fmuKbuH2SMy0uOQUcJEXNw/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 21:46:34 INFO - Downloaded 1199 bytes. 21:46:34 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 21:46:34 INFO - Using the following test package requirements: 21:46:34 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 21:46:34 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 21:46:34 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 21:46:34 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 21:46:34 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 21:46:34 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 21:46:34 INFO - u'jsshell-mac.zip'], 21:46:34 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 21:46:34 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 21:46:34 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 21:46:34 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 21:46:34 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 21:46:34 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 21:46:34 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 21:46:34 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 21:46:34 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 21:46:34 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 21:46:34 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 21:46:34 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 21:46:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:46:34 INFO - https://queue.taskcluster.net/v1/task/fmuKbuH2SMy0uOQUcJEXNw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 21:46:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fmuKbuH2SMy0uOQUcJEXNw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 21:46:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fmuKbuH2SMy0uOQUcJEXNw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 21:46:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fmuKbuH2SMy0uOQUcJEXNw/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 21:46:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fmuKbuH2SMy0uOQUcJEXNw/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 21:46:36 INFO - Downloaded 18150604 bytes. 21:46:36 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'] 21:46:36 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 21:46:39 INFO - Return code: 0 21:46:39 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 21:46:39 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')]} 21:46:39 INFO - https://queue.taskcluster.net/v1/task/fmuKbuH2SMy0uOQUcJEXNw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 21:46:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fmuKbuH2SMy0uOQUcJEXNw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 21:46:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fmuKbuH2SMy0uOQUcJEXNw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 21:46:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fmuKbuH2SMy0uOQUcJEXNw/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 21:46:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fmuKbuH2SMy0uOQUcJEXNw/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 21:46:40 INFO - Downloaded 18150604 bytes. 21:46:40 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'] 21:46:40 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 21:46:43 INFO - Return code: 0 21:46:43 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')]} 21:46:43 INFO - https://queue.taskcluster.net/v1/task/fmuKbuH2SMy0uOQUcJEXNw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 21:46:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fmuKbuH2SMy0uOQUcJEXNw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 21:46:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fmuKbuH2SMy0uOQUcJEXNw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 21:46:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fmuKbuH2SMy0uOQUcJEXNw/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 21:46:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fmuKbuH2SMy0uOQUcJEXNw/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 21:46:45 INFO - Downloaded 10840152 bytes. 21:46:45 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'] 21:46:45 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 21:46:45 INFO - Return code: 0 21:46:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:46:45 INFO - https://queue.taskcluster.net/v1/task/fmuKbuH2SMy0uOQUcJEXNw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 21:46:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fmuKbuH2SMy0uOQUcJEXNw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 21:46:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fmuKbuH2SMy0uOQUcJEXNw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 21:46:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fmuKbuH2SMy0uOQUcJEXNw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 21:46:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fmuKbuH2SMy0uOQUcJEXNw/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 21:46:48 INFO - Downloaded 109846907 bytes. 21:46:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fmuKbuH2SMy0uOQUcJEXNw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 21:46:48 INFO - mkdir: /builds/slave/test/properties 21:46:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:46:48 INFO - Writing to file /builds/slave/test/properties/build_url 21:46:48 INFO - Contents: 21:46:48 INFO - build_url:https://queue.taskcluster.net/v1/task/fmuKbuH2SMy0uOQUcJEXNw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 21:46:49 INFO - Running post-action listener: _resource_record_post_action 21:46:49 INFO - Running post-action listener: set_extra_try_arguments 21:46:49 INFO - ##### 21:46:49 INFO - ##### Finished download-and-extract step (success) 21:46:49 INFO - ##### 21:46:49 INFO - ##### 21:46:49 INFO - ##### Running populate-webroot step. 21:46:49 INFO - ##### 21:46:49 INFO - Running pre-action listener: _resource_record_pre_action 21:46:49 INFO - Running main action method: populate_webroot 21:46:49 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:46:49 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:46:49 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:46:49 INFO - '--tppagecycles', 21:46:49 INFO - '7'], 21:46:49 INFO - 'tests': ['tsvgm']}}, 21:46:49 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:46:49 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:46:49 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:46:49 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:46:49 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:46:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:46:49 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:46:49 INFO - 'talos_options': ['--disable-e10s'], 21:46:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:46:49 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:46:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:46:49 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:46:49 INFO - 'talos_options': ['--disable-e10s'], 21:46:49 INFO - 'tests': ['damp', 'tps']}, 21:46:49 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:46:49 INFO - 'tests': ['damp', 'tps']}, 21:46:49 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:46:49 INFO - 'tests': ['dromaeo_dom']}, 21:46:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:46:49 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:46:49 INFO - 'tests': ['a11yr', 21:46:49 INFO - 'ts_paint', 21:46:49 INFO - 'tpaint', 21:46:49 INFO - 'sessionrestore', 21:46:49 INFO - 'sessionrestore_no_auto_restore', 21:46:49 INFO - 'tabpaint']}, 21:46:49 INFO - 'other-e10s': {'tests': ['a11yr', 21:46:49 INFO - 'ts_paint', 21:46:49 INFO - 'tpaint', 21:46:49 INFO - 'sessionrestore', 21:46:49 INFO - 'sessionrestore_no_auto_restore', 21:46:49 INFO - 'tabpaint']}, 21:46:49 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:46:49 INFO - 'ts_paint', 21:46:49 INFO - 'tpaint', 21:46:49 INFO - 'sessionrestore', 21:46:49 INFO - 'sessionrestore_no_auto_restore', 21:46:49 INFO - 'tabpaint']}, 21:46:49 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:46:49 INFO - 'ts_paint', 21:46:49 INFO - 'tpaint', 21:46:49 INFO - 'sessionrestore', 21:46:49 INFO - 'sessionrestore_no_auto_restore', 21:46:49 INFO - 'tabpaint']}, 21:46:49 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:46:49 INFO - 'tests': ['a11yr', 21:46:49 INFO - 'ts_paint', 21:46:49 INFO - 'tpaint', 21:46:49 INFO - 'sessionrestore', 21:46:49 INFO - 'sessionrestore_no_auto_restore', 21:46:49 INFO - 'tabpaint']}, 21:46:49 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:46:49 INFO - 'tests': ['a11yr', 21:46:49 INFO - 'ts_paint', 21:46:49 INFO - 'tpaint', 21:46:49 INFO - 'sessionrestore', 21:46:49 INFO - 'sessionrestore_no_auto_restore', 21:46:49 INFO - 'tabpaint']}, 21:46:49 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:46:49 INFO - 'tests': ['tsvgx', 21:46:49 INFO - 'tsvgr_opacity', 21:46:49 INFO - 'tart', 21:46:49 INFO - 'tscrollx', 21:46:49 INFO - 'cart']}, 21:46:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:46:49 INFO - 'tsvgr_opacity', 21:46:49 INFO - 'tart', 21:46:49 INFO - 'tscrollx', 21:46:49 INFO - 'cart']}, 21:46:49 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:46:49 INFO - 'talos_options': ['--disable-e10s'], 21:46:49 INFO - 'tests': ['tp5o']}, 21:46:49 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:46:49 INFO - 'tests': ['tp5o']}, 21:46:49 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:46:49 INFO - 'talos_options': ['--disable-e10s', 21:46:49 INFO - '--xperf_path', 21:46:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:46:49 INFO - 'tests': ['tp5n']}, 21:46:49 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:46:49 INFO - 'talos_options': ['--xperf_path', 21:46:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:46:49 INFO - 'tests': ['tp5n']}}, 21:46:49 INFO - 'talos.zip': {'path': '', 21:46:49 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:46:49 INFO - Running post-action listener: _resource_record_post_action 21:46:49 INFO - ##### 21:46:49 INFO - ##### Finished populate-webroot step (success) 21:46:49 INFO - ##### 21:46:49 INFO - ##### 21:46:49 INFO - ##### Running create-virtualenv step. 21:46:49 INFO - ##### 21:46:49 INFO - Running pre-action listener: _resource_record_pre_action 21:46:49 INFO - Running main action method: create_virtualenv 21:46:49 INFO - Creating virtualenv /builds/slave/test/build/venv 21:46:49 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 21:46:49 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:46:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:46:49 INFO - Using real prefix '/tools/python27' 21:46:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:46:50 INFO - Installing distribute.............................................................................................................................................................................................done. 21:46:53 INFO - Installing pip.................done. 21:46:53 INFO - Return code: 0 21:46:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:46:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:46:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:46:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:46:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:46:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:46:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:46:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10347ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103484570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8429cb1220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103322d30>, '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 0x103146db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103319b20>, '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.ZiE1QBBzh9/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.iLALYGnLw2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 21:46:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 21:46:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 21:46:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZiE1QBBzh9/Render', 21:46:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:46:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:46:53 INFO - 'HOME': '/Users/cltbld', 21:46:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:46:53 INFO - 'LOGNAME': 'cltbld', 21:46:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:46:53 INFO - 'MOZ_NO_REMOTE': '1', 21:46:53 INFO - 'NO_EM_RESTART': '1', 21:46:53 INFO - 'PAGER': '/bin/cat', 21:46:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:46:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:46:53 INFO - 'PWD': '/builds/slave/test', 21:46:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:46:53 INFO - 'SHELL': '/bin/bash', 21:46:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iLALYGnLw2/Listeners', 21:46:53 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 21:46:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:46:53 INFO - 'USER': 'cltbld', 21:46:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:46:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:46:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:46:53 INFO - 'XPC_FLAGS': '0x0', 21:46:53 INFO - 'XPC_SERVICE_NAME': '0', 21:46:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:46:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:46:53 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 21:46:53 INFO - Cleaning up... 21:46:53 INFO - Return code: 0 21:46:53 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 21:46:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:46:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:46:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:46:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:46:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:46:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:46:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10347ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103484570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8429cb1220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103322d30>, '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 0x103146db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103319b20>, '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.ZiE1QBBzh9/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.iLALYGnLw2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 21:46:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 21:46:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 21:46:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZiE1QBBzh9/Render', 21:46:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:46:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:46:53 INFO - 'HOME': '/Users/cltbld', 21:46:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:46:53 INFO - 'LOGNAME': 'cltbld', 21:46:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:46:53 INFO - 'MOZ_NO_REMOTE': '1', 21:46:53 INFO - 'NO_EM_RESTART': '1', 21:46:53 INFO - 'PAGER': '/bin/cat', 21:46:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:46:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:46:53 INFO - 'PWD': '/builds/slave/test', 21:46:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:46:53 INFO - 'SHELL': '/bin/bash', 21:46:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iLALYGnLw2/Listeners', 21:46:53 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 21:46:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:46:53 INFO - 'USER': 'cltbld', 21:46:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:46:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:46:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:46:53 INFO - 'XPC_FLAGS': '0x0', 21:46:53 INFO - 'XPC_SERVICE_NAME': '0', 21:46:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:46:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:46:54 INFO - Downloading/unpacking psutil>=0.7.1 21:46:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:46:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:46:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:46:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:46:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:46:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:46:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:46:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:46:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:46:56 INFO - Installing collected packages: psutil 21:46:56 INFO - Running setup.py install for psutil 21:46:56 INFO - building 'psutil._psutil_osx' extension 21:46:56 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 21:46:57 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 21:46:57 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 21:46:57 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 21:46:57 INFO - building 'psutil._psutil_posix' extension 21:46:57 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 21:46:57 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 21:46:57 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 21:46:57 INFO - ^ 21:46:57 INFO - 1 warning generated. 21:46:57 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 21:46:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:46:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:46:58 INFO - Successfully installed psutil 21:46:58 INFO - Cleaning up... 21:46:58 INFO - Return code: 0 21:46:58 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 21:46:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:46:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:46:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:46:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:46:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:46:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:46:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10347ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103484570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8429cb1220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103322d30>, '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 0x103146db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103319b20>, '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.ZiE1QBBzh9/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.iLALYGnLw2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 21:46:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 21:46:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 21:46:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZiE1QBBzh9/Render', 21:46:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:46:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:46:58 INFO - 'HOME': '/Users/cltbld', 21:46:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:46:58 INFO - 'LOGNAME': 'cltbld', 21:46:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:46:58 INFO - 'MOZ_NO_REMOTE': '1', 21:46:58 INFO - 'NO_EM_RESTART': '1', 21:46:58 INFO - 'PAGER': '/bin/cat', 21:46:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:46:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:46:58 INFO - 'PWD': '/builds/slave/test', 21:46:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:46:58 INFO - 'SHELL': '/bin/bash', 21:46:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iLALYGnLw2/Listeners', 21:46:58 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 21:46:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:46:58 INFO - 'USER': 'cltbld', 21:46:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:46:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:46:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:46:58 INFO - 'XPC_FLAGS': '0x0', 21:46:58 INFO - 'XPC_SERVICE_NAME': '0', 21:46:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:46:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:46:58 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 21:46:58 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 21:46:58 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 21:46:58 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 21:46:58 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 21:46:58 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 21:46:58 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 21:47:00 INFO - Downloading mozsystemmonitor-0.0.tar.gz 21:47:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:47:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 21:47:00 INFO - Installing collected packages: mozsystemmonitor 21:47:00 INFO - Running setup.py install for mozsystemmonitor 21:47:01 INFO - Successfully installed mozsystemmonitor 21:47:01 INFO - Cleaning up... 21:47:01 INFO - Return code: 0 21:47:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:47: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')]} 21:47:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:47:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47: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')]} 21:47:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:47:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47:01 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 0x10347ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103484570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8429cb1220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103322d30>, '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 0x103146db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103319b20>, '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.ZiE1QBBzh9/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.iLALYGnLw2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 21:47:01 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 21:47:01 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 21:47:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZiE1QBBzh9/Render', 21:47:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:47:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:47:01 INFO - 'HOME': '/Users/cltbld', 21:47:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:47:01 INFO - 'LOGNAME': 'cltbld', 21:47:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:47:01 INFO - 'MOZ_NO_REMOTE': '1', 21:47:01 INFO - 'NO_EM_RESTART': '1', 21:47:01 INFO - 'PAGER': '/bin/cat', 21:47:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:47:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:47:01 INFO - 'PWD': '/builds/slave/test', 21:47:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:47:01 INFO - 'SHELL': '/bin/bash', 21:47:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iLALYGnLw2/Listeners', 21:47:01 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 21:47:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:47:01 INFO - 'USER': 'cltbld', 21:47:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:47:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:47:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:47:01 INFO - 'XPC_FLAGS': '0x0', 21:47:01 INFO - 'XPC_SERVICE_NAME': '0', 21:47:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:47:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:47:01 INFO - Downloading/unpacking blobuploader==1.2.4 21:47: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 21:47: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 21:47: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 21:47: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 21:47: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 21:47: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 21:47:03 INFO - Downloading blobuploader-1.2.4.tar.gz 21:47:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:47:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:47: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 21:47: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 21:47: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 21:47: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 21:47: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 21:47: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 21:47:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:47:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:47: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 21:47: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 21:47: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 21:47: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 21:47: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 21:47: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 21:47:05 INFO - Downloading docopt-0.6.1.tar.gz 21:47:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:47:05 INFO - Installing collected packages: blobuploader, requests, docopt 21:47:05 INFO - Running setup.py install for blobuploader 21:47:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:47:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:47:05 INFO - Running setup.py install for requests 21:47:05 INFO - Running setup.py install for docopt 21:47:05 INFO - Successfully installed blobuploader requests docopt 21:47:05 INFO - Cleaning up... 21:47:06 INFO - Return code: 0 21:47:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:47:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:47:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:47:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:47:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:47:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47:06 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 0x10347ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103484570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8429cb1220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103322d30>, '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 0x103146db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103319b20>, '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.ZiE1QBBzh9/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.iLALYGnLw2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 21:47:06 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 21:47:06 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 21:47:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZiE1QBBzh9/Render', 21:47:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:47:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:47:06 INFO - 'HOME': '/Users/cltbld', 21:47:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:47:06 INFO - 'LOGNAME': 'cltbld', 21:47:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:47:06 INFO - 'MOZ_NO_REMOTE': '1', 21:47:06 INFO - 'NO_EM_RESTART': '1', 21:47:06 INFO - 'PAGER': '/bin/cat', 21:47:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:47:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:47:06 INFO - 'PWD': '/builds/slave/test', 21:47:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:47:06 INFO - 'SHELL': '/bin/bash', 21:47:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iLALYGnLw2/Listeners', 21:47:06 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 21:47:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:47:06 INFO - 'USER': 'cltbld', 21:47:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:47:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:47:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:47:06 INFO - 'XPC_FLAGS': '0x0', 21:47:06 INFO - 'XPC_SERVICE_NAME': '0', 21:47:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:47:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:47:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:47:06 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-WpZ9a8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:47:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:47:06 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-DoTB37-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:47:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:47:06 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-zIyd7i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:47:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:47:06 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-dtM0O_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:47:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:47:06 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-mcmqFo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:47:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:47:06 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-T5PK7G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:47:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:47:06 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-yBaBex-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:47:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:47:07 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-G3I97S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:47:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:47:07 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-a0Mvvw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:47:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:47:07 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-bWRAMQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:47:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:47:07 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-zCLJoB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:47:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:47:07 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-QT90wB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:47:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:47:07 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-6CsGFu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:47:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:47:07 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-N75dke-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:47:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:47:07 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-CNQEsX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:47:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:47:07 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-C3_Xuv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:47:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:47:07 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-cEsJ2F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:47:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:47:08 INFO - Running setup.py install for manifestparser 21:47:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:47:08 INFO - Running setup.py install for mozcrash 21:47:08 INFO - Running setup.py install for mozdebug 21:47:08 INFO - Running setup.py install for mozdevice 21:47:08 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:47:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:47:08 INFO - Running setup.py install for mozfile 21:47:08 INFO - Running setup.py install for mozhttpd 21:47:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:47:08 INFO - Running setup.py install for mozinfo 21:47:09 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:47:09 INFO - Running setup.py install for mozInstall 21:47:09 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:47:09 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:47:09 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:47:09 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:47:09 INFO - Running setup.py install for mozleak 21:47:09 INFO - Running setup.py install for mozlog 21:47:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:47:09 INFO - Running setup.py install for moznetwork 21:47:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:47:09 INFO - Running setup.py install for mozprocess 21:47:09 INFO - Running setup.py install for mozprofile 21:47:10 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:47:10 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:47:10 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:47:10 INFO - Running setup.py install for mozrunner 21:47:10 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:47:10 INFO - Running setup.py install for mozscreenshot 21:47:10 INFO - Running setup.py install for moztest 21:47:10 INFO - Running setup.py install for mozversion 21:47:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:47:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:47:10 INFO - Cleaning up... 21:47:10 INFO - Return code: 0 21:47:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:47:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:47:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:47:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:47:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:47:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47:10 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 0x10347ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103484570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8429cb1220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103322d30>, '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 0x103146db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103319b20>, '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.ZiE1QBBzh9/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.iLALYGnLw2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 21:47:10 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 21:47:10 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 21:47:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZiE1QBBzh9/Render', 21:47:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:47:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:47:10 INFO - 'HOME': '/Users/cltbld', 21:47:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:47:10 INFO - 'LOGNAME': 'cltbld', 21:47:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:47:10 INFO - 'MOZ_NO_REMOTE': '1', 21:47:10 INFO - 'NO_EM_RESTART': '1', 21:47:10 INFO - 'PAGER': '/bin/cat', 21:47:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:47:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:47:10 INFO - 'PWD': '/builds/slave/test', 21:47:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:47:10 INFO - 'SHELL': '/bin/bash', 21:47:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iLALYGnLw2/Listeners', 21:47:10 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 21:47:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:47:10 INFO - 'USER': 'cltbld', 21:47:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:47:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:47:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:47:10 INFO - 'XPC_FLAGS': '0x0', 21:47:10 INFO - 'XPC_SERVICE_NAME': '0', 21:47:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:47:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:47:10 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-zYgTWf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:47:11 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)) 21:47:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:47:11 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-9de5Bb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:47:11 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)) 21:47:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:47:11 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-hAxw0I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:47:11 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)) 21:47:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:47:11 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-C59U7z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:47:11 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)) 21:47:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:47:11 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-poA3sa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:47:11 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)) 21:47:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:47:11 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-AmyGGa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:47:11 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)) 21:47:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:47:11 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-YRRd5U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:47:11 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)) 21:47:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:47:11 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-UTiXLM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:47:11 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)) 21:47:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:47:11 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-t1jgny-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:47:11 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)) 21:47:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:47:11 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-Wk3Sms-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:47:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:47:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:47:11 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-WTtB53-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:47:12 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)) 21:47:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:47:12 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-4_4eWG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:47:12 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)) 21:47:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:47:12 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-sYecaJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:47:12 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)) 21:47:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:47:12 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-UFkUbo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:47:12 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)) 21:47:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:47:12 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-0rbIb3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:47:12 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)) 21:47:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:47:12 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-j36SG8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:47:12 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)) 21:47:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:47:12 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-dwMoII-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:47:12 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)) 21:47:12 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)) 21:47:12 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)) 21:47:12 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)) 21:47:12 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)) 21:47:12 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)) 21:47:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:47:12 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 21:47:12 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 21:47:12 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 21:47:12 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 21:47:12 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 21:47:12 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 21:47:15 INFO - Downloading blessings-1.6.tar.gz 21:47:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:47:15 INFO - Installing collected packages: blessings 21:47:15 INFO - Running setup.py install for blessings 21:47:15 INFO - Successfully installed blessings 21:47:15 INFO - Cleaning up... 21:47:15 INFO - Return code: 0 21:47:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:47:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:47:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:47:15 INFO - Reading from file tmpfile_stdout 21:47:15 INFO - Current package versions: 21:47:15 INFO - blessings == 1.6 21:47:15 INFO - blobuploader == 1.2.4 21:47:15 INFO - docopt == 0.6.1 21:47:15 INFO - manifestparser == 1.1 21:47:15 INFO - mozInstall == 1.12 21:47:15 INFO - mozcrash == 0.17 21:47:15 INFO - mozdebug == 0.1 21:47:15 INFO - mozdevice == 0.48 21:47:15 INFO - mozfile == 1.2 21:47:15 INFO - mozhttpd == 0.7 21:47:15 INFO - mozinfo == 0.9 21:47:15 INFO - mozleak == 0.1 21:47:15 INFO - mozlog == 3.1 21:47:15 INFO - moznetwork == 0.27 21:47:15 INFO - mozprocess == 0.22 21:47:15 INFO - mozprofile == 0.28 21:47:15 INFO - mozrunner == 6.11 21:47:15 INFO - mozscreenshot == 0.1 21:47:15 INFO - mozsystemmonitor == 0.0 21:47:15 INFO - moztest == 0.7 21:47:15 INFO - mozversion == 1.4 21:47:15 INFO - psutil == 3.1.1 21:47:15 INFO - requests == 1.2.3 21:47:15 INFO - wsgiref == 0.1.2 21:47:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:47:15 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')]} 21:47:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:47:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47:15 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')]} 21:47:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:47:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47:15 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 0x10347ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103484570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8429cb1220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103322d30>, '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 0x103146db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103319b20>, '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.ZiE1QBBzh9/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.iLALYGnLw2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 21:47:15 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 21:47:15 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 21:47:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZiE1QBBzh9/Render', 21:47:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:47:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:47:15 INFO - 'HOME': '/Users/cltbld', 21:47:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:47:15 INFO - 'LOGNAME': 'cltbld', 21:47:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:47:15 INFO - 'MOZ_NO_REMOTE': '1', 21:47:15 INFO - 'NO_EM_RESTART': '1', 21:47:15 INFO - 'PAGER': '/bin/cat', 21:47:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:47:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:47:15 INFO - 'PWD': '/builds/slave/test', 21:47:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:47:15 INFO - 'SHELL': '/bin/bash', 21:47:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iLALYGnLw2/Listeners', 21:47:15 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 21:47:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:47:15 INFO - 'USER': 'cltbld', 21:47:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:47:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:47:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:47:15 INFO - 'XPC_FLAGS': '0x0', 21:47:15 INFO - 'XPC_SERVICE_NAME': '0', 21:47:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:47:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:47:15 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)) 21:47:15 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)) 21:47:15 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)) 21:47:15 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)) 21:47:15 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)) 21:47:15 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)) 21:47:15 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)) 21:47:15 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)) 21:47:15 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)) 21:47:15 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)) 21:47:15 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)) 21:47:15 INFO - Cleaning up... 21:47:15 INFO - Return code: 0 21:47:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:47:15 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')]} 21:47:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:47:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47:15 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')]} 21:47:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:47:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47:15 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 0x10347ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103484570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8429cb1220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103322d30>, '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 0x103146db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103319b20>, '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.ZiE1QBBzh9/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.iLALYGnLw2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 21:47:15 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 21:47:15 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 21:47:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZiE1QBBzh9/Render', 21:47:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:47:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:47:15 INFO - 'HOME': '/Users/cltbld', 21:47:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:47:15 INFO - 'LOGNAME': 'cltbld', 21:47:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:47:15 INFO - 'MOZ_NO_REMOTE': '1', 21:47:15 INFO - 'NO_EM_RESTART': '1', 21:47:15 INFO - 'PAGER': '/bin/cat', 21:47:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:47:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:47:15 INFO - 'PWD': '/builds/slave/test', 21:47:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:47:15 INFO - 'SHELL': '/bin/bash', 21:47:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iLALYGnLw2/Listeners', 21:47:15 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 21:47:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:47:15 INFO - 'USER': 'cltbld', 21:47:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:47:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:47:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:47:16 INFO - 'XPC_FLAGS': '0x0', 21:47:16 INFO - 'XPC_SERVICE_NAME': '0', 21:47:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:47:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:47:16 INFO - Downloading/unpacking jsonschema 21:47:16 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 21:47:16 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 21:47:16 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 21:47:16 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 21:47:16 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 21:47:16 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 21:47:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:47:18 INFO - Downloading/unpacking functools32 (from jsonschema) 21:47:18 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 21:47:18 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 21:47:18 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 21:47:18 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 21:47:18 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 21:47:18 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 21:47:19 INFO - Downloading functools32-3.2.3-2.tar.gz 21:47:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:47:19 INFO - warning: no files found matching '*.txt' 21:47:19 INFO - no previously-included directories found matching 'build' 21:47:19 INFO - no previously-included directories found matching 'dist' 21:47:19 INFO - no previously-included directories found matching '.git*' 21:47:19 INFO - Installing collected packages: jsonschema, functools32 21:47:19 INFO - Running setup.py install for functools32 21:47:19 INFO - warning: no files found matching '*.txt' 21:47:19 INFO - no previously-included directories found matching 'build' 21:47:19 INFO - no previously-included directories found matching 'dist' 21:47:19 INFO - no previously-included directories found matching '.git*' 21:47:19 INFO - Successfully installed jsonschema functools32 21:47:19 INFO - Cleaning up... 21:47:19 INFO - Return code: 0 21:47:19 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 21:47:19 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')]} 21:47:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:47:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47:19 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')]} 21:47:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:47:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47:19 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 0x10347ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103484570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8429cb1220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103322d30>, '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 0x103146db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103319b20>, '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.ZiE1QBBzh9/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.iLALYGnLw2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 21:47:19 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 21:47:19 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 21:47:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZiE1QBBzh9/Render', 21:47:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:47:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:47:19 INFO - 'HOME': '/Users/cltbld', 21:47:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:47:19 INFO - 'LOGNAME': 'cltbld', 21:47:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:47:19 INFO - 'MOZ_NO_REMOTE': '1', 21:47:19 INFO - 'NO_EM_RESTART': '1', 21:47:19 INFO - 'PAGER': '/bin/cat', 21:47:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:47:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:47:19 INFO - 'PWD': '/builds/slave/test', 21:47:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:47:19 INFO - 'SHELL': '/bin/bash', 21:47:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iLALYGnLw2/Listeners', 21:47:19 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 21:47:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:47:19 INFO - 'USER': 'cltbld', 21:47:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:47:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:47:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:47:19 INFO - 'XPC_FLAGS': '0x0', 21:47:19 INFO - 'XPC_SERVICE_NAME': '0', 21:47:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:47:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:47:19 INFO - Downloading/unpacking flake8 21:47: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 21:47: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 21:47: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 21:47: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 21:47: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 21:47: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 21:47:22 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 21:47:22 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 21:47: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 21:47: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 21:47: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 21:47: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 21:47: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 21:47: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 21:47:22 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 21:47:22 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 21:47: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 21:47: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 21:47: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 21:47: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 21:47: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 21:47: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 21:47:22 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 21:47: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 21:47: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 21:47: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 21:47: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 21:47: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 21:47: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 21:47:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 21:47:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 21:47:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 21:47:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 21:47:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 21:47:23 INFO - no previously-included directories found matching 'docs/_build' 21:47:23 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 21:47:23 INFO - Running setup.py install for pep8 21:47:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 21:47:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 21:47:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 21:47:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 21:47:23 INFO - no previously-included directories found matching 'docs/_build' 21:47:23 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 21:47:23 INFO - Successfully installed flake8 mccabe pyflakes pep8 21:47:23 INFO - Cleaning up... 21:47:23 INFO - Return code: 0 21:47:23 INFO - Running post-action listener: _resource_record_post_action 21:47:23 INFO - Running post-action listener: _start_resource_monitoring 21:47:23 INFO - Starting resource monitoring. 21:47:23 INFO - ##### 21:47:23 INFO - ##### Finished create-virtualenv step (success) 21:47:23 INFO - ##### 21:47:23 INFO - ##### 21:47:23 INFO - ##### Running install step. 21:47:23 INFO - ##### 21:47:23 INFO - Running pre-action listener: _resource_record_pre_action 21:47:23 INFO - Running main action method: install 21:47:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:47:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:47:24 INFO - Reading from file tmpfile_stdout 21:47:24 INFO - Detecting whether we're running mozinstall >=1.0... 21:47:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:47:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:47:24 INFO - Reading from file tmpfile_stdout 21:47:24 INFO - Output received: 21:47:24 INFO - Usage: mozinstall [options] installer 21:47:24 INFO - Options: 21:47:24 INFO - -h, --help show this help message and exit 21:47:24 INFO - -d DEST, --destination=DEST 21:47:24 INFO - Directory to install application into. [default: 21:47:24 INFO - "/builds/slave/test"] 21:47:24 INFO - --app=APP Application being installed. [default: firefox] 21:47:24 INFO - mkdir: /builds/slave/test/build/application 21:47:24 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'] 21:47:24 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 21:47:58 INFO - Reading from file tmpfile_stdout 21:47:58 INFO - Output received: 21:47:58 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 21:47:58 INFO - Running post-action listener: _resource_record_post_action 21:47:58 INFO - ##### 21:47:58 INFO - ##### Finished install step (success) 21:47:58 INFO - ##### 21:47:58 INFO - ##### 21:47:58 INFO - ##### Running run-tests step. 21:47:58 INFO - ##### 21:47:58 INFO - Running pre-action listener: _resource_record_pre_action 21:47:58 INFO - Running main action method: run_tests 21:47:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:47:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:47:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:47:58 INFO - 2016-05-10 21:47:58.276 screenresolution[1635:7519] starting screenresolution argv=screenresolution get 21:47:58 INFO - 2016-05-10 21:47:58.278 screenresolution[1635:7519] Display 0: 1600x1200x32@60 21:47:58 INFO - 2016-05-10 21:47:58.284 screenresolution[1636:7521] starting screenresolution argv=screenresolution list 21:47:58 INFO - Available Modes on Display 0 21:47:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:47:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:47:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:47:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:47:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:47:58 INFO - Intel Iris: 21:47:58 INFO - Chipset Model: Intel Iris 21:47:58 INFO - Type: GPU 21:47:58 INFO - Bus: Built-In 21:47:58 INFO - VRAM (Dynamic, Max): 1536 MB 21:47:58 INFO - Vendor: Intel (0x8086) 21:47:58 INFO - Device ID: 0x0a2e 21:47:58 INFO - Revision ID: 0x0009 21:47:58 INFO - Displays: 21:47:58 INFO - Display: 21:47:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:47:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:47:58 INFO - Display Serial Number: 1600x1200 60 21:47:58 INFO - Main Display: Yes 21:47:58 INFO - Mirror: Off 21:47:58 INFO - Online: Yes 21:47:58 INFO - Rotation: Supported 21:47:58 INFO - Return code: 0 21:47:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:47:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:47:58 INFO - Python 2.7.3 21:47:58 INFO - Return code: 0 21:47:58 INFO - grabbing minidump binary from tooltool 21:47:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:47:58 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 0x103322d30>, '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 0x103146db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103319b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:47:58 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 21:47:58 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 21:47:58 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 21:47:58 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:47:58 INFO - Return code: 0 21:47:58 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 21:47:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:47:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:47:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 21:47:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:47:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 21:47:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 21:47:59 INFO - Return code: 0 21:47:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fmuKbuH2SMy0uOQUcJEXNw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0167', '--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 21:47:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fmuKbuH2SMy0uOQUcJEXNw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0167 --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 21:47:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZiE1QBBzh9/Render', 21:47:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:47:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:47:59 INFO - 'HOME': '/Users/cltbld', 21:47:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:47:59 INFO - 'LOGNAME': 'cltbld', 21:47:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:47:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 21:47:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:47:59 INFO - 'MOZ_NO_REMOTE': '1', 21:47:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:47:59 INFO - 'NO_EM_RESTART': '1', 21:47:59 INFO - 'PAGER': '/bin/cat', 21:47:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:47:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:47:59 INFO - 'PWD': '/builds/slave/test', 21:47:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:47:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:47:59 INFO - 'SHELL': '/bin/bash', 21:47:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iLALYGnLw2/Listeners', 21:47:59 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 21:47:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:47:59 INFO - 'USER': 'cltbld', 21:47:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:47:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:47:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:47:59 INFO - 'XPC_FLAGS': '0x0', 21:47:59 INFO - 'XPC_SERVICE_NAME': '0', 21:47:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:47:59 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fmuKbuH2SMy0uOQUcJEXNw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0167', '--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 21:47:59 INFO - mozversion application_buildid: 20160510194316 21:47:59 INFO - mozversion application_changeset: b8f01297e8fca2f828b996a8ddefc2009e4f35cf 21:47:59 INFO - mozversion application_display_name: Nightly 21:47:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:47:59 INFO - mozversion application_name: Firefox 21:47:59 INFO - mozversion application_remotingname: firefox 21:47:59 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 21:47:59 INFO - mozversion application_vendor: Mozilla 21:47:59 INFO - mozversion application_version: 49.0a1 21:47:59 INFO - mozversion platform_buildid: 20160510194316 21:47:59 INFO - mozversion platform_changeset: b8f01297e8fca2f828b996a8ddefc2009e4f35cf 21:47:59 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 21:47:59 INFO - mozversion platform_version: 49.0a1 21:47:59 INFO - using testdate: 1462942079 21:47:59 INFO - actual date: 1462942079 21:47:59 INFO - starting webserver on 'localhost:49275' 21:47:59 INFO - SUITE-START | Running 2 tests 21:47:59 INFO - TEST-START | dromaeo_css 21:47:59 INFO - operating with platform_type : mac_ 21:47:59 INFO - Initialising browser for dromaeo_css test... 21:47:59 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/tmp8rFREj/profile http://localhost:49275/getInfo.html) 21:48:03 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 21:48:03 INFO - PROCESS | 1654 | colorDepth:24 21:48:03 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 21:48:03 INFO - PROCESS | 1654 | __metrics 21:48:03 ERROR - PROCESS | 1654 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 21:48:03 INFO - TEST-INFO | 1654: exit 0 21:48:03 INFO - Browser initialized. 21:48:03 INFO - Running cycle 1/1 for dromaeo_css test... 21:48:03 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/tmp8rFREj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:48:15 INFO - PROCESS | 1657 | RSS: Main: 249597952 21:48:15 INFO - PROCESS | 1657 | 21:49:05 INFO - PROCESS | 1657 | 1462942145412 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] 21:51:16 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 21:51:17 INFO - PROCESS | 1657 | RSS: Main: 263520256 21:51:17 INFO - PROCESS | 1657 | 21:54:20 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 21:54:20 INFO - PROCESS | 1657 | RSS: Main: 274833408 21:54:20 INFO - PROCESS | 1657 | 21:57:22 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 21:57:23 INFO - PROCESS | 1657 | RSS: Main: 276267008 21:57:23 INFO - PROCESS | 1657 | 22:00:24 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 22:00:25 INFO - PROCESS | 1657 | RSS: Main: 278069248 22:00:25 INFO - PROCESS | 1657 | 22:03:28 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 22:03:29 INFO - PROCESS | 1657 | RSS: Main: 280969216 22:03:29 INFO - PROCESS | 1657 | 22:06:31 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 22:06:31 INFO - PROCESS | 1657 | RSS: Main: 281845760 22:06:31 INFO - PROCESS | 1657 | 22:06:31 INFO - PROCESS | 1657 | __start_tp_report 22:06:31 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 22:06:31 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 22:06:31 INFO - PROCESS | 1657 | |i|pagename|runs| 22:06:31 INFO - PROCESS | 1657 | |0;dojo.html;3202.4235637585234;3241.2221066943935;3265.575475188225;3499.265154317593;3512.420450625647;5748.5688573357065;5773.48038676519;5803.448672376123;5803.6227645203035;5882.4705776480105;5686.800961966332;5763.769449222036;5811.302643682764;5818.389069147732;5893.90962671906;3720.2001569662516;4945.6043516518685;5165.405978312486;5405.972970135143;5406.297181366417;353.261173731517;5229.843104706865;5272.604554658396;5305.522502974732;5310.946890531084;5309.309789727349;5322.973385133088;5331.920021199685;5360.222767698681;5389.946100539023;5320.840374788762;5365;5372.382176049726;5388.973055134699;5392.272043274186;5193.454687257859;5240.318758561362;5256.500632439912;5257.737113144328;5280.445553216934;4489.528599497038;5682.545396368322;5730.713464326809;5730.7707691692285;5744;4878.975605121987;6135.661410523238;6354.428101470844;6359;6362.0456931460185;6251.4061164189325;6285.9057114143325;6302.338254483259;6309.747610095591;6313.747450101991;5953.226080609536;5957.314908785502;6041.1240370146525;6047.334793172748;6091.695415229266;6170.136180934673;6249.468795152463;6268.431794252495;6346;6349.888769465325;6170.321264660884;6172.055802422223;6174.351693072253;6174.598651087755;6185.876282474325;5146.562542183881;5277.287566178594;6225;6255.812325630239;6423.646699431576;6241.78153764616;6272.717727702265;6297.2128483939505;6309.400606942333;6372.808815735535;6315.305316415191;6322.9051564226575;6365.777197798147;6367.013112967497;6386.776462823872;4909.286012360193;4999.225120106389;5007.37407824022;5016.122178618727;5061.924071138936;4329.874232699521;4403.4495688039;4440.511543730187;4494.842680506166;4517.5256598057385;4855.247436647325;5441.700706461183;5843.737031833578;5952.166696662471;5975.462208401264;5642.871425714873;6289.937100629027;6325.114483972247;6336.778212762531;6357.205349331333;5794.188813566104;5870.3249126350165;5903.793367232126;5953.851153721105;6004.519638428916;5431.321084864392;5459.508644222039;5482.17767334903;5484.862878428071;5504.834854954358;6405.519586031066;6437.742490300336;6439.935600643934;6445.838854028687;6447.516436267299;6226.470749986302;6273.278572964167;6279.905801413074;6317.178766760292;6325.493960483058;6384.712687928962;6411.551191416556;6426;6441.259255185713;6459.031145328238;6122.733612273255;6313.337099604475;6320.957042088004;6326.810195694136;6346.587471814318;6187.566870319034;6224.346443623535;6285.402886725754;6288.308286088618;6333.113364128933;6195.070739389128;6196.659183744938;6198.690065496617;6199.876002480015;6217.4715149211015;5951.791687290924;6074.301455332693;6168.444839964424;6192.938070619236;6202.4417802398;5999.52003839683;6028.0355143176885;6099.491378240913;6117.174181485442;6131.223750760645;6189.960561477376;6222.191115155001;6232.314445410911;6260.906086408617;6263.029230469285;5956.195913551615;6058;6080.969595152173;6094.542909281733;6140.385961403824 22:06:31 INFO - PROCESS | 1657 | |1;ext.html;512.1140427061184;513.1712284660274;550.2736387967883;560.285635814337;572.8052462162864;38609.42085868714;39381.212375752504;41458.58541414593;41504.96237594062;41575.37636935448;42223.3666495002;42860;42934.57065429345;43102.56897431025;43294;8710.956445217766;9542.666006689784;11950.7609847803;12169.026477881793;12190.634280971584;11876.049916006741;11904.98807601355;11943.984761295256;11959.820602690967;11968.401579921012;10944.960228778253;11220.214584979054;11963.7607247855;11967.640970770848;11975.281483110997;11884.227525210834;11912.285262884212;11939.701507462296;11955.581554645589;11967;11450.94274528622;11825.290482570987;11871;11949.342786146759;11975.46110425033;34739;40807.183856323005;40907.59092409068;40921.79539102315;40973.79513102445;3389.4576867701294;3503.8423270952867;3786.4320351947154;3820.121372084436;3826.253880493305;29352.559711604343;30002.84998574993;30089.041743605594;30613.775448982236;30771.69228307733;2820.689724130344;2847.4732174547776;2878.8848446062134;2890.132960111958;2903.3757742085554;506586.604803713;512356;513895;514022;514445;65935.35161620998;66357;66551.33448665451;66552.00171997424;66649;69511.30488695149;69873;70084.64957675281;70250;70520;15396.384144634314;16725.16374181286;17304.740428893576;17324.220410081576;17591.824081759274;13056.412461439259;13104.344782760823;13162.7495387938;13170.275634840176;13177.868221317856;18305.81694183041;18651.906740466482;18656;18723.25106995705;18850.86894786317;6520.0871877937125;6672.266050734415;7023.367896889203;7109.28907109285;7176.569405835667;891.1177933845446;921;990.5938565188238;1107.9889201108047;1167.9357635329968;23244.535109298056;30225.244368890955;30245.546316805267;30546;31050.844745776125;1060.8567843341204;1099.6035035504865;1163.2264544078262;1230.4831970572382;1250.5435516036764;15559.29983150761;15712.21438928049;15785.84765309079;15849;16032.438864639682;578956;632241;642724;642778;646993;61206.693966529885;62779;62891.685541572;62945.370546294864;62980;1338.3642769684323;1366.3100134432048;1394.9790753138711;1456.155429850684;1490.7614781634886;29667.40665186641;29832.25419364533;29877.551836722032;30399.240018999702;30601.23496912595;54913.450865490835;59376.81246375135;60683.69658151681;60981.39018609757;64827.67586162039;604976;609069;611943;612988;614101;23232;27053.323666908484;27151.185464436097;27191.320216994733;27945.021924232555;47227.76386118047;47262.76368618135;48376.758116209196;48398.790030249525;49205.01589968252;38975.02562435848;39003;39071.6092839068;39464.40803388009;39845;37939.86180414592;38047.61952380441;38595.2280954385;38798.41802373023;38979.830605081894;37450.06374840651;37974.43038354372;38288.61711382962;38743;38873.41689874598;26949.86525067362;33144.50283245801;34127.658723412445;34419.827900860335;34669.47995780015;19661.410157695293;22062.558748825253;22607.09571617117;24890.50218995646;25287.2991888027 22:06:31 INFO - PROCESS | 1657 | |2;jquery.html;354.3462312034297;397.61828644501276;403.92325458162964;404.02226611599934;420.13242653919656;19959.600807983832;20449;21131.788682113194;21230.938453077342;21386.893065534652;18903.90548047258;19032.524186895334;20706;21483.570328593458;21534.13863445464;5661.5187709044785;6047.183630209931;6064.151018857364;6181.07283907413;6210.50315974721;5647.943520564803;5924.644521328734;6040.57715959883;6043.909341359883;6077.361877002917;5923.466887980081;6052.455279024887;6059.636421814683;6101.969490152543;6105.572609917308;6104.4200800923845;6524.510661700368;6575.53971222017;6635.8009259722285;6642.501812364044;4183.518895327015;5429.8099566515375;5487.670739755627;5561.443855614447;5894.734736936848;13274.738899804503;13719.588412347646;13943;13961.650958726012;13979.161250325013;2002.8797624445092;2166.166026079955;2210.7457642371173;2243.5512897420417;2246.2025980776916;9220.8616870747;9556.569954352071;9888.703338899844;10290.382577045326;10344.637937672145;1708.1886104100577;1712.383541924906;1718.7593736876722;1753.3424965637885;1772.5480002599384;104116.47941760319;127798.3610081953;134279.32860335734;134988;134994.65005349918;11958.461869215906;12083.395830208454;12083.637490875288;12100;12243.265404075784;15192.848071519364;15562.844371556366;15636.983596066219;15638.9218053909;15701;8781.780455488664;8822.647094116164;8841.823163536694;8842.778930526789;9018.774530636787;7046.189688185822;8548.74353769388;8654.78363040929;8765.386422950332;8786.516741579275;3800.7339486235696;4309.715850599187;4397.934030989538;4666.533346665374;4781.928271075936;1697.414392034746;2006.4783156379156;2054.116729806198;2058.217877206652;2084.8123668869875;865.9177378149066;880.5421180985851;902.6163880350824;933.8972713001565;983.1790454970037;14963.326650300764;17118.743218851727;21280.574388512454;21426.571468570542;21440.142394304366;969.2924165359186;988.6342053440129;1015.9992407478628;1021.6322124035341;1091.732525531234;9293.488858112734;10553.683389498327;11299.661010169708;11319.886801132048;11334.376609286404;545398.2730086325;546574;553936;569230;616056;80676.59661701653;84168;129179.35410323075;131272;131994.34002829925;984.0995489127512;1000.6197644894893;1017.6997785653102;1019.3221507697447;1021.2953062386929;19024.52438689044;19217.8078219216;19319.323823666105;19474.902625486782;19502.707459387835;35868.28263434769;49652.75173624109;53197.93604127973;54560.18159727528;56727.43272567387;55041;80528.19471805207;81264.18735812567;81708.59145704233;82121.35757284702;6422.871542569216;7248.60132692707;7562.0925488941675;7615.771526854203;8516.70191543293;7106.751263705745;8058.3956203283815;9159.175674189355;13025.804612930624;13180.868191317964;11029.61396351124;11160.181427548496;11213.66359009231;11276.379799111128;11551.653450396501;9379.953100234456;11166.436698862328;11291.43542822872;11412.942935285271;11461.369624670722;10045.799084018425;10893.836592451278;11489.712757181138;11558.133140014364;11812.586559470721;6991.811392063463;7628.504147230412;7745.264199901;7810.960945195237;8000.239977202156;9010.954945225232;10769.353838769699;11444.427778611203;11491.080713543064;11540.94229528847 22:06:31 INFO - PROCESS | 1657 | |3;mootools.html;1605.7591361295808;1639.6228867360508;1892.4228110426313;1937.2638397409;1948.658984677681;8035.6383962721675;8495.617697203625;8547.10255423181;8954;8991.775205619862;6962.373386395224;8672.219500244977;8880.95559522203;8895.154960278764;8901.510416927067;2263.864168149912;2401.735809061002;2428.623563347684;2442.426029882976;2449.2652204338715;2414.5774489464275;2417.806575473967;2441.792447641944;2444.9144279950206;2450.4363996280867;2390.3187591536416;2434.2453839309783;2435.3789783605157;2440.511897620483;2444.7799698027175;2050.1799280288005;2289.370423133635;2418.9153379631716;2420.2739178246543;2433.367324495635;2003.5091402606454;2303.1593468384085;2346.378209774411;2394.389430695179;2411.843230190032;8712.651493940235;8733.7816554586;8839.29285657146;8914.28685705142;8931.73204803857;5306.442823503515;5395.892082158374;5418.729063546807;5466.972665136688;5488.17677348397;3126.4685003549225;3590.120420496969;3630.056185391817;3652.3243200008083;3664.029032306441;1038.3302769713569;1059.4861492176287;1061.9681409557725;1065.2649671726483;1065.8134826405426;10008.549615267331;11356.261842980262;11397.715057123638;11724.120690948213;11744.588939387166;4781.258904869751;4784.179463956975;4895.167821470287;4916.0905232532095;4928.507149285043;9166.450012999241;9606.423614583147;9992.850107248398;9995.000499950092;10002;5326.280952071498;5393.892122157535;5412.134058550613;5434.804343044524;5438.129899216186;4218.240716671029;4824.204006338985;4912.754362281943;4956.77694503741;4960.627952903475;4075.8369665213654;4084.357040141743;4123.97938010308;4144.7513149211145;4159.10579225462;1184.224333061846;1345.273671356101;1362.1214316765631;1388.3682924269433;1398.8181536400407;828.6809578312273;838.1618381618382;910.526526206369;919.7148883846029;950.5674917912471;5786.855328616818;5889.823305300848;5985.760569577255;6032.004719221367;6141.634008137626;445.44319600499375;463.82142874992695;469.0712389468871;469.53046953046953;477.4604696692715;3954.169624378855;4006.1787333596544;4068.613481719232;4114.6091121343425;4121.443605113332;8544.57277136153;10301.948490257651;10380.273380863267;10589.311694739818;10832.187585931088;10672.786544269227;10759.83860242097;10799.946000269949;10882.347059176474;10886.29239099456;7940.007499062617;8214.548199849065;8224.465409748227;8263.834723305501;8276.337892968548;5768.855778605568;5859.5019423347785;5919.556033297606;5947.31605865331;6021.9397806023135;9728.562214700498;9739.415635061636;9827.705168844946;10056;10230.23273254494;9969.003099689973;10015.799684006424;10198.490075496047;10391.948040260053;10408.323458975143;6877.89683154743;7433.405327573673;7522.887156692764;7603.973463582345;7635.847283054419;8501.659933602834;8504.914950850412;8546.401751877309;8554.973403191656;8574.485530868167;7393.445491588045;7562.432817538596;7581.658825352762;7588.392928565591;7682.961585192038;7365.52124111931;7392.630368481447;7420.257974202536;7467.626618669154;7499.287567681062;7035.964820176071;7111.324424179695;7175.174854891754;7198.100237470317;7299.197088320174;4144.7513149211145;4811.109944660249;4960.801567937387;5055.797768089383;5064.215046667772;5018.397792264952;5043.646944713982;5075.720835354091;5081.8475445736685;5111.386633603991 22:06:31 INFO - PROCESS | 1657 | |4;prototype.html;318.8756385009847;319.0762741862309;322.79663811798565;323.6456080591751;328.18446161289205;5337.70642614657;5439.211314359415;5547.44525547445;5601.523870471015;5612.438756124386;5301.648079739661;5408.513233808966;5495.945040549594;5498.487915823151;5516.531094856932;4012.458318127052;4036.8183431745642;4101.610347017028;4112.650424713903;4212.220739163265;3363.327334533091;3806.373602214477;4047.8380864765522;4173.24881521326;4191.497020357561;3956.4065390191413;3963.7910437316605;4107.342825147977;4240.982164280581;4251.193242871783;3618.2582570572968;3708.629137086297;4123.361048928688;4143.585641435862;4245.660347172219;3063.5404689296784;3551.8456501636815;3553.022918697353;3562.536870206882;3577.284543091392;4624.676272660915;4762.476127625959;4862.41651001882;5291.523762861322;5296.788128474856;4428.269335559628;4513.7517436540975;4734.597559207472;4760.619150467955;4783.904321913577;5088.720120393377;5340.385855626575;5765.827025189251;5775.660857352779;5786.42135786418;3694.6674799268194;3947.4473573699706;3958.8218530166214;3976.284268831609;3979.6816254699556;5877.56162644849;6052.788152414644;6060.787872424488;6113.877722445571;6165.876682466325;3964.306246406867;3964.4053391991433;3975.4434379186937;3991.341428664238;3994.3009973254566;3102.7672924530752;3137.490007993596;3586.8923932282073;3740.2696031896867;3748.437734339871;4171.6036976487185;4475.664325175625;4738.620910327166;4743.454502732161;4774.928376074362;4206;4817.843717507772;5580.91628625571;5829.889927601031;5870.503021729432;1245.7044673539417;1338.4177882620897;1348.885344745688;1365.0922136778931;1376.0230236531975;755.4334249313015;801.6452195789097;852.3224036890563;860.5740158621397;903.4579252448478;5335.33308336458;5352.199938044032;5365.892682146336;5376.677399356051;5391.169635941163;5810.738516766755;5853.824385268449;5856.385079566669;5898.852528686817;5901.291844978544;4654.86035418938;4854.441739199967;5278.917821846512;5331.226972089025;5573.860653483614;2727.9727202727718;2760.3513174403997;3139.7959132656306;3152.5586417901513;3184.601924759405;5718.513926316226;5763.250777398911;5927.873703996227;6031.366706495843;6308.558400911892;5218.739063046833;5735.1970724098655;6136.9079463808075;6169.8457538561;6182.536309776785;5267.183586544092;5756.438384918754;5946.672932988728;6274.215723034621;6305.572354643496;5251.6323857331145;5481.6287982891035;5814.127880817911;6068.332483426731;6124.846878828065;5357.624966252325;5383.380911195262;5930;5955.731992060281;5994.850128746816;5330.173823057432;5778.422157784188;5857.384974577608;6165.506759459143;6215.533834962305;3391.2878295558207;3622.9275414491035;3742.812859357076;3933.8623148189677;3960.861369852041;5029.471905450021;5943.762249510144;6015.218021657157;6036.6679832609625;6065.878682426415;4833.468318484894;5083.771230294719;5473.28847249855;5626.396477004034;5716.957452567759;4775.6657034007285;4885.682430641997;5806.912896306643;5807.680577568105;5820.621659592113;4632.591052652534;4835.492273311252;5609.990201763721;5649.785296161345;5692.857678558069;5163.97907224278;5675.77296908119;5746.281714785652;5798.246227990334;5803.535717142703;4852.762545550862;5270.235815806764;5367.946320536745;5731.140328950691;5770.730439303346 22:06:31 INFO - PROCESS | 1657 | |5;yui.html;655.7344275568395;680.3910500102412;690.9654517274136;696.7665831946299;704.3308856586244;14952.327145278461;15043.623909402271;15233.771493427608;15256;15437.536873893772;14091.295435228249;14250.432452430225;14329.283535823219;14349.210793406384;14352.210628415432;704.6582407532368;885.0618344554761;887.9778005549881;902.5397047505786;903.8779764731743;908.309684639672;911.9224865886407;912.3476714149382;913.7441516375392;914.2685851318919;953.1612181280464;953.7234202081388;954.4416516337951;957.5068839547639;957.8419560772498;922.5571725571696;934.7195841247667;940.9294302927274;952.7189479103681;958.1616085924851;678.0913575808391;685.9382655560973;700.5201437015661;700.8458139209416;702.2275496953362;14465.49370772018;14480.131192128507;14616.70766584673;15043.699126017418;15054.623634409118;12596.181248218836;12626.81059784104;12650.557230496981;12744.553940612126;12745;9049.457032578066;9344.345895787297;10023.498825058718;10306.072453479148;10515.316504427188;3495.1436897959907;3499.842507087187;3503.7197024238;3546.078019714893;3584;65851.67074164646;77089.84365234525;79284;80175.39649207042;83817.74273385904;9318.394304370195;9403.200727938065;9605.567749451291;9677.322587418954;9739.954609531937;21872.23447179341;22090.558188836134;22109.33671989843;22227.22204722859;22237.11051557952;7198.388137008413;7233.927660723431;7252.456065795086;7317.0487836580905;7347.375473084848;3299.9175020624675;4020.3768415895584;4029.5164580250557;4056.0671045659665;4071.572484889103;3287.6520626543;3392.9830350848583;3407.6251612322626;3408.454647256427;3411.7611767176554;551.5587529976003;629.6379581740517;629.9433051025429;636.2238069555135;644.0821828893808;525.5952916254463;574.4657468554285;584.6024703201779;597.88042391521;612.9579714485393;6285.371462853769;7303.561786292839;7322.048133742686;7375.225601311785;7511.322542400575;524.8530411484791;530.2417542913594;550.2269311617184;575.8301301116181;611.4252602553586;3758.9248215035545;3786.5456145262747;3853.556840963325;3921.784301863425;3925.2345792570177;92804;94772;95142;96619.51690241504;98081;82827.58586207031;82926.17073829305;84835.15164848273;85564.71652925211;86438.67806609644;68474.3152568478;69689.65155174192;70554.29445705477;70621;75340;7655.770326890202;7677.347425468898;7851.60741962911;7897.526148431112;7900.012498437695;37159.81420092882;44470.88822779457;44854.10291794081;45013.549864500936;45046;45872.770636146604;58890.055497225825;60919.69540152271;61626.0756088668;63018;10229.97700229971;10351.171906247331;10385.688429347132;10823;11033.227674062739;10756.247062705535;10990.450477475933;11378.03286720638;11383.26008809458;11662.70843228926;10228.590856365661;10429.739256518647;10898.836517452086;10908.945455272673;11016.834747478633;10222.28444008942;10605.41670208146;10606.257561970588;10658.467076645982;10764.3541387517;8598.527081010605;10043.347182433119;10372.066514013775;10555.89444105549;10572.84140737874;8717.91282087171;8803.471791692518;8845.867311990454;8881.245094167069;8945.37382383227;7609.771706848804;7917.604119793872;8662.307015438624;8719.12808719123;9129.087091290818 22:06:31 INFO - PROCESS | 1657 | __end_tp_report 22:06:31 INFO - PROCESS | 1657 | __start_cc_report 22:06:31 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,416 22:06:31 INFO - PROCESS | 1657 | __end_cc_report 22:06:31 INFO - PROCESS | 1657 | __startTimestamp1462943191900__endTimestamp 22:06:31 INFO - PROCESS | 1657 | 22:06:31 INFO - PROCESS | 1657 | ------- Summary: start ------- 22:06:31 INFO - PROCESS | 1657 | Number of tests: 6 22:06:31 INFO - PROCESS | 1657 | 22:06:31 INFO - PROCESS | 1657 | [#0] dojo.html Cycles:165 Average:5754.54 Median:6058.00 stddev:808.13 (13.3%) stddev-sans-first:785.41 22:06:31 INFO - PROCESS | 1657 | Values: 3202.4 3241.2 3265.6 3499.3 3512.4 5748.6 5773.5 5803.4 5803.6 5882.5 5686.8 5763.8 5811.3 5818.4 5893.9 3720.2 4945.6 5165.4 5406.0 5406.3 353.3 5229.8 5272.6 5305.5 5310.9 5309.3 5323.0 5331.9 5360.2 5389.9 5320.8 5365.0 5372.4 5389.0 5392.3 5193.5 5240.3 5256.5 5257.7 5280.4 4489.5 5682.5 5730.7 5730.8 5744.0 4879.0 6135.7 6354.4 6359.0 6362.0 6251.4 6285.9 6302.3 6309.7 6313.7 5953.2 5957.3 6041.1 6047.3 6091.7 6170.1 6249.5 6268.4 6346.0 6349.9 6170.3 6172.1 6174.4 6174.6 6185.9 5146.6 5277.3 6225.0 6255.8 6423.6 6241.8 6272.7 6297.2 6309.4 6372.8 6315.3 6322.9 6365.8 6367.0 6386.8 4909.3 4999.2 5007.4 5016.1 5061.9 4329.9 4403.4 4440.5 4494.8 4517.5 4855.2 5441.7 5843.7 5952.2 5975.5 5642.9 6289.9 6325.1 6336.8 6357.2 5794.2 5870.3 5903.8 5953.9 6004.5 5431.3 5459.5 5482.2 5484.9 5504.8 6405.5 6437.7 6439.9 6445.8 6447.5 6226.5 6273.3 6279.9 6317.2 6325.5 6384.7 6411.6 6426.0 6441.3 6459.0 6122.7 6313.3 6321.0 6326.8 6346.6 6187.6 6224.3 6285.4 6288.3 6333.1 6195.1 6196.7 6198.7 6199.9 6217.5 5951.8 6074.3 6168.4 6192.9 6202.4 5999.5 6028.0 6099.5 6117.2 6131.2 6190.0 6222.2 6232.3 6260.9 6263.0 5956.2 6058.0 6081.0 6094.5 6140.4 22:06:31 INFO - PROCESS | 1657 | 22:06:31 INFO - PROCESS | 1657 | [#1] ext.html Cycles:180 Average:72486.87 Median:34704.24 stddev:156568.45 (451.2%) stddev-sans-first:156914.41 22:06:31 INFO - PROCESS | 1657 | Values: 512.1 513.2 550.3 560.3 572.8 38609.4 39381.2 41458.6 41505.0 41575.4 42223.4 42860.0 42934.6 43102.6 43294.0 8711.0 9542.7 11950.8 12169.0 12190.6 11876.0 11905.0 11944.0 11959.8 11968.4 10945.0 11220.2 11963.8 11967.6 11975.3 11884.2 11912.3 11939.7 11955.6 11967.0 11450.9 11825.3 11871.0 11949.3 11975.5 34739.0 40807.2 40907.6 40921.8 40973.8 3389.5 3503.8 3786.4 3820.1 3826.3 29352.6 30002.8 30089.0 30613.8 30771.7 2820.7 2847.5 2878.9 2890.1 2903.4 506586.6 512356.0 513895.0 514022.0 514445.0 65935.4 66357.0 66551.3 66552.0 66649.0 69511.3 69873.0 70084.6 70250.0 70520.0 15396.4 16725.2 17304.7 17324.2 17591.8 13056.4 13104.3 13162.7 13170.3 13177.9 18305.8 18651.9 18656.0 18723.3 18850.9 6520.1 6672.3 7023.4 7109.3 7176.6 891.1 921.0 990.6 1108.0 1167.9 23244.5 30225.2 30245.5 30546.0 31050.8 1060.9 1099.6 1163.2 1230.5 1250.5 15559.3 15712.2 15785.8 15849.0 16032.4 578956.0 632241.0 642724.0 642778.0 646993.0 61206.7 62779.0 62891.7 62945.4 62980.0 1338.4 1366.3 1395.0 1456.2 1490.8 29667.4 29832.3 29877.6 30399.2 30601.2 54913.5 59376.8 60683.7 60981.4 64827.7 604976.0 609069.0 611943.0 612988.0 614101.0 23232.0 27053.3 27151.2 27191.3 27945.0 47227.8 47262.8 48376.8 48398.8 49205.0 38975.0 39003.0 39071.6 39464.4 39845.0 37939.9 38047.6 38595.2 38798.4 38979.8 37450.1 37974.4 38288.6 38743.0 38873.4 26949.9 33144.5 34127.7 34419.8 34669.5 19661.4 22062.6 22607.1 24890.5 25287.3 22:06:31 INFO - PROCESS | 1657 | 22:06:31 INFO - PROCESS | 1657 | [#2] jquery.html Cycles:180 Average:33414.08 Median:1300.27 stddev:94893.65 (7298.0%) stddev-sans-first:95127.38 22:06:31 INFO - PROCESS | 1657 | Values: 354.3 397.6 403.9 404.0 420.1 19959.6 20449.0 21131.8 21230.9 21386.9 18903.9 19032.5 20706.0 21483.6 21534.1 5661.5 6047.2 6064.2 6181.1 6210.5 5647.9 5924.6 6040.6 6043.9 6077.4 5923.5 6052.5 6059.6 6102.0 6105.6 6104.4 6524.5 6575.5 6635.8 6642.5 4183.5 5429.8 5487.7 5561.4 5894.7 13274.7 13719.6 13943.0 13961.7 13979.2 2002.9 2166.2 2210.7 2243.6 2246.2 9220.9 9556.6 9888.7 10290.4 10344.6 1708.2 1712.4 1718.8 1753.3 1772.5 104116.5 127798.4 134279.3 134988.0 134994.7 11958.5 12083.4 12083.6 12100.0 12243.3 15192.8 15562.8 15637.0 15638.9 15701.0 8781.8 8822.6 8841.8 8842.8 9018.8 7046.2 8548.7 8654.8 8765.4 8786.5 3800.7 4309.7 4397.9 4666.5 4781.9 1697.4 2006.5 2054.1 2058.2 2084.8 865.9 880.5 902.6 933.9 983.2 14963.3 17118.7 21280.6 21426.6 21440.1 969.3 988.6 1016.0 1021.6 1091.7 9293.5 10553.7 11299.7 11319.9 11334.4 545398.3 546574.0 553936.0 569230.0 616056.0 80676.6 84168.0 129179.4 131272.0 131994.3 984.1 1000.6 1017.7 1019.3 1021.3 19024.5 19217.8 19319.3 19474.9 19502.7 35868.3 49652.8 53197.9 54560.2 56727.4 55041.0 80528.2 81264.2 81708.6 82121.4 6422.9 7248.6 7562.1 7615.8 8516.7 7106.8 8058.4 9159.2 13025.8 13180.9 11029.6 11160.2 11213.7 11276.4 11551.7 9380.0 11166.4 11291.4 11412.9 11461.4 10045.8 10893.8 11489.7 11558.1 11812.6 6991.8 7628.5 7745.3 7811.0 8000.2 9011.0 10769.4 11444.4 11491.1 11540.9 22:06:31 INFO - PROCESS | 1657 | 22:06:31 INFO - PROCESS | 1657 | [#3] mootools.html Cycles:180 Average:5709.79 Median:4942.64 stddev:3168.48 (64.1%) stddev-sans-first:3162.36 22:06:31 INFO - PROCESS | 1657 | Values: 1605.8 1639.6 1892.4 1937.3 1948.7 8035.6 8495.6 8547.1 8954.0 8991.8 6962.4 8672.2 8881.0 8895.2 8901.5 2263.9 2401.7 2428.6 2442.4 2449.3 2414.6 2417.8 2441.8 2444.9 2450.4 2390.3 2434.2 2435.4 2440.5 2444.8 2050.2 2289.4 2418.9 2420.3 2433.4 2003.5 2303.2 2346.4 2394.4 2411.8 8712.7 8733.8 8839.3 8914.3 8931.7 5306.4 5395.9 5418.7 5467.0 5488.2 3126.5 3590.1 3630.1 3652.3 3664.0 1038.3 1059.5 1062.0 1065.3 1065.8 10008.5 11356.3 11397.7 11724.1 11744.6 4781.3 4784.2 4895.2 4916.1 4928.5 9166.5 9606.4 9992.9 9995.0 10002.0 5326.3 5393.9 5412.1 5434.8 5438.1 4218.2 4824.2 4912.8 4956.8 4960.6 4075.8 4084.4 4124.0 4144.8 4159.1 1184.2 1345.3 1362.1 1388.4 1398.8 828.7 838.2 910.5 919.7 950.6 5786.9 5889.8 5985.8 6032.0 6141.6 445.4 463.8 469.1 469.5 477.5 3954.2 4006.2 4068.6 4114.6 4121.4 8544.6 10301.9 10380.3 10589.3 10832.2 10672.8 10759.8 10799.9 10882.3 10886.3 7940.0 8214.5 8224.5 8263.8 8276.3 5768.9 5859.5 5919.6 5947.3 6021.9 9728.6 9739.4 9827.7 10056.0 10230.2 9969.0 10015.8 10198.5 10391.9 10408.3 6877.9 7433.4 7522.9 7604.0 7635.8 8501.7 8504.9 8546.4 8555.0 8574.5 7393.4 7562.4 7581.7 7588.4 7683.0 7365.5 7392.6 7420.3 7467.6 7499.3 7036.0 7111.3 7175.2 7198.1 7299.2 4144.8 4811.1 4960.8 5055.8 5064.2 5018.4 5043.6 5075.7 5081.8 5111.4 22:06:31 INFO - PROCESS | 1657 | 22:06:31 INFO - PROCESS | 1657 | [#4] prototype.html Cycles:180 Average:4624.62 Median:5274.58 stddev:1455.77 (27.6%) stddev-sans-first:1423.53 22:06:31 INFO - PROCESS | 1657 | Values: 318.9 319.1 322.8 323.6 328.2 5337.7 5439.2 5547.4 5601.5 5612.4 5301.6 5408.5 5495.9 5498.5 5516.5 4012.5 4036.8 4101.6 4112.7 4212.2 3363.3 3806.4 4047.8 4173.2 4191.5 3956.4 3963.8 4107.3 4241.0 4251.2 3618.3 3708.6 4123.4 4143.6 4245.7 3063.5 3551.8 3553.0 3562.5 3577.3 4624.7 4762.5 4862.4 5291.5 5296.8 4428.3 4513.8 4734.6 4760.6 4783.9 5088.7 5340.4 5765.8 5775.7 5786.4 3694.7 3947.4 3958.8 3976.3 3979.7 5877.6 6052.8 6060.8 6113.9 6165.9 3964.3 3964.4 3975.4 3991.3 3994.3 3102.8 3137.5 3586.9 3740.3 3748.4 4171.6 4475.7 4738.6 4743.5 4774.9 4206.0 4817.8 5580.9 5829.9 5870.5 1245.7 1338.4 1348.9 1365.1 1376.0 755.4 801.6 852.3 860.6 903.5 5335.3 5352.2 5365.9 5376.7 5391.2 5810.7 5853.8 5856.4 5898.9 5901.3 4654.9 4854.4 5278.9 5331.2 5573.9 2728.0 2760.4 3139.8 3152.6 3184.6 5718.5 5763.3 5927.9 6031.4 6308.6 5218.7 5735.2 6136.9 6169.8 6182.5 5267.2 5756.4 5946.7 6274.2 6305.6 5251.6 5481.6 5814.1 6068.3 6124.8 5357.6 5383.4 5930.0 5955.7 5994.9 5330.2 5778.4 5857.4 6165.5 6215.5 3391.3 3622.9 3742.8 3933.9 3960.9 5029.5 5943.8 6015.2 6036.7 6065.9 4833.5 5083.8 5473.3 5626.4 5717.0 4775.7 4885.7 5806.9 5807.7 5820.6 4632.6 4835.5 5610.0 5649.8 5692.9 5164.0 5675.8 5746.3 5798.2 5803.5 4852.8 5270.2 5367.9 5731.1 5770.7 22:06:31 INFO - PROCESS | 1657 | 22:06:31 INFO - PROCESS | 1657 | [#5] yui.html Cycles:180 Average:17831.79 Median:31119.52 stddev:25666.42 (82.5%) stddev-sans-first:25706.02 22:06:31 INFO - PROCESS | 1657 | Values: 655.7 680.4 691.0 696.8 704.3 14952.3 15043.6 15233.8 15256.0 15437.5 14091.3 14250.4 14329.3 14349.2 14352.2 704.7 885.1 888.0 902.5 903.9 908.3 911.9 912.3 913.7 914.3 953.2 953.7 954.4 957.5 957.8 922.6 934.7 940.9 952.7 958.2 678.1 685.9 700.5 700.8 702.2 14465.5 14480.1 14616.7 15043.7 15054.6 12596.2 12626.8 12650.6 12744.6 12745.0 9049.5 9344.3 10023.5 10306.1 10515.3 3495.1 3499.8 3503.7 3546.1 3584.0 65851.7 77089.8 79284.0 80175.4 83817.7 9318.4 9403.2 9605.6 9677.3 9740.0 21872.2 22090.6 22109.3 22227.2 22237.1 7198.4 7233.9 7252.5 7317.0 7347.4 3299.9 4020.4 4029.5 4056.1 4071.6 3287.7 3393.0 3407.6 3408.5 3411.8 551.6 629.6 629.9 636.2 644.1 525.6 574.5 584.6 597.9 613.0 6285.4 7303.6 7322.0 7375.2 7511.3 524.9 530.2 550.2 575.8 611.4 3758.9 3786.5 3853.6 3921.8 3925.2 92804.0 94772.0 95142.0 96619.5 98081.0 82827.6 82926.2 84835.2 85564.7 86438.7 68474.3 69689.7 70554.3 70621.0 75340.0 7655.8 7677.3 7851.6 7897.5 7900.0 37159.8 44470.9 44854.1 45013.5 45046.0 45872.8 58890.1 60919.7 61626.1 63018.0 10230.0 10351.2 10385.7 10823.0 11033.2 10756.2 10990.5 11378.0 11383.3 11662.7 10228.6 10429.7 10898.8 10908.9 11016.8 10222.3 10605.4 10606.3 10658.5 10764.4 8598.5 10043.3 10372.1 10555.9 10572.8 8717.9 8803.5 8845.9 8881.2 8945.4 7609.8 7917.6 8662.3 8719.1 9129.1 22:06:31 INFO - PROCESS | 1657 | -------- Summary: end -------- 22:06:31 INFO - PROCESS | 1657 | 22:06:32 INFO - TEST-INFO | 1657: exit 0 22:06:32 INFO - TEST-OK | dromaeo_css | took 1112513ms 22:06:32 INFO - TEST-START | kraken 22:06:32 INFO - operating with platform_type : mac_ 22:06:32 INFO - Initialising browser for kraken test... 22:06:32 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/tmplNpYQw/profile http://localhost:49275/getInfo.html) 22:06:35 INFO - PROCESS | 1666 | __metrics Screen width/height:1600/1200 22:06:35 INFO - PROCESS | 1666 | colorDepth:24 22:06:35 INFO - PROCESS | 1666 | Browser inner width/height: 1024/689 22:06:35 INFO - PROCESS | 1666 | __metrics 22:06:35 ERROR - PROCESS | 1666 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 22:06:36 INFO - TEST-INFO | 1666: exit 0 22:06:36 INFO - Browser initialized. 22:06:36 INFO - Running cycle 1/1 for kraken test... 22:06:36 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/tmplNpYQw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:06:48 INFO - PROCESS | 1669 | RSS: Main: 249663488 22:06:48 INFO - PROCESS | 1669 | 22:06:51 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 22:06:52 INFO - PROCESS | 1669 | RSS: Main: 261586944 22:06:52 INFO - PROCESS | 1669 | 22:06:55 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 22:06:55 INFO - PROCESS | 1669 | RSS: Main: 266297344 22:06:55 INFO - PROCESS | 1669 | 22:06:59 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 22:06:59 INFO - PROCESS | 1669 | RSS: Main: 266690560 22:06:59 INFO - PROCESS | 1669 | 22:07:01 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 22:07:02 INFO - PROCESS | 1669 | RSS: Main: 267264000 22:07:02 INFO - PROCESS | 1669 | 22:07:04 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:07:05 INFO - PROCESS | 1669 | RSS: Main: 268328960 22:07:05 INFO - PROCESS | 1669 | 22:07:08 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 22:07:08 INFO - PROCESS | 1669 | RSS: Main: 267681792 22:07:08 INFO - PROCESS | 1669 | 22:07:11 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 22:07:12 INFO - PROCESS | 1669 | RSS: Main: 267894784 22:07:12 INFO - PROCESS | 1669 | 22:07:15 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 22:07:16 INFO - PROCESS | 1669 | RSS: Main: 268005376 22:07:16 INFO - PROCESS | 1669 | 22:07:17 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:07:18 INFO - PROCESS | 1669 | RSS: Main: 267939840 22:07:18 INFO - PROCESS | 1669 | 22:07:20 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:07:21 INFO - PROCESS | 1669 | RSS: Main: 267935744 22:07:21 INFO - PROCESS | 1669 | 22:07:23 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:07:24 INFO - PROCESS | 1669 | RSS: Main: 268222464 22:07:24 INFO - PROCESS | 1669 | 22:07:27 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:07:27 INFO - PROCESS | 1669 | RSS: Main: 268226560 22:07:27 INFO - PROCESS | 1669 | 22:07:30 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:07:31 INFO - PROCESS | 1669 | RSS: Main: 268226560 22:07:31 INFO - PROCESS | 1669 | 22:07:33 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 22:07:33 INFO - PROCESS | 1669 | RSS: Main: 270143488 22:07:33 INFO - PROCESS | 1669 | 22:07:33 INFO - PROCESS | 1669 | __start_tp_report 22:07:33 INFO - PROCESS | 1669 | _x_x_mozilla_page_load 22:07:33 INFO - PROCESS | 1669 | _x_x_mozilla_page_load_details 22:07:33 INFO - PROCESS | 1669 | |i|pagename|runs| 22:07:33 INFO - PROCESS | 1669 | |0;ai-astar;117;116;111;119;169;170;121;116;115;116 22:07:33 INFO - PROCESS | 1669 | |1;audio-beat-detection;190;148;139;248;144;156;136;127;164;121 22:07:33 INFO - PROCESS | 1669 | |2;audio-dft;197;211;247;282;264;203;198;196;196;193 22:07:33 INFO - PROCESS | 1669 | |3;audio-fft;67;81;76;168;59;81;51;73;152;81 22:07:33 INFO - PROCESS | 1669 | |4;audio-oscillator;81;84;147;80;83;80;106;98;112;160 22:07:33 INFO - PROCESS | 1669 | |5;imaging-gaussian-blur;100;108;89;87;89;85;109;87;90;87 22:07:33 INFO - PROCESS | 1669 | |6;imaging-darkroom;94;96;95;104;95;95;95;96;95;95 22:07:33 INFO - PROCESS | 1669 | |7;imaging-desaturate;91;92;95;90;90;90;91;91;92;93 22:07:33 INFO - PROCESS | 1669 | |8;json-parse-financial;59;62;61;62;62;60;59;124;63;60 22:07:33 INFO - PROCESS | 1669 | |9;json-stringify-tinderbox;66;66;65;89;94;71;55;67;97;74 22:07:33 INFO - PROCESS | 1669 | |10;stanford-crypto-aes;87;87;92;103;109;113;88;87;84;86 22:07:33 INFO - PROCESS | 1669 | |11;stanford-crypto-ccm;163;147;146;142;150;153;154;142;218;144 22:07:33 INFO - PROCESS | 1669 | |12;stanford-crypto-pbkdf2;175;171;181;234;174;166;172;167;170;173 22:07:33 INFO - PROCESS | 1669 | |13;stanford-crypto-sha256-iterative;68;66;69;69;68;66;66;64;89;90 22:07:33 INFO - PROCESS | 1669 | __end_tp_report 22:07:33 INFO - PROCESS | 1669 | __start_cc_report 22:07:33 INFO - PROCESS | 1669 | _x_x_mozilla_cycle_collect,1075 22:07:33 INFO - PROCESS | 1669 | __end_cc_report 22:07:33 INFO - PROCESS | 1669 | __startTimestamp1462943253347__endTimestamp 22:07:33 INFO - PROCESS | 1669 | 22:07:33 INFO - PROCESS | 1669 | ------- Summary: start ------- 22:07:33 INFO - PROCESS | 1669 | Number of tests: 14 22:07:33 INFO - PROCESS | 1669 | 22:07:33 INFO - PROCESS | 1669 | [#0] ai-astar Cycles:10 Average:127.00 Median:118.00 stddev:22.55 (19.1%) stddev-sans-first:23.62 22:07:33 INFO - PROCESS | 1669 | Values: 117.0 116.0 111.0 119.0 169.0 170.0 121.0 116.0 115.0 116.0 22:07:33 INFO - PROCESS | 1669 | 22:07:33 INFO - PROCESS | 1669 | [#1] audio-beat-detection Cycles:10 Average:157.30 Median:152.00 stddev:37.46 (24.6%) stddev-sans-first:37.82 22:07:33 INFO - PROCESS | 1669 | Values: 190.0 148.0 139.0 248.0 144.0 156.0 136.0 127.0 164.0 121.0 22:07:33 INFO - PROCESS | 1669 | 22:07:33 INFO - PROCESS | 1669 | [#2] audio-dft Cycles:10 Average:218.70 Median:207.00 stddev:32.92 (15.9%) stddev-sans-first:33.97 22:07:33 INFO - PROCESS | 1669 | Values: 197.0 211.0 247.0 282.0 264.0 203.0 198.0 196.0 196.0 193.0 22:07:33 INFO - PROCESS | 1669 | 22:07:33 INFO - PROCESS | 1669 | [#3] audio-fft Cycles:10 Average:88.90 Median:74.50 stddev:38.95 (52.3%) stddev-sans-first:40.50 22:07:33 INFO - PROCESS | 1669 | Values: 67.0 81.0 76.0 168.0 59.0 81.0 51.0 73.0 152.0 81.0 22:07:33 INFO - PROCESS | 1669 | 22:07:33 INFO - PROCESS | 1669 | [#4] audio-oscillator Cycles:10 Average:103.10 Median:80.50 stddev:29.06 (36.1%) stddev-sans-first:29.71 22:07:33 INFO - PROCESS | 1669 | Values: 81.0 84.0 147.0 80.0 83.0 80.0 106.0 98.0 112.0 160.0 22:07:33 INFO - PROCESS | 1669 | 22:07:33 INFO - PROCESS | 1669 | [#5] imaging-gaussian-blur Cycles:10 Average:93.10 Median:87.00 stddev:9.09 (10.4%) stddev-sans-first:9.29 22:07:33 INFO - PROCESS | 1669 | Values: 100.0 108.0 89.0 87.0 89.0 85.0 109.0 87.0 90.0 87.0 22:07:33 INFO - PROCESS | 1669 | 22:07:33 INFO - PROCESS | 1669 | [#6] imaging-darkroom Cycles:10 Average:96.00 Median:95.00 stddev:2.87 (3.0%) stddev-sans-first:2.95 22:07:33 INFO - PROCESS | 1669 | Values: 94.0 96.0 95.0 104.0 95.0 95.0 95.0 96.0 95.0 95.0 22:07:33 INFO - PROCESS | 1669 | 22:07:33 INFO - PROCESS | 1669 | [#7] imaging-desaturate Cycles:10 Average:91.50 Median:91.50 stddev:1.58 (1.7%) stddev-sans-first:1.67 22:07:33 INFO - PROCESS | 1669 | Values: 91.0 92.0 95.0 90.0 90.0 90.0 91.0 91.0 92.0 93.0 22:07:33 INFO - PROCESS | 1669 | 22:07:33 INFO - PROCESS | 1669 | [#8] json-parse-financial Cycles:10 Average:67.20 Median:61.50 stddev:20.00 (32.5%) stddev-sans-first:21.00 22:07:33 INFO - PROCESS | 1669 | Values: 59.0 62.0 61.0 62.0 62.0 60.0 59.0 124.0 63.0 60.0 22:07:33 INFO - PROCESS | 1669 | 22:07:33 INFO - PROCESS | 1669 | [#9] json-stringify-tinderbox Cycles:10 Average:74.40 Median:72.50 stddev:14.06 (19.4%) stddev-sans-first:14.59 22:07:33 INFO - PROCESS | 1669 | Values: 66.0 66.0 65.0 89.0 94.0 71.0 55.0 67.0 97.0 74.0 22:07:33 INFO - PROCESS | 1669 | 22:07:33 INFO - PROCESS | 1669 | [#10] stanford-crypto-aes Cycles:10 Average:93.60 Median:87.00 stddev:10.63 (12.2%) stddev-sans-first:11.00 22:07:33 INFO - PROCESS | 1669 | Values: 87.0 87.0 92.0 103.0 109.0 113.0 88.0 87.0 84.0 86.0 22:07:33 INFO - PROCESS | 1669 | 22:07:33 INFO - PROCESS | 1669 | [#11] stanford-crypto-ccm Cycles:10 Average:155.90 Median:151.50 stddev:22.75 (15.0%) stddev-sans-first:23.99 22:07:33 INFO - PROCESS | 1669 | Values: 163.0 147.0 146.0 142.0 150.0 153.0 154.0 142.0 218.0 144.0 22:07:33 INFO - PROCESS | 1669 | 22:07:33 INFO - PROCESS | 1669 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.30 Median:173.50 stddev:20.02 (11.5%) stddev-sans-first:21.20 22:07:33 INFO - PROCESS | 1669 | Values: 175.0 171.0 181.0 234.0 174.0 166.0 172.0 167.0 170.0 173.0 22:07:33 INFO - PROCESS | 1669 | 22:07:33 INFO - PROCESS | 1669 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.50 Median:68.50 stddev:9.62 (14.0%) stddev-sans-first:10.12 22:07:33 INFO - PROCESS | 1669 | Values: 68.0 66.0 69.0 69.0 68.0 66.0 66.0 64.0 89.0 90.0 22:07:33 INFO - PROCESS | 1669 | -------- Summary: end -------- 22:07:33 INFO - PROCESS | 1669 | 22:07:34 INFO - TEST-INFO | 1669: exit 0 22:07:34 INFO - TEST-OK | kraken | took 61644ms 22:07:34 INFO - SUITE-END | took 1174s 22:07:34 INFO - Completed test suite (00:19:34) 22:07:34 INFO - javascript benchmark 22:07:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3202.4235637585234, 3241.2221066943935, 3265.575475188225, 3499.265154317593, 3512.420450625647, 5748.5688573357065, 5773.48038676519, 5803.448672376123, 5803.6227645203035, 5882.4705776480105, 5686.800961966332, 5763.769449222036, 5811.302643682764, 5818.389069147732, 5893.90962671906, 3720.2001569662516, 4945.6043516518685, 5165.405978312486, 5405.972970135143, 5406.297181366417, 353.261173731517, 5229.843104706865, 5272.604554658396, 5305.522502974732, 5310.946890531084, 5309.309789727349, 5322.973385133088, 5331.920021199685, 5360.222767698681, 5389.946100539023, 5320.840374788762, 5365.0, 5372.382176049726, 5388.973055134699, 5392.272043274186, 5193.454687257859, 5240.318758561362, 5256.500632439912, 5257.737113144328, 5280.445553216934, 4489.528599497038, 5682.545396368322, 5730.713464326809, 5730.7707691692285, 5744.0, 4878.975605121987, 6135.661410523238, 6354.428101470844, 6359.0, 6362.0456931460185, 6251.4061164189325, 6285.9057114143325, 6302.338254483259, 6309.747610095591, 6313.747450101991, 5953.226080609536, 5957.314908785502, 6041.1240370146525, 6047.334793172748, 6091.695415229266, 6170.136180934673, 6249.468795152463, 6268.431794252495, 6346.0, 6349.888769465325, 6170.321264660884, 6172.055802422223, 6174.351693072253, 6174.598651087755, 6185.876282474325, 5146.562542183881, 5277.287566178594, 6225.0, 6255.812325630239, 6423.646699431576, 6241.78153764616, 6272.717727702265, 6297.2128483939505, 6309.400606942333, 6372.808815735535, 6315.305316415191, 6322.9051564226575, 6365.777197798147, 6367.013112967497, 6386.776462823872, 4909.286012360193, 4999.225120106389, 5007.37407824022, 5016.122178618727, 5061.924071138936, 4329.874232699521, 4403.4495688039, 4440.511543730187, 4494.842680506166, 4517.5256598057385, 4855.247436647325, 5441.700706461183, 5843.737031833578, 5952.166696662471, 5975.462208401264, 5642.871425714873, 6289.937100629027, 6325.114483972247, 6336.778212762531, 6357.205349331333, 5794.188813566104, 5870.3249126350165, 5903.793367232126, 5953.851153721105, 6004.519638428916, 5431.321084864392, 5459.508644222039, 5482.17767334903, 5484.862878428071, 5504.834854954358, 6405.519586031066, 6437.742490300336, 6439.935600643934, 6445.838854028687, 6447.516436267299, 6226.470749986302, 6273.278572964167, 6279.905801413074, 6317.178766760292, 6325.493960483058, 6384.712687928962, 6411.551191416556, 6426.0, 6441.259255185713, 6459.031145328238, 6122.733612273255, 6313.337099604475, 6320.957042088004, 6326.810195694136, 6346.587471814318, 6187.566870319034, 6224.346443623535, 6285.402886725754, 6288.308286088618, 6333.113364128933, 6195.070739389128, 6196.659183744938, 6198.690065496617, 6199.876002480015, 6217.4715149211015, 5951.791687290924, 6074.301455332693, 6168.444839964424, 6192.938070619236, 6202.4417802398, 5999.52003839683, 6028.0355143176885, 6099.491378240913, 6117.174181485442, 6131.223750760645, 6189.960561477376, 6222.191115155001, 6232.314445410911, 6260.906086408617, 6263.029230469285, 5956.195913551615, 6058.0, 6080.969595152173, 6094.542909281733, 6140.385961403824], "value": 5704.662199626736, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [512.1140427061184, 513.1712284660274, 550.2736387967883, 560.285635814337, 572.8052462162864, 38609.42085868714, 39381.212375752504, 41458.58541414593, 41504.96237594062, 41575.37636935448, 42223.3666495002, 42860.0, 42934.57065429345, 43102.56897431025, 43294.0, 8710.956445217766, 9542.666006689784, 11950.7609847803, 12169.026477881793, 12190.634280971584, 11876.049916006741, 11904.98807601355, 11943.984761295256, 11959.820602690967, 11968.401579921012, 10944.960228778253, 11220.214584979054, 11963.7607247855, 11967.640970770848, 11975.281483110997, 11884.227525210834, 11912.285262884212, 11939.701507462296, 11955.581554645589, 11967.0, 11450.94274528622, 11825.290482570987, 11871.0, 11949.342786146759, 11975.46110425033, 34739.0, 40807.183856323005, 40907.59092409068, 40921.79539102315, 40973.79513102445, 3389.4576867701294, 3503.8423270952867, 3786.4320351947154, 3820.121372084436, 3826.253880493305, 29352.559711604343, 30002.84998574993, 30089.041743605594, 30613.775448982236, 30771.69228307733, 2820.689724130344, 2847.4732174547776, 2878.8848446062134, 2890.132960111958, 2903.3757742085554, 506586.604803713, 512356.0, 513895.0, 514022.0, 514445.0, 65935.35161620998, 66357.0, 66551.33448665451, 66552.00171997424, 66649.0, 69511.30488695149, 69873.0, 70084.64957675281, 70250.0, 70520.0, 15396.384144634314, 16725.16374181286, 17304.740428893576, 17324.220410081576, 17591.824081759274, 13056.412461439259, 13104.344782760823, 13162.7495387938, 13170.275634840176, 13177.868221317856, 18305.81694183041, 18651.906740466482, 18656.0, 18723.25106995705, 18850.86894786317, 6520.0871877937125, 6672.266050734415, 7023.367896889203, 7109.28907109285, 7176.569405835667, 891.1177933845446, 921.0, 990.5938565188238, 1107.9889201108047, 1167.9357635329968, 23244.535109298056, 30225.244368890955, 30245.546316805267, 30546.0, 31050.844745776125, 1060.8567843341204, 1099.6035035504865, 1163.2264544078262, 1230.4831970572382, 1250.5435516036764, 15559.29983150761, 15712.21438928049, 15785.84765309079, 15849.0, 16032.438864639682, 578956.0, 632241.0, 642724.0, 642778.0, 646993.0, 61206.693966529885, 62779.0, 62891.685541572, 62945.370546294864, 62980.0, 1338.3642769684323, 1366.3100134432048, 1394.9790753138711, 1456.155429850684, 1490.7614781634886, 29667.40665186641, 29832.25419364533, 29877.551836722032, 30399.240018999702, 30601.23496912595, 54913.450865490835, 59376.81246375135, 60683.69658151681, 60981.39018609757, 64827.67586162039, 604976.0, 609069.0, 611943.0, 612988.0, 614101.0, 23232.0, 27053.323666908484, 27151.185464436097, 27191.320216994733, 27945.021924232555, 47227.76386118047, 47262.76368618135, 48376.758116209196, 48398.790030249525, 49205.01589968252, 38975.02562435848, 39003.0, 39071.6092839068, 39464.40803388009, 39845.0, 37939.86180414592, 38047.61952380441, 38595.2280954385, 38798.41802373023, 38979.830605081894, 37450.06374840651, 37974.43038354372, 38288.61711382962, 38743.0, 38873.41689874598, 26949.86525067362, 33144.50283245801, 34127.658723412445, 34419.827900860335, 34669.47995780015, 19661.410157695293, 22062.558748825253, 22607.09571617117, 24890.50218995646, 25287.2991888027], "value": 20863.087355936506, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.3462312034297, 397.61828644501276, 403.92325458162964, 404.02226611599934, 420.13242653919656, 19959.600807983832, 20449.0, 21131.788682113194, 21230.938453077342, 21386.893065534652, 18903.90548047258, 19032.524186895334, 20706.0, 21483.570328593458, 21534.13863445464, 5661.5187709044785, 6047.183630209931, 6064.151018857364, 6181.07283907413, 6210.50315974721, 5647.943520564803, 5924.644521328734, 6040.57715959883, 6043.909341359883, 6077.361877002917, 5923.466887980081, 6052.455279024887, 6059.636421814683, 6101.969490152543, 6105.572609917308, 6104.4200800923845, 6524.510661700368, 6575.53971222017, 6635.8009259722285, 6642.501812364044, 4183.518895327015, 5429.8099566515375, 5487.670739755627, 5561.443855614447, 5894.734736936848, 13274.738899804503, 13719.588412347646, 13943.0, 13961.650958726012, 13979.161250325013, 2002.8797624445092, 2166.166026079955, 2210.7457642371173, 2243.5512897420417, 2246.2025980776916, 9220.8616870747, 9556.569954352071, 9888.703338899844, 10290.382577045326, 10344.637937672145, 1708.1886104100577, 1712.383541924906, 1718.7593736876722, 1753.3424965637885, 1772.5480002599384, 104116.47941760319, 127798.3610081953, 134279.32860335734, 134988.0, 134994.65005349918, 11958.461869215906, 12083.395830208454, 12083.637490875288, 12100.0, 12243.265404075784, 15192.848071519364, 15562.844371556366, 15636.983596066219, 15638.9218053909, 15701.0, 8781.780455488664, 8822.647094116164, 8841.823163536694, 8842.778930526789, 9018.774530636787, 7046.189688185822, 8548.74353769388, 8654.78363040929, 8765.386422950332, 8786.516741579275, 3800.7339486235696, 4309.715850599187, 4397.934030989538, 4666.533346665374, 4781.928271075936, 1697.414392034746, 2006.4783156379156, 2054.116729806198, 2058.217877206652, 2084.8123668869875, 865.9177378149066, 880.5421180985851, 902.6163880350824, 933.8972713001565, 983.1790454970037, 14963.326650300764, 17118.743218851727, 21280.574388512454, 21426.571468570542, 21440.142394304366, 969.2924165359186, 988.6342053440129, 1015.9992407478628, 1021.6322124035341, 1091.732525531234, 9293.488858112734, 10553.683389498327, 11299.661010169708, 11319.886801132048, 11334.376609286404, 545398.2730086325, 546574.0, 553936.0, 569230.0, 616056.0, 80676.59661701653, 84168.0, 129179.35410323075, 131272.0, 131994.34002829925, 984.0995489127512, 1000.6197644894893, 1017.6997785653102, 1019.3221507697447, 1021.2953062386929, 19024.52438689044, 19217.8078219216, 19319.323823666105, 19474.902625486782, 19502.707459387835, 35868.28263434769, 49652.75173624109, 53197.93604127973, 54560.18159727528, 56727.43272567387, 55041.0, 80528.19471805207, 81264.18735812567, 81708.59145704233, 82121.35757284702, 6422.871542569216, 7248.60132692707, 7562.0925488941675, 7615.771526854203, 8516.70191543293, 7106.751263705745, 8058.3956203283815, 9159.175674189355, 13025.804612930624, 13180.868191317964, 11029.61396351124, 11160.181427548496, 11213.66359009231, 11276.379799111128, 11551.653450396501, 9379.953100234456, 11166.436698862328, 11291.43542822872, 11412.942935285271, 11461.369624670722, 10045.799084018425, 10893.836592451278, 11489.712757181138, 11558.133140014364, 11812.586559470721, 6991.811392063463, 7628.504147230412, 7745.264199901, 7810.960945195237, 8000.239977202156, 9010.954945225232, 10769.353838769699, 11444.427778611203, 11491.080713543064, 11540.94229528847], "value": 9312.883649631964, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1605.7591361295808, 1639.6228867360508, 1892.4228110426313, 1937.2638397409, 1948.658984677681, 8035.6383962721675, 8495.617697203625, 8547.10255423181, 8954.0, 8991.775205619862, 6962.373386395224, 8672.219500244977, 8880.95559522203, 8895.154960278764, 8901.510416927067, 2263.864168149912, 2401.735809061002, 2428.623563347684, 2442.426029882976, 2449.2652204338715, 2414.5774489464275, 2417.806575473967, 2441.792447641944, 2444.9144279950206, 2450.4363996280867, 2390.3187591536416, 2434.2453839309783, 2435.3789783605157, 2440.511897620483, 2444.7799698027175, 2050.1799280288005, 2289.370423133635, 2418.9153379631716, 2420.2739178246543, 2433.367324495635, 2003.5091402606454, 2303.1593468384085, 2346.378209774411, 2394.389430695179, 2411.843230190032, 8712.651493940235, 8733.7816554586, 8839.29285657146, 8914.28685705142, 8931.73204803857, 5306.442823503515, 5395.892082158374, 5418.729063546807, 5466.972665136688, 5488.17677348397, 3126.4685003549225, 3590.120420496969, 3630.056185391817, 3652.3243200008083, 3664.029032306441, 1038.3302769713569, 1059.4861492176287, 1061.9681409557725, 1065.2649671726483, 1065.8134826405426, 10008.549615267331, 11356.261842980262, 11397.715057123638, 11724.120690948213, 11744.588939387166, 4781.258904869751, 4784.179463956975, 4895.167821470287, 4916.0905232532095, 4928.507149285043, 9166.450012999241, 9606.423614583147, 9992.850107248398, 9995.000499950092, 10002.0, 5326.280952071498, 5393.892122157535, 5412.134058550613, 5434.804343044524, 5438.129899216186, 4218.240716671029, 4824.204006338985, 4912.754362281943, 4956.77694503741, 4960.627952903475, 4075.8369665213654, 4084.357040141743, 4123.97938010308, 4144.7513149211145, 4159.10579225462, 1184.224333061846, 1345.273671356101, 1362.1214316765631, 1388.3682924269433, 1398.8181536400407, 828.6809578312273, 838.1618381618382, 910.526526206369, 919.7148883846029, 950.5674917912471, 5786.855328616818, 5889.823305300848, 5985.760569577255, 6032.004719221367, 6141.634008137626, 445.44319600499375, 463.82142874992695, 469.0712389468871, 469.53046953046953, 477.4604696692715, 3954.169624378855, 4006.1787333596544, 4068.613481719232, 4114.6091121343425, 4121.443605113332, 8544.57277136153, 10301.948490257651, 10380.273380863267, 10589.311694739818, 10832.187585931088, 10672.786544269227, 10759.83860242097, 10799.946000269949, 10882.347059176474, 10886.29239099456, 7940.007499062617, 8214.548199849065, 8224.465409748227, 8263.834723305501, 8276.337892968548, 5768.855778605568, 5859.5019423347785, 5919.556033297606, 5947.31605865331, 6021.9397806023135, 9728.562214700498, 9739.415635061636, 9827.705168844946, 10056.0, 10230.23273254494, 9969.003099689973, 10015.799684006424, 10198.490075496047, 10391.948040260053, 10408.323458975143, 6877.89683154743, 7433.405327573673, 7522.887156692764, 7603.973463582345, 7635.847283054419, 8501.659933602834, 8504.914950850412, 8546.401751877309, 8554.973403191656, 8574.485530868167, 7393.445491588045, 7562.432817538596, 7581.658825352762, 7588.392928565591, 7682.961585192038, 7365.52124111931, 7392.630368481447, 7420.257974202536, 7467.626618669154, 7499.287567681062, 7035.964820176071, 7111.324424179695, 7175.174854891754, 7198.100237470317, 7299.197088320174, 4144.7513149211145, 4811.109944660249, 4960.801567937387, 5055.797768089383, 5064.215046667772, 5018.397792264952, 5043.646944713982, 5075.720835354091, 5081.8475445736685, 5111.386633603991], "value": 4538.638324549967, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [318.8756385009847, 319.0762741862309, 322.79663811798565, 323.6456080591751, 328.18446161289205, 5337.70642614657, 5439.211314359415, 5547.44525547445, 5601.523870471015, 5612.438756124386, 5301.648079739661, 5408.513233808966, 5495.945040549594, 5498.487915823151, 5516.531094856932, 4012.458318127052, 4036.8183431745642, 4101.610347017028, 4112.650424713903, 4212.220739163265, 3363.327334533091, 3806.373602214477, 4047.8380864765522, 4173.24881521326, 4191.497020357561, 3956.4065390191413, 3963.7910437316605, 4107.342825147977, 4240.982164280581, 4251.193242871783, 3618.2582570572968, 3708.629137086297, 4123.361048928688, 4143.585641435862, 4245.660347172219, 3063.5404689296784, 3551.8456501636815, 3553.022918697353, 3562.536870206882, 3577.284543091392, 4624.676272660915, 4762.476127625959, 4862.41651001882, 5291.523762861322, 5296.788128474856, 4428.269335559628, 4513.7517436540975, 4734.597559207472, 4760.619150467955, 4783.904321913577, 5088.720120393377, 5340.385855626575, 5765.827025189251, 5775.660857352779, 5786.42135786418, 3694.6674799268194, 3947.4473573699706, 3958.8218530166214, 3976.284268831609, 3979.6816254699556, 5877.56162644849, 6052.788152414644, 6060.787872424488, 6113.877722445571, 6165.876682466325, 3964.306246406867, 3964.4053391991433, 3975.4434379186937, 3991.341428664238, 3994.3009973254566, 3102.7672924530752, 3137.490007993596, 3586.8923932282073, 3740.2696031896867, 3748.437734339871, 4171.6036976487185, 4475.664325175625, 4738.620910327166, 4743.454502732161, 4774.928376074362, 4206.0, 4817.843717507772, 5580.91628625571, 5829.889927601031, 5870.503021729432, 1245.7044673539417, 1338.4177882620897, 1348.885344745688, 1365.0922136778931, 1376.0230236531975, 755.4334249313015, 801.6452195789097, 852.3224036890563, 860.5740158621397, 903.4579252448478, 5335.33308336458, 5352.199938044032, 5365.892682146336, 5376.677399356051, 5391.169635941163, 5810.738516766755, 5853.824385268449, 5856.385079566669, 5898.852528686817, 5901.291844978544, 4654.86035418938, 4854.441739199967, 5278.917821846512, 5331.226972089025, 5573.860653483614, 2727.9727202727718, 2760.3513174403997, 3139.7959132656306, 3152.5586417901513, 3184.601924759405, 5718.513926316226, 5763.250777398911, 5927.873703996227, 6031.366706495843, 6308.558400911892, 5218.739063046833, 5735.1970724098655, 6136.9079463808075, 6169.8457538561, 6182.536309776785, 5267.183586544092, 5756.438384918754, 5946.672932988728, 6274.215723034621, 6305.572354643496, 5251.6323857331145, 5481.6287982891035, 5814.127880817911, 6068.332483426731, 6124.846878828065, 5357.624966252325, 5383.380911195262, 5930.0, 5955.731992060281, 5994.850128746816, 5330.173823057432, 5778.422157784188, 5857.384974577608, 6165.506759459143, 6215.533834962305, 3391.2878295558207, 3622.9275414491035, 3742.812859357076, 3933.8623148189677, 3960.861369852041, 5029.471905450021, 5943.762249510144, 6015.218021657157, 6036.6679832609625, 6065.878682426415, 4833.468318484894, 5083.771230294719, 5473.28847249855, 5626.396477004034, 5716.957452567759, 4775.6657034007285, 4885.682430641997, 5806.912896306643, 5807.680577568105, 5820.621659592113, 4632.591052652534, 4835.492273311252, 5609.990201763721, 5649.785296161345, 5692.857678558069, 5163.97907224278, 5675.77296908119, 5746.281714785652, 5798.246227990334, 5803.535717142703, 4852.762545550862, 5270.235815806764, 5367.946320536745, 5731.140328950691, 5770.730439303346], "value": 4159.26362810614, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [655.7344275568395, 680.3910500102412, 690.9654517274136, 696.7665831946299, 704.3308856586244, 14952.327145278461, 15043.623909402271, 15233.771493427608, 15256.0, 15437.536873893772, 14091.295435228249, 14250.432452430225, 14329.283535823219, 14349.210793406384, 14352.210628415432, 704.6582407532368, 885.0618344554761, 887.9778005549881, 902.5397047505786, 903.8779764731743, 908.309684639672, 911.9224865886407, 912.3476714149382, 913.7441516375392, 914.2685851318919, 953.1612181280464, 953.7234202081388, 954.4416516337951, 957.5068839547639, 957.8419560772498, 922.5571725571696, 934.7195841247667, 940.9294302927274, 952.7189479103681, 958.1616085924851, 678.0913575808391, 685.9382655560973, 700.5201437015661, 700.8458139209416, 702.2275496953362, 14465.49370772018, 14480.131192128507, 14616.70766584673, 15043.699126017418, 15054.623634409118, 12596.181248218836, 12626.81059784104, 12650.557230496981, 12744.553940612126, 12745.0, 9049.457032578066, 9344.345895787297, 10023.498825058718, 10306.072453479148, 10515.316504427188, 3495.1436897959907, 3499.842507087187, 3503.7197024238, 3546.078019714893, 3584.0, 65851.67074164646, 77089.84365234525, 79284.0, 80175.39649207042, 83817.74273385904, 9318.394304370195, 9403.200727938065, 9605.567749451291, 9677.322587418954, 9739.954609531937, 21872.23447179341, 22090.558188836134, 22109.33671989843, 22227.22204722859, 22237.11051557952, 7198.388137008413, 7233.927660723431, 7252.456065795086, 7317.0487836580905, 7347.375473084848, 3299.9175020624675, 4020.3768415895584, 4029.5164580250557, 4056.0671045659665, 4071.572484889103, 3287.6520626543, 3392.9830350848583, 3407.6251612322626, 3408.454647256427, 3411.7611767176554, 551.5587529976003, 629.6379581740517, 629.9433051025429, 636.2238069555135, 644.0821828893808, 525.5952916254463, 574.4657468554285, 584.6024703201779, 597.88042391521, 612.9579714485393, 6285.371462853769, 7303.561786292839, 7322.048133742686, 7375.225601311785, 7511.322542400575, 524.8530411484791, 530.2417542913594, 550.2269311617184, 575.8301301116181, 611.4252602553586, 3758.9248215035545, 3786.5456145262747, 3853.556840963325, 3921.784301863425, 3925.2345792570177, 92804.0, 94772.0, 95142.0, 96619.51690241504, 98081.0, 82827.58586207031, 82926.17073829305, 84835.15164848273, 85564.71652925211, 86438.67806609644, 68474.3152568478, 69689.65155174192, 70554.29445705477, 70621.0, 75340.0, 7655.770326890202, 7677.347425468898, 7851.60741962911, 7897.526148431112, 7900.012498437695, 37159.81420092882, 44470.88822779457, 44854.10291794081, 45013.549864500936, 45046.0, 45872.770636146604, 58890.055497225825, 60919.69540152271, 61626.0756088668, 63018.0, 10229.97700229971, 10351.171906247331, 10385.688429347132, 10823.0, 11033.227674062739, 10756.247062705535, 10990.450477475933, 11378.03286720638, 11383.26008809458, 11662.70843228926, 10228.590856365661, 10429.739256518647, 10898.836517452086, 10908.945455272673, 11016.834747478633, 10222.28444008942, 10605.41670208146, 10606.257561970588, 10658.467076645982, 10764.3541387517, 8598.527081010605, 10043.347182433119, 10372.066514013775, 10555.89444105549, 10572.84140737874, 8717.91282087171, 8803.471791692518, 8845.867311990454, 8881.245094167069, 8945.37382383227, 7609.771706848804, 7917.604119793872, 8662.307015438624, 8719.12808719123, 9129.087091290818], "value": 6666.869792923424, "unit": "score"}], "name": "dromaeo_css", "value": 7201.682074367372, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 111.0, 119.0, 169.0, 170.0, 121.0, 116.0, 115.0, 116.0], "value": 127.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [190.0, 148.0, 139.0, 248.0, 144.0, 156.0, 136.0, 127.0, 164.0, 121.0], "value": 157.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 211.0, 247.0, 282.0, 264.0, 203.0, 198.0, 196.0, 196.0, 193.0], "value": 218.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 81.0, 76.0, 168.0, 59.0, 81.0, 51.0, 73.0, 152.0, 81.0], "value": 88.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 84.0, 147.0, 80.0, 83.0, 80.0, 106.0, 98.0, 112.0, 160.0], "value": 103.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 108.0, 89.0, 87.0, 89.0, 85.0, 109.0, 87.0, 90.0, 87.0], "value": 93.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 96.0, 95.0, 104.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0], "value": 96.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 92.0, 95.0, 90.0, 90.0, 90.0, 91.0, 91.0, 92.0, 93.0], "value": 91.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 62.0, 61.0, 62.0, 62.0, 60.0, 59.0, 124.0, 63.0, 60.0], "value": 67.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 65.0, 89.0, 94.0, 71.0, 55.0, 67.0, 97.0, 74.0], "value": 74.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 92.0, 103.0, 109.0, 113.0, 88.0, 87.0, 84.0, 86.0], "value": 93.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 147.0, 146.0, 142.0, 150.0, 153.0, 154.0, 142.0, 218.0, 144.0], "value": 155.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 171.0, 181.0, 234.0, 174.0, 166.0, 172.0, 167.0, 170.0, 173.0], "value": 178.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 69.0, 69.0, 68.0, 66.0, 66.0, 64.0, 89.0, 90.0], "value": 71.5, "unit": "score"}], "name": "kraken", "value": 1616.5, "alertThreshold": 2.0}]} 22:07:34 INFO - Return code: 0 22:07:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 22:07:34 ERROR - # TBPL SUCCESS # 22:07:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:07:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:07:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:07:34 INFO - 2016-05-10 22:07:34.663 screenresolution[1674:10296] starting screenresolution argv=screenresolution get 22:07:34 INFO - 2016-05-10 22:07:34.666 screenresolution[1674:10296] Display 0: 1600x1200x32@60 22:07:34 INFO - 2016-05-10 22:07:34.671 screenresolution[1675:10299] starting screenresolution argv=screenresolution list 22:07:34 INFO - Available Modes on Display 0 22:07:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:07:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:07:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:07:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:07:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:07:34 INFO - Intel Iris: 22:07:34 INFO - Chipset Model: Intel Iris 22:07:34 INFO - Type: GPU 22:07:34 INFO - Bus: Built-In 22:07:34 INFO - VRAM (Dynamic, Max): 1536 MB 22:07:34 INFO - Vendor: Intel (0x8086) 22:07:34 INFO - Device ID: 0x0a2e 22:07:34 INFO - Revision ID: 0x0009 22:07:34 INFO - Displays: 22:07:34 INFO - Display: 22:07:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:07:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:07:34 INFO - Display Serial Number: 1600x1200 60 22:07:34 INFO - Main Display: Yes 22:07:34 INFO - Mirror: Off 22:07:34 INFO - Online: Yes 22:07:34 INFO - Rotation: Supported 22:07:34 INFO - Return code: 0 22:07:34 INFO - Running post-action listener: _resource_record_post_action 22:07:34 INFO - ##### 22:07:34 INFO - ##### Finished run-tests step (success) 22:07:34 INFO - ##### 22:07:34 INFO - Running post-run listener: _resource_record_post_run 22:07:35 INFO - Total resource usage - Wall time: 1210s; CPU: 55.0%; Read bytes: 37406208; Write bytes: 696061440; Read time: 422; Write time: 2138 22:07:35 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 393359872; Write bytes: 383325696; Read time: 30008; Write time: 774 22:07:35 INFO - run-tests - Wall time: 1177s; CPU: 55.0%; Read bytes: 30363136; Write bytes: 297535488; Read time: 308; Write time: 1329 22:07:35 INFO - Running post-run listener: _upload_blobber_files 22:07:35 INFO - Blob upload gear active. 22:07:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:07:35 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 22:07:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:07:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:07:36 INFO - (blobuploader) - INFO - Open directory for files ... 22:07:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 22:07:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:07:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:07:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 22:07:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:07:37 INFO - (blobuploader) - INFO - Done attempting. 22:07:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 22:07:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:07:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:07:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 22:07:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:07:38 INFO - (blobuploader) - INFO - Done attempting. 22:07:38 INFO - (blobuploader) - INFO - Iteration through files over. 22:07:38 INFO - Return code: 0 22:07:38 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:07:38 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:07:38 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e929af17596c9d0963f362797b81d0b49af08beabb0fe66ed860dc4ac67a7c1c963d04ed74ac0b877c721fa34669ab0b2528104135a148581a3efe67468a1f81", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f8d3e06ca00636cee262effbf588c73c0226c3166e0a2e666c41ffe98283c98445ef88b13b74a17cd8223809ffa3424ef78831ebe2327418a4725b058a18072f"} 22:07:38 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:07:38 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:07:38 INFO - Contents: 22:07:38 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e929af17596c9d0963f362797b81d0b49af08beabb0fe66ed860dc4ac67a7c1c963d04ed74ac0b877c721fa34669ab0b2528104135a148581a3efe67468a1f81", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f8d3e06ca00636cee262effbf588c73c0226c3166e0a2e666c41ffe98283c98445ef88b13b74a17cd8223809ffa3424ef78831ebe2327418a4725b058a18072f"} 22:07:38 INFO - Running post-run listener: copy_logs_to_upload_dir 22:07:38 INFO - Copying logs to upload dir... 22:07:38 INFO - mkdir: /builds/slave/test/build/upload/logs 22:07:38 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.324940 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-05-10 22:07:38.556722) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-10 22:07:38.560452) ========= 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.ZiE1QBBzh9/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.iLALYGnLw2/Listeners TMPDIR=/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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/Fx-Team/sha512/e929af17596c9d0963f362797b81d0b49af08beabb0fe66ed860dc4ac67a7c1c963d04ed74ac0b877c721fa34669ab0b2528104135a148581a3efe67468a1f81", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f8d3e06ca00636cee262effbf588c73c0226c3166e0a2e666c41ffe98283c98445ef88b13b74a17cd8223809ffa3424ef78831ebe2327418a4725b058a18072f"} build_url:https://queue.taskcluster.net/v1/task/fmuKbuH2SMy0uOQUcJEXNw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011094 build_url: 'https://queue.taskcluster.net/v1/task/fmuKbuH2SMy0uOQUcJEXNw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e929af17596c9d0963f362797b81d0b49af08beabb0fe66ed860dc4ac67a7c1c963d04ed74ac0b877c721fa34669ab0b2528104135a148581a3efe67468a1f81", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f8d3e06ca00636cee262effbf588c73c0226c3166e0a2e666c41ffe98283c98445ef88b13b74a17cd8223809ffa3424ef78831ebe2327418a4725b058a18072f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-10 22:07:38.588321) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 22:07:38.588635) ========= 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.ZiE1QBBzh9/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.iLALYGnLw2/Listeners TMPDIR=/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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.004882 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 22:07:38.636996) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-10 22:07:38.637334) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-10 22:07:38.637647) ========= ========= Total master_lag: 0.15 =========