{"level":"warn","ts":"2026-05-27T05:55:44.846674Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-27T05:55:44.846746Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.196:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.196:2380","--initial-cluster=instance=https://199.19.213.196:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.196:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.196:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-27T05:55:44.846805Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-27T05:55:44.846816Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.196:2380"]} {"level":"info","ts":"2026-05-27T05:55:44.846834Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-27T05:55:44.847477Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.196:2379"]} {"level":"info","ts":"2026-05-27T05:55:44.847569Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.196:2380"],"listen-peer-urls":["https://199.19.213.196:2380"],"advertise-client-urls":["https://199.19.213.196:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.196:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.196:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-27T05:55:44.851326Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.549063ms"} {"level":"info","ts":"2026-05-27T05:55:44.862159Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"8f376d4d42da413a","cluster-id":"f5ef03c8da56d984"} {"level":"info","ts":"2026-05-27T05:55:44.862294Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8f376d4d42da413a switched to configuration voters=()"} {"level":"info","ts":"2026-05-27T05:55:44.862359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8f376d4d42da413a became follower at term 0"} {"level":"info","ts":"2026-05-27T05:55:44.862419Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8f376d4d42da413a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-27T05:55:44.862464Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8f376d4d42da413a became follower at term 1"} {"level":"info","ts":"2026-05-27T05:55:44.862533Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8f376d4d42da413a switched to configuration voters=(10319837249744290106)"} {"level":"warn","ts":"2026-05-27T05:55:44.871385Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-27T05:55:44.873095Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-27T05:55:44.87487Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-27T05:55:44.877085Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"8f376d4d42da413a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-27T05:55:44.882445Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-27T05:55:44.882567Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-27T05:55:44.882618Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-27T05:55:44.882529Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8f376d4d42da413a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-27T05:55:44.882954Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8f376d4d42da413a switched to configuration voters=(10319837249744290106)"} {"level":"info","ts":"2026-05-27T05:55:44.883059Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f5ef03c8da56d984","local-member-id":"8f376d4d42da413a","added-peer-id":"8f376d4d42da413a","added-peer-peer-urls":["https://199.19.213.196:2380"]} {"level":"info","ts":"2026-05-27T05:55:44.883823Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-27T05:55:44.884121Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.196:2380"} {"level":"info","ts":"2026-05-27T05:55:44.884144Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.196:2380"} {"level":"info","ts":"2026-05-27T05:55:44.885282Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"8f376d4d42da413a","initial-advertise-peer-urls":["https://199.19.213.196:2380"],"listen-peer-urls":["https://199.19.213.196:2380"],"advertise-client-urls":["https://199.19.213.196:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.196:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-27T05:55:44.885335Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-27T05:55:44.963102Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8f376d4d42da413a is starting a new election at term 1"} {"level":"info","ts":"2026-05-27T05:55:44.963148Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8f376d4d42da413a became pre-candidate at term 1"} {"level":"info","ts":"2026-05-27T05:55:44.963179Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8f376d4d42da413a received MsgPreVoteResp from 8f376d4d42da413a at term 1"} {"level":"info","ts":"2026-05-27T05:55:44.963197Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8f376d4d42da413a became candidate at term 2"} {"level":"info","ts":"2026-05-27T05:55:44.963208Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8f376d4d42da413a received MsgVoteResp from 8f376d4d42da413a at term 2"} {"level":"info","ts":"2026-05-27T05:55:44.963223Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8f376d4d42da413a became leader at term 2"} {"level":"info","ts":"2026-05-27T05:55:44.963235Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8f376d4d42da413a elected leader 8f376d4d42da413a at term 2"} {"level":"info","ts":"2026-05-27T05:55:44.964269Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T05:55:44.964984Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"8f376d4d42da413a","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.196:2379]}","request-path":"/0/members/8f376d4d42da413a/attributes","cluster-id":"f5ef03c8da56d984","publish-timeout":"7s"} {"level":"info","ts":"2026-05-27T05:55:44.965024Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-27T05:55:44.965405Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-27T05:55:44.965868Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-27T05:55:44.965943Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-27T05:55:44.968744Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f5ef03c8da56d984","local-member-id":"8f376d4d42da413a","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T05:55:44.968831Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T05:55:44.968861Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T05:55:44.969033Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.196:2379"} {"level":"info","ts":"2026-05-27T05:55:44.970376Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} 2026/05/27 05:55:49 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-05-27T05:55:49.928427Z","caller":"traceutil/trace.go:171","msg":"trace[1813889108] transaction","detail":"{read_only:false; response_revision:238; number_of_response:1; }","duration":"250.769346ms","start":"2026-05-27T05:55:49.677637Z","end":"2026-05-27T05:55:49.928407Z","steps":["trace[1813889108] 'process raft request' (duration: 250.672895ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T05:55:50.219118Z","caller":"traceutil/trace.go:171","msg":"trace[1268410362] linearizableReadLoop","detail":"{readStateIndex:245; appliedIndex:244; }","duration":"349.280037ms","start":"2026-05-27T05:55:49.869813Z","end":"2026-05-27T05:55:50.219093Z","steps":["trace[1268410362] 'read index received' (duration: 58.639025ms)","trace[1268410362] 'applied index is now lower than readState.Index' (duration: 290.639612ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T05:55:50.219363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.48547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/\" range_end:\"/registry/deployments/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T05:55:50.21945Z","caller":"traceutil/trace.go:171","msg":"trace[888730732] range","detail":"{range_begin:/registry/deployments/kube-system/; range_end:/registry/deployments/kube-system0; response_count:0; response_revision:239; }","duration":"349.652092ms","start":"2026-05-27T05:55:49.869777Z","end":"2026-05-27T05:55:50.219429Z","steps":["trace[888730732] 'agreement among raft nodes before linearized reading' (duration: 349.460259ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T05:55:50.219518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T05:55:49.869761Z","time spent":"349.738494ms","remote":"127.0.0.1:44520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/kube-system/\" range_end:\"/registry/deployments/kube-system0\" "} {"level":"info","ts":"2026-05-27T05:55:50.363496Z","caller":"traceutil/trace.go:171","msg":"trace[1350405232] transaction","detail":"{read_only:false; response_revision:240; number_of_response:1; }","duration":"419.327038ms","start":"2026-05-27T05:55:49.944141Z","end":"2026-05-27T05:55:50.363468Z","steps":["trace[1350405232] 'process raft request' (duration: 417.106706ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T05:55:50.363604Z","caller":"traceutil/trace.go:171","msg":"trace[871040644] transaction","detail":"{read_only:false; number_of_response:0; response_revision:240; }","duration":"298.41825ms","start":"2026-05-27T05:55:50.065161Z","end":"2026-05-27T05:55:50.363579Z","steps":["trace[871040644] 'process raft request' (duration: 298.226618ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T05:55:50.363765Z","caller":"traceutil/trace.go:171","msg":"trace[1901720506] linearizableReadLoop","detail":"{readStateIndex:252; appliedIndex:245; }","duration":"144.543238ms","start":"2026-05-27T05:55:50.219202Z","end":"2026-05-27T05:55:50.363745Z","steps":["trace[1901720506] 'read index received' (duration: 142.033581ms)","trace[1901720506] 'applied index is now lower than readState.Index' (duration: 2.508527ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T05:55:50.363891Z","caller":"traceutil/trace.go:171","msg":"trace[1102201437] transaction","detail":"{read_only:false; number_of_response:0; response_revision:242; }","duration":"250.411784ms","start":"2026-05-27T05:55:50.113468Z","end":"2026-05-27T05:55:50.36388Z","steps":["trace[1102201437] 'process raft request' (duration: 250.241841ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T05:55:50.363898Z","caller":"traceutil/trace.go:171","msg":"trace[1031349266] transaction","detail":"{read_only:false; response_revision:242; number_of_response:1; }","duration":"298.265238ms","start":"2026-05-27T05:55:50.065603Z","end":"2026-05-27T05:55:50.363868Z","steps":["trace[1031349266] 'process raft request' (duration: 298.063805ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T05:55:50.364182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.151046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/coredns\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T05:55:50.364232Z","caller":"traceutil/trace.go:171","msg":"trace[1930797636] transaction","detail":"{read_only:false; number_of_response:0; response_revision:241; }","duration":"298.768565ms","start":"2026-05-27T05:55:50.065458Z","end":"2026-05-27T05:55:50.364226Z","steps":["trace[1930797636] 'process raft request' (duration: 298.135356ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T05:55:50.364219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.883936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-05-27T05:55:50.364249Z","caller":"traceutil/trace.go:171","msg":"trace[378231141] range","detail":"{range_begin:/registry/configmaps/kube-system/coredns; range_end:; response_count:0; response_revision:242; }","duration":"136.217947ms","start":"2026-05-27T05:55:50.228013Z","end":"2026-05-27T05:55:50.364231Z","steps":["trace[378231141] 'agreement among raft nodes before linearized reading' (duration: 136.106765ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T05:55:50.364268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T05:55:49.944133Z","time spent":"419.458791ms","remote":"127.0.0.1:44128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T05:55:50.36433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.513362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T05:55:50.364358Z","caller":"traceutil/trace.go:171","msg":"trace[2064959278] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:242; }","duration":"415.540202ms","start":"2026-05-27T05:55:49.948809Z","end":"2026-05-27T05:55:50.36435Z","steps":["trace[2064959278] 'agreement among raft nodes before linearized reading' (duration: 415.493962ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T05:55:50.364389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T05:55:49.948791Z","time spent":"415.591093ms","remote":"127.0.0.1:44326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"info","ts":"2026-05-27T05:55:50.36428Z","caller":"traceutil/trace.go:171","msg":"trace[649968935] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:242; }","duration":"432.990797ms","start":"2026-05-27T05:55:49.931275Z","end":"2026-05-27T05:55:50.364266Z","steps":["trace[649968935] 'agreement among raft nodes before linearized reading' (duration: 432.811705ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T05:55:50.364203Z","caller":"traceutil/trace.go:171","msg":"trace[237408411] transaction","detail":"{read_only:false; response_revision:241; number_of_response:1; }","duration":"298.969108ms","start":"2026-05-27T05:55:50.065225Z","end":"2026-05-27T05:55:50.364194Z","steps":["trace[237408411] 'process raft request' (duration: 298.305399ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T05:55:50.364477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T05:55:49.93126Z","time spent":"433.20121ms","remote":"127.0.0.1:44266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":240,"request content":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" "} {"level":"warn","ts":"2026-05-27T05:55:50.364583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.536236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-05-27T05:55:50.364612Z","caller":"traceutil/trace.go:171","msg":"trace[308980062] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:242; }","duration":"420.585186ms","start":"2026-05-27T05:55:49.944018Z","end":"2026-05-27T05:55:50.364604Z","steps":["trace[308980062] 'agreement among raft nodes before linearized reading' (duration: 420.532645ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T05:55:50.364641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T05:55:49.944006Z","time spent":"420.629017ms","remote":"127.0.0.1:44462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":688,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-05-27T05:55:50.364289Z","caller":"traceutil/trace.go:171","msg":"trace[478627204] transaction","detail":"{read_only:false; number_of_response:0; response_revision:241; }","duration":"298.827936ms","start":"2026-05-27T05:55:50.065456Z","end":"2026-05-27T05:55:50.364283Z","steps":["trace[478627204] 'process raft request' (duration: 298.186376ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T05:56:20.550409Z","caller":"traceutil/trace.go:171","msg":"trace[1990497525] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"105.458532ms","start":"2026-05-27T05:56:20.44491Z","end":"2026-05-27T05:56:20.550368Z","steps":["trace[1990497525] 'process raft request' (duration: 105.105327ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T05:56:20.550983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.62405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/cilium-operator-869df985b8\" ","response":"range_response_count:1 size:4354"} {"level":"info","ts":"2026-05-27T05:56:20.551066Z","caller":"traceutil/trace.go:171","msg":"trace[980670536] range","detail":"{range_begin:/registry/replicasets/kube-system/cilium-operator-869df985b8; range_end:; response_count:1; response_revision:481; }","duration":"107.730252ms","start":"2026-05-27T05:56:20.443315Z","end":"2026-05-27T05:56:20.551045Z","steps":["trace[980670536] 'agreement among raft nodes before linearized reading' (duration: 107.512069ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T05:56:20.820038Z","caller":"traceutil/trace.go:171","msg":"trace[1061361481] linearizableReadLoop","detail":"{readStateIndex:504; appliedIndex:503; }","duration":"205.573707ms","start":"2026-05-27T05:56:20.614437Z","end":"2026-05-27T05:56:20.820011Z","steps":["trace[1061361481] 'read index received' (duration: 201.632976ms)","trace[1061361481] 'applied index is now lower than readState.Index' (duration: 3.939511ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T05:56:20.820126Z","caller":"traceutil/trace.go:171","msg":"trace[1207283415] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"225.606483ms","start":"2026-05-27T05:56:20.594492Z","end":"2026-05-27T05:56:20.820098Z","steps":["trace[1207283415] 'process raft request' (duration: 221.50493ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T05:56:20.820172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.712619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-27T05:56:20.820231Z","caller":"traceutil/trace.go:171","msg":"trace[353991396] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:483; }","duration":"205.810689ms","start":"2026-05-27T05:56:20.614404Z","end":"2026-05-27T05:56:20.820215Z","steps":["trace[353991396] 'agreement among raft nodes before linearized reading' (duration: 205.701328ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T05:56:20.820561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.596229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329956"} {"level":"info","ts":"2026-05-27T05:56:20.820603Z","caller":"traceutil/trace.go:171","msg":"trace[1322060794] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:483; }","duration":"195.648869ms","start":"2026-05-27T05:56:20.624942Z","end":"2026-05-27T05:56:20.82059Z","steps":["trace[1322060794] 'agreement among raft nodes before linearized reading' (duration: 195.219054ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T05:56:22.378812Z","caller":"traceutil/trace.go:171","msg":"trace[44360396] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"203.521142ms","start":"2026-05-27T05:56:22.175247Z","end":"2026-05-27T05:56:22.378768Z","steps":["trace[44360396] 'process raft request' (duration: 192.268399ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T05:56:22.378867Z","caller":"traceutil/trace.go:171","msg":"trace[286715840] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"203.515062ms","start":"2026-05-27T05:56:22.175333Z","end":"2026-05-27T05:56:22.378848Z","steps":["trace[286715840] 'process raft request' (duration: 203.38342ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T05:56:22.378912Z","caller":"traceutil/trace.go:171","msg":"trace[56656347] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"199.583972ms","start":"2026-05-27T05:56:22.179292Z","end":"2026-05-27T05:56:22.378876Z","steps":["trace[56656347] 'process raft request' (duration: 199.475201ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T05:56:22.378981Z","caller":"traceutil/trace.go:171","msg":"trace[511497332] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"188.45734ms","start":"2026-05-27T05:56:22.190514Z","end":"2026-05-27T05:56:22.378972Z","steps":["trace[511497332] 'process raft request' (duration: 188.296408ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T05:56:22.379003Z","caller":"traceutil/trace.go:171","msg":"trace[2115642114] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"168.214443ms","start":"2026-05-27T05:56:22.210773Z","end":"2026-05-27T05:56:22.378987Z","steps":["trace[2115642114] 'process raft request' (duration: 168.178902ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T05:56:22.379012Z","caller":"traceutil/trace.go:171","msg":"trace[837268729] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"168.295953ms","start":"2026-05-27T05:56:22.210689Z","end":"2026-05-27T05:56:22.378985Z","steps":["trace[837268729] 'process raft request' (duration: 168.158492ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T05:56:22.379039Z","caller":"traceutil/trace.go:171","msg":"trace[945806121] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"168.290093ms","start":"2026-05-27T05:56:22.210741Z","end":"2026-05-27T05:56:22.379031Z","steps":["trace[945806121] 'process raft request' (duration: 168.144361ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T05:56:22.662204Z","caller":"traceutil/trace.go:171","msg":"trace[892396129] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"435.234064ms","start":"2026-05-27T05:56:22.226943Z","end":"2026-05-27T05:56:22.662177Z","steps":["trace[892396129] 'process raft request' (duration: 433.884727ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T05:56:22.66235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T05:56:22.226928Z","time spent":"435.349946ms","remote":"127.0.0.1:44588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T05:56:22.66259Z","caller":"traceutil/trace.go:171","msg":"trace[1476853842] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"432.797283ms","start":"2026-05-27T05:56:22.229739Z","end":"2026-05-27T05:56:22.662536Z","steps":["trace[1476853842] 'process raft request' (duration: 432.486279ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T05:56:22.66286Z","caller":"traceutil/trace.go:171","msg":"trace[1773776158] transaction","detail":"{read_only:false; number_of_response:1; response_revision:501; }","duration":"431.592988ms","start":"2026-05-27T05:56:22.231243Z","end":"2026-05-27T05:56:22.662836Z","steps":["trace[1773776158] 'process raft request' (duration: 431.050051ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T05:56:22.663068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T05:56:22.229733Z","time spent":"433.257559ms","remote":"127.0.0.1:44108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T05:56:22.662778Z","caller":"traceutil/trace.go:171","msg":"trace[1830104425] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"308.976486ms","start":"2026-05-27T05:56:22.35378Z","end":"2026-05-27T05:56:22.662756Z","steps":["trace[1830104425] 'process raft request' (duration: 308.839934ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T05:56:22.663241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T05:56:22.353756Z","time spent":"309.439722ms","remote":"127.0.0.1:44108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T05:56:22.663317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T05:56:22.231238Z","time spent":"431.79451ms","remote":"127.0.0.1:44108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7367,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T05:56:22.662779Z","caller":"traceutil/trace.go:171","msg":"trace[668151278] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"433.784386ms","start":"2026-05-27T05:56:22.22898Z","end":"2026-05-27T05:56:22.662765Z","steps":["trace[668151278] 'process raft request' (duration: 433.040886ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T05:56:22.663483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T05:56:22.228974Z","time spent":"434.452144ms","remote":"127.0.0.1:44108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7317,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T05:56:22.662829Z","caller":"traceutil/trace.go:171","msg":"trace[1226889478] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"419.453993ms","start":"2026-05-27T05:56:22.243364Z","end":"2026-05-27T05:56:22.662818Z","steps":["trace[1226889478] 'process raft request' (duration: 419.012248ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T05:56:22.663574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T05:56:22.243334Z","time spent":"420.210503ms","remote":"127.0.0.1:44108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T05:56:22.662838Z","caller":"traceutil/trace.go:171","msg":"trace[204376856] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"411.537373ms","start":"2026-05-27T05:56:22.251287Z","end":"2026-05-27T05:56:22.662824Z","steps":["trace[204376856] 'process raft request' (duration: 411.243599ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T05:56:22.663706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T05:56:22.251276Z","time spent":"412.361533ms","remote":"127.0.0.1:44108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T05:56:22.670547Z","caller":"traceutil/trace.go:171","msg":"trace[911017986] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"279.592422ms","start":"2026-05-27T05:56:22.390934Z","end":"2026-05-27T05:56:22.670526Z","steps":["trace[911017986] 'process raft request' (duration: 279.394999ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T05:56:22.670669Z","caller":"traceutil/trace.go:171","msg":"trace[2036439448] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"279.575941ms","start":"2026-05-27T05:56:22.391079Z","end":"2026-05-27T05:56:22.670655Z","steps":["trace[2036439448] 'process raft request' (duration: 279.307408ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T05:56:22.670621Z","caller":"traceutil/trace.go:171","msg":"trace[124196822] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"285.235714ms","start":"2026-05-27T05:56:22.385144Z","end":"2026-05-27T05:56:22.67038Z","steps":["trace[124196822] 'process raft request' (duration: 285.080072ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T05:56:22.671477Z","caller":"traceutil/trace.go:171","msg":"trace[1479415815] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"279.187797ms","start":"2026-05-27T05:56:22.39228Z","end":"2026-05-27T05:56:22.671467Z","steps":["trace[1479415815] 'process raft request' (duration: 278.704561ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T05:56:22.671489Z","caller":"traceutil/trace.go:171","msg":"trace[848941940] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"277.750779ms","start":"2026-05-27T05:56:22.393719Z","end":"2026-05-27T05:56:22.67147Z","steps":["trace[848941940] 'process raft request' (duration: 277.324303ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T05:56:22.671599Z","caller":"traceutil/trace.go:171","msg":"trace[770797080] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"276.047246ms","start":"2026-05-27T05:56:22.39554Z","end":"2026-05-27T05:56:22.671588Z","steps":["trace[770797080] 'process raft request' (duration: 275.57954ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T05:56:22.671692Z","caller":"traceutil/trace.go:171","msg":"trace[1152496257] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"272.857866ms","start":"2026-05-27T05:56:22.398823Z","end":"2026-05-27T05:56:22.671681Z","steps":["trace[1152496257] 'process raft request' (duration: 272.36867ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T05:56:22.672325Z","caller":"traceutil/trace.go:171","msg":"trace[549385474] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"279.764394ms","start":"2026-05-27T05:56:22.391264Z","end":"2026-05-27T05:56:22.671029Z","steps":["trace[549385474] 'process raft request' (duration: 279.206077ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T05:56:29.004067Z","caller":"traceutil/trace.go:171","msg":"trace[295998284] linearizableReadLoop","detail":"{readStateIndex:616; appliedIndex:615; }","duration":"133.74337ms","start":"2026-05-27T05:56:28.870301Z","end":"2026-05-27T05:56:29.004044Z","steps":["trace[295998284] 'read index received' (duration: 133.097742ms)","trace[295998284] 'applied index is now lower than readState.Index' (duration: 644.558µs)"],"step_count":2} {"level":"warn","ts":"2026-05-27T05:56:29.004527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.229637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-27T05:56:29.004612Z","caller":"traceutil/trace.go:171","msg":"trace[1270954955] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:589; }","duration":"134.370058ms","start":"2026-05-27T05:56:28.870228Z","end":"2026-05-27T05:56:29.004598Z","steps":["trace[1270954955] 'agreement among raft nodes before linearized reading' (duration: 134.217296ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T05:56:29.004599Z","caller":"traceutil/trace.go:171","msg":"trace[414827749] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"231.451811ms","start":"2026-05-27T05:56:28.772822Z","end":"2026-05-27T05:56:29.004274Z","steps":["trace[414827749] 'process raft request' (duration: 230.721312ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T05:56:29.683868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.223939ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T05:56:29.684141Z","caller":"traceutil/trace.go:171","msg":"trace[1319177059] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"672.139314ms","start":"2026-05-27T05:56:29.01196Z","end":"2026-05-27T05:56:29.6841Z","steps":["trace[1319177059] 'process raft request' (duration: 83.278348ms)","trace[1319177059] 'compare' (duration: 586.894481ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T05:56:29.684195Z","caller":"traceutil/trace.go:171","msg":"trace[792107437] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:617; }","duration":"617.962439ms","start":"2026-05-27T05:56:29.066216Z","end":"2026-05-27T05:56:29.684178Z","steps":["trace[792107437] 'read index received' (duration: 29.013281ms)","trace[792107437] 'applied index is now lower than readState.Index' (duration: 588.948148ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T05:56:29.684285Z","caller":"traceutil/trace.go:171","msg":"trace[1530335945] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"620.362209ms","start":"2026-05-27T05:56:29.063914Z","end":"2026-05-27T05:56:29.684276Z","steps":["trace[1530335945] 'process raft request' (duration: 620.182387ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T05:56:29.68441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.411233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-27T05:56:29.684478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T05:56:29.063892Z","time spent":"620.45582ms","remote":"127.0.0.1:44410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":897,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T05:56:29.684507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.384417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-27T05:56:29.684536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.303603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-djfgh\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-27T05:56:29.684587Z","caller":"traceutil/trace.go:171","msg":"trace[310429343] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:592; }","duration":"538.468959ms","start":"2026-05-27T05:56:29.146096Z","end":"2026-05-27T05:56:29.684565Z","steps":["trace[310429343] 'agreement among raft nodes before linearized reading' (duration: 538.204295ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T05:56:29.684503Z","caller":"traceutil/trace.go:171","msg":"trace[1005158829] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:592; }","duration":"101.547365ms","start":"2026-05-27T05:56:29.582943Z","end":"2026-05-27T05:56:29.68449Z","steps":["trace[1005158829] 'agreement among raft nodes before linearized reading' (duration: 101.394803ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T05:56:29.684637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T05:56:29.146053Z","time spent":"538.57594ms","remote":"127.0.0.1:44326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-27T05:56:29.684636Z","caller":"traceutil/trace.go:171","msg":"trace[1852909970] range","detail":"{range_begin:/registry/pods/kube-system/cilium-djfgh; range_end:; response_count:1; response_revision:592; }","duration":"618.434895ms","start":"2026-05-27T05:56:29.066186Z","end":"2026-05-27T05:56:29.68462Z","steps":["trace[1852909970] 'agreement among raft nodes before linearized reading' (duration: 618.172252ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T05:56:29.68472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T05:56:29.066173Z","time spent":"618.536276ms","remote":"127.0.0.1:44248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-djfgh\" "} {"level":"warn","ts":"2026-05-27T05:56:29.685055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.225253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-27T05:56:29.685081Z","caller":"traceutil/trace.go:171","msg":"trace[2015197338] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:592; }","duration":"293.283124ms","start":"2026-05-27T05:56:29.391791Z","end":"2026-05-27T05:56:29.685074Z","steps":["trace[2015197338] 'agreement among raft nodes before linearized reading' (duration: 293.203273ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T05:56:29.685342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T05:56:29.011937Z","time spent":"672.286785ms","remote":"127.0.0.1:44326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T05:57:46.298132Z","caller":"traceutil/trace.go:171","msg":"trace[1075521210] transaction","detail":"{read_only:false; response_revision:1494; number_of_response:1; }","duration":"137.622082ms","start":"2026-05-27T05:57:46.160462Z","end":"2026-05-27T05:57:46.298084Z","steps":["trace[1075521210] 'process raft request' (duration: 137.172538ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T05:58:09.523542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.391725ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T05:58:09.523645Z","caller":"traceutil/trace.go:171","msg":"trace[2025579149] linearizableReadLoop","detail":"{readStateIndex:1935; appliedIndex:1934; }","duration":"667.427902ms","start":"2026-05-27T05:58:08.856201Z","end":"2026-05-27T05:58:09.523629Z","steps":["trace[2025579149] 'read index received' (duration: 118.848288ms)","trace[2025579149] 'applied index is now lower than readState.Index' (duration: 548.578134ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T05:58:09.52382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.614541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-def1da3e-5f72-4f1f-a0dd-51c1eadb7a3b\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-27T05:58:09.523856Z","caller":"traceutil/trace.go:171","msg":"trace[924812219] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-def1da3e-5f72-4f1f-a0dd-51c1eadb7a3b; range_end:; response_count:1; response_revision:1857; }","duration":"667.649343ms","start":"2026-05-27T05:58:08.856197Z","end":"2026-05-27T05:58:09.523846Z","steps":["trace[924812219] 'agreement among raft nodes before linearized reading' (duration: 667.476765ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T05:58:09.523861Z","caller":"traceutil/trace.go:171","msg":"trace[1037873035] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"668.636089ms","start":"2026-05-27T05:58:08.855191Z","end":"2026-05-27T05:58:09.523827Z","steps":["trace[1037873035] 'process raft request' (duration: 119.871567ms)","trace[1037873035] 'compare' (duration: 548.214546ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T05:58:09.523894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T05:58:08.856157Z","time spent":"667.729406ms","remote":"127.0.0.1:44248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3651,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-def1da3e-5f72-4f1f-a0dd-51c1eadb7a3b\" "} {"level":"warn","ts":"2026-05-27T05:58:09.524029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T05:58:08.855166Z","time spent":"668.760954ms","remote":"127.0.0.1:44326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T05:58:10.02754Z","caller":"traceutil/trace.go:171","msg":"trace[167953334] transaction","detail":"{read_only:false; response_revision:1858; number_of_response:1; }","duration":"479.888588ms","start":"2026-05-27T05:58:09.547562Z","end":"2026-05-27T05:58:10.027451Z","steps":["trace[167953334] 'process raft request' (duration: 447.458627ms)","trace[167953334] 'compare' (duration: 32.124546ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T05:58:10.027741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T05:58:09.547537Z","time spent":"480.087197ms","remote":"127.0.0.1:44248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T05:58:10.036344Z","caller":"traceutil/trace.go:171","msg":"trace[1215551051] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"476.57239ms","start":"2026-05-27T05:58:09.559748Z","end":"2026-05-27T05:58:10.03632Z","steps":["trace[1215551051] 'process raft request' (duration: 476.377131ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T05:58:10.036241Z","caller":"traceutil/trace.go:171","msg":"trace[1607459449] linearizableReadLoop","detail":"{readStateIndex:1937; appliedIndex:1935; }","duration":"454.196354ms","start":"2026-05-27T05:58:09.58203Z","end":"2026-05-27T05:58:10.036227Z","steps":["trace[1607459449] 'read index received' (duration: 413.162773ms)","trace[1607459449] 'applied index is now lower than readState.Index' (duration: 41.032911ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T05:58:10.03647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T05:58:09.559736Z","time spent":"476.663255ms","remote":"127.0.0.1:44326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T05:58:10.036475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.423224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T05:58:10.036536Z","caller":"traceutil/trace.go:171","msg":"trace[707777326] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1859; }","duration":"454.52861ms","start":"2026-05-27T05:58:09.581993Z","end":"2026-05-27T05:58:10.036521Z","steps":["trace[707777326] 'agreement among raft nodes before linearized reading' (duration: 454.408954ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T05:58:10.036561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.731006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-27T05:58:10.036578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T05:58:09.581981Z","time spent":"454.591922ms","remote":"127.0.0.1:44070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-27T05:58:10.036597Z","caller":"traceutil/trace.go:171","msg":"trace[1347527081] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1859; }","duration":"449.82638ms","start":"2026-05-27T05:58:09.586761Z","end":"2026-05-27T05:58:10.036587Z","steps":["trace[1347527081] 'agreement among raft nodes before linearized reading' (duration: 449.716365ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T05:58:10.036629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T05:58:09.586746Z","time spent":"449.872362ms","remote":"127.0.0.1:44326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-27T05:58:10.036753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.801578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T05:58:10.036786Z","caller":"traceutil/trace.go:171","msg":"trace[828603079] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1859; }","duration":"319.867382ms","start":"2026-05-27T05:58:09.71691Z","end":"2026-05-27T05:58:10.036777Z","steps":["trace[828603079] 'agreement among raft nodes before linearized reading' (duration: 319.807419ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T05:58:10.036807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T05:58:09.716895Z","time spent":"319.907163ms","remote":"127.0.0.1:49172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-05-27T05:58:10.036862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.638765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-05-27T05:58:10.036902Z","caller":"traceutil/trace.go:171","msg":"trace[761934449] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1859; }","duration":"361.715459ms","start":"2026-05-27T05:58:09.675177Z","end":"2026-05-27T05:58:10.036893Z","steps":["trace[761934449] 'agreement among raft nodes before linearized reading' (duration: 361.618984ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T05:58:10.036905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.920126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-05-27T05:58:10.036935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T05:58:09.675152Z","time spent":"361.775382ms","remote":"127.0.0.1:41938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5468,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-05-27T05:58:10.036948Z","caller":"traceutil/trace.go:171","msg":"trace[1586084281] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1859; }","duration":"377.98493ms","start":"2026-05-27T05:58:09.658943Z","end":"2026-05-27T05:58:10.036928Z","steps":["trace[1586084281] 'agreement among raft nodes before linearized reading' (duration: 377.882635ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T05:58:10.036968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T05:58:09.658919Z","time spent":"378.043892ms","remote":"127.0.0.1:44326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-27T05:58:10.351542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.881378ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T05:58:10.351988Z","caller":"traceutil/trace.go:171","msg":"trace[1419187962] linearizableReadLoop","detail":"{readStateIndex:1939; appliedIndex:1938; }","duration":"109.41728ms","start":"2026-05-27T05:58:10.242549Z","end":"2026-05-27T05:58:10.351966Z","steps":["trace[1419187962] 'read index received' (duration: 61.863µs)","trace[1419187962] 'applied index is now lower than readState.Index' (duration: 109.352947ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T05:58:10.352265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.717615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-27T05:58:10.352321Z","caller":"traceutil/trace.go:171","msg":"trace[298458603] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1861; }","duration":"109.812279ms","start":"2026-05-27T05:58:10.242494Z","end":"2026-05-27T05:58:10.352306Z","steps":["trace[298458603] 'agreement among raft nodes before linearized reading' (duration: 109.61252ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T05:58:10.352314Z","caller":"traceutil/trace.go:171","msg":"trace[1915962509] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"311.923111ms","start":"2026-05-27T05:58:10.040144Z","end":"2026-05-27T05:58:10.352067Z","steps":["trace[1915962509] 'process raft request' (duration: 108.356351ms)","trace[1915962509] 'compare' (duration: 202.770993ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T05:58:10.352552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T05:58:10.040132Z","time spent":"312.285967ms","remote":"127.0.0.1:44326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T05:58:10.382087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.6215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-27T05:58:10.382174Z","caller":"traceutil/trace.go:171","msg":"trace[650465751] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1862; }","duration":"129.700374ms","start":"2026-05-27T05:58:10.252433Z","end":"2026-05-27T05:58:10.382133Z","steps":["trace[650465751] 'agreement among raft nodes before linearized reading' (duration: 129.474393ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T05:58:10.382338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.202524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-27T05:58:10.382374Z","caller":"traceutil/trace.go:171","msg":"trace[72482875] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1862; }","duration":"131.254957ms","start":"2026-05-27T05:58:10.25111Z","end":"2026-05-27T05:58:10.382365Z","steps":["trace[72482875] 'agreement among raft nodes before linearized reading' (duration: 131.167353ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T05:58:10.38271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.464445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-27T05:58:10.382826Z","caller":"traceutil/trace.go:171","msg":"trace[1515391103] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1862; }","duration":"134.619573ms","start":"2026-05-27T05:58:10.248195Z","end":"2026-05-27T05:58:10.382815Z","steps":["trace[1515391103] 'agreement among raft nodes before linearized reading' (duration: 133.687989ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T05:58:43.337239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.978302ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-27T05:58:44.504895Z","caller":"traceutil/trace.go:171","msg":"trace[200375454] transaction","detail":"{read_only:false; response_revision:2101; number_of_response:1; }","duration":"355.767262ms","start":"2026-05-27T05:58:44.148873Z","end":"2026-05-27T05:58:44.50464Z","steps":["trace[200375454] 'process raft request' (duration: 355.552753ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T05:58:44.505245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T05:58:44.14885Z","time spent":"356.111155ms","remote":"127.0.0.1:44326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T05:58:44.52207Z","caller":"traceutil/trace.go:171","msg":"trace[1379307852] linearizableReadLoop","detail":"{readStateIndex:2187; appliedIndex:2185; }","duration":"252.271872ms","start":"2026-05-27T05:58:44.269768Z","end":"2026-05-27T05:58:44.52204Z","steps":["trace[1379307852] 'read index received' (duration: 234.733746ms)","trace[1379307852] 'applied index is now lower than readState.Index' (duration: 17.537376ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T05:58:44.522576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.796912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-27T05:58:44.522648Z","caller":"traceutil/trace.go:171","msg":"trace[1781012192] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2103; }","duration":"252.913667ms","start":"2026-05-27T05:58:44.269723Z","end":"2026-05-27T05:58:44.522637Z","steps":["trace[1781012192] 'agreement among raft nodes before linearized reading' (duration: 252.683688ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T05:58:44.522642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.719894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-05-27T05:58:44.523053Z","caller":"traceutil/trace.go:171","msg":"trace[1138112935] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2103; }","duration":"178.199483ms","start":"2026-05-27T05:58:44.344834Z","end":"2026-05-27T05:58:44.523034Z","steps":["trace[1138112935] 'agreement among raft nodes before linearized reading' (duration: 177.632591ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T05:58:44.522396Z","caller":"traceutil/trace.go:171","msg":"trace[1915698328] transaction","detail":"{read_only:false; response_revision:2102; number_of_response:1; }","duration":"351.185473ms","start":"2026-05-27T05:58:44.171033Z","end":"2026-05-27T05:58:44.522218Z","steps":["trace[1915698328] 'process raft request' (duration: 350.880781ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T05:58:44.52342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T05:58:44.170993Z","time spent":"352.353018ms","remote":"127.0.0.1:44128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T05:58:45.013516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.331927ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T05:58:45.013754Z","caller":"traceutil/trace.go:171","msg":"trace[1253831678] linearizableReadLoop","detail":"{readStateIndex:2190; appliedIndex:2188; }","duration":"479.524885ms","start":"2026-05-27T05:58:44.534212Z","end":"2026-05-27T05:58:45.013736Z","steps":["trace[1253831678] 'read index received' (duration: 23.883835ms)","trace[1253831678] 'applied index is now lower than readState.Index' (duration: 455.63983ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T05:58:45.013854Z","caller":"traceutil/trace.go:171","msg":"trace[40009875] transaction","detail":"{read_only:false; response_revision:2104; number_of_response:1; }","duration":"485.986167ms","start":"2026-05-27T05:58:44.527857Z","end":"2026-05-27T05:58:45.013843Z","steps":["trace[40009875] 'process raft request' (duration: 30.224233ms)","trace[40009875] 'compare' (duration: 455.173161ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T05:58:45.013924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T05:58:44.527815Z","time spent":"486.070201ms","remote":"127.0.0.1:44326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T05:58:45.014362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.137565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-27T05:58:45.014437Z","caller":"traceutil/trace.go:171","msg":"trace[1299662837] transaction","detail":"{read_only:false; response_revision:2105; number_of_response:1; }","duration":"485.236598ms","start":"2026-05-27T05:58:44.529187Z","end":"2026-05-27T05:58:45.014424Z","steps":["trace[1299662837] 'process raft request' (duration: 484.451617ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T05:58:45.014448Z","caller":"traceutil/trace.go:171","msg":"trace[2100742616] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2105; }","duration":"449.228239ms","start":"2026-05-27T05:58:44.5652Z","end":"2026-05-27T05:58:45.014428Z","steps":["trace[2100742616] 'agreement among raft nodes before linearized reading' (duration: 449.053542ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T05:58:45.014494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T05:58:44.565174Z","time spent":"449.311702ms","remote":"127.0.0.1:44326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-27T05:58:45.014622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.300344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-27T05:58:45.014362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.129308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-27T05:58:45.014656Z","caller":"traceutil/trace.go:171","msg":"trace[318796646] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2105; }","duration":"434.352236ms","start":"2026-05-27T05:58:44.580294Z","end":"2026-05-27T05:58:45.014646Z","steps":["trace[318796646] 'agreement among raft nodes before linearized reading' (duration: 434.295234ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T05:58:45.014682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T05:58:44.580283Z","time spent":"434.393198ms","remote":"127.0.0.1:44070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-27T05:58:45.014719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.085686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-27T05:58:45.014708Z","caller":"traceutil/trace.go:171","msg":"trace[1208347114] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2105; }","duration":"480.495632ms","start":"2026-05-27T05:58:44.534183Z","end":"2026-05-27T05:58:45.014679Z","steps":["trace[1208347114] 'agreement among raft nodes before linearized reading' (duration: 479.686111ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T05:58:45.014792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.625187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-27T05:58:45.014768Z","caller":"traceutil/trace.go:171","msg":"trace[758589608] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2105; }","duration":"446.192359ms","start":"2026-05-27T05:58:44.568562Z","end":"2026-05-27T05:58:45.014755Z","steps":["trace[758589608] 'agreement among raft nodes before linearized reading' (duration: 446.037473ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T05:58:45.014806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T05:58:44.534175Z","time spent":"480.599516ms","remote":"127.0.0.1:44326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-27T05:58:45.014859Z","caller":"traceutil/trace.go:171","msg":"trace[470314023] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2105; }","duration":"445.715761ms","start":"2026-05-27T05:58:44.569111Z","end":"2026-05-27T05:58:45.014827Z","steps":["trace[470314023] 'agreement among raft nodes before linearized reading' (duration: 445.546155ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T05:58:45.014855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T05:58:44.56854Z","time spent":"446.300024ms","remote":"127.0.0.1:44326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-27T05:58:45.014558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T05:58:44.529172Z","time spent":"485.305221ms","remote":"127.0.0.1:44326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T05:58:45.014939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T05:58:44.569101Z","time spent":"445.819945ms","remote":"127.0.0.1:44326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-27T05:59:39.394456Z","caller":"traceutil/trace.go:171","msg":"trace[1945629043] transaction","detail":"{read_only:false; response_revision:2448; number_of_response:1; }","duration":"102.368648ms","start":"2026-05-27T05:59:39.292064Z","end":"2026-05-27T05:59:39.394433Z","steps":["trace[1945629043] 'process raft request' (duration: 98.739997ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:00:21.37767Z","caller":"traceutil/trace.go:171","msg":"trace[1675675410] linearizableReadLoop","detail":"{readStateIndex:2839; appliedIndex:2838; }","duration":"234.499085ms","start":"2026-05-27T06:00:21.143148Z","end":"2026-05-27T06:00:21.377647Z","steps":["trace[1675675410] 'read index received' (duration: 175.25943ms)","trace[1675675410] 'applied index is now lower than readState.Index' (duration: 59.238615ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:00:21.377988Z","caller":"traceutil/trace.go:171","msg":"trace[1934967365] transaction","detail":"{read_only:false; response_revision:2732; number_of_response:1; }","duration":"331.977024ms","start":"2026-05-27T06:00:21.045981Z","end":"2026-05-27T06:00:21.377958Z","steps":["trace[1934967365] 'process raft request' (duration: 272.421461ms)","trace[1934967365] 'compare' (duration: 59.069481ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T06:00:21.378069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.906327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-27T06:00:21.378493Z","caller":"traceutil/trace.go:171","msg":"trace[769403395] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:2732; }","duration":"235.340738ms","start":"2026-05-27T06:00:21.143136Z","end":"2026-05-27T06:00:21.378476Z","steps":["trace[769403395] 'agreement among raft nodes before linearized reading' (duration: 234.833325ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:00:21.378852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:00:21.045957Z","time spent":"332.426336ms","remote":"127.0.0.1:44128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":764,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T06:00:21.644012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.462399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-27T06:00:21.644103Z","caller":"traceutil/trace.go:171","msg":"trace[1836685025] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2732; }","duration":"158.583993ms","start":"2026-05-27T06:00:21.485502Z","end":"2026-05-27T06:00:21.644086Z","steps":["trace[1836685025] 'range keys from in-memory index tree' (duration: 158.322856ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:00:21.644369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.257142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-05-27T06:00:21.644435Z","caller":"traceutil/trace.go:171","msg":"trace[290059965] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2732; }","duration":"164.364405ms","start":"2026-05-27T06:00:21.48006Z","end":"2026-05-27T06:00:21.644424Z","steps":["trace[290059965] 'range keys from in-memory index tree' (duration: 163.843071ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:03:52.778859Z","caller":"traceutil/trace.go:171","msg":"trace[233925838] transaction","detail":"{read_only:false; response_revision:4277; number_of_response:1; }","duration":"117.797385ms","start":"2026-05-27T06:03:52.661028Z","end":"2026-05-27T06:03:52.778825Z","steps":["trace[233925838] 'process raft request' (duration: 67.509185ms)","trace[233925838] 'compare' (duration: 50.129507ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:03:52.781434Z","caller":"traceutil/trace.go:171","msg":"trace[118371989] transaction","detail":"{read_only:false; response_revision:4278; number_of_response:1; }","duration":"108.09698ms","start":"2026-05-27T06:03:52.673315Z","end":"2026-05-27T06:03:52.781412Z","steps":["trace[118371989] 'process raft request' (duration: 107.991728ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:04:07.446892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.409146ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T06:04:07.446979Z","caller":"traceutil/trace.go:171","msg":"trace[1522091258] linearizableReadLoop","detail":"{readStateIndex:4867; appliedIndex:4866; }","duration":"212.565545ms","start":"2026-05-27T06:04:07.234398Z","end":"2026-05-27T06:04:07.446963Z","steps":["trace[1522091258] 'read index received' (duration: 35.970456ms)","trace[1522091258] 'applied index is now lower than readState.Index' (duration: 176.594149ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:04:07.447253Z","caller":"traceutil/trace.go:171","msg":"trace[1819446811] transaction","detail":"{read_only:false; response_revision:4687; number_of_response:1; }","duration":"239.355618ms","start":"2026-05-27T06:04:07.207861Z","end":"2026-05-27T06:04:07.447217Z","steps":["trace[1819446811] 'process raft request' (duration: 62.552595ms)","trace[1819446811] 'compare' (duration: 176.313274ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T06:04:07.447342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.063051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-27T06:04:07.447374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.108663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-05-27T06:04:07.44742Z","caller":"traceutil/trace.go:171","msg":"trace[424396446] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4687; }","duration":"153.155164ms","start":"2026-05-27T06:04:07.294251Z","end":"2026-05-27T06:04:07.447406Z","steps":["trace[424396446] 'agreement among raft nodes before linearized reading' (duration: 152.975611ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:04:07.447401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.973952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9637"} {"level":"info","ts":"2026-05-27T06:04:07.447664Z","caller":"traceutil/trace.go:171","msg":"trace[55494013] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4687; }","duration":"213.285968ms","start":"2026-05-27T06:04:07.234358Z","end":"2026-05-27T06:04:07.447644Z","steps":["trace[55494013] 'agreement among raft nodes before linearized reading' (duration: 212.83283ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:04:07.447468Z","caller":"traceutil/trace.go:171","msg":"trace[1456082869] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:4687; }","duration":"201.215365ms","start":"2026-05-27T06:04:07.246181Z","end":"2026-05-27T06:04:07.447397Z","steps":["trace[1456082869] 'agreement among raft nodes before linearized reading' (duration: 201.017521ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:04:07.448353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.238985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2206973"} {"level":"info","ts":"2026-05-27T06:04:07.448433Z","caller":"traceutil/trace.go:171","msg":"trace[650243399] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4687; }","duration":"191.338718ms","start":"2026-05-27T06:04:07.257076Z","end":"2026-05-27T06:04:07.448415Z","steps":["trace[650243399] 'agreement among raft nodes before linearized reading' (duration: 190.347401ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:04:07.634177Z","caller":"traceutil/trace.go:171","msg":"trace[1576290856] linearizableReadLoop","detail":"{readStateIndex:4868; appliedIndex:4867; }","duration":"180.176289ms","start":"2026-05-27T06:04:07.453935Z","end":"2026-05-27T06:04:07.634111Z","steps":["trace[1576290856] 'read index received' (duration: 177.209189ms)","trace[1576290856] 'applied index is now lower than readState.Index' (duration: 2.96561ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:04:07.634271Z","caller":"traceutil/trace.go:171","msg":"trace[208971161] transaction","detail":"{read_only:false; response_revision:4688; number_of_response:1; }","duration":"180.774169ms","start":"2026-05-27T06:04:07.453462Z","end":"2026-05-27T06:04:07.634236Z","steps":["trace[208971161] 'process raft request' (duration: 177.751879ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:04:07.634532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.569206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-keystone.18b356eb77dc698c\" ","response":"range_response_count:1 size:751"} {"level":"info","ts":"2026-05-27T06:04:07.634626Z","caller":"traceutil/trace.go:171","msg":"trace[419722824] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-keystone.18b356eb77dc698c; range_end:; response_count:1; response_revision:4688; }","duration":"180.689599ms","start":"2026-05-27T06:04:07.453913Z","end":"2026-05-27T06:04:07.634602Z","steps":["trace[419722824] 'agreement among raft nodes before linearized reading' (duration: 180.420744ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:04:07.692034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.143987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3243"} {"level":"info","ts":"2026-05-27T06:04:07.692111Z","caller":"traceutil/trace.go:171","msg":"trace[1219644287] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4690; }","duration":"235.274608ms","start":"2026-05-27T06:04:07.456819Z","end":"2026-05-27T06:04:07.692094Z","steps":["trace[1219644287] 'agreement among raft nodes before linearized reading' (duration: 235.067405ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:04:07.692169Z","caller":"traceutil/trace.go:171","msg":"trace[235880983] transaction","detail":"{read_only:false; response_revision:4690; number_of_response:1; }","duration":"232.209227ms","start":"2026-05-27T06:04:07.459924Z","end":"2026-05-27T06:04:07.692134Z","steps":["trace[235880983] 'process raft request' (duration: 231.892712ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:04:07.692341Z","caller":"traceutil/trace.go:171","msg":"trace[1284841390] transaction","detail":"{read_only:false; response_revision:4689; number_of_response:1; }","duration":"237.594608ms","start":"2026-05-27T06:04:07.454735Z","end":"2026-05-27T06:04:07.69233Z","steps":["trace[1284841390] 'process raft request' (duration: 236.852556ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:04:07.69241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.273285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-05-27T06:04:07.692442Z","caller":"traceutil/trace.go:171","msg":"trace[1236686223] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4690; }","duration":"214.350865ms","start":"2026-05-27T06:04:07.478082Z","end":"2026-05-27T06:04:07.692433Z","steps":["trace[1236686223] 'agreement among raft nodes before linearized reading' (duration: 214.259914ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:04:07.692798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.035909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T06:04:07.692848Z","caller":"traceutil/trace.go:171","msg":"trace[135826175] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4690; }","duration":"109.146431ms","start":"2026-05-27T06:04:07.583685Z","end":"2026-05-27T06:04:07.692832Z","steps":["trace[135826175] 'agreement among raft nodes before linearized reading' (duration: 109.056869ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:04:07.75448Z","caller":"traceutil/trace.go:171","msg":"trace[1558233838] transaction","detail":"{read_only:false; response_revision:4691; number_of_response:1; }","duration":"116.629108ms","start":"2026-05-27T06:04:07.637828Z","end":"2026-05-27T06:04:07.754457Z","steps":["trace[1558233838] 'process raft request' (duration: 112.778802ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:04:07.75464Z","caller":"traceutil/trace.go:171","msg":"trace[151919141] transaction","detail":"{read_only:false; response_revision:4692; number_of_response:1; }","duration":"101.157686ms","start":"2026-05-27T06:04:07.653462Z","end":"2026-05-27T06:04:07.754619Z","steps":["trace[151919141] 'process raft request' (duration: 100.951173ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:04:08.138053Z","caller":"traceutil/trace.go:171","msg":"trace[1645631167] linearizableReadLoop","detail":"{readStateIndex:4883; appliedIndex:4882; }","duration":"221.751849ms","start":"2026-05-27T06:04:07.916274Z","end":"2026-05-27T06:04:08.138026Z","steps":["trace[1645631167] 'read index received' (duration: 151.549196ms)","trace[1645631167] 'applied index is now lower than readState.Index' (duration: 70.201583ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:04:08.138217Z","caller":"traceutil/trace.go:171","msg":"trace[61555707] transaction","detail":"{read_only:false; response_revision:4703; number_of_response:1; }","duration":"222.788886ms","start":"2026-05-27T06:04:07.915414Z","end":"2026-05-27T06:04:08.138203Z","steps":["trace[61555707] 'process raft request' (duration: 152.45075ms)","trace[61555707] 'compare' (duration: 70.045261ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T06:04:08.138708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.41399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/prometheus-operated\" ","response":"range_response_count:1 size:993"} {"level":"info","ts":"2026-05-27T06:04:08.138765Z","caller":"traceutil/trace.go:171","msg":"trace[966659712] range","detail":"{range_begin:/registry/services/specs/monitoring/prometheus-operated; range_end:; response_count:1; response_revision:4703; }","duration":"222.497971ms","start":"2026-05-27T06:04:07.916255Z","end":"2026-05-27T06:04:08.138753Z","steps":["trace[966659712] 'agreement among raft nodes before linearized reading' (duration: 222.340978ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:04:08.139488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.012249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.196\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-27T06:04:08.139537Z","caller":"traceutil/trace.go:171","msg":"trace[1984486046] range","detail":"{range_begin:/registry/masterleases/199.19.213.196; range_end:; response_count:1; response_revision:4703; }","duration":"118.08515ms","start":"2026-05-27T06:04:08.02144Z","end":"2026-05-27T06:04:08.139525Z","steps":["trace[1984486046] 'agreement among raft nodes before linearized reading' (duration: 116.991202ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:04:08.343606Z","caller":"traceutil/trace.go:171","msg":"trace[2092702542] transaction","detail":"{read_only:false; response_revision:4706; number_of_response:1; }","duration":"121.572089ms","start":"2026-05-27T06:04:08.222009Z","end":"2026-05-27T06:04:08.343581Z","steps":["trace[2092702542] 'process raft request' (duration: 121.441507ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:04:08.343891Z","caller":"traceutil/trace.go:171","msg":"trace[1600069715] transaction","detail":"{read_only:false; response_revision:4707; number_of_response:1; }","duration":"113.082416ms","start":"2026-05-27T06:04:08.230785Z","end":"2026-05-27T06:04:08.343868Z","steps":["trace[1600069715] 'process raft request' (duration: 112.71513ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:04:08.344343Z","caller":"traceutil/trace.go:171","msg":"trace[693663801] transaction","detail":"{read_only:false; response_revision:4708; number_of_response:1; }","duration":"109.708709ms","start":"2026-05-27T06:04:08.234619Z","end":"2026-05-27T06:04:08.344327Z","steps":["trace[693663801] 'process raft request' (duration: 109.123419ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:04:08.345029Z","caller":"traceutil/trace.go:171","msg":"trace[798529036] transaction","detail":"{read_only:false; response_revision:4705; number_of_response:1; }","duration":"123.048754ms","start":"2026-05-27T06:04:08.221954Z","end":"2026-05-27T06:04:08.345003Z","steps":["trace[798529036] 'process raft request' (duration: 121.341715ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:04:11.456401Z","caller":"traceutil/trace.go:171","msg":"trace[1387425091] transaction","detail":"{read_only:false; response_revision:4746; number_of_response:1; }","duration":"194.512079ms","start":"2026-05-27T06:04:11.261865Z","end":"2026-05-27T06:04:11.456377Z","steps":["trace[1387425091] 'process raft request' (duration: 194.394847ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:04:20.690333Z","caller":"traceutil/trace.go:171","msg":"trace[1358724867] linearizableReadLoop","detail":"{readStateIndex:5068; appliedIndex:5067; }","duration":"108.000401ms","start":"2026-05-27T06:04:20.582311Z","end":"2026-05-27T06:04:20.690312Z","steps":["trace[1358724867] 'read index received' (duration: 107.714816ms)","trace[1358724867] 'applied index is now lower than readState.Index' (duration: 284.625µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:04:20.690372Z","caller":"traceutil/trace.go:171","msg":"trace[479733106] transaction","detail":"{read_only:false; response_revision:4881; number_of_response:1; }","duration":"338.06285ms","start":"2026-05-27T06:04:20.352281Z","end":"2026-05-27T06:04:20.690344Z","steps":["trace[479733106] 'process raft request' (duration: 337.843006ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:04:20.69049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.167465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-27T06:04:20.690499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:04:20.352249Z","time spent":"338.182341ms","remote":"127.0.0.1:44248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10670,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T06:04:20.69053Z","caller":"traceutil/trace.go:171","msg":"trace[1206820100] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4881; }","duration":"108.269226ms","start":"2026-05-27T06:04:20.582249Z","end":"2026-05-27T06:04:20.690518Z","steps":["trace[1206820100] 'agreement among raft nodes before linearized reading' (duration: 108.193824ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:04:21.184504Z","caller":"traceutil/trace.go:171","msg":"trace[1005859252] linearizableReadLoop","detail":"{readStateIndex:5078; appliedIndex:5077; }","duration":"119.818387ms","start":"2026-05-27T06:04:21.064657Z","end":"2026-05-27T06:04:21.184476Z","steps":["trace[1005859252] 'read index received' (duration: 82.881791ms)","trace[1005859252] 'applied index is now lower than readState.Index' (duration: 36.935546ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:04:21.184671Z","caller":"traceutil/trace.go:171","msg":"trace[1250360600] transaction","detail":"{read_only:false; response_revision:4891; number_of_response:1; }","duration":"120.514509ms","start":"2026-05-27T06:04:21.064032Z","end":"2026-05-27T06:04:21.184547Z","steps":["trace[1250360600] 'process raft request' (duration: 83.497302ms)","trace[1250360600] 'compare' (duration: 36.812884ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T06:04:21.184928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.245344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-27T06:04:21.184982Z","caller":"traceutil/trace.go:171","msg":"trace[1134807148] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4891; }","duration":"120.422047ms","start":"2026-05-27T06:04:21.064549Z","end":"2026-05-27T06:04:21.184971Z","steps":["trace[1134807148] 'agreement among raft nodes before linearized reading' (duration: 120.031421ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:04:21.196539Z","caller":"traceutil/trace.go:171","msg":"trace[665977281] transaction","detail":"{read_only:false; response_revision:4892; number_of_response:1; }","duration":"131.229438ms","start":"2026-05-27T06:04:21.065294Z","end":"2026-05-27T06:04:21.196523Z","steps":["trace[665977281] 'process raft request' (duration: 131.122706ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:04:58.178077Z","caller":"traceutil/trace.go:171","msg":"trace[1265589806] linearizableReadLoop","detail":"{readStateIndex:5407; appliedIndex:5406; }","duration":"140.622333ms","start":"2026-05-27T06:04:58.037432Z","end":"2026-05-27T06:04:58.178054Z","steps":["trace[1265589806] 'read index received' (duration: 46.400024ms)","trace[1265589806] 'applied index is now lower than readState.Index' (duration: 94.221319ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:04:58.178205Z","caller":"traceutil/trace.go:171","msg":"trace[1563752423] transaction","detail":"{read_only:false; response_revision:5211; number_of_response:1; }","duration":"141.70772ms","start":"2026-05-27T06:04:58.036484Z","end":"2026-05-27T06:04:58.178192Z","steps":["trace[1563752423] 'process raft request' (duration: 47.273137ms)","trace[1563752423] 'compare' (duration: 93.970646ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:04:58.178523Z","caller":"traceutil/trace.go:171","msg":"trace[1724433990] transaction","detail":"{read_only:false; response_revision:5212; number_of_response:1; }","duration":"140.609513ms","start":"2026-05-27T06:04:58.037885Z","end":"2026-05-27T06:04:58.178494Z","steps":["trace[1724433990] 'process raft request' (duration: 140.130275ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:04:58.178669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.205463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-27T06:04:58.178802Z","caller":"traceutil/trace.go:171","msg":"trace[1771332616] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:5211; }","duration":"141.330855ms","start":"2026-05-27T06:04:58.037425Z","end":"2026-05-27T06:04:58.178756Z","steps":["trace[1771332616] 'agreement among raft nodes before linearized reading' (duration: 140.745775ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:04:59.393452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.473449ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T06:04:59.393638Z","caller":"traceutil/trace.go:171","msg":"trace[682252304] transaction","detail":"{read_only:false; response_revision:5216; number_of_response:1; }","duration":"178.841363ms","start":"2026-05-27T06:04:59.214773Z","end":"2026-05-27T06:04:59.393614Z","steps":["trace[682252304] 'process raft request' (duration: 35.1274ms)","trace[682252304] 'compare' (duration: 143.355047ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:04:59.393891Z","caller":"traceutil/trace.go:171","msg":"trace[922608911] transaction","detail":"{read_only:false; response_revision:5217; number_of_response:1; }","duration":"178.320954ms","start":"2026-05-27T06:04:59.215555Z","end":"2026-05-27T06:04:59.393876Z","steps":["trace[922608911] 'process raft request' (duration: 178.016189ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:05:07.592114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.697959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-27T06:05:07.592182Z","caller":"traceutil/trace.go:171","msg":"trace[2133543406] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5278; }","duration":"107.808641ms","start":"2026-05-27T06:05:07.484357Z","end":"2026-05-27T06:05:07.592165Z","steps":["trace[2133543406] 'range keys from in-memory index tree' (duration: 107.509686ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:05:45.186662Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2859} {"level":"info","ts":"2026-05-27T06:05:45.299644Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2859,"took":"111.068469ms","hash":550888153,"current-db-size-bytes":54460416,"current-db-size":"54 MB","current-db-size-in-use-bytes":29388800,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-27T06:05:45.299697Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":550888153,"revision":2859,"compact-revision":-1} {"level":"warn","ts":"2026-05-27T06:07:13.980243Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4700243330642122171,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-27T06:07:14.443855Z","caller":"traceutil/trace.go:171","msg":"trace[1660065638] linearizableReadLoop","detail":"{readStateIndex:6292; appliedIndex:6289; }","duration":"963.908943ms","start":"2026-05-27T06:07:13.479931Z","end":"2026-05-27T06:07:14.44384Z","steps":["trace[1660065638] 'read index received' (duration: 961.669883ms)","trace[1660065638] 'applied index is now lower than readState.Index' (duration: 2.23843ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:07:14.443923Z","caller":"traceutil/trace.go:171","msg":"trace[297514231] transaction","detail":"{read_only:false; response_revision:6065; number_of_response:1; }","duration":"962.389749ms","start":"2026-05-27T06:07:13.481491Z","end":"2026-05-27T06:07:14.443881Z","steps":["trace[297514231] 'process raft request' (duration: 962.234894ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:07:14.444241Z","caller":"traceutil/trace.go:171","msg":"trace[480447230] transaction","detail":"{read_only:false; response_revision:6066; number_of_response:1; }","duration":"853.649845ms","start":"2026-05-27T06:07:13.590577Z","end":"2026-05-27T06:07:14.444227Z","steps":["trace[480447230] 'process raft request' (duration: 853.2192ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:07:14.444263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"964.317948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keystone-bootstrap-s9zqh\" ","response":"range_response_count:1 size:8689"} {"level":"info","ts":"2026-05-27T06:07:14.444308Z","caller":"traceutil/trace.go:171","msg":"trace[2036260570] range","detail":"{range_begin:/registry/pods/openstack/keystone-bootstrap-s9zqh; range_end:; response_count:1; response_revision:6066; }","duration":"964.393391ms","start":"2026-05-27T06:07:13.4799Z","end":"2026-05-27T06:07:14.444294Z","steps":["trace[2036260570] 'agreement among raft nodes before linearized reading' (duration: 964.205964ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:07:14.444429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:07:13.59055Z","time spent":"853.726638ms","remote":"127.0.0.1:44326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T06:07:14.444761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:07:13.481477Z","time spent":"962.528425ms","remote":"127.0.0.1:44128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":861,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T06:07:14.445161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"863.238665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T06:07:14.445194Z","caller":"traceutil/trace.go:171","msg":"trace[1781639144] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6066; }","duration":"863.313568ms","start":"2026-05-27T06:07:13.581868Z","end":"2026-05-27T06:07:14.445181Z","steps":["trace[1781639144] 'agreement among raft nodes before linearized reading' (duration: 863.249576ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:07:14.445222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:07:13.581846Z","time spent":"863.371621ms","remote":"127.0.0.1:44054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-27T06:07:14.445519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.399391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-27T06:07:14.445539Z","caller":"traceutil/trace.go:171","msg":"trace[605775029] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6066; }","duration":"356.445983ms","start":"2026-05-27T06:07:14.089087Z","end":"2026-05-27T06:07:14.445533Z","steps":["trace[605775029] 'agreement among raft nodes before linearized reading' (duration: 356.35386ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:07:14.445559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:07:14.089075Z","time spent":"356.477634ms","remote":"127.0.0.1:44326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-27T06:07:14.445677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.188201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-05-27T06:07:14.445696Z","caller":"traceutil/trace.go:171","msg":"trace[64937325] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:6066; }","duration":"361.225352ms","start":"2026-05-27T06:07:14.084463Z","end":"2026-05-27T06:07:14.445689Z","steps":["trace[64937325] 'agreement among raft nodes before linearized reading' (duration: 361.176591ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:07:14.445712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:07:14.084451Z","time spent":"361.256523ms","remote":"127.0.0.1:44326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-27T06:07:14.445801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.964537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T06:07:14.44582Z","caller":"traceutil/trace.go:171","msg":"trace[1331208021] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:6066; }","duration":"369.030359ms","start":"2026-05-27T06:07:14.076782Z","end":"2026-05-27T06:07:14.445812Z","steps":["trace[1331208021] 'agreement among raft nodes before linearized reading' (duration: 369.000988ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:07:14.445837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:07:14.07676Z","time spent":"369.0724ms","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-05-27T06:07:14.44593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.184741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T06:07:14.445946Z","caller":"traceutil/trace.go:171","msg":"trace[247126564] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:6066; }","duration":"383.235053ms","start":"2026-05-27T06:07:14.062706Z","end":"2026-05-27T06:07:14.445941Z","steps":["trace[247126564] 'agreement among raft nodes before linearized reading' (duration: 383.208802ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:07:14.445963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:07:14.062688Z","time spent":"383.270464ms","remote":"127.0.0.1:44296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-05-27T06:07:14.446076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.177589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-27T06:07:14.446091Z","caller":"traceutil/trace.go:171","msg":"trace[1566619325] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6066; }","duration":"676.232381ms","start":"2026-05-27T06:07:13.769854Z","end":"2026-05-27T06:07:14.446086Z","steps":["trace[1566619325] 'agreement among raft nodes before linearized reading' (duration: 676.179909ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:07:14.446106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:07:13.76983Z","time spent":"676.272882ms","remote":"127.0.0.1:44326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-27T06:07:14.4444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:07:13.479882Z","time spent":"964.505864ms","remote":"127.0.0.1:44248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":8713,"request content":"key:\"/registry/pods/openstack/keystone-bootstrap-s9zqh\" "} {"level":"info","ts":"2026-05-27T06:07:21.766658Z","caller":"traceutil/trace.go:171","msg":"trace[765593360] linearizableReadLoop","detail":"{readStateIndex:6342; appliedIndex:6341; }","duration":"184.897579ms","start":"2026-05-27T06:07:21.581738Z","end":"2026-05-27T06:07:21.766635Z","steps":["trace[765593360] 'read index received' (duration: 184.685152ms)","trace[765593360] 'applied index is now lower than readState.Index' (duration: 211.527µs)"],"step_count":2} {"level":"warn","ts":"2026-05-27T06:07:21.766805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.046114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T06:07:21.766837Z","caller":"traceutil/trace.go:171","msg":"trace[1830232145] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6115; }","duration":"185.098166ms","start":"2026-05-27T06:07:21.581728Z","end":"2026-05-27T06:07:21.766826Z","steps":["trace[1830232145] 'agreement among raft nodes before linearized reading' (duration: 184.996522ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:07:21.76726Z","caller":"traceutil/trace.go:171","msg":"trace[1651339782] transaction","detail":"{read_only:false; response_revision:6115; number_of_response:1; }","duration":"216.798694ms","start":"2026-05-27T06:07:21.550444Z","end":"2026-05-27T06:07:21.767242Z","steps":["trace[1651339782] 'process raft request' (duration: 216.047428ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:07:23.907979Z","caller":"traceutil/trace.go:171","msg":"trace[1915802049] linearizableReadLoop","detail":"{readStateIndex:6358; appliedIndex:6357; }","duration":"313.222807ms","start":"2026-05-27T06:07:23.594733Z","end":"2026-05-27T06:07:23.907955Z","steps":["trace[1915802049] 'read index received' (duration: 307.122604ms)","trace[1915802049] 'applied index is now lower than readState.Index' (duration: 6.097993ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:07:23.90799Z","caller":"traceutil/trace.go:171","msg":"trace[1044404300] transaction","detail":"{read_only:false; response_revision:6131; number_of_response:1; }","duration":"293.360055ms","start":"2026-05-27T06:07:23.614607Z","end":"2026-05-27T06:07:23.907967Z","steps":["trace[1044404300] 'process raft request' (duration: 293.250041ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:07:23.908081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.339921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T06:07:23.908106Z","caller":"traceutil/trace.go:171","msg":"trace[1088436071] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6131; }","duration":"313.408343ms","start":"2026-05-27T06:07:23.594692Z","end":"2026-05-27T06:07:23.9081Z","steps":["trace[1088436071] 'agreement among raft nodes before linearized reading' (duration: 313.342811ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:07:23.908126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:07:23.594677Z","time spent":"313.445055ms","remote":"127.0.0.1:44070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-27T06:10:45.201131Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5565} {"level":"info","ts":"2026-05-27T06:10:45.280865Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5565,"took":"78.40088ms","hash":3194540706,"current-db-size-bytes":54460416,"current-db-size":"54 MB","current-db-size-in-use-bytes":18206720,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-27T06:10:45.280937Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3194540706,"revision":5565,"compact-revision":2859}