{"level":"warn","ts":"2026-04-09T00:59:14.382645Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-09T00:59:14.382743Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.209: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.204.45.209:2380","--initial-cluster=instance=https://199.204.45.209:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.209:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.209: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-04-09T00:59:14.382816Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-09T00:59:14.382824Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.209:2380"]} {"level":"info","ts":"2026-04-09T00:59:14.382843Z","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-04-09T00:59:14.384647Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.209:2379"]} {"level":"info","ts":"2026-04-09T00:59:14.384735Z","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.204.45.209:2380"],"listen-peer-urls":["https://199.204.45.209:2380"],"advertise-client-urls":["https://199.204.45.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.209: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-04-09T00:59:14.389149Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.182662ms"} {"level":"info","ts":"2026-04-09T00:59:14.400381Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"adfb641115bf6cbd","cluster-id":"3da6b677d4b80c6"} {"level":"info","ts":"2026-04-09T00:59:14.400566Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd switched to configuration voters=()"} {"level":"info","ts":"2026-04-09T00:59:14.40063Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became follower at term 0"} {"level":"info","ts":"2026-04-09T00:59:14.400673Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft adfb641115bf6cbd [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-09T00:59:14.400733Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became follower at term 1"} {"level":"info","ts":"2026-04-09T00:59:14.400824Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd switched to configuration voters=(12536724012257995965)"} {"level":"warn","ts":"2026-04-09T00:59:14.409656Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-09T00:59:14.4118Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-09T00:59:14.413475Z","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-04-09T00:59:14.417363Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"adfb641115bf6cbd","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-09T00:59:14.417753Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"adfb641115bf6cbd","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-09T00:59:14.418971Z","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-04-09T00:59:14.419418Z","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-04-09T00:59:14.419433Z","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-04-09T00:59:14.420177Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd switched to configuration voters=(12536724012257995965)"} {"level":"info","ts":"2026-04-09T00:59:14.42132Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3da6b677d4b80c6","local-member-id":"adfb641115bf6cbd","added-peer-id":"adfb641115bf6cbd","added-peer-peer-urls":["https://199.204.45.209:2380"]} {"level":"info","ts":"2026-04-09T00:59:14.423777Z","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-04-09T00:59:14.423864Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.209:2380"} {"level":"info","ts":"2026-04-09T00:59:14.4239Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.209:2380"} {"level":"info","ts":"2026-04-09T00:59:14.424288Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"adfb641115bf6cbd","initial-advertise-peer-urls":["https://199.204.45.209:2380"],"listen-peer-urls":["https://199.204.45.209:2380"],"advertise-client-urls":["https://199.204.45.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-09T00:59:14.424334Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-09T00:59:15.002044Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd is starting a new election at term 1"} {"level":"info","ts":"2026-04-09T00:59:15.002134Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became pre-candidate at term 1"} {"level":"info","ts":"2026-04-09T00:59:15.002169Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd received MsgPreVoteResp from adfb641115bf6cbd at term 1"} {"level":"info","ts":"2026-04-09T00:59:15.00219Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became candidate at term 2"} {"level":"info","ts":"2026-04-09T00:59:15.00235Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd received MsgVoteResp from adfb641115bf6cbd at term 2"} {"level":"info","ts":"2026-04-09T00:59:15.002368Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became leader at term 2"} {"level":"info","ts":"2026-04-09T00:59:15.002382Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: adfb641115bf6cbd elected leader adfb641115bf6cbd at term 2"} {"level":"info","ts":"2026-04-09T00:59:15.003552Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T00:59:15.004313Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"adfb641115bf6cbd","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.209:2379]}","request-path":"/0/members/adfb641115bf6cbd/attributes","cluster-id":"3da6b677d4b80c6","publish-timeout":"7s"} {"level":"info","ts":"2026-04-09T00:59:15.004498Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-09T00:59:15.004572Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-09T00:59:15.004776Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-09T00:59:15.004842Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-09T00:59:15.005014Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3da6b677d4b80c6","local-member-id":"adfb641115bf6cbd","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T00:59:15.005552Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T00:59:15.005602Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T00:59:15.0078Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.209:2379"} {"level":"info","ts":"2026-04-09T00:59:15.007956Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-09T00:59:17.13253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.19197ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-09T00:59:17.132938Z","caller":"traceutil/trace.go:171","msg":"trace[2025568624] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"660.186949ms","start":"2026-04-09T00:59:16.472715Z","end":"2026-04-09T00:59:17.132902Z","steps":["trace[2025568624] 'process raft request' (duration: 660.041747ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:59:17.133037Z","caller":"traceutil/trace.go:171","msg":"trace[687515998] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"649.291419ms","start":"2026-04-09T00:59:16.483728Z","end":"2026-04-09T00:59:17.133019Z","steps":["trace[687515998] 'process raft request' (duration: 649.223488ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:59:17.133006Z","caller":"traceutil/trace.go:171","msg":"trace[816825371] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"655.106878ms","start":"2026-04-09T00:59:16.477847Z","end":"2026-04-09T00:59:17.132954Z","steps":["trace[816825371] 'process raft request' (duration: 654.983397ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:59:17.133118Z","caller":"traceutil/trace.go:171","msg":"trace[1505440641] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"649.819695ms","start":"2026-04-09T00:59:16.483212Z","end":"2026-04-09T00:59:17.133031Z","steps":["trace[1505440641] 'process raft request' (duration: 649.677054ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:59:17.133242Z","caller":"traceutil/trace.go:171","msg":"trace[1232410662] transaction","detail":"{read_only:false; response_revision:22; number_of_response:1; }","duration":"649.807265ms","start":"2026-04-09T00:59:16.483425Z","end":"2026-04-09T00:59:17.133233Z","steps":["trace[1232410662] 'process raft request' (duration: 649.498971ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T00:59:17.133296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T00:59:16.483416Z","time spent":"649.859066ms","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T00:59:17.133338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T00:59:16.483187Z","time spent":"650.067738ms","remote":"127.0.0.1:33594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":651,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T00:59:17.13355Z","caller":"traceutil/trace.go:171","msg":"trace[851504910] linearizableReadLoop","detail":"{readStateIndex:24; appliedIndex:20; }","duration":"653.738042ms","start":"2026-04-09T00:59:16.479799Z","end":"2026-04-09T00:59:17.133537Z","steps":["trace[851504910] 'read index received' (duration: 224.729431ms)","trace[851504910] 'applied index is now lower than readState.Index' (duration: 429.006861ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T00:59:17.133632Z","caller":"traceutil/trace.go:171","msg":"trace[369004087] transaction","detail":"{read_only:false; response_revision:25; number_of_response:1; }","duration":"649.806915ms","start":"2026-04-09T00:59:16.483818Z","end":"2026-04-09T00:59:17.133625Z","steps":["trace[369004087] 'process raft request' (duration: 649.648953ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T00:59:17.133713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T00:59:16.477838Z","time spent":"655.364261ms","remote":"127.0.0.1:33364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3479,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T00:59:17.133144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T00:59:16.483706Z","time spent":"649.39994ms","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":956,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T00:59:17.133745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T00:59:16.483809Z","time spent":"649.869685ms","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1007,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T00:59:17.133581Z","caller":"traceutil/trace.go:171","msg":"trace[617664689] transaction","detail":"{read_only:false; response_revision:26; number_of_response:1; }","duration":"649.504521ms","start":"2026-04-09T00:59:16.484065Z","end":"2026-04-09T00:59:17.133569Z","steps":["trace[617664689] 'process raft request' (duration: 649.473901ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:59:17.132964Z","caller":"traceutil/trace.go:171","msg":"trace[644226614] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"661.883948ms","start":"2026-04-09T00:59:16.471046Z","end":"2026-04-09T00:59:17.132929Z","steps":["trace[644226614] 'process raft request' (duration: 233.465764ms)","trace[644226614] 'compare' (duration: 426.885786ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T00:59:17.13393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.788139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-04-09T00:59:17.133966Z","caller":"traceutil/trace.go:171","msg":"trace[1160336436] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:26; }","duration":"592.89362ms","start":"2026-04-09T00:59:16.54106Z","end":"2026-04-09T00:59:17.133953Z","steps":["trace[1160336436] 'agreement among raft nodes before linearized reading' (duration: 592.817429ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T00:59:17.133958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T00:59:16.471037Z","time spent":"662.892839ms","remote":"127.0.0.1:33284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":338,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T00:59:17.133921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.455194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-09T00:59:17.13404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.51919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-04-09T00:59:17.133825Z","caller":"traceutil/trace.go:171","msg":"trace[1703756071] transaction","detail":"{read_only:false; response_revision:24; number_of_response:1; }","duration":"650.003657ms","start":"2026-04-09T00:59:16.483804Z","end":"2026-04-09T00:59:17.133808Z","steps":["trace[1703756071] 'process raft request' (duration: 649.175318ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:59:17.134078Z","caller":"traceutil/trace.go:171","msg":"trace[628709308] transaction","detail":"{read_only:false; number_of_response:0; response_revision:18; }","duration":"660.348721ms","start":"2026-04-09T00:59:16.472521Z","end":"2026-04-09T00:59:17.13287Z","steps":["trace[628709308] 'process raft request' (duration: 660.190339ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:59:17.134087Z","caller":"traceutil/trace.go:171","msg":"trace[1505152452] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:26; }","duration":"420.577951ms","start":"2026-04-09T00:59:16.713499Z","end":"2026-04-09T00:59:17.134077Z","steps":["trace[1505152452] 'agreement among raft nodes before linearized reading' (duration: 420.49735ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T00:59:17.134124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T00:59:16.713493Z","time spent":"420.625292ms","remote":"127.0.0.1:33284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-09T00:59:17.134136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T00:59:16.483798Z","time spent":"650.307331ms","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":947,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T00:59:17.134155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T00:59:16.472511Z","time spent":"661.605015ms","remote":"127.0.0.1:33284","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T00:59:17.134221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.878463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-09T00:59:17.134217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.222411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-x9lgv\" ","response":"range_response_count:1 size:861"} {"level":"info","ts":"2026-04-09T00:59:17.13426Z","caller":"traceutil/trace.go:171","msg":"trace[1375041313] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:26; }","duration":"560.951163ms","start":"2026-04-09T00:59:16.573294Z","end":"2026-04-09T00:59:17.134246Z","steps":["trace[1375041313] 'agreement among raft nodes before linearized reading' (duration: 560.850812ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:59:17.13429Z","caller":"traceutil/trace.go:171","msg":"trace[1800677683] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-x9lgv; range_end:; response_count:1; response_revision:26; }","duration":"661.349243ms","start":"2026-04-09T00:59:16.472907Z","end":"2026-04-09T00:59:17.134256Z","steps":["trace[1800677683] 'agreement among raft nodes before linearized reading' (duration: 661.237311ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T00:59:17.13431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T00:59:16.573281Z","time spent":"561.023234ms","remote":"127.0.0.1:33454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" "} {"level":"warn","ts":"2026-04-09T00:59:17.134372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T00:59:16.472898Z","time spent":"661.452274ms","remote":"127.0.0.1:33440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":884,"request content":"key:\"/registry/certificatesigningrequests/csr-x9lgv\" "} {"level":"info","ts":"2026-04-09T00:59:17.134084Z","caller":"traceutil/trace.go:171","msg":"trace[2026044345] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26; }","duration":"662.681757ms","start":"2026-04-09T00:59:16.471369Z","end":"2026-04-09T00:59:17.134051Z","steps":["trace[2026044345] 'agreement among raft nodes before linearized reading' (duration: 662.333074ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T00:59:17.134548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T00:59:16.471342Z","time spent":"663.185534ms","remote":"127.0.0.1:33160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-09T00:59:17.134568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T00:59:16.541039Z","time spent":"593.507448ms","remote":"127.0.0.1:33284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-09T00:59:17.133914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T00:59:16.484057Z","time spent":"649.825236ms","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1007,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T00:59:17.133301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T00:59:16.47271Z","time spent":"660.353981ms","remote":"127.0.0.1:33652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T00:59:17.439347Z","caller":"traceutil/trace.go:171","msg":"trace[1952547871] transaction","detail":"{read_only:false; response_revision:29; number_of_response:1; }","duration":"300.146546ms","start":"2026-04-09T00:59:17.139166Z","end":"2026-04-09T00:59:17.439313Z","steps":["trace[1952547871] 'process raft request' (duration: 299.978014ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:59:17.43952Z","caller":"traceutil/trace.go:171","msg":"trace[1720597313] linearizableReadLoop","detail":"{readStateIndex:36; appliedIndex:30; }","duration":"300.141607ms","start":"2026-04-09T00:59:17.139276Z","end":"2026-04-09T00:59:17.439418Z","steps":["trace[1720597313] 'read index received' (duration: 289.581654ms)","trace[1720597313] 'applied index is now lower than readState.Index' (duration: 10.558283ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T00:59:17.439683Z","caller":"traceutil/trace.go:171","msg":"trace[193339986] transaction","detail":"{read_only:false; response_revision:33; number_of_response:1; }","duration":"299.230947ms","start":"2026-04-09T00:59:17.140388Z","end":"2026-04-09T00:59:17.439619Z","steps":["trace[193339986] 'process raft request' (duration: 298.936423ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:59:17.439744Z","caller":"traceutil/trace.go:171","msg":"trace[1081377265] transaction","detail":"{read_only:false; response_revision:30; number_of_response:1; }","duration":"300.524601ms","start":"2026-04-09T00:59:17.13917Z","end":"2026-04-09T00:59:17.439694Z","steps":["trace[1081377265] 'process raft request' (duration: 300.054835ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:59:17.439815Z","caller":"traceutil/trace.go:171","msg":"trace[1494659462] transaction","detail":"{read_only:false; response_revision:34; number_of_response:1; }","duration":"299.113996ms","start":"2026-04-09T00:59:17.140687Z","end":"2026-04-09T00:59:17.439801Z","steps":["trace[1494659462] 'process raft request' (duration: 298.693561ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T00:59:17.439992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T00:59:17.139155Z","time spent":"300.699283ms","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T00:59:17.440178Z","caller":"traceutil/trace.go:171","msg":"trace[1149519475] transaction","detail":"{read_only:false; response_revision:31; number_of_response:1; }","duration":"300.945247ms","start":"2026-04-09T00:59:17.139221Z","end":"2026-04-09T00:59:17.440166Z","steps":["trace[1149519475] 'process raft request' (duration: 300.036936ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:59:17.439273Z","caller":"traceutil/trace.go:171","msg":"trace[1143435289] transaction","detail":"{read_only:false; response_revision:28; number_of_response:1; }","duration":"300.242697ms","start":"2026-04-09T00:59:17.13901Z","end":"2026-04-09T00:59:17.439252Z","steps":["trace[1143435289] 'process raft request' (duration: 300.084116ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T00:59:17.440315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.407457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-09T00:59:17.440356Z","caller":"traceutil/trace.go:171","msg":"trace[1033493201] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:34; }","duration":"100.498417ms","start":"2026-04-09T00:59:17.339848Z","end":"2026-04-09T00:59:17.440347Z","steps":["trace[1033493201] 'agreement among raft nodes before linearized reading' (duration: 100.320326ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T00:59:17.440395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T00:59:17.139214Z","time spent":"300.997917ms","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":926,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T00:59:17.440303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T00:59:17.139153Z","time spent":"300.342009ms","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":959,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T00:59:17.44044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.767482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-09T00:59:17.440482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T00:59:17.138993Z","time spent":"301.372571ms","remote":"127.0.0.1:33276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":357,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T00:59:17.440544Z","caller":"traceutil/trace.go:171","msg":"trace[589593223] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:34; }","duration":"299.872783ms","start":"2026-04-09T00:59:17.140632Z","end":"2026-04-09T00:59:17.440505Z","steps":["trace[589593223] 'agreement among raft nodes before linearized reading' (duration: 299.58536ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T00:59:17.440512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.233439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-09T00:59:17.440718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.013294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-09T00:59:17.440748Z","caller":"traceutil/trace.go:171","msg":"trace[619889750] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:34; }","duration":"207.091146ms","start":"2026-04-09T00:59:17.233649Z","end":"2026-04-09T00:59:17.440741Z","steps":["trace[619889750] 'agreement among raft nodes before linearized reading' (duration: 207.046984ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:59:17.440745Z","caller":"traceutil/trace.go:171","msg":"trace[254382293] transaction","detail":"{read_only:false; response_revision:32; number_of_response:1; }","duration":"301.476623ms","start":"2026-04-09T00:59:17.139255Z","end":"2026-04-09T00:59:17.440731Z","steps":["trace[254382293] 'process raft request' (duration: 300.034965ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:59:17.440746Z","caller":"traceutil/trace.go:171","msg":"trace[1806465150] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:34; }","duration":"301.480912ms","start":"2026-04-09T00:59:17.139206Z","end":"2026-04-09T00:59:17.440686Z","steps":["trace[1806465150] 'agreement among raft nodes before linearized reading' (duration: 301.28419ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T00:59:17.440763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.675485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-09T00:59:17.440835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T00:59:17.139183Z","time spent":"301.635915ms","remote":"127.0.0.1:33316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" "} {"level":"info","ts":"2026-04-09T00:59:17.440871Z","caller":"traceutil/trace.go:171","msg":"trace[1503137210] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:34; }","duration":"152.823756ms","start":"2026-04-09T00:59:17.288026Z","end":"2026-04-09T00:59:17.440849Z","steps":["trace[1503137210] 'agreement among raft nodes before linearized reading' (duration: 152.642823ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T00:59:17.440939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.632713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-09T00:59:17.440966Z","caller":"traceutil/trace.go:171","msg":"trace[1258516154] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:34; }","duration":"152.728304ms","start":"2026-04-09T00:59:17.288232Z","end":"2026-04-09T00:59:17.44096Z","steps":["trace[1258516154] 'agreement among raft nodes before linearized reading' (duration: 152.665504ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T00:59:17.440869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T00:59:17.139249Z","time spent":"301.578254ms","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":983,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T00:59:17.441134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.41524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-09T00:59:17.441177Z","caller":"traceutil/trace.go:171","msg":"trace[2115431095] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:0; response_revision:34; }","duration":"300.483392ms","start":"2026-04-09T00:59:17.140687Z","end":"2026-04-09T00:59:17.44117Z","steps":["trace[2115431095] 'agreement among raft nodes before linearized reading' (duration: 300.431451ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T00:59:17.441198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T00:59:17.140389Z","time spent":"300.803955ms","remote":"127.0.0.1:33276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":0,"response size":27,"request content":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" "} {"level":"info","ts":"2026-04-09T00:59:17.43921Z","caller":"traceutil/trace.go:171","msg":"trace[1946140858] transaction","detail":"{read_only:false; response_revision:27; number_of_response:1; }","duration":"300.138147ms","start":"2026-04-09T00:59:17.13904Z","end":"2026-04-09T00:59:17.439178Z","steps":["trace[1946140858] 'process raft request' (duration: 289.667945ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T00:59:17.441569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T00:59:17.139026Z","time spent":"302.277872ms","remote":"127.0.0.1:33454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T00:59:48.286412Z","caller":"traceutil/trace.go:171","msg":"trace[1583019504] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"156.957383ms","start":"2026-04-09T00:59:48.129431Z","end":"2026-04-09T00:59:48.286388Z","steps":["trace[1583019504] 'process raft request' (duration: 86.53067ms)","trace[1583019504] 'compare' (duration: 70.188131ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T00:59:48.286622Z","caller":"traceutil/trace.go:171","msg":"trace[175443000] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"157.014362ms","start":"2026-04-09T00:59:48.129598Z","end":"2026-04-09T00:59:48.286613Z","steps":["trace[175443000] 'process raft request' (duration: 156.68166ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:59:48.286782Z","caller":"traceutil/trace.go:171","msg":"trace[634436541] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"157.007103ms","start":"2026-04-09T00:59:48.12976Z","end":"2026-04-09T00:59:48.286767Z","steps":["trace[634436541] 'process raft request' (duration: 156.884881ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:59:48.286869Z","caller":"traceutil/trace.go:171","msg":"trace[103117331] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"157.065434ms","start":"2026-04-09T00:59:48.129796Z","end":"2026-04-09T00:59:48.286861Z","steps":["trace[103117331] 'process raft request' (duration: 156.918522ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:59:48.287053Z","caller":"traceutil/trace.go:171","msg":"trace[126970405] linearizableReadLoop","detail":"{readStateIndex:522; appliedIndex:518; }","duration":"155.22469ms","start":"2026-04-09T00:59:48.131813Z","end":"2026-04-09T00:59:48.287037Z","steps":["trace[126970405] 'read index received' (duration: 309.172µs)","trace[126970405] 'applied index is now lower than readState.Index' (duration: 154.913878ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T00:59:48.287179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.353271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/cilium-operator\" ","response":"range_response_count:1 size:4335"} {"level":"info","ts":"2026-04-09T00:59:48.2872Z","caller":"traceutil/trace.go:171","msg":"trace[1645763467] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:506; }","duration":"155.401651ms","start":"2026-04-09T00:59:48.131794Z","end":"2026-04-09T00:59:48.287195Z","steps":["trace[1645763467] 'agreement among raft nodes before linearized reading' (duration: 155.3017ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:59:48.287313Z","caller":"traceutil/trace.go:171","msg":"trace[1120736523] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"153.9688ms","start":"2026-04-09T00:59:48.13332Z","end":"2026-04-09T00:59:48.287289Z","steps":["trace[1120736523] 'process raft request' (duration: 153.664638ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:59:48.289905Z","caller":"traceutil/trace.go:171","msg":"trace[1785608341] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"116.956501ms","start":"2026-04-09T00:59:48.17291Z","end":"2026-04-09T00:59:48.289867Z","steps":["trace[1785608341] 'process raft request' (duration: 115.34767ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:59:48.290023Z","caller":"traceutil/trace.go:171","msg":"trace[205631783] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"154.612885ms","start":"2026-04-09T00:59:48.135401Z","end":"2026-04-09T00:59:48.290014Z","steps":["trace[205631783] 'process raft request' (duration: 152.5405ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T00:59:48.290485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.251992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-zmz5l\" ","response":"range_response_count:1 size:15076"} {"level":"info","ts":"2026-04-09T00:59:48.290548Z","caller":"traceutil/trace.go:171","msg":"trace[31964389] range","detail":"{range_begin:/registry/pods/kube-system/cilium-zmz5l; range_end:; response_count:1; response_revision:509; }","duration":"154.330393ms","start":"2026-04-09T00:59:48.136201Z","end":"2026-04-09T00:59:48.290531Z","steps":["trace[31964389] 'agreement among raft nodes before linearized reading' (duration: 154.138731ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:59:49.973614Z","caller":"traceutil/trace.go:171","msg":"trace[1028404793] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"188.709467ms","start":"2026-04-09T00:59:49.784881Z","end":"2026-04-09T00:59:49.97359Z","steps":["trace[1028404793] 'process raft request' (duration: 188.556476ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:59:49.980796Z","caller":"traceutil/trace.go:171","msg":"trace[2020009177] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"180.68578ms","start":"2026-04-09T00:59:49.800094Z","end":"2026-04-09T00:59:49.98078Z","steps":["trace[2020009177] 'process raft request' (duration: 180.415788ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:59:49.980949Z","caller":"traceutil/trace.go:171","msg":"trace[16105432] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"168.655074ms","start":"2026-04-09T00:59:49.812269Z","end":"2026-04-09T00:59:49.980924Z","steps":["trace[16105432] 'process raft request' (duration: 168.411381ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:59:49.981068Z","caller":"traceutil/trace.go:171","msg":"trace[1249122544] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"187.599201ms","start":"2026-04-09T00:59:49.793416Z","end":"2026-04-09T00:59:49.981015Z","steps":["trace[1249122544] 'process raft request' (duration: 186.885115ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:59:49.981113Z","caller":"traceutil/trace.go:171","msg":"trace[782676898] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"179.989226ms","start":"2026-04-09T00:59:49.801111Z","end":"2026-04-09T00:59:49.981101Z","steps":["trace[782676898] 'process raft request' (duration: 179.434272ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:59:49.981246Z","caller":"traceutil/trace.go:171","msg":"trace[1962298190] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"187.825282ms","start":"2026-04-09T00:59:49.793413Z","end":"2026-04-09T00:59:49.981239Z","steps":["trace[1962298190] 'process raft request' (duration: 187.019326ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:59:49.981308Z","caller":"traceutil/trace.go:171","msg":"trace[692169122] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"181.259905ms","start":"2026-04-09T00:59:49.800042Z","end":"2026-04-09T00:59:49.981302Z","steps":["trace[692169122] 'process raft request' (duration: 180.432269ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:59:49.981334Z","caller":"traceutil/trace.go:171","msg":"trace[1041483904] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"155.69925ms","start":"2026-04-09T00:59:49.8256Z","end":"2026-04-09T00:59:49.981299Z","steps":["trace[1041483904] 'process raft request' (duration: 155.177406ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:59:49.981401Z","caller":"traceutil/trace.go:171","msg":"trace[991834361] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"169.044037ms","start":"2026-04-09T00:59:49.812351Z","end":"2026-04-09T00:59:49.981395Z","steps":["trace[991834361] 'process raft request' (duration: 168.386592ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:59:49.98144Z","caller":"traceutil/trace.go:171","msg":"trace[771223640] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"133.3564ms","start":"2026-04-09T00:59:49.848056Z","end":"2026-04-09T00:59:49.981412Z","steps":["trace[771223640] 'process raft request' (duration: 132.771715ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:59:49.981283Z","caller":"traceutil/trace.go:171","msg":"trace[1973245108] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"172.798344ms","start":"2026-04-09T00:59:49.808474Z","end":"2026-04-09T00:59:49.981272Z","steps":["trace[1973245108] 'process raft request' (duration: 172.154249ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T00:59:56.310877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.285456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-zmz5l\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-09T00:59:56.310944Z","caller":"traceutil/trace.go:171","msg":"trace[1106219482] range","detail":"{range_begin:/registry/pods/kube-system/cilium-zmz5l; range_end:; response_count:1; response_revision:629; }","duration":"141.380527ms","start":"2026-04-09T00:59:56.16955Z","end":"2026-04-09T00:59:56.310931Z","steps":["trace[1106219482] 'range keys from in-memory index tree' (duration: 141.148355ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:59:56.471155Z","caller":"traceutil/trace.go:171","msg":"trace[1857628347] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"143.184898ms","start":"2026-04-09T00:59:56.327944Z","end":"2026-04-09T00:59:56.471129Z","steps":["trace[1857628347] 'process raft request' (duration: 143.045287ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:59:56.923852Z","caller":"traceutil/trace.go:171","msg":"trace[1148799899] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:655; }","duration":"376.326ms","start":"2026-04-09T00:59:56.547491Z","end":"2026-04-09T00:59:56.923817Z","steps":["trace[1148799899] 'read index received' (duration: 375.517295ms)","trace[1148799899] 'applied index is now lower than readState.Index' (duration: 807.615µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T00:59:56.923913Z","caller":"traceutil/trace.go:171","msg":"trace[739176189] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"560.808785ms","start":"2026-04-09T00:59:56.363077Z","end":"2026-04-09T00:59:56.923886Z","steps":["trace[739176189] 'process raft request' (duration: 560.231012ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T00:59:56.924141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.635142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-09T00:59:56.924215Z","caller":"traceutil/trace.go:171","msg":"trace[42210643] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:631; }","duration":"376.761263ms","start":"2026-04-09T00:59:56.547437Z","end":"2026-04-09T00:59:56.924198Z","steps":["trace[42210643] 'agreement among raft nodes before linearized reading' (duration: 376.533431ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T00:59:56.924162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T00:59:56.36306Z","time spent":"561.014808ms","remote":"127.0.0.1:33264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036297,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T00:59:56.924247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T00:59:56.547416Z","time spent":"376.825034ms","remote":"127.0.0.1:33454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-09T00:59:57.469347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.857418ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T00:59:57.469523Z","caller":"traceutil/trace.go:171","msg":"trace[1623109448] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"484.521215ms","start":"2026-04-09T00:59:56.984986Z","end":"2026-04-09T00:59:57.469507Z","steps":["trace[1623109448] 'process raft request' (duration: 484.459695ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T00:59:57.469592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T00:59:56.984963Z","time spent":"484.605735ms","remote":"127.0.0.1:33418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T00:59:57.46962Z","caller":"traceutil/trace.go:171","msg":"trace[1187595636] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:657; }","duration":"505.611517ms","start":"2026-04-09T00:59:56.963981Z","end":"2026-04-09T00:59:57.469592Z","steps":["trace[1187595636] 'read index received' (duration: 26.435397ms)","trace[1187595636] 'applied index is now lower than readState.Index' (duration: 479.17393ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T00:59:57.469677Z","caller":"traceutil/trace.go:171","msg":"trace[1065677225] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"536.822496ms","start":"2026-04-09T00:59:56.932821Z","end":"2026-04-09T00:59:57.469644Z","steps":["trace[1065677225] 'process raft request' (duration: 57.588876ms)","trace[1065677225] 'compare' (duration: 478.635776ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T00:59:57.469835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T00:59:56.932807Z","time spent":"536.946407ms","remote":"127.0.0.1:33454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T00:59:57.469845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.856638ms","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-04-09T00:59:57.469885Z","caller":"traceutil/trace.go:171","msg":"trace[79207880] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:634; }","duration":"505.963128ms","start":"2026-04-09T00:59:56.963912Z","end":"2026-04-09T00:59:57.469875Z","steps":["trace[79207880] 'agreement among raft nodes before linearized reading' (duration: 505.775057ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T00:59:57.469928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T00:59:56.963897Z","time spent":"506.023179ms","remote":"127.0.0.1:33454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-09T00:59:57.752838Z","caller":"traceutil/trace.go:171","msg":"trace[689201954] linearizableReadLoop","detail":"{readStateIndex:660; appliedIndex:659; }","duration":"274.43834ms","start":"2026-04-09T00:59:57.478379Z","end":"2026-04-09T00:59:57.752818Z","steps":["trace[689201954] 'read index received' (duration: 237.464955ms)","trace[689201954] 'applied index is now lower than readState.Index' (duration: 36.972385ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T00:59:57.752947Z","caller":"traceutil/trace.go:171","msg":"trace[1658930555] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"277.695812ms","start":"2026-04-09T00:59:57.475237Z","end":"2026-04-09T00:59:57.752933Z","steps":["trace[1658930555] 'process raft request' (duration: 240.693767ms)","trace[1658930555] 'compare' (duration: 36.787314ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T00:59:57.753104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.716724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-09T00:59:57.753145Z","caller":"traceutil/trace.go:171","msg":"trace[1666561046] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:635; }","duration":"274.789914ms","start":"2026-04-09T00:59:57.478345Z","end":"2026-04-09T00:59:57.753135Z","steps":["trace[1666561046] 'agreement among raft nodes before linearized reading' (duration: 274.707064ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T00:59:57.753216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.41858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T00:59:57.753279Z","caller":"traceutil/trace.go:171","msg":"trace[1172928118] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:635; }","duration":"230.52755ms","start":"2026-04-09T00:59:57.522737Z","end":"2026-04-09T00:59:57.753264Z","steps":["trace[1172928118] 'agreement among raft nodes before linearized reading' (duration: 230.386649ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:00:26.073922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.992647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-09T01:00:26.074015Z","caller":"traceutil/trace.go:171","msg":"trace[270753176] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:831; }","duration":"357.156588ms","start":"2026-04-09T01:00:25.716841Z","end":"2026-04-09T01:00:26.073997Z","steps":["trace[270753176] 'range keys from in-memory index tree' (duration: 356.797256ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:00:26.074053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T01:00:25.716811Z","time spent":"357.232728ms","remote":"127.0.0.1:33454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-09T01:00:26.074328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.087268ms","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-04-09T01:00:26.074395Z","caller":"traceutil/trace.go:171","msg":"trace[1284076915] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:831; }","duration":"166.219669ms","start":"2026-04-09T01:00:25.90816Z","end":"2026-04-09T01:00:26.07438Z","steps":["trace[1284076915] 'range keys from in-memory index tree' (duration: 165.869307ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:00:26.074516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.257467ms","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-04-09T01:00:26.074632Z","caller":"traceutil/trace.go:171","msg":"trace[285223317] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:831; }","duration":"116.426958ms","start":"2026-04-09T01:00:25.958178Z","end":"2026-04-09T01:00:26.074605Z","steps":["trace[285223317] 'range keys from in-memory index tree' (duration: 116.031444ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:01:47.769233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.348527ms","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-04-09T01:01:47.769331Z","caller":"traceutil/trace.go:171","msg":"trace[1256805413] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1924; }","duration":"209.483979ms","start":"2026-04-09T01:01:47.559829Z","end":"2026-04-09T01:01:47.769313Z","steps":["trace[1256805413] 'range keys from in-memory index tree' (duration: 209.192884ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:01:47.769656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.123797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-09T01:01:47.769746Z","caller":"traceutil/trace.go:171","msg":"trace[394574604] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1924; }","duration":"214.257591ms","start":"2026-04-09T01:01:47.555467Z","end":"2026-04-09T01:01:47.769724Z","steps":["trace[394574604] 'range keys from in-memory index tree' (duration: 213.941774ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:01:47.953327Z","caller":"traceutil/trace.go:171","msg":"trace[1270389639] transaction","detail":"{read_only:false; response_revision:1926; number_of_response:1; }","duration":"176.086049ms","start":"2026-04-09T01:01:47.77721Z","end":"2026-04-09T01:01:47.953296Z","steps":["trace[1270389639] 'process raft request' (duration: 153.728483ms)","trace[1270389639] 'compare' (duration: 22.101401ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T01:01:47.953551Z","caller":"traceutil/trace.go:171","msg":"trace[1797782505] transaction","detail":"{read_only:false; response_revision:1927; number_of_response:1; }","duration":"173.159189ms","start":"2026-04-09T01:01:47.780354Z","end":"2026-04-09T01:01:47.953513Z","steps":["trace[1797782505] 'process raft request' (duration: 172.873025ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:01:48.28371Z","caller":"traceutil/trace.go:171","msg":"trace[1318051946] linearizableReadLoop","detail":"{readStateIndex:2010; appliedIndex:2009; }","duration":"241.564986ms","start":"2026-04-09T01:01:48.042113Z","end":"2026-04-09T01:01:48.283678Z","steps":["trace[1318051946] 'read index received' (duration: 221.390229ms)","trace[1318051946] 'applied index is now lower than readState.Index' (duration: 20.173187ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T01:01:48.28383Z","caller":"traceutil/trace.go:171","msg":"trace[1523669074] transaction","detail":"{read_only:false; response_revision:1929; number_of_response:1; }","duration":"251.146436ms","start":"2026-04-09T01:01:48.032655Z","end":"2026-04-09T01:01:48.283802Z","steps":["trace[1523669074] 'process raft request' (duration: 230.905428ms)","trace[1523669074] 'compare' (duration: 19.954633ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T01:01:48.283949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.816721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-09T01:01:48.284015Z","caller":"traceutil/trace.go:171","msg":"trace[1492738396] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1929; }","duration":"241.931932ms","start":"2026-04-09T01:01:48.042064Z","end":"2026-04-09T01:01:48.283996Z","steps":["trace[1492738396] 'agreement among raft nodes before linearized reading' (duration: 241.732349ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:02:00.010218Z","caller":"traceutil/trace.go:171","msg":"trace[1794249222] transaction","detail":"{read_only:false; response_revision:2025; number_of_response:1; }","duration":"107.763587ms","start":"2026-04-09T01:01:59.902429Z","end":"2026-04-09T01:02:00.010193Z","steps":["trace[1794249222] 'process raft request' (duration: 107.608595ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:02:00.298758Z","caller":"traceutil/trace.go:171","msg":"trace[574595035] linearizableReadLoop","detail":"{readStateIndex:2111; appliedIndex:2110; }","duration":"187.876771ms","start":"2026-04-09T01:02:00.110854Z","end":"2026-04-09T01:02:00.298731Z","steps":["trace[574595035] 'read index received' (duration: 187.675548ms)","trace[574595035] 'applied index is now lower than readState.Index' (duration: 200.103µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T01:02:00.298913Z","caller":"traceutil/trace.go:171","msg":"trace[183635599] transaction","detail":"{read_only:false; response_revision:2026; number_of_response:1; }","duration":"203.504577ms","start":"2026-04-09T01:02:00.095394Z","end":"2026-04-09T01:02:00.298899Z","steps":["trace[183635599] 'process raft request' (duration: 203.185443ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:02:00.299131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.742884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-09T01:02:00.299215Z","caller":"traceutil/trace.go:171","msg":"trace[830541148] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:2026; }","duration":"159.871965ms","start":"2026-04-09T01:02:00.13932Z","end":"2026-04-09T01:02:00.299192Z","steps":["trace[830541148] 'agreement among raft nodes before linearized reading' (duration: 159.699043ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:02:00.299297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.426439ms","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-04-09T01:02:00.299331Z","caller":"traceutil/trace.go:171","msg":"trace[919825092] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2026; }","duration":"188.51417ms","start":"2026-04-09T01:02:00.110808Z","end":"2026-04-09T01:02:00.299322Z","steps":["trace[919825092] 'agreement among raft nodes before linearized reading' (duration: 188.385018ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:02:16.881649Z","caller":"traceutil/trace.go:171","msg":"trace[868674058] transaction","detail":"{read_only:false; response_revision:2138; number_of_response:1; }","duration":"131.495675ms","start":"2026-04-09T01:02:16.750101Z","end":"2026-04-09T01:02:16.881596Z","steps":["trace[868674058] 'process raft request' (duration: 94.125328ms)","trace[868674058] 'compare' (duration: 37.023223ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T01:02:16.881702Z","caller":"traceutil/trace.go:171","msg":"trace[1927565517] transaction","detail":"{read_only:false; response_revision:2139; number_of_response:1; }","duration":"117.726288ms","start":"2026-04-09T01:02:16.763936Z","end":"2026-04-09T01:02:16.881662Z","steps":["trace[1927565517] 'process raft request' (duration: 117.617497ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:02:16.881852Z","caller":"traceutil/trace.go:171","msg":"trace[521870137] linearizableReadLoop","detail":"{readStateIndex:2226; appliedIndex:2225; }","duration":"122.799087ms","start":"2026-04-09T01:02:16.759015Z","end":"2026-04-09T01:02:16.881814Z","steps":["trace[521870137] 'read index received' (duration: 85.166696ms)","trace[521870137] 'applied index is now lower than readState.Index' (duration: 37.630901ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T01:02:16.882004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.477804ms","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-04-09T01:02:16.88242Z","caller":"traceutil/trace.go:171","msg":"trace[837607805] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2139; }","duration":"181.966511ms","start":"2026-04-09T01:02:16.700434Z","end":"2026-04-09T01:02:16.8824Z","steps":["trace[837607805] 'agreement among raft nodes before linearized reading' (duration: 181.460604ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:02:22.539347Z","caller":"traceutil/trace.go:171","msg":"trace[422193212] linearizableReadLoop","detail":"{readStateIndex:2254; appliedIndex:2253; }","duration":"134.034573ms","start":"2026-04-09T01:02:22.405292Z","end":"2026-04-09T01:02:22.539326Z","steps":["trace[422193212] 'read index received' (duration: 133.881231ms)","trace[422193212] 'applied index is now lower than readState.Index' (duration: 152.132µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T01:02:22.539591Z","caller":"traceutil/trace.go:171","msg":"trace[1955490724] transaction","detail":"{read_only:false; response_revision:2165; number_of_response:1; }","duration":"138.744954ms","start":"2026-04-09T01:02:22.400832Z","end":"2026-04-09T01:02:22.539577Z","steps":["trace[1955490724] 'process raft request' (duration: 138.386049ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:02:22.539836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.536469ms","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-04-09T01:02:22.53988Z","caller":"traceutil/trace.go:171","msg":"trace[1039464372] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2165; }","duration":"134.628409ms","start":"2026-04-09T01:02:22.405241Z","end":"2026-04-09T01:02:22.539869Z","steps":["trace[1039464372] 'agreement among raft nodes before linearized reading' (duration: 134.503318ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:02:24.012981Z","caller":"traceutil/trace.go:171","msg":"trace[1349920654] transaction","detail":"{read_only:false; response_revision:2172; number_of_response:1; }","duration":"444.998672ms","start":"2026-04-09T01:02:23.567951Z","end":"2026-04-09T01:02:24.01295Z","steps":["trace[1349920654] 'process raft request' (duration: 444.83036ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:02:24.013134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T01:02:23.567921Z","time spent":"445.130463ms","remote":"127.0.0.1:33364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T01:02:24.4044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.892448ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-09T01:02:24.40477Z","caller":"traceutil/trace.go:171","msg":"trace[1596666354] linearizableReadLoop","detail":"{readStateIndex:2263; appliedIndex:2261; }","duration":"224.52796ms","start":"2026-04-09T01:02:24.180212Z","end":"2026-04-09T01:02:24.40474Z","steps":["trace[1596666354] 'read index received' (duration: 61.301µs)","trace[1596666354] 'applied index is now lower than readState.Index' (duration: 224.465609ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T01:02:24.404973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.749172ms","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-04-09T01:02:24.404926Z","caller":"traceutil/trace.go:171","msg":"trace[1986703245] transaction","detail":"{read_only:false; response_revision:2173; number_of_response:1; }","duration":"389.315688ms","start":"2026-04-09T01:02:24.015563Z","end":"2026-04-09T01:02:24.404879Z","steps":["trace[1986703245] 'process raft request' (duration: 388.988314ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:02:24.405015Z","caller":"traceutil/trace.go:171","msg":"trace[2136422533] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2173; }","duration":"224.848574ms","start":"2026-04-09T01:02:24.180154Z","end":"2026-04-09T01:02:24.405003Z","steps":["trace[2136422533] 'agreement among raft nodes before linearized reading' (duration: 224.663741ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:02:24.405504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T01:02:24.015529Z","time spent":"389.745424ms","remote":"127.0.0.1:33454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T01:02:24.656968Z","caller":"traceutil/trace.go:171","msg":"trace[954801198] transaction","detail":"{read_only:false; response_revision:2174; number_of_response:1; }","duration":"250.257162ms","start":"2026-04-09T01:02:24.406672Z","end":"2026-04-09T01:02:24.65693Z","steps":["trace[954801198] 'process raft request' (duration: 205.548395ms)","trace[954801198] 'compare' (duration: 44.07179ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T01:02:24.657239Z","caller":"traceutil/trace.go:171","msg":"trace[178666586] transaction","detail":"{read_only:false; response_revision:2176; number_of_response:1; }","duration":"111.329448ms","start":"2026-04-09T01:02:24.545873Z","end":"2026-04-09T01:02:24.657202Z","steps":["trace[178666586] 'process raft request' (duration: 110.930273ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:02:24.656958Z","caller":"traceutil/trace.go:171","msg":"trace[1703739079] transaction","detail":"{read_only:false; response_revision:2175; number_of_response:1; }","duration":"247.639368ms","start":"2026-04-09T01:02:24.409271Z","end":"2026-04-09T01:02:24.65691Z","steps":["trace[1703739079] 'process raft request' (duration: 247.403685ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:02:24.657463Z","caller":"traceutil/trace.go:171","msg":"trace[696903236] linearizableReadLoop","detail":"{readStateIndex:2265; appliedIndex:2263; }","duration":"202.83274ms","start":"2026-04-09T01:02:24.454612Z","end":"2026-04-09T01:02:24.657445Z","steps":["trace[696903236] 'read index received' (duration: 157.601575ms)","trace[696903236] 'applied index is now lower than readState.Index' (duration: 45.229805ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T01:02:24.657573Z","caller":"traceutil/trace.go:171","msg":"trace[699128828] transaction","detail":"{read_only:false; response_revision:2177; number_of_response:1; }","duration":"101.486361ms","start":"2026-04-09T01:02:24.55607Z","end":"2026-04-09T01:02:24.657556Z","steps":["trace[699128828] 'process raft request' (duration: 100.782181ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:02:24.657648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.270587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T01:02:24.657687Z","caller":"traceutil/trace.go:171","msg":"trace[187856458] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2177; }","duration":"135.341299ms","start":"2026-04-09T01:02:24.522337Z","end":"2026-04-09T01:02:24.657678Z","steps":["trace[187856458] 'agreement among raft nodes before linearized reading' (duration: 135.268118ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:02:24.657859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.611323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-09T01:02:24.65789Z","caller":"traceutil/trace.go:171","msg":"trace[1285813448] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2177; }","duration":"101.675563ms","start":"2026-04-09T01:02:24.556205Z","end":"2026-04-09T01:02:24.65788Z","steps":["trace[1285813448] 'agreement among raft nodes before linearized reading' (duration: 101.580342ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:02:24.658021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.395746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T01:02:24.658079Z","caller":"traceutil/trace.go:171","msg":"trace[1173297741] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2177; }","duration":"203.538388ms","start":"2026-04-09T01:02:24.454526Z","end":"2026-04-09T01:02:24.658065Z","steps":["trace[1173297741] 'agreement among raft nodes before linearized reading' (duration: 203.423987ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:04:03.065937Z","caller":"traceutil/trace.go:171","msg":"trace[437957031] transaction","detail":"{read_only:false; response_revision:2810; number_of_response:1; }","duration":"239.987761ms","start":"2026-04-09T01:04:02.825924Z","end":"2026-04-09T01:04:03.065912Z","steps":["trace[437957031] 'process raft request' (duration: 239.81741ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:04:03.220405Z","caller":"traceutil/trace.go:171","msg":"trace[897684240] transaction","detail":"{read_only:false; response_revision:2812; number_of_response:1; }","duration":"148.008137ms","start":"2026-04-09T01:04:03.072329Z","end":"2026-04-09T01:04:03.220337Z","steps":["trace[897684240] 'process raft request' (duration: 147.813855ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:04:03.220589Z","caller":"traceutil/trace.go:171","msg":"trace[542725158] linearizableReadLoop","detail":"{readStateIndex:2926; appliedIndex:2924; }","duration":"103.219713ms","start":"2026-04-09T01:04:03.117346Z","end":"2026-04-09T01:04:03.220566Z","steps":["trace[542725158] 'read index received' (duration: 102.739918ms)","trace[542725158] 'applied index is now lower than readState.Index' (duration: 478.825µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T01:04:03.220802Z","caller":"traceutil/trace.go:171","msg":"trace[22883490] transaction","detail":"{read_only:false; response_revision:2813; number_of_response:1; }","duration":"135.852178ms","start":"2026-04-09T01:04:03.084936Z","end":"2026-04-09T01:04:03.220788Z","steps":["trace[22883490] 'process raft request' (duration: 135.364322ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:04:03.22107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.702607ms","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-04-09T01:04:03.221113Z","caller":"traceutil/trace.go:171","msg":"trace[2051239200] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2813; }","duration":"103.805698ms","start":"2026-04-09T01:04:03.117295Z","end":"2026-04-09T01:04:03.221101Z","steps":["trace[2051239200] 'agreement among raft nodes before linearized reading' (duration: 103.669457ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:05:19.563414Z","caller":"traceutil/trace.go:171","msg":"trace[224415824] transaction","detail":"{read_only:false; response_revision:3214; number_of_response:1; }","duration":"129.174204ms","start":"2026-04-09T01:05:19.43421Z","end":"2026-04-09T01:05:19.563384Z","steps":["trace[224415824] 'process raft request' (duration: 128.995608ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:05:19.752422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.296414ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T01:05:19.75273Z","caller":"traceutil/trace.go:171","msg":"trace[595893265] transaction","detail":"{read_only:false; response_revision:3217; number_of_response:1; }","duration":"133.103752ms","start":"2026-04-09T01:05:19.619595Z","end":"2026-04-09T01:05:19.752699Z","steps":["trace[595893265] 'process raft request' (duration: 28.401315ms)","trace[595893265] 'compare' (duration: 104.085488ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T01:05:43.856024Z","caller":"traceutil/trace.go:171","msg":"trace[1618765817] transaction","detail":"{read_only:false; response_revision:3336; number_of_response:1; }","duration":"124.486298ms","start":"2026-04-09T01:05:43.731499Z","end":"2026-04-09T01:05:43.855985Z","steps":["trace[1618765817] 'process raft request' (duration: 124.413796ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:05:43.856319Z","caller":"traceutil/trace.go:171","msg":"trace[1870451124] transaction","detail":"{read_only:false; response_revision:3335; number_of_response:1; }","duration":"140.848399ms","start":"2026-04-09T01:05:43.715432Z","end":"2026-04-09T01:05:43.85628Z","steps":["trace[1870451124] 'process raft request' (duration: 137.429031ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:05:44.217697Z","caller":"traceutil/trace.go:171","msg":"trace[1459396321] transaction","detail":"{read_only:false; response_revision:3342; number_of_response:1; }","duration":"101.557461ms","start":"2026-04-09T01:05:44.116111Z","end":"2026-04-09T01:05:44.217668Z","steps":["trace[1459396321] 'process raft request' (duration: 101.469408ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:05:44.217748Z","caller":"traceutil/trace.go:171","msg":"trace[1513493968] transaction","detail":"{read_only:false; response_revision:3341; number_of_response:1; }","duration":"131.569214ms","start":"2026-04-09T01:05:44.086149Z","end":"2026-04-09T01:05:44.217718Z","steps":["trace[1513493968] 'process raft request' (duration: 130.05307ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:05:48.180608Z","caller":"traceutil/trace.go:171","msg":"trace[1663874389] linearizableReadLoop","detail":"{readStateIndex:3493; appliedIndex:3491; }","duration":"228.868906ms","start":"2026-04-09T01:05:47.951707Z","end":"2026-04-09T01:05:48.180576Z","steps":["trace[1663874389] 'read index received' (duration: 148.486167ms)","trace[1663874389] 'applied index is now lower than readState.Index' (duration: 80.381359ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T01:05:48.180789Z","caller":"traceutil/trace.go:171","msg":"trace[740796057] transaction","detail":"{read_only:false; response_revision:3357; number_of_response:1; }","duration":"235.592005ms","start":"2026-04-09T01:05:47.94518Z","end":"2026-04-09T01:05:48.180772Z","steps":["trace[740796057] 'process raft request' (duration: 154.996501ms)","trace[740796057] 'compare' (duration: 80.115361ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T01:05:48.180894Z","caller":"traceutil/trace.go:171","msg":"trace[1354947326] transaction","detail":"{read_only:false; response_revision:3358; number_of_response:1; }","duration":"235.616106ms","start":"2026-04-09T01:05:47.945236Z","end":"2026-04-09T01:05:48.180852Z","steps":["trace[1354947326] 'process raft request' (duration: 235.276737ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:05:48.18103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.99302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-04-09T01:05:48.181063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.345439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-09T01:05:48.181135Z","caller":"traceutil/trace.go:171","msg":"trace[1305227904] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3358; }","duration":"216.125274ms","start":"2026-04-09T01:05:47.964987Z","end":"2026-04-09T01:05:48.181112Z","steps":["trace[1305227904] 'agreement among raft nodes before linearized reading' (duration: 215.867967ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:05:48.181222Z","caller":"traceutil/trace.go:171","msg":"trace[544610473] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3358; }","duration":"229.544164ms","start":"2026-04-09T01:05:47.951658Z","end":"2026-04-09T01:05:48.181203Z","steps":["trace[544610473] 'agreement among raft nodes before linearized reading' (duration: 229.160284ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:05:48.640347Z","caller":"traceutil/trace.go:171","msg":"trace[375344168] linearizableReadLoop","detail":"{readStateIndex:3503; appliedIndex:3502; }","duration":"117.421074ms","start":"2026-04-09T01:05:48.5229Z","end":"2026-04-09T01:05:48.640321Z","steps":["trace[375344168] 'read index received' (duration: 43.038145ms)","trace[375344168] 'applied index is now lower than readState.Index' (duration: 74.381809ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T01:05:48.640605Z","caller":"traceutil/trace.go:171","msg":"trace[1362478612] transaction","detail":"{read_only:false; response_revision:3367; number_of_response:1; }","duration":"141.689918ms","start":"2026-04-09T01:05:48.498899Z","end":"2026-04-09T01:05:48.640589Z","steps":["trace[1362478612] 'process raft request' (duration: 66.9658ms)","trace[1362478612] 'compare' (duration: 74.230175ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T01:05:48.640825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.905917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T01:05:48.640865Z","caller":"traceutil/trace.go:171","msg":"trace[1211353286] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3367; }","duration":"117.992501ms","start":"2026-04-09T01:05:48.522863Z","end":"2026-04-09T01:05:48.640855Z","steps":["trace[1211353286] 'agreement among raft nodes before linearized reading' (duration: 117.918037ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:05:48.642065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.887785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T01:05:48.642145Z","caller":"traceutil/trace.go:171","msg":"trace[1221064690] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:3367; }","duration":"113.002568ms","start":"2026-04-09T01:05:48.529124Z","end":"2026-04-09T01:05:48.642126Z","steps":["trace[1221064690] 'agreement among raft nodes before linearized reading' (duration: 112.851433ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:05:56.345798Z","caller":"traceutil/trace.go:171","msg":"trace[1956401052] transaction","detail":"{read_only:false; response_revision:3487; number_of_response:1; }","duration":"136.029418ms","start":"2026-04-09T01:05:56.209728Z","end":"2026-04-09T01:05:56.345758Z","steps":["trace[1956401052] 'process raft request' (duration: 121.149885ms)","trace[1956401052] 'compare' (duration: 14.724379ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T01:06:12.676885Z","caller":"traceutil/trace.go:171","msg":"trace[1975052555] transaction","detail":"{read_only:false; response_revision:3583; number_of_response:1; }","duration":"125.533126ms","start":"2026-04-09T01:06:12.551324Z","end":"2026-04-09T01:06:12.676857Z","steps":["trace[1975052555] 'process raft request' (duration: 59.324681ms)","trace[1975052555] 'compare' (duration: 66.013641ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T01:06:12.677102Z","caller":"traceutil/trace.go:171","msg":"trace[1255616574] transaction","detail":"{read_only:false; response_revision:3584; number_of_response:1; }","duration":"125.707661ms","start":"2026-04-09T01:06:12.551382Z","end":"2026-04-09T01:06:12.67709Z","steps":["trace[1255616574] 'process raft request' (duration: 125.399003ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:07:39.401626Z","caller":"traceutil/trace.go:171","msg":"trace[639611050] transaction","detail":"{read_only:false; response_revision:4143; number_of_response:1; }","duration":"140.805136ms","start":"2026-04-09T01:07:39.260786Z","end":"2026-04-09T01:07:39.401591Z","steps":["trace[639611050] 'process raft request' (duration: 62.192101ms)","trace[639611050] 'compare' (duration: 78.453461ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T01:07:47.082529Z","caller":"traceutil/trace.go:171","msg":"trace[2117937667] transaction","detail":"{read_only:false; response_revision:4596; number_of_response:1; }","duration":"376.548438ms","start":"2026-04-09T01:07:46.705956Z","end":"2026-04-09T01:07:47.082504Z","steps":["trace[2117937667] 'process raft request' (duration: 376.401836ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:07:47.082685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T01:07:46.705939Z","time spent":"376.66611ms","remote":"127.0.0.1:33454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T01:07:47.256203Z","caller":"traceutil/trace.go:171","msg":"trace[1282996742] linearizableReadLoop","detail":"{readStateIndex:4780; appliedIndex:4779; }","duration":"108.710499ms","start":"2026-04-09T01:07:47.147459Z","end":"2026-04-09T01:07:47.25617Z","steps":["trace[1282996742] 'read index received' (duration: 78.283464ms)","trace[1282996742] 'applied index is now lower than readState.Index' (duration: 30.425735ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T01:07:47.256527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.039286ms","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-04-09T01:07:47.256656Z","caller":"traceutil/trace.go:171","msg":"trace[1606694488] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4597; }","duration":"109.226178ms","start":"2026-04-09T01:07:47.147411Z","end":"2026-04-09T01:07:47.256637Z","steps":["trace[1606694488] 'agreement among raft nodes before linearized reading' (duration: 108.966924ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:07:47.256926Z","caller":"traceutil/trace.go:171","msg":"trace[2026512702] transaction","detail":"{read_only:false; response_revision:4597; number_of_response:1; }","duration":"336.291195ms","start":"2026-04-09T01:07:46.92062Z","end":"2026-04-09T01:07:47.256911Z","steps":["trace[2026512702] 'process raft request' (duration: 305.175527ms)","trace[2026512702] 'compare' (duration: 30.236182ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T01:07:47.25707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T01:07:46.920588Z","time spent":"336.417137ms","remote":"127.0.0.1:33454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T01:07:47.569856Z","caller":"traceutil/trace.go:171","msg":"trace[519072386] linearizableReadLoop","detail":"{readStateIndex:4786; appliedIndex:4785; }","duration":"103.393783ms","start":"2026-04-09T01:07:47.46643Z","end":"2026-04-09T01:07:47.569824Z","steps":["trace[519072386] 'read index received' (duration: 103.22898ms)","trace[519072386] 'applied index is now lower than readState.Index' (duration: 163.553µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T01:07:47.569885Z","caller":"traceutil/trace.go:171","msg":"trace[873591028] transaction","detail":"{read_only:false; response_revision:4603; number_of_response:1; }","duration":"150.873535ms","start":"2026-04-09T01:07:47.418973Z","end":"2026-04-09T01:07:47.569846Z","steps":["trace[873591028] 'process raft request' (duration: 150.731612ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:07:47.570082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.646328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-09T01:07:47.570118Z","caller":"traceutil/trace.go:171","msg":"trace[798168779] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4603; }","duration":"103.73937ms","start":"2026-04-09T01:07:47.466368Z","end":"2026-04-09T01:07:47.570108Z","steps":["trace[798168779] 'agreement among raft nodes before linearized reading' (duration: 103.578587ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:07:53.666271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.205039ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T01:07:53.666941Z","caller":"traceutil/trace.go:171","msg":"trace[132235610] linearizableReadLoop","detail":"{readStateIndex:4898; appliedIndex:4897; }","duration":"348.897939ms","start":"2026-04-09T01:07:53.318008Z","end":"2026-04-09T01:07:53.666906Z","steps":["trace[132235610] 'read index received' (duration: 76.789193ms)","trace[132235610] 'applied index is now lower than readState.Index' (duration: 272.107256ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T01:07:53.6671Z","caller":"traceutil/trace.go:171","msg":"trace[1234758701] transaction","detail":"{read_only:false; response_revision:4711; number_of_response:1; }","duration":"388.030519ms","start":"2026-04-09T01:07:53.279053Z","end":"2026-04-09T01:07:53.667084Z","steps":["trace[1234758701] 'process raft request' (duration: 115.860492ms)","trace[1234758701] 'compare' (duration: 270.952175ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T01:07:53.667158Z","caller":"traceutil/trace.go:171","msg":"trace[742224709] transaction","detail":"{read_only:false; response_revision:4712; number_of_response:1; }","duration":"202.980108ms","start":"2026-04-09T01:07:53.464112Z","end":"2026-04-09T01:07:53.667092Z","steps":["trace[742224709] 'process raft request' (duration: 202.344007ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:07:53.667252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T01:07:53.279012Z","time spent":"388.12231ms","remote":"127.0.0.1:33454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T01:07:53.756044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.409017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T01:07:53.756053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.009349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-09T01:07:53.756105Z","caller":"traceutil/trace.go:171","msg":"trace[1858305462] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4712; }","duration":"232.515878ms","start":"2026-04-09T01:07:53.523574Z","end":"2026-04-09T01:07:53.75609Z","steps":["trace[1858305462] 'agreement among raft nodes before linearized reading' (duration: 232.383736ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:07:53.756134Z","caller":"traceutil/trace.go:171","msg":"trace[1708728762] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4712; }","duration":"438.153162ms","start":"2026-04-09T01:07:53.317954Z","end":"2026-04-09T01:07:53.756107Z","steps":["trace[1708728762] 'agreement among raft nodes before linearized reading' (duration: 349.202584ms)","trace[1708728762] 'range keys from in-memory index tree' (duration: 88.725175ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T01:07:53.756179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T01:07:53.317936Z","time spent":"438.231893ms","remote":"127.0.0.1:33454","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":"warn","ts":"2026-04-09T01:07:53.756179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.59129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-09T01:07:53.756255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.164638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-k98ps\" ","response":"range_response_count:1 size:9633"} {"level":"info","ts":"2026-04-09T01:07:53.756285Z","caller":"traceutil/trace.go:171","msg":"trace[2072238833] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4712; }","duration":"409.771703ms","start":"2026-04-09T01:07:53.346488Z","end":"2026-04-09T01:07:53.75626Z","steps":["trace[2072238833] 'agreement among raft nodes before linearized reading' (duration: 409.451427ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:07:53.756336Z","caller":"traceutil/trace.go:171","msg":"trace[1114719147] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-k98ps; range_end:; response_count:1; response_revision:4712; }","duration":"293.2782ms","start":"2026-04-09T01:07:53.463035Z","end":"2026-04-09T01:07:53.756313Z","steps":["trace[1114719147] 'agreement among raft nodes before linearized reading' (duration: 293.035065ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:07:53.756344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T01:07:53.346462Z","time spent":"409.871784ms","remote":"127.0.0.1:33454","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-04-09T01:07:53.756368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.152738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-04-09T01:07:53.756325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.715182ms","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-04-09T01:07:53.756398Z","caller":"traceutil/trace.go:171","msg":"trace[1572491387] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4712; }","duration":"132.23819ms","start":"2026-04-09T01:07:53.624153Z","end":"2026-04-09T01:07:53.756391Z","steps":["trace[1572491387] 'agreement among raft nodes before linearized reading' (duration: 132.125178ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:07:53.756439Z","caller":"traceutil/trace.go:171","msg":"trace[1054555391] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4712; }","duration":"396.898055ms","start":"2026-04-09T01:07:53.359515Z","end":"2026-04-09T01:07:53.756413Z","steps":["trace[1054555391] 'agreement among raft nodes before linearized reading' (duration: 396.528619ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:07:53.756487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T01:07:53.359498Z","time spent":"396.980956ms","remote":"127.0.0.1:33454","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-04-09T01:07:53.756354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.447627ms","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-04-09T01:07:53.756562Z","caller":"traceutil/trace.go:171","msg":"trace[1811278342] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4712; }","duration":"409.686611ms","start":"2026-04-09T01:07:53.346861Z","end":"2026-04-09T01:07:53.756548Z","steps":["trace[1811278342] 'agreement among raft nodes before linearized reading' (duration: 409.322164ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:07:53.756625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T01:07:53.346856Z","time spent":"409.757603ms","remote":"127.0.0.1:33454","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":"info","ts":"2026-04-09T01:07:53.939066Z","caller":"traceutil/trace.go:171","msg":"trace[1697948508] transaction","detail":"{read_only:false; response_revision:4715; number_of_response:1; }","duration":"177.739773ms","start":"2026-04-09T01:07:53.761295Z","end":"2026-04-09T01:07:53.939035Z","steps":["trace[1697948508] 'process raft request' (duration: 177.666131ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:07:53.939065Z","caller":"traceutil/trace.go:171","msg":"trace[990975947] transaction","detail":"{read_only:false; response_revision:4714; number_of_response:1; }","duration":"178.262052ms","start":"2026-04-09T01:07:53.760779Z","end":"2026-04-09T01:07:53.939041Z","steps":["trace[990975947] 'process raft request' (duration: 155.503881ms)","trace[990975947] 'compare' (duration: 22.548867ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T01:07:53.939226Z","caller":"traceutil/trace.go:171","msg":"trace[671310488] transaction","detail":"{read_only:false; response_revision:4716; number_of_response:1; }","duration":"175.609256ms","start":"2026-04-09T01:07:53.763606Z","end":"2026-04-09T01:07:53.939215Z","steps":["trace[671310488] 'process raft request' (duration: 175.387461ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:07:53.939418Z","caller":"traceutil/trace.go:171","msg":"trace[808844984] transaction","detail":"{read_only:false; response_revision:4718; number_of_response:1; }","duration":"170.304673ms","start":"2026-04-09T01:07:53.769094Z","end":"2026-04-09T01:07:53.939399Z","steps":["trace[808844984] 'process raft request' (duration: 170.231221ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:07:53.939441Z","caller":"traceutil/trace.go:171","msg":"trace[855094423] transaction","detail":"{read_only:false; response_revision:4717; number_of_response:1; }","duration":"175.705887ms","start":"2026-04-09T01:07:53.76371Z","end":"2026-04-09T01:07:53.939416Z","steps":["trace[855094423] 'process raft request' (duration: 175.449423ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:07:58.494208Z","caller":"traceutil/trace.go:171","msg":"trace[371779863] linearizableReadLoop","detail":"{readStateIndex:4987; appliedIndex:4986; }","duration":"122.819565ms","start":"2026-04-09T01:07:58.371362Z","end":"2026-04-09T01:07:58.494181Z","steps":["trace[371779863] 'read index received' (duration: 122.639642ms)","trace[371779863] 'applied index is now lower than readState.Index' (duration: 178.643µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T01:07:58.494251Z","caller":"traceutil/trace.go:171","msg":"trace[1112063576] transaction","detail":"{read_only:false; response_revision:4798; number_of_response:1; }","duration":"168.634381ms","start":"2026-04-09T01:07:58.325581Z","end":"2026-04-09T01:07:58.494215Z","steps":["trace[1112063576] 'process raft request' (duration: 168.412177ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:07:58.494565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.17258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.209\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-09T01:07:58.49465Z","caller":"traceutil/trace.go:171","msg":"trace[1754407860] range","detail":"{range_begin:/registry/masterleases/199.204.45.209; range_end:; response_count:1; response_revision:4798; }","duration":"123.317423ms","start":"2026-04-09T01:07:58.371313Z","end":"2026-04-09T01:07:58.49463Z","steps":["trace[1754407860] 'agreement among raft nodes before linearized reading' (duration: 122.983117ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:07:58.752565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.507385ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T01:07:58.752962Z","caller":"traceutil/trace.go:171","msg":"trace[361876234] linearizableReadLoop","detail":"{readStateIndex:4990; appliedIndex:4987; }","duration":"244.413058ms","start":"2026-04-09T01:07:58.508528Z","end":"2026-04-09T01:07:58.752941Z","steps":["trace[361876234] 'read index received' (duration: 93.528555ms)","trace[361876234] 'applied index is now lower than readState.Index' (duration: 150.883453ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T01:07:58.753056Z","caller":"traceutil/trace.go:171","msg":"trace[698097421] transaction","detail":"{read_only:false; response_revision:4800; number_of_response:1; }","duration":"253.807291ms","start":"2026-04-09T01:07:58.499227Z","end":"2026-04-09T01:07:58.753034Z","steps":["trace[698097421] 'process raft request' (duration: 253.545556ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:07:58.753087Z","caller":"traceutil/trace.go:171","msg":"trace[732592032] transaction","detail":"{read_only:false; response_revision:4799; number_of_response:1; }","duration":"278.972879ms","start":"2026-04-09T01:07:58.474052Z","end":"2026-04-09T01:07:58.753025Z","steps":["trace[732592032] 'process raft request' (duration: 127.884883ms)","trace[732592032] 'compare' (duration: 150.369373ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T01:07:58.753222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.468514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T01:07:58.753261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.716544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d0d39d46-ed04-4afd-b6cf-74624a5a5b14\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-09T01:07:58.75332Z","caller":"traceutil/trace.go:171","msg":"trace[1035158094] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4800; }","duration":"231.600256ms","start":"2026-04-09T01:07:58.521693Z","end":"2026-04-09T01:07:58.753294Z","steps":["trace[1035158094] 'agreement among raft nodes before linearized reading' (duration: 231.381182ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:07:58.753331Z","caller":"traceutil/trace.go:171","msg":"trace[1018341918] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d0d39d46-ed04-4afd-b6cf-74624a5a5b14; range_end:; response_count:1; response_revision:4800; }","duration":"244.804025ms","start":"2026-04-09T01:07:58.508506Z","end":"2026-04-09T01:07:58.75331Z","steps":["trace[1018341918] 'agreement among raft nodes before linearized reading' (duration: 244.542521ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:07:59.255155Z","caller":"traceutil/trace.go:171","msg":"trace[4090820] linearizableReadLoop","detail":"{readStateIndex:4993; appliedIndex:4992; }","duration":"116.074312ms","start":"2026-04-09T01:07:59.139043Z","end":"2026-04-09T01:07:59.255117Z","steps":["trace[4090820] 'read index received' (duration: 115.864519ms)","trace[4090820] 'applied index is now lower than readState.Index' (duration: 208.223µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T01:07:59.255219Z","caller":"traceutil/trace.go:171","msg":"trace[2102676593] transaction","detail":"{read_only:false; response_revision:4803; number_of_response:1; }","duration":"117.921484ms","start":"2026-04-09T01:07:59.137243Z","end":"2026-04-09T01:07:59.255164Z","steps":["trace[2102676593] 'process raft request' (duration: 117.65598ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:07:59.255556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.48854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d0d39d46-ed04-4afd-b6cf-74624a5a5b14\" ","response":"range_response_count:1 size:3766"} {"level":"info","ts":"2026-04-09T01:07:59.255629Z","caller":"traceutil/trace.go:171","msg":"trace[994155006] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d0d39d46-ed04-4afd-b6cf-74624a5a5b14; range_end:; response_count:1; response_revision:4803; }","duration":"116.636122ms","start":"2026-04-09T01:07:59.138964Z","end":"2026-04-09T01:07:59.2556Z","steps":["trace[994155006] 'agreement among raft nodes before linearized reading' (duration: 116.286995ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:07:59.828289Z","caller":"traceutil/trace.go:171","msg":"trace[1929247380] transaction","detail":"{read_only:false; response_revision:4804; number_of_response:1; }","duration":"125.267841ms","start":"2026-04-09T01:07:59.702975Z","end":"2026-04-09T01:07:59.828243Z","steps":["trace[1929247380] 'process raft request' (duration: 125.037857ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:08:03.53376Z","caller":"traceutil/trace.go:171","msg":"trace[449396482] linearizableReadLoop","detail":"{readStateIndex:5062; appliedIndex:5060; }","duration":"102.214023ms","start":"2026-04-09T01:08:03.431523Z","end":"2026-04-09T01:08:03.533737Z","steps":["trace[449396482] 'read index received' (duration: 14.55214ms)","trace[449396482] 'applied index is now lower than readState.Index' (duration: 87.660603ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T01:08:03.533811Z","caller":"traceutil/trace.go:171","msg":"trace[1501642377] transaction","detail":"{read_only:false; response_revision:4872; number_of_response:1; }","duration":"118.399989ms","start":"2026-04-09T01:08:03.415382Z","end":"2026-04-09T01:08:03.533782Z","steps":["trace[1501642377] 'process raft request' (duration: 118.278907ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:08:03.533937Z","caller":"traceutil/trace.go:171","msg":"trace[674626205] transaction","detail":"{read_only:false; response_revision:4871; number_of_response:1; }","duration":"118.584163ms","start":"2026-04-09T01:08:03.415338Z","end":"2026-04-09T01:08:03.533923Z","steps":["trace[674626205] 'process raft request' (duration: 30.814919ms)","trace[674626205] 'compare' (duration: 87.332397ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T01:08:03.533946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.402086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T01:08:03.534139Z","caller":"traceutil/trace.go:171","msg":"trace[481230553] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:4872; }","duration":"102.638019ms","start":"2026-04-09T01:08:03.431446Z","end":"2026-04-09T01:08:03.534084Z","steps":["trace[481230553] 'agreement among raft nodes before linearized reading' (duration: 102.442566ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:08:03.694731Z","caller":"traceutil/trace.go:171","msg":"trace[1882428500] transaction","detail":"{read_only:false; response_revision:4873; number_of_response:1; }","duration":"153.345338ms","start":"2026-04-09T01:08:03.541348Z","end":"2026-04-09T01:08:03.694694Z","steps":["trace[1882428500] 'process raft request' (duration: 143.983408ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:08:09.98834Z","caller":"traceutil/trace.go:171","msg":"trace[462915549] transaction","detail":"{read_only:false; response_revision:4959; number_of_response:1; }","duration":"125.06283ms","start":"2026-04-09T01:08:09.86325Z","end":"2026-04-09T01:08:09.988313Z","steps":["trace[462915549] 'process raft request' (duration: 120.95637ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:08:15.216502Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835592029984558356,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-09T01:08:15.71756Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835592029984558356,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-09T01:08:15.877289Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.184932563s","expected-duration":"1s"} {"level":"info","ts":"2026-04-09T01:08:15.877899Z","caller":"traceutil/trace.go:171","msg":"trace[1957088090] transaction","detail":"{read_only:false; response_revision:4999; number_of_response:1; }","duration":"1.185653345s","start":"2026-04-09T01:08:14.692213Z","end":"2026-04-09T01:08:15.877867Z","steps":["trace[1957088090] 'process raft request' (duration: 1.18543502s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:08:15.878081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T01:08:14.692183Z","time spent":"1.185788657s","remote":"127.0.0.1:33378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T01:08:15.889401Z","caller":"traceutil/trace.go:171","msg":"trace[1319325461] transaction","detail":"{read_only:false; response_revision:5000; number_of_response:1; }","duration":"1.077726646s","start":"2026-04-09T01:08:14.811649Z","end":"2026-04-09T01:08:15.889376Z","steps":["trace[1319325461] 'process raft request' (duration: 1.077578024s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:08:15.889542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T01:08:14.811629Z","time spent":"1.077844349s","remote":"127.0.0.1:33454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T01:08:15.889692Z","caller":"traceutil/trace.go:171","msg":"trace[1768584043] transaction","detail":"{read_only:false; response_revision:5001; number_of_response:1; }","duration":"1.065443182s","start":"2026-04-09T01:08:14.824206Z","end":"2026-04-09T01:08:15.88965Z","steps":["trace[1768584043] 'process raft request' (duration: 1.065138797s)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:08:15.889713Z","caller":"traceutil/trace.go:171","msg":"trace[423315277] linearizableReadLoop","detail":"{readStateIndex:5197; appliedIndex:5193; }","duration":"1.173997589s","start":"2026-04-09T01:08:14.715698Z","end":"2026-04-09T01:08:15.889695Z","steps":["trace[423315277] 'read index received' (duration: 1.161997119s)","trace[423315277] 'applied index is now lower than readState.Index' (duration: 11.99954ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T01:08:15.889805Z","caller":"traceutil/trace.go:171","msg":"trace[1409232066] transaction","detail":"{read_only:false; response_revision:5002; number_of_response:1; }","duration":"576.936777ms","start":"2026-04-09T01:08:15.312861Z","end":"2026-04-09T01:08:15.889798Z","steps":["trace[1409232066] 'process raft request' (duration: 576.736704ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:08:15.88979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T01:08:14.824185Z","time spent":"1.065551684s","remote":"127.0.0.1:33454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T01:08:15.889891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T01:08:15.312826Z","time spent":"577.002597ms","remote":"127.0.0.1:33454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T01:08:15.890031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.174330175s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"warn","ts":"2026-04-09T01:08:15.890214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.422804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-09T01:08:15.890241Z","caller":"traceutil/trace.go:171","msg":"trace[1832061688] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:5002; }","duration":"101.486376ms","start":"2026-04-09T01:08:15.788746Z","end":"2026-04-09T01:08:15.890233Z","steps":["trace[1832061688] 'agreement among raft nodes before linearized reading' (duration: 101.446245ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:08:15.890348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.921364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-09T01:08:15.890449Z","caller":"traceutil/trace.go:171","msg":"trace[498271457] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:5002; }","duration":"344.066978ms","start":"2026-04-09T01:08:15.546367Z","end":"2026-04-09T01:08:15.890434Z","steps":["trace[498271457] 'agreement among raft nodes before linearized reading' (duration: 343.856034ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:08:15.8905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T01:08:15.546347Z","time spent":"344.142929ms","remote":"127.0.0.1:33356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":37,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-04-09T01:08:15.890499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.408109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T01:08:15.890609Z","caller":"traceutil/trace.go:171","msg":"trace[2116505606] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5002; }","duration":"366.656413ms","start":"2026-04-09T01:08:15.52394Z","end":"2026-04-09T01:08:15.890597Z","steps":["trace[2116505606] 'agreement among raft nodes before linearized reading' (duration: 365.884909ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:08:15.89069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T01:08:15.523908Z","time spent":"366.775345ms","remote":"127.0.0.1:33146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-09T01:08:15.890071Z","caller":"traceutil/trace.go:171","msg":"trace[480159234] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5002; }","duration":"1.174412907s","start":"2026-04-09T01:08:14.715641Z","end":"2026-04-09T01:08:15.890054Z","steps":["trace[480159234] 'agreement among raft nodes before linearized reading' (duration: 1.174269135s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:08:15.890899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T01:08:14.715618Z","time spent":"1.175248782s","remote":"127.0.0.1:33342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2105,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"info","ts":"2026-04-09T01:08:16.320387Z","caller":"traceutil/trace.go:171","msg":"trace[1692330627] transaction","detail":"{read_only:false; response_revision:5004; number_of_response:1; }","duration":"421.169066ms","start":"2026-04-09T01:08:15.899189Z","end":"2026-04-09T01:08:16.320358Z","steps":["trace[1692330627] 'process raft request' (duration: 420.919111ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:08:16.32053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T01:08:15.899179Z","time spent":"421.275957ms","remote":"127.0.0.1:33378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17108,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T01:08:16.320892Z","caller":"traceutil/trace.go:171","msg":"trace[685399165] linearizableReadLoop","detail":"{readStateIndex:5199; appliedIndex:5199; }","duration":"353.037713ms","start":"2026-04-09T01:08:15.967794Z","end":"2026-04-09T01:08:16.320832Z","steps":["trace[685399165] 'read index received' (duration: 353.027743ms)","trace[685399165] 'applied index is now lower than readState.Index' (duration: 7.51µs)"],"step_count":2} {"level":"warn","ts":"2026-04-09T01:08:16.321072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.761319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-04-09T01:08:16.32112Z","caller":"traceutil/trace.go:171","msg":"trace[1650740861] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5004; }","duration":"417.831959ms","start":"2026-04-09T01:08:15.903277Z","end":"2026-04-09T01:08:16.321109Z","steps":["trace[1650740861] 'agreement among raft nodes before linearized reading' (duration: 417.658287ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:08:16.321187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T01:08:15.903267Z","time spent":"417.87974ms","remote":"127.0.0.1:33378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12236,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-09T01:08:16.333398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.094772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-09T01:08:16.33347Z","caller":"traceutil/trace.go:171","msg":"trace[70892903] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5005; }","duration":"149.215884ms","start":"2026-04-09T01:08:16.184237Z","end":"2026-04-09T01:08:16.333453Z","steps":["trace[70892903] 'agreement among raft nodes before linearized reading' (duration: 148.98178ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:08:16.333533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.175413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-09T01:08:16.333664Z","caller":"traceutil/trace.go:171","msg":"trace[855118554] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5005; }","duration":"149.396677ms","start":"2026-04-09T01:08:16.184236Z","end":"2026-04-09T01:08:16.333633Z","steps":["trace[855118554] 'agreement among raft nodes before linearized reading' (duration: 148.995481ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:08:16.33401Z","caller":"traceutil/trace.go:171","msg":"trace[230524343] transaction","detail":"{read_only:false; response_revision:5005; number_of_response:1; }","duration":"327.426207ms","start":"2026-04-09T01:08:16.006569Z","end":"2026-04-09T01:08:16.333995Z","steps":["trace[230524343] 'process raft request' (duration: 326.37823ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:08:16.334102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T01:08:16.006507Z","time spent":"327.529238ms","remote":"127.0.0.1:33454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T01:08:16.334348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.940235ms","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-04-09T01:08:16.334392Z","caller":"traceutil/trace.go:171","msg":"trace[410067230] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5005; }","duration":"155.983436ms","start":"2026-04-09T01:08:16.178398Z","end":"2026-04-09T01:08:16.334381Z","steps":["trace[410067230] 'agreement among raft nodes before linearized reading' (duration: 155.846944ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:08:16.334596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.334294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-k98ps.18a48ae5bf2b30ac\" ","response":"range_response_count:1 size:906"} {"level":"info","ts":"2026-04-09T01:08:16.334637Z","caller":"traceutil/trace.go:171","msg":"trace[240213612] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-k98ps.18a48ae5bf2b30ac; range_end:; response_count:1; response_revision:5005; }","duration":"272.391675ms","start":"2026-04-09T01:08:16.062232Z","end":"2026-04-09T01:08:16.334624Z","steps":["trace[240213612] 'agreement among raft nodes before linearized reading' (duration: 272.293493ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:08:16.335364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.592996ms","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-04-09T01:08:16.335447Z","caller":"traceutil/trace.go:171","msg":"trace[1838166342] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5005; }","duration":"147.731857ms","start":"2026-04-09T01:08:16.187695Z","end":"2026-04-09T01:08:16.335427Z","steps":["trace[1838166342] 'agreement among raft nodes before linearized reading' (duration: 147.498154ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:08:16.335401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.712759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-d0d39d46-ed04-4afd-b6cf-74624a5a5b14\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-04-09T01:08:16.335599Z","caller":"traceutil/trace.go:171","msg":"trace[940086980] range","detail":"{range_begin:/registry/persistentvolumes/pvc-d0d39d46-ed04-4afd-b6cf-74624a5a5b14; range_end:; response_count:1; response_revision:5005; }","duration":"363.936363ms","start":"2026-04-09T01:08:15.971647Z","end":"2026-04-09T01:08:16.335583Z","steps":["trace[940086980] 'agreement among raft nodes before linearized reading' (duration: 363.680059ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:08:16.335644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T01:08:15.971561Z","time spent":"364.069227ms","remote":"127.0.0.1:33326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1345,"request content":"key:\"/registry/persistentvolumes/pvc-d0d39d46-ed04-4afd-b6cf-74624a5a5b14\" "} {"level":"warn","ts":"2026-04-09T01:08:16.336097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.929734ms","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-04-09T01:08:16.33615Z","caller":"traceutil/trace.go:171","msg":"trace[789341641] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5005; }","duration":"321.203535ms","start":"2026-04-09T01:08:16.014934Z","end":"2026-04-09T01:08:16.336138Z","steps":["trace[789341641] 'agreement among raft nodes before linearized reading' (duration: 319.873773ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:08:16.33619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T01:08:16.014892Z","time spent":"321.280405ms","remote":"127.0.0.1:33454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-09T01:08:16.663662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.034698ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T01:08:16.664009Z","caller":"traceutil/trace.go:171","msg":"trace[1754561917] linearizableReadLoop","detail":"{readStateIndex:5203; appliedIndex:5201; }","duration":"323.537983ms","start":"2026-04-09T01:08:16.340452Z","end":"2026-04-09T01:08:16.66399Z","steps":["trace[1754561917] 'read index received' (duration: 8.912578ms)","trace[1754561917] 'applied index is now lower than readState.Index' (duration: 314.624515ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T01:08:16.66411Z","caller":"traceutil/trace.go:171","msg":"trace[282551763] transaction","detail":"{read_only:false; response_revision:5009; number_of_response:1; }","duration":"320.066835ms","start":"2026-04-09T01:08:16.344031Z","end":"2026-04-09T01:08:16.664098Z","steps":["trace[282551763] 'process raft request' (duration: 319.77677ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:08:16.664078Z","caller":"traceutil/trace.go:171","msg":"trace[731403989] transaction","detail":"{read_only:false; response_revision:5008; number_of_response:1; }","duration":"323.678875ms","start":"2026-04-09T01:08:16.340336Z","end":"2026-04-09T01:08:16.664015Z","steps":["trace[731403989] 'process raft request' (duration: 323.423331ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:08:16.66432Z","caller":"traceutil/trace.go:171","msg":"trace[1256588516] transaction","detail":"{read_only:false; response_revision:5007; number_of_response:1; }","duration":"324.443887ms","start":"2026-04-09T01:08:16.339859Z","end":"2026-04-09T01:08:16.664303Z","steps":["trace[1256588516] 'process raft request' (duration: 147.682627ms)","trace[1256588516] 'compare' (duration: 175.861615ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T01:08:16.664338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T01:08:16.340326Z","time spent":"323.883638ms","remote":"127.0.0.1:33454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T01:08:16.664334Z","caller":"traceutil/trace.go:171","msg":"trace[598971696] transaction","detail":"{read_only:false; response_revision:5012; number_of_response:1; }","duration":"316.932782ms","start":"2026-04-09T01:08:16.347273Z","end":"2026-04-09T01:08:16.664206Z","steps":["trace[598971696] 'process raft request' (duration: 316.643588ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:08:16.664596Z","caller":"traceutil/trace.go:171","msg":"trace[374816459] transaction","detail":"{read_only:false; response_revision:5011; number_of_response:1; }","duration":"320.143196ms","start":"2026-04-09T01:08:16.344426Z","end":"2026-04-09T01:08:16.664569Z","steps":["trace[374816459] 'process raft request' (duration: 319.447695ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:08:16.664715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T01:08:16.339839Z","time spent":"324.522459ms","remote":"127.0.0.1:33454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T01:08:16.664761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T01:08:16.34442Z","time spent":"320.251669ms","remote":"127.0.0.1:33454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T01:08:16.664818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T01:08:16.347268Z","time spent":"317.244258ms","remote":"127.0.0.1:33378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10670,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T01:08:16.664899Z","caller":"traceutil/trace.go:171","msg":"trace[1611569411] transaction","detail":"{read_only:false; response_revision:5010; number_of_response:1; }","duration":"320.550613ms","start":"2026-04-09T01:08:16.344338Z","end":"2026-04-09T01:08:16.664888Z","steps":["trace[1611569411] 'process raft request' (duration: 319.502935ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:08:16.66495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T01:08:16.34433Z","time spent":"320.590744ms","remote":"127.0.0.1:33454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T01:08:16.664993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.362165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:37 size:55839"} {"level":"info","ts":"2026-04-09T01:08:16.665067Z","caller":"traceutil/trace.go:171","msg":"trace[1156269782] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:37; response_revision:5012; }","duration":"322.452656ms","start":"2026-04-09T01:08:16.342596Z","end":"2026-04-09T01:08:16.665049Z","steps":["trace[1156269782] 'agreement among raft nodes before linearized reading' (duration: 321.897136ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:08:16.665123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T01:08:16.342589Z","time spent":"322.519777ms","remote":"127.0.0.1:33386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":37,"response size":55862,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" "} {"level":"warn","ts":"2026-04-09T01:08:16.665211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T01:08:16.344023Z","time spent":"320.119436ms","remote":"127.0.0.1:33454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T01:08:16.665405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.732701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T01:08:16.665903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.402853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:37 size:55839"} {"level":"info","ts":"2026-04-09T01:08:16.666012Z","caller":"traceutil/trace.go:171","msg":"trace[452128185] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:37; response_revision:5012; }","duration":"325.550766ms","start":"2026-04-09T01:08:16.340435Z","end":"2026-04-09T01:08:16.665986Z","steps":["trace[452128185] 'agreement among raft nodes before linearized reading' (duration: 324.252935ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:08:16.666153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T01:08:16.340429Z","time spent":"325.707729ms","remote":"127.0.0.1:33386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":37,"response size":55862,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" "} {"level":"info","ts":"2026-04-09T01:08:16.665487Z","caller":"traceutil/trace.go:171","msg":"trace[1103835633] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5012; }","duration":"272.872933ms","start":"2026-04-09T01:08:16.392595Z","end":"2026-04-09T01:08:16.665468Z","steps":["trace[1103835633] 'agreement among raft nodes before linearized reading' (duration: 272.316385ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:08:16.931418Z","caller":"traceutil/trace.go:171","msg":"trace[846715008] linearizableReadLoop","detail":"{readStateIndex:5210; appliedIndex:5207; }","duration":"255.417553ms","start":"2026-04-09T01:08:16.675975Z","end":"2026-04-09T01:08:16.931392Z","steps":["trace[846715008] 'read index received' (duration: 39.279631ms)","trace[846715008] 'applied index is now lower than readState.Index' (duration: 216.136632ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T01:08:16.931601Z","caller":"traceutil/trace.go:171","msg":"trace[1759692720] transaction","detail":"{read_only:false; response_revision:5016; number_of_response:1; }","duration":"254.955366ms","start":"2026-04-09T01:08:16.676559Z","end":"2026-04-09T01:08:16.931515Z","steps":["trace[1759692720] 'process raft request' (duration: 254.781863ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:08:16.931724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.710218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"warn","ts":"2026-04-09T01:08:16.931744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.476817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-09T01:08:16.931767Z","caller":"traceutil/trace.go:171","msg":"trace[1655190246] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5016; }","duration":"255.808729ms","start":"2026-04-09T01:08:16.675946Z","end":"2026-04-09T01:08:16.931754Z","steps":["trace[1655190246] 'agreement among raft nodes before linearized reading' (duration: 255.580145ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:08:16.931844Z","caller":"traceutil/trace.go:171","msg":"trace[1760997108] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:5016; }","duration":"193.602089ms","start":"2026-04-09T01:08:16.738181Z","end":"2026-04-09T01:08:16.931784Z","steps":["trace[1760997108] 'agreement among raft nodes before linearized reading' (duration: 193.458357ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:08:16.931968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.464251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-09T01:08:16.932131Z","caller":"traceutil/trace.go:171","msg":"trace[271461371] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:5016; }","duration":"136.892519ms","start":"2026-04-09T01:08:16.795224Z","end":"2026-04-09T01:08:16.932117Z","steps":["trace[271461371] 'agreement among raft nodes before linearized reading' (duration: 136.437751ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:08:16.932187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.0363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-04-09T01:08:16.932272Z","caller":"traceutil/trace.go:171","msg":"trace[171335912] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5016; }","duration":"187.149712ms","start":"2026-04-09T01:08:16.745108Z","end":"2026-04-09T01:08:16.932257Z","steps":["trace[171335912] 'agreement among raft nodes before linearized reading' (duration: 187.003969ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:08:16.932158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.614532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-04-09T01:08:16.932465Z","caller":"traceutil/trace.go:171","msg":"trace[2134427286] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:5016; }","duration":"163.960248ms","start":"2026-04-09T01:08:16.768495Z","end":"2026-04-09T01:08:16.932455Z","steps":["trace[2134427286] 'agreement among raft nodes before linearized reading' (duration: 163.572951ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:08:16.931436Z","caller":"traceutil/trace.go:171","msg":"trace[974598317] transaction","detail":"{read_only:false; response_revision:5015; number_of_response:1; }","duration":"255.923062ms","start":"2026-04-09T01:08:16.675493Z","end":"2026-04-09T01:08:16.931416Z","steps":["trace[974598317] 'process raft request' (duration: 255.76651ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:08:16.931411Z","caller":"traceutil/trace.go:171","msg":"trace[2058917230] transaction","detail":"{read_only:false; response_revision:5014; number_of_response:1; }","duration":"255.909671ms","start":"2026-04-09T01:08:16.67544Z","end":"2026-04-09T01:08:16.93135Z","steps":["trace[2058917230] 'process raft request' (duration: 157.926767ms)","trace[2058917230] 'compare' (duration: 97.666959ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T01:08:16.934036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.983672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"info","ts":"2026-04-09T01:08:16.934088Z","caller":"traceutil/trace.go:171","msg":"trace[200495242] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5016; }","duration":"122.123185ms","start":"2026-04-09T01:08:16.811953Z","end":"2026-04-09T01:08:16.934076Z","steps":["trace[200495242] 'agreement among raft nodes before linearized reading' (duration: 120.09854ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:08:18.694416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.7212ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-04-09T01:08:18.69454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T01:08:18.373564Z","time spent":"320.972503ms","remote":"127.0.0.1:33190","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-09T01:08:19.060517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.126816ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T01:08:19.06068Z","caller":"traceutil/trace.go:171","msg":"trace[1581049693] transaction","detail":"{read_only:false; response_revision:5022; number_of_response:1; }","duration":"365.345733ms","start":"2026-04-09T01:08:18.695264Z","end":"2026-04-09T01:08:19.06061Z","steps":["trace[1581049693] 'process raft request' (duration: 110.044214ms)","trace[1581049693] 'compare' (duration: 254.996014ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T01:08:19.06105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T01:08:18.695241Z","time spent":"365.511645ms","remote":"127.0.0.1:33190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T01:08:19.089913Z","caller":"traceutil/trace.go:171","msg":"trace[1096118294] transaction","detail":"{read_only:false; response_revision:5023; number_of_response:1; }","duration":"388.593155ms","start":"2026-04-09T01:08:18.701281Z","end":"2026-04-09T01:08:19.089874Z","steps":["trace[1096118294] 'process raft request' (duration: 388.28564ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:08:19.090142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T01:08:18.701264Z","time spent":"388.749549ms","remote":"127.0.0.1:33454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T01:08:19.090209Z","caller":"traceutil/trace.go:171","msg":"trace[223228141] transaction","detail":"{read_only:false; response_revision:5024; number_of_response:1; }","duration":"388.546055ms","start":"2026-04-09T01:08:18.701616Z","end":"2026-04-09T01:08:19.090162Z","steps":["trace[223228141] 'process raft request' (duration: 388.158019ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:08:19.09033Z","caller":"traceutil/trace.go:171","msg":"trace[339406144] transaction","detail":"{read_only:false; response_revision:5026; number_of_response:1; }","duration":"388.464244ms","start":"2026-04-09T01:08:18.701821Z","end":"2026-04-09T01:08:19.090285Z","steps":["trace[339406144] 'process raft request' (duration: 388.323222ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:08:19.090427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T01:08:18.701582Z","time spent":"388.737638ms","remote":"127.0.0.1:33454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T01:08:19.090373Z","caller":"traceutil/trace.go:171","msg":"trace[128834181] transaction","detail":"{read_only:false; response_revision:5025; number_of_response:1; }","duration":"388.519874ms","start":"2026-04-09T01:08:18.701789Z","end":"2026-04-09T01:08:19.090309Z","steps":["trace[128834181] 'process raft request' (duration: 388.26012ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:08:19.090534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T01:08:18.701798Z","time spent":"388.627376ms","remote":"127.0.0.1:33454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T01:08:19.090405Z","caller":"traceutil/trace.go:171","msg":"trace[574636590] transaction","detail":"{read_only:false; response_revision:5027; number_of_response:1; }","duration":"388.460083ms","start":"2026-04-09T01:08:18.701929Z","end":"2026-04-09T01:08:19.090389Z","steps":["trace[574636590] 'process raft request' (duration: 388.251569ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:08:19.090668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T01:08:18.701783Z","time spent":"388.711267ms","remote":"127.0.0.1:33454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T01:08:19.09072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T01:08:18.70192Z","time spent":"388.735917ms","remote":"127.0.0.1:33454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T01:08:19.302096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.88891ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T01:08:19.302446Z","caller":"traceutil/trace.go:171","msg":"trace[570119778] transaction","detail":"{read_only:false; response_revision:5028; number_of_response:1; }","duration":"157.998508ms","start":"2026-04-09T01:08:19.144356Z","end":"2026-04-09T01:08:19.302354Z","steps":["trace[570119778] 'process raft request' (duration: 25.722912ms)","trace[570119778] 'compare' (duration: 131.738448ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T01:08:19.302963Z","caller":"traceutil/trace.go:171","msg":"trace[2025730036] transaction","detail":"{read_only:false; response_revision:5029; number_of_response:1; }","duration":"115.942648ms","start":"2026-04-09T01:08:19.186996Z","end":"2026-04-09T01:08:19.302938Z","steps":["trace[2025730036] 'process raft request' (duration: 115.221295ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:08:20.324491Z","caller":"traceutil/trace.go:171","msg":"trace[89361507] transaction","detail":"{read_only:false; response_revision:5031; number_of_response:1; }","duration":"371.63106ms","start":"2026-04-09T01:08:19.952823Z","end":"2026-04-09T01:08:20.324454Z","steps":["trace[89361507] 'process raft request' (duration: 371.395806ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:08:20.324715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T01:08:19.952784Z","time spent":"371.820284ms","remote":"127.0.0.1:33454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T01:08:20.325306Z","caller":"traceutil/trace.go:171","msg":"trace[942702107] transaction","detail":"{read_only:false; response_revision:5032; number_of_response:1; }","duration":"371.344505ms","start":"2026-04-09T01:08:19.953914Z","end":"2026-04-09T01:08:20.325259Z","steps":["trace[942702107] 'process raft request' (duration: 370.488361ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:08:20.325593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T01:08:19.953873Z","time spent":"371.569959ms","remote":"127.0.0.1:33454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T01:08:41.905452Z","caller":"traceutil/trace.go:171","msg":"trace[996041559] transaction","detail":"{read_only:false; response_revision:5181; number_of_response:1; }","duration":"103.681721ms","start":"2026-04-09T01:08:41.801723Z","end":"2026-04-09T01:08:41.905404Z","steps":["trace[996041559] 'process raft request' (duration: 103.514718ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:08:49.654877Z","caller":"traceutil/trace.go:171","msg":"trace[211548575] linearizableReadLoop","detail":"{readStateIndex:5522; appliedIndex:5521; }","duration":"128.466764ms","start":"2026-04-09T01:08:49.526374Z","end":"2026-04-09T01:08:49.654841Z","steps":["trace[211548575] 'read index received' (duration: 55.681576ms)","trace[211548575] 'applied index is now lower than readState.Index' (duration: 72.783528ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T01:08:49.655278Z","caller":"traceutil/trace.go:171","msg":"trace[1806207453] transaction","detail":"{read_only:false; response_revision:5319; number_of_response:1; }","duration":"239.860226ms","start":"2026-04-09T01:08:49.415378Z","end":"2026-04-09T01:08:49.655238Z","steps":["trace[1806207453] 'process raft request' (duration: 166.639741ms)","trace[1806207453] 'compare' (duration: 72.365981ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T01:08:49.655976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.431068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T01:08:49.656064Z","caller":"traceutil/trace.go:171","msg":"trace[49534103] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5319; }","duration":"129.787035ms","start":"2026-04-09T01:08:49.526256Z","end":"2026-04-09T01:08:49.656043Z","steps":["trace[49534103] 'agreement among raft nodes before linearized reading' (duration: 128.708448ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:08:51.398684Z","caller":"traceutil/trace.go:171","msg":"trace[1415748818] transaction","detail":"{read_only:false; response_revision:5325; number_of_response:1; }","duration":"115.642208ms","start":"2026-04-09T01:08:51.283013Z","end":"2026-04-09T01:08:51.398655Z","steps":["trace[1415748818] 'process raft request' (duration: 114.921197ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:08:51.398086Z","caller":"traceutil/trace.go:171","msg":"trace[112681632] linearizableReadLoop","detail":"{readStateIndex:5528; appliedIndex:5527; }","duration":"112.925207ms","start":"2026-04-09T01:08:51.285141Z","end":"2026-04-09T01:08:51.398066Z","steps":["trace[112681632] 'read index received' (duration: 112.739505ms)","trace[112681632] 'applied index is now lower than readState.Index' (duration: 185.002µs)"],"step_count":2} {"level":"warn","ts":"2026-04-09T01:08:51.398912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.465199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-09T01:08:51.398946Z","caller":"traceutil/trace.go:171","msg":"trace[1678605477] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5325; }","duration":"112.536611ms","start":"2026-04-09T01:08:51.286401Z","end":"2026-04-09T01:08:51.398938Z","steps":["trace[1678605477] 'agreement among raft nodes before linearized reading' (duration: 112.408849ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:08:51.398955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.78626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-09T01:08:51.399042Z","caller":"traceutil/trace.go:171","msg":"trace[1537632409] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5325; }","duration":"113.936362ms","start":"2026-04-09T01:08:51.285086Z","end":"2026-04-09T01:08:51.399022Z","steps":["trace[1537632409] 'agreement among raft nodes before linearized reading' (duration: 113.645418ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:08:51.39926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.547672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-09T01:08:51.399512Z","caller":"traceutil/trace.go:171","msg":"trace[93361144] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5325; }","duration":"112.617253ms","start":"2026-04-09T01:08:51.28668Z","end":"2026-04-09T01:08:51.399298Z","steps":["trace[93361144] 'agreement among raft nodes before linearized reading' (duration: 112.489261ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:08:51.706244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.195813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T01:08:51.706327Z","caller":"traceutil/trace.go:171","msg":"trace[1392349245] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5329; }","duration":"181.340475ms","start":"2026-04-09T01:08:51.524968Z","end":"2026-04-09T01:08:51.706309Z","steps":["trace[1392349245] 'range keys from in-memory index tree' (duration: 181.098881ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:08:51.707017Z","caller":"traceutil/trace.go:171","msg":"trace[904371910] transaction","detail":"{read_only:false; response_revision:5330; number_of_response:1; }","duration":"162.350224ms","start":"2026-04-09T01:08:51.544619Z","end":"2026-04-09T01:08:51.706969Z","steps":["trace[904371910] 'process raft request' (duration: 159.639723ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:09:15.042216Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2873} {"level":"info","ts":"2026-04-09T01:09:15.151346Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2873,"took":"107.037304ms","hash":2198046695,"current-db-size-bytes":52326400,"current-db-size":"52 MB","current-db-size-in-use-bytes":28663808,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-09T01:09:15.151565Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2198046695,"revision":2873,"compact-revision":-1} {"level":"info","ts":"2026-04-09T01:10:02.117142Z","caller":"traceutil/trace.go:171","msg":"trace[1929499196] transaction","detail":"{read_only:false; response_revision:5826; number_of_response:1; }","duration":"111.18364ms","start":"2026-04-09T01:10:02.00593Z","end":"2026-04-09T01:10:02.117113Z","steps":["trace[1929499196] 'process raft request' (duration: 111.002227ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:10:02.117382Z","caller":"traceutil/trace.go:171","msg":"trace[975142675] transaction","detail":"{read_only:false; response_revision:5827; number_of_response:1; }","duration":"109.571798ms","start":"2026-04-09T01:10:02.007798Z","end":"2026-04-09T01:10:02.11737Z","steps":["trace[975142675] 'process raft request' (duration: 109.274504ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:11:48.639195Z","caller":"traceutil/trace.go:171","msg":"trace[321677492] linearizableReadLoop","detail":"{readStateIndex:6783; appliedIndex:6781; }","duration":"191.309846ms","start":"2026-04-09T01:11:48.447861Z","end":"2026-04-09T01:11:48.639171Z","steps":["trace[321677492] 'read index received' (duration: 103.719532ms)","trace[321677492] 'applied index is now lower than readState.Index' (duration: 87.589054ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T01:11:48.639307Z","caller":"traceutil/trace.go:171","msg":"trace[1886435400] transaction","detail":"{read_only:false; response_revision:6537; number_of_response:1; }","duration":"237.280141ms","start":"2026-04-09T01:11:48.402019Z","end":"2026-04-09T01:11:48.639299Z","steps":["trace[1886435400] 'process raft request' (duration: 149.371033ms)","trace[1886435400] 'compare' (duration: 87.607844ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T01:11:48.639723Z","caller":"traceutil/trace.go:171","msg":"trace[1814245827] transaction","detail":"{read_only:false; response_revision:6538; number_of_response:1; }","duration":"235.120345ms","start":"2026-04-09T01:11:48.404555Z","end":"2026-04-09T01:11:48.639675Z","steps":["trace[1814245827] 'process raft request' (duration: 234.576788ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:11:48.639814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.920235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-09T01:11:48.639918Z","caller":"traceutil/trace.go:171","msg":"trace[298042649] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6538; }","duration":"192.099797ms","start":"2026-04-09T01:11:48.447798Z","end":"2026-04-09T01:11:48.639898Z","steps":["trace[298042649] 'agreement among raft nodes before linearized reading' (duration: 191.63736ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:14:15.049469Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5500} {"level":"info","ts":"2026-04-09T01:14:15.127808Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5500,"took":"76.910714ms","hash":479046290,"current-db-size-bytes":52326400,"current-db-size":"52 MB","current-db-size-in-use-bytes":18079744,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-09T01:14:15.127859Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":479046290,"revision":5500,"compact-revision":2873}