additional logging around startup

go/bind/keybase.go

@@ -524,6 +524,7 @@ func ReadArr() (data []byte, err error) {
 // ensureConnection establishes the loopback connection if not already connected.
 // Must be called with connMutex held.
 func ensureConnection() error {
+	start := time.Now()
 	if !isInited() {
 		return errors.New("keybase not initialized")
 	}
@@ -536,7 +537,7 @@ func ensureConnection() error {
 	if err != nil {
 		return fmt.Errorf("Failed to dial loopback listener: %s", err)
 	}
-	log("Go: Established loopback connection")
+	log("Go: Established loopback connection in %v", time.Since(start))
 	return nil
 }
 

protocol/yarn.lock

@@ -17,7 +17,7 @@ amdefine@>=0.0.4:
   resolved "https://registry.yarnpkg.com/amdefine/-/amdefine-1.0.1.tgz#4a5282ac164729e93619bcfd3ad151f817ce91f5"
   integrity sha1-SlKCrBZHKek2Gbz9OtFR+BfOkfU=
 
-"avdl-compiler@github:keybase/node-avdl-compiler#master":
+"avdl-compiler@github:keybase/node-avdl-compiler#7fe780ac783fa214e2d0f54d8f48ea490e8c1612":
   version "1.4.10"
   resolved "https://codeload.github.com/keybase/node-avdl-compiler/tar.gz/7fe780ac783fa214e2d0f54d8f48ea490e8c1612"
   dependencies:

shared/constants/daemon/index.tsx

@@ -107,8 +107,11 @@ export const useDaemonState = Z.createZustand<State>((set, get) => {
         const name = 'config.getBootstrapStatus'
         const {wait} = get().dispatch
         wait(name, version, true)
+        const t = Date.now()
+        logger.info('[Bootstrap] loadDaemonBootstrapStatus: starting')
         try {
           await get().dispatch.loadDaemonBootstrapStatus()
+          logger.info(`[Bootstrap] loadDaemonBootstrapStatus: done in ${Date.now() - t}ms`)
           storeRegistry.getState('dark-mode').dispatch.loadDarkPrefs()
           storeRegistry.getState('chat').dispatch.loadStaticConfig()
         } finally {
@@ -303,6 +306,8 @@ export const useDaemonState = Z.createZustand<State>((set, get) => {
           s.handshakeWaiters.set(name, newCount)
         }
       })
+      const remaining = get().handshakeWaiters.size
+      logger.info(`[Bootstrap] waiter ${increment ? '+' : '-'} ${name} v${version}, remaining: ${remaining}`)
 
       if (failedFatal) {
         get().dispatch.setFailed(failedReason || '')

shared/ios/Keybase/AppDelegate.swift

@@ -408,6 +408,8 @@ public class AppDelegate: ExpoAppDelegate, UNUserNotificationCenterDelegate, UID
   }
 
   public override func applicationDidBecomeActive(_ application: UIApplication) {
+    let elapsed = CFAbsoluteTimeGetCurrent() - AppDelegate.appStartTime
+    writeStartupTimingLog(String(format: "applicationDidBecomeActive: %.1fms after launch", elapsed * 1000))
     NSLog("applicationDidBecomeActive: hiding keyz screen.")
     hideCover()
     NSLog("applicationDidBecomeActive: notifying service.")

shared/router-v2/router.native.tsx

@@ -182,6 +182,9 @@ const RNApp = React.memo(function RNApp() {
   const rootKey = Hooks.useRootKey()
 
   if (initialStateState !== 'loaded' || !loggedInLoaded) {
+    logger.info(
+      `[Router] showing SimpleLoading: initialStateState=${initialStateState} loggedInLoaded=${loggedInLoaded}`
+    )
     return (
       <Kb.Box2 direction="vertical" style={Kb.Styles.globalStyles.fillAbsolute}>
         <Shared.SimpleLoading />