{"level":"warn","ts":"2026-07-23T23:52:26.78026Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-23T23:52:26.780373Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.113: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.113:2380","--initial-cluster=instance=https://199.204.45.113:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.113:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.113: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-07-23T23:52:26.780461Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-23T23:52:26.780529Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-07-23T23:52:26.780555Z","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-07-23T23:52:26.781418Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"]} {"level":"info","ts":"2026-07-23T23:52:26.781511Z","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.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.113: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-07-23T23:52:26.784304Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.437907ms"} {"level":"info","ts":"2026-07-23T23:52:26.792043Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a31e123290fcf0df","cluster-id":"37661d6ee45ed9e8"} {"level":"info","ts":"2026-07-23T23:52:26.792155Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=()"} {"level":"info","ts":"2026-07-23T23:52:26.792195Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 0"} {"level":"info","ts":"2026-07-23T23:52:26.792215Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a31e123290fcf0df [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-23T23:52:26.792239Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 1"} {"level":"info","ts":"2026-07-23T23:52:26.792307Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"warn","ts":"2026-07-23T23:52:26.804634Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-23T23:52:26.806595Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-23T23:52:26.808771Z","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-07-23T23:52:26.81141Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a31e123290fcf0df","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-23T23:52:26.81211Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a31e123290fcf0df","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-23T23:52:26.812724Z","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-07-23T23:52:26.812925Z","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-07-23T23:52:26.812951Z","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-07-23T23:52:26.813311Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"info","ts":"2026-07-23T23:52:26.813489Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","added-peer-id":"a31e123290fcf0df","added-peer-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-07-23T23:52:26.816906Z","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-07-23T23:52:26.816996Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-07-23T23:52:26.817056Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-07-23T23:52:26.817223Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a31e123290fcf0df","initial-advertise-peer-urls":["https://199.204.45.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-23T23:52:26.817285Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-23T23:52:27.793475Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df is starting a new election at term 1"} {"level":"info","ts":"2026-07-23T23:52:27.79352Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became pre-candidate at term 1"} {"level":"info","ts":"2026-07-23T23:52:27.793546Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgPreVoteResp from a31e123290fcf0df at term 1"} {"level":"info","ts":"2026-07-23T23:52:27.793558Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became candidate at term 2"} {"level":"info","ts":"2026-07-23T23:52:27.793564Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgVoteResp from a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-07-23T23:52:27.793572Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became leader at term 2"} {"level":"info","ts":"2026-07-23T23:52:27.793581Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a31e123290fcf0df elected leader a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-07-23T23:52:27.794537Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a31e123290fcf0df","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.113:2379]}","request-path":"/0/members/a31e123290fcf0df/attributes","cluster-id":"37661d6ee45ed9e8","publish-timeout":"7s"} {"level":"info","ts":"2026-07-23T23:52:27.794592Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-23T23:52:27.794565Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-23T23:52:27.794735Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-23T23:52:27.794796Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-23T23:52:27.795274Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-23T23:52:27.796629Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","cluster-version":"3.5"} {"level":"info","ts":"2026-07-23T23:52:27.79675Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-23T23:52:27.796794Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-23T23:52:27.798066Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.113:2379"} {"level":"info","ts":"2026-07-23T23:52:27.798477Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-23T23:52:31.515172Z","caller":"traceutil/trace.go:171","msg":"trace[1540377405] linearizableReadLoop","detail":"{readStateIndex:143; appliedIndex:142; }","duration":"385.24696ms","start":"2026-07-23T23:52:31.1299Z","end":"2026-07-23T23:52:31.515147Z","steps":["trace[1540377405] 'read index received' (duration: 385.103496ms)","trace[1540377405] 'applied index is now lower than readState.Index' (duration: 142.444µs)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:52:31.51533Z","caller":"traceutil/trace.go:171","msg":"trace[122676381] transaction","detail":"{read_only:false; response_revision:139; number_of_response:1; }","duration":"998.555887ms","start":"2026-07-23T23:52:30.516763Z","end":"2026-07-23T23:52:31.515319Z","steps":["trace[122676381] 'process raft request' (duration: 998.244038ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:52:31.515504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.11902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:52:31.51561Z","caller":"traceutil/trace.go:171","msg":"trace[1663499687] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:139; }","duration":"373.261524ms","start":"2026-07-23T23:52:31.142326Z","end":"2026-07-23T23:52:31.515588Z","steps":["trace[1663499687] 'agreement among raft nodes before linearized reading' (duration: 373.058819ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:52:31.51565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:52:31.142304Z","time spent":"373.338466ms","remote":"127.0.0.1:47474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-23T23:52:31.515793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.842177ms","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:5"} {"level":"info","ts":"2026-07-23T23:52:31.51589Z","caller":"traceutil/trace.go:171","msg":"trace[1705239546] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:139; }","duration":"386.028682ms","start":"2026-07-23T23:52:31.129838Z","end":"2026-07-23T23:52:31.515867Z","steps":["trace[1705239546] 'agreement among raft nodes before linearized reading' (duration: 385.810356ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:52:31.51599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:52:31.129814Z","time spent":"386.164286ms","remote":"127.0.0.1:47618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" "} {"level":"warn","ts":"2026-07-23T23:52:31.516072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.257182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:52:31.516165Z","caller":"traceutil/trace.go:171","msg":"trace[766525742] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:139; }","duration":"279.420176ms","start":"2026-07-23T23:52:31.236725Z","end":"2026-07-23T23:52:31.516145Z","steps":["trace[766525742] 'agreement among raft nodes before linearized reading' (duration: 278.959734ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:52:31.516409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:52:30.516747Z","time spent":"998.617608ms","remote":"127.0.0.1:47840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":768,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-23T23:52:32.05187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.167837ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-23T23:52:32.051988Z","caller":"traceutil/trace.go:171","msg":"trace[435933580] linearizableReadLoop","detail":"{readStateIndex:144; appliedIndex:143; }","duration":"532.870374ms","start":"2026-07-23T23:52:31.5191Z","end":"2026-07-23T23:52:32.051971Z","steps":["trace[435933580] 'read index received' (duration: 172.332237ms)","trace[435933580] 'applied index is now lower than readState.Index' (duration: 360.536707ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:52:32.052015Z","caller":"traceutil/trace.go:171","msg":"trace[1853862077] transaction","detail":"{read_only:false; response_revision:140; number_of_response:1; }","duration":"532.941366ms","start":"2026-07-23T23:52:31.519035Z","end":"2026-07-23T23:52:32.051977Z","steps":["trace[1853862077] 'process raft request' (duration: 172.42661ms)","trace[1853862077] 'compare' (duration: 359.987731ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:52:32.052122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.013497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:node-proxier\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:52:32.052224Z","caller":"traceutil/trace.go:171","msg":"trace[1760102781] range","detail":"{range_begin:/registry/clusterrolebindings/system:node-proxier; range_end:; response_count:0; response_revision:140; }","duration":"533.143481ms","start":"2026-07-23T23:52:31.51907Z","end":"2026-07-23T23:52:32.052214Z","steps":["trace[1760102781] 'agreement among raft nodes before linearized reading' (duration: 532.950346ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:52:32.052267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:52:31.519053Z","time spent":"533.205573ms","remote":"127.0.0.1:47840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":0,"response size":28,"request content":"key:\"/registry/clusterrolebindings/system:node-proxier\" "} {"level":"warn","ts":"2026-07-23T23:52:32.052171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:52:31.519014Z","time spent":"533.07397ms","remote":"127.0.0.1:47660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-23T23:52:32.643255Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356766935723649387,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-23T23:52:32.690892Z","caller":"traceutil/trace.go:171","msg":"trace[1106410305] linearizableReadLoop","detail":"{readStateIndex:146; appliedIndex:144; }","duration":"548.199581ms","start":"2026-07-23T23:52:32.14267Z","end":"2026-07-23T23:52:32.69087Z","steps":["trace[1106410305] 'read index received' (duration: 537.414437ms)","trace[1106410305] 'applied index is now lower than readState.Index' (duration: 10.784504ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:52:32.690997Z","caller":"traceutil/trace.go:171","msg":"trace[412037370] transaction","detail":"{read_only:false; response_revision:141; number_of_response:1; }","duration":"1.169327271s","start":"2026-07-23T23:52:31.521661Z","end":"2026-07-23T23:52:32.690988Z","steps":["trace[412037370] 'process raft request' (duration: 1.158442754s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:52:32.691091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:52:31.521644Z","time spent":"1.169385592s","remote":"127.0.0.1:47736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-23T23:52:32.691167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.481909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:52:32.69124Z","caller":"traceutil/trace.go:171","msg":"trace[434214542] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:142; }","duration":"548.596801ms","start":"2026-07-23T23:52:32.142622Z","end":"2026-07-23T23:52:32.691219Z","steps":["trace[434214542] 'agreement among raft nodes before linearized reading' (duration: 548.436337ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:52:32.69128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:52:32.142606Z","time spent":"548.666944ms","remote":"127.0.0.1:47474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-23T23:52:32.691332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.039311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:52:32.691365Z","caller":"traceutil/trace.go:171","msg":"trace[1361161199] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:142; }","duration":"521.092092ms","start":"2026-07-23T23:52:32.170263Z","end":"2026-07-23T23:52:32.691355Z","steps":["trace[1361161199] 'agreement among raft nodes before linearized reading' (duration: 521.03102ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:52:32.691392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:52:32.170248Z","time spent":"521.138053ms","remote":"127.0.0.1:47736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-23T23:52:32.691491Z","caller":"traceutil/trace.go:171","msg":"trace[1484477407] transaction","detail":"{read_only:false; response_revision:142; number_of_response:1; }","duration":"636.539429ms","start":"2026-07-23T23:52:32.054922Z","end":"2026-07-23T23:52:32.691461Z","steps":["trace[1484477407] 'process raft request' (duration: 635.897501ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:52:32.691591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:52:32.054899Z","time spent":"636.645281ms","remote":"127.0.0.1:47840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":684,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-23T23:53:06.918412Z","caller":"traceutil/trace.go:171","msg":"trace[1413083770] linearizableReadLoop","detail":"{readStateIndex:498; appliedIndex:497; }","duration":"235.770106ms","start":"2026-07-23T23:53:06.682618Z","end":"2026-07-23T23:53:06.918388Z","steps":["trace[1413083770] 'read index received' (duration: 235.54472ms)","trace[1413083770] 'applied index is now lower than readState.Index' (duration: 224.256µs)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:53:06.91858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.955171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:53:06.918617Z","caller":"traceutil/trace.go:171","msg":"trace[1807507258] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:481; }","duration":"236.048074ms","start":"2026-07-23T23:53:06.682557Z","end":"2026-07-23T23:53:06.918605Z","steps":["trace[1807507258] 'agreement among raft nodes before linearized reading' (duration: 235.970752ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:53:06.918477Z","caller":"traceutil/trace.go:171","msg":"trace[869176804] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"290.883168ms","start":"2026-07-23T23:53:06.62757Z","end":"2026-07-23T23:53:06.918453Z","steps":["trace[869176804] 'process raft request' (duration: 290.666112ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:53:06.918673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.767528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:53:06.91873Z","caller":"traceutil/trace.go:171","msg":"trace[90685807] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:481; }","duration":"114.873281ms","start":"2026-07-23T23:53:06.803839Z","end":"2026-07-23T23:53:06.918712Z","steps":["trace[90685807] 'agreement among raft nodes before linearized reading' (duration: 114.779168ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:53:07.980355Z","caller":"traceutil/trace.go:171","msg":"trace[1056668542] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"813.38373ms","start":"2026-07-23T23:53:07.166943Z","end":"2026-07-23T23:53:07.980326Z","steps":["trace[1056668542] 'process raft request' (duration: 813.237826ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:53:07.98052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:53:07.166908Z","time spent":"813.533594ms","remote":"127.0.0.1:47506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-23T23:53:07.991412Z","caller":"traceutil/trace.go:171","msg":"trace[1261988382] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"815.23446ms","start":"2026-07-23T23:53:07.176148Z","end":"2026-07-23T23:53:07.991383Z","steps":["trace[1261988382] 'process raft request' (duration: 815.031995ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:53:07.991587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:53:07.17612Z","time spent":"815.373024ms","remote":"127.0.0.1:47506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-23T23:53:07.991625Z","caller":"traceutil/trace.go:171","msg":"trace[1512592786] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"815.485127ms","start":"2026-07-23T23:53:07.176012Z","end":"2026-07-23T23:53:07.991497Z","steps":["trace[1512592786] 'process raft request' (duration: 815.000024ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:53:07.991735Z","caller":"traceutil/trace.go:171","msg":"trace[96454127] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"810.146511ms","start":"2026-07-23T23:53:07.181576Z","end":"2026-07-23T23:53:07.991723Z","steps":["trace[96454127] 'process raft request' (duration: 809.797332ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:53:07.99179Z","caller":"traceutil/trace.go:171","msg":"trace[1154225114] linearizableReadLoop","detail":"{readStateIndex:513; appliedIndex:501; }","duration":"186.159195ms","start":"2026-07-23T23:53:07.805534Z","end":"2026-07-23T23:53:07.991693Z","steps":["trace[1154225114] 'read index received' (duration: 174.758974ms)","trace[1154225114] 'applied index is now lower than readState.Index' (duration: 11.399381ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:53:07.991825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:53:07.181568Z","time spent":"810.232714ms","remote":"127.0.0.1:47506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-23T23:53:07.991861Z","caller":"traceutil/trace.go:171","msg":"trace[456228656] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"812.385283ms","start":"2026-07-23T23:53:07.179468Z","end":"2026-07-23T23:53:07.991853Z","steps":["trace[456228656] 'process raft request' (duration: 811.789527ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:53:07.991868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:53:07.175998Z","time spent":"815.765885ms","remote":"127.0.0.1:47506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-23T23:53:07.991908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:53:07.17946Z","time spent":"812.430484ms","remote":"127.0.0.1:47506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-23T23:53:07.991974Z","caller":"traceutil/trace.go:171","msg":"trace[1454170173] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"812.362592ms","start":"2026-07-23T23:53:07.179603Z","end":"2026-07-23T23:53:07.991965Z","steps":["trace[1454170173] 'process raft request' (duration: 811.712664ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:53:07.992031Z","caller":"traceutil/trace.go:171","msg":"trace[2075228769] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"799.366078ms","start":"2026-07-23T23:53:07.192659Z","end":"2026-07-23T23:53:07.992026Z","steps":["trace[2075228769] 'process raft request' (duration: 798.798512ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:53:07.992098Z","caller":"traceutil/trace.go:171","msg":"trace[1394622154] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"812.547887ms","start":"2026-07-23T23:53:07.179543Z","end":"2026-07-23T23:53:07.992091Z","steps":["trace[1394622154] 'process raft request' (duration: 811.742866ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:53:07.992093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:53:07.179593Z","time spent":"812.478735ms","remote":"127.0.0.1:47506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-23T23:53:07.992155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:53:07.179537Z","time spent":"812.593349ms","remote":"127.0.0.1:47506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-23T23:53:07.992154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:53:07.192645Z","time spent":"799.44271ms","remote":"127.0.0.1:47506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-23T23:53:07.992009Z","caller":"traceutil/trace.go:171","msg":"trace[1525839859] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"800.687234ms","start":"2026-07-23T23:53:07.191315Z","end":"2026-07-23T23:53:07.992003Z","steps":["trace[1525839859] 'process raft request' (duration: 800.094698ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:53:07.992274Z","caller":"traceutil/trace.go:171","msg":"trace[211618473] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"629.366504ms","start":"2026-07-23T23:53:07.3629Z","end":"2026-07-23T23:53:07.992266Z","steps":["trace[211618473] 'process raft request' (duration: 628.715877ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:53:07.992283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:53:07.1913Z","time spent":"800.93854ms","remote":"127.0.0.1:47506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-23T23:53:07.992305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.776381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-23T23:53:07.992315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:53:07.362859Z","time spent":"629.438406ms","remote":"127.0.0.1:47506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-23T23:53:07.992014Z","caller":"traceutil/trace.go:171","msg":"trace[1131028111] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"811.669363ms","start":"2026-07-23T23:53:07.180333Z","end":"2026-07-23T23:53:07.992003Z","steps":["trace[1131028111] 'process raft request' (duration: 811.009945ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:53:07.992338Z","caller":"traceutil/trace.go:171","msg":"trace[1035242475] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:496; }","duration":"186.867354ms","start":"2026-07-23T23:53:07.805461Z","end":"2026-07-23T23:53:07.992328Z","steps":["trace[1035242475] 'agreement among raft nodes before linearized reading' (duration: 186.765361ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:53:07.992365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:53:07.180327Z","time spent":"812.024893ms","remote":"127.0.0.1:47506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-23T23:53:07.991958Z","caller":"traceutil/trace.go:171","msg":"trace[407546575] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"780.109043ms","start":"2026-07-23T23:53:07.211834Z","end":"2026-07-23T23:53:07.991943Z","steps":["trace[407546575] 'process raft request' (duration: 779.710633ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:53:07.992406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:53:07.211804Z","time spent":"780.590036ms","remote":"127.0.0.1:47506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-23T23:53:14.147092Z","caller":"traceutil/trace.go:171","msg":"trace[1960041384] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"282.304806ms","start":"2026-07-23T23:53:13.864756Z","end":"2026-07-23T23:53:14.147061Z","steps":["trace[1960041384] 'process raft request' (duration: 282.09463ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:53:14.693226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.08827ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-23T23:53:14.693568Z","caller":"traceutil/trace.go:171","msg":"trace[1575324128] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"640.123802ms","start":"2026-07-23T23:53:14.05341Z","end":"2026-07-23T23:53:14.693534Z","steps":["trace[1575324128] 'process raft request' (duration: 391.008002ms)","trace[1575324128] 'compare' (duration: 241.262096ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:53:14.693692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:53:14.053382Z","time spent":"640.231225ms","remote":"127.0.0.1:47562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036105,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T23:53:16.30419Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356766935723650364,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-23T23:53:16.671958Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.489451904s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-23T23:53:16.673151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.663833644s","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-07-23T23:53:16.673244Z","caller":"traceutil/trace.go:171","msg":"trace[1102868128] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:583; }","duration":"1.663990548s","start":"2026-07-23T23:53:15.00923Z","end":"2026-07-23T23:53:16.673221Z","steps":["trace[1102868128] 'range keys from in-memory index tree' (duration: 1.663623547s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:53:16.6733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:53:15.009205Z","time spent":"1.66408566s","remote":"127.0.0.1:47736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-23T23:53:16.673592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.70948956s","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-07-23T23:53:16.673661Z","caller":"traceutil/trace.go:171","msg":"trace[1750082269] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:583; }","duration":"1.709604443s","start":"2026-07-23T23:53:14.964038Z","end":"2026-07-23T23:53:16.673642Z","steps":["trace[1750082269] 'range keys from in-memory index tree' (duration: 1.709329505s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:53:16.67368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.664553163s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-23T23:53:16.673701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:53:14.964002Z","time spent":"1.709691245s","remote":"127.0.0.1:47736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-23T23:53:16.673712Z","caller":"traceutil/trace.go:171","msg":"trace[968286377] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:583; }","duration":"1.664599425s","start":"2026-07-23T23:53:15.0091Z","end":"2026-07-23T23:53:16.6737Z","steps":["trace[968286377] 'range keys from in-memory index tree' (duration: 1.664479291s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:53:16.673741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:53:15.009092Z","time spent":"1.664643576s","remote":"127.0.0.1:47736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-23T23:53:16.673714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.195591913s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:53:16.673886Z","caller":"traceutil/trace.go:171","msg":"trace[1394467031] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:583; }","duration":"1.195780748s","start":"2026-07-23T23:53:15.478074Z","end":"2026-07-23T23:53:16.673854Z","steps":["trace[1394467031] 'range keys from in-memory index tree' (duration: 1.195559422s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:53:16.673617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.666839175s","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-07-23T23:53:16.673936Z","caller":"traceutil/trace.go:171","msg":"trace[719055662] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:583; }","duration":"1.667174775s","start":"2026-07-23T23:53:15.006749Z","end":"2026-07-23T23:53:16.673923Z","steps":["trace[719055662] 'range keys from in-memory index tree' (duration: 1.666742233s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:53:16.673975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:53:15.006739Z","time spent":"1.667226236s","remote":"127.0.0.1:47736","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-07-23T23:53:16.674478Z","caller":"traceutil/trace.go:171","msg":"trace[1890576042] linearizableReadLoop","detail":"{readStateIndex:608; appliedIndex:606; }","duration":"870.533051ms","start":"2026-07-23T23:53:15.803928Z","end":"2026-07-23T23:53:16.674461Z","steps":["trace[1890576042] 'read index received' (duration: 868.439164ms)","trace[1890576042] 'applied index is now lower than readState.Index' (duration: 2.092757ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:53:16.674593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"870.663625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:53:16.674621Z","caller":"traceutil/trace.go:171","msg":"trace[2038951252] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:585; }","duration":"870.723807ms","start":"2026-07-23T23:53:15.803888Z","end":"2026-07-23T23:53:16.674612Z","steps":["trace[2038951252] 'agreement among raft nodes before linearized reading' (duration: 870.639454ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:53:16.674647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:53:15.803871Z","time spent":"870.770797ms","remote":"127.0.0.1:47474","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-07-23T23:53:16.675996Z","caller":"traceutil/trace.go:171","msg":"trace[649279947] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"1.495062066s","start":"2026-07-23T23:53:15.180895Z","end":"2026-07-23T23:53:16.675957Z","steps":["trace[649279947] 'process raft request' (duration: 1.491836388s)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:53:16.676067Z","caller":"traceutil/trace.go:171","msg":"trace[959146902] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"839.575587ms","start":"2026-07-23T23:53:15.836464Z","end":"2026-07-23T23:53:16.67604Z","steps":["trace[959146902] 'process raft request' (duration: 837.910591ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:53:16.676127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:53:15.180867Z","time spent":"1.49518833s","remote":"127.0.0.1:47562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036161,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T23:53:16.676206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:53:15.836432Z","time spent":"839.69138ms","remote":"127.0.0.1:47736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:53:18.52332Z","caller":"traceutil/trace.go:171","msg":"trace[1863667856] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"519.434158ms","start":"2026-07-23T23:53:18.003862Z","end":"2026-07-23T23:53:18.523296Z","steps":["trace[1863667856] 'process raft request' (duration: 519.288944ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:53:18.523637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:53:18.003846Z","time spent":"519.557282ms","remote":"127.0.0.1:47506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4165,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:53:18.537591Z","caller":"traceutil/trace.go:171","msg":"trace[1533144837] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"376.316842ms","start":"2026-07-23T23:53:18.161231Z","end":"2026-07-23T23:53:18.537548Z","steps":["trace[1533144837] 'process raft request' (duration: 375.916461ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:53:18.537826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:53:18.161208Z","time spent":"376.540348ms","remote":"127.0.0.1:47506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-23T23:53:18.537838Z","caller":"traceutil/trace.go:171","msg":"trace[310590442] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"177.517605ms","start":"2026-07-23T23:53:18.3603Z","end":"2026-07-23T23:53:18.537818Z","steps":["trace[310590442] 'process raft request' (duration: 176.886848ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:53:18.537668Z","caller":"traceutil/trace.go:171","msg":"trace[1937759341] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"402.26455ms","start":"2026-07-23T23:53:18.135379Z","end":"2026-07-23T23:53:18.537644Z","steps":["trace[1937759341] 'process raft request' (duration: 401.318264ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:53:18.53809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:53:18.135348Z","time spent":"402.64072ms","remote":"127.0.0.1:47562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:53:40.91232Z","caller":"traceutil/trace.go:171","msg":"trace[266787378] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"111.690789ms","start":"2026-07-23T23:53:40.80061Z","end":"2026-07-23T23:53:40.912301Z","steps":["trace[266787378] 'process raft request' (duration: 111.542675ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:53:40.915776Z","caller":"traceutil/trace.go:171","msg":"trace[904219119] linearizableReadLoop","detail":"{readStateIndex:813; appliedIndex:811; }","duration":"112.140921ms","start":"2026-07-23T23:53:40.803554Z","end":"2026-07-23T23:53:40.915695Z","steps":["trace[904219119] 'read index received' (duration: 108.594584ms)","trace[904219119] 'applied index is now lower than readState.Index' (duration: 3.545207ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:53:40.915886Z","caller":"traceutil/trace.go:171","msg":"trace[1974058339] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"112.590114ms","start":"2026-07-23T23:53:40.803246Z","end":"2026-07-23T23:53:40.915836Z","steps":["trace[1974058339] 'process raft request' (duration: 112.310856ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:53:40.915979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.224596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-23T23:53:40.915965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.388538ms","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-07-23T23:53:40.916028Z","caller":"traceutil/trace.go:171","msg":"trace[467968826] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:783; }","duration":"111.312009ms","start":"2026-07-23T23:53:40.804704Z","end":"2026-07-23T23:53:40.916016Z","steps":["trace[467968826] 'agreement among raft nodes before linearized reading' (duration: 111.203986ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:53:40.916045Z","caller":"traceutil/trace.go:171","msg":"trace[567365505] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:783; }","duration":"112.495831ms","start":"2026-07-23T23:53:40.803532Z","end":"2026-07-23T23:53:40.916027Z","steps":["trace[567365505] 'agreement among raft nodes before linearized reading' (duration: 112.312926ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:53:40.916183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.543902ms","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-07-23T23:53:40.916243Z","caller":"traceutil/trace.go:171","msg":"trace[35714238] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:783; }","duration":"112.639495ms","start":"2026-07-23T23:53:40.80359Z","end":"2026-07-23T23:53:40.91623Z","steps":["trace[35714238] 'agreement among raft nodes before linearized reading' (duration: 112.320506ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:53:43.479864Z","caller":"traceutil/trace.go:171","msg":"trace[113936551] linearizableReadLoop","detail":"{readStateIndex:829; appliedIndex:828; }","duration":"433.372218ms","start":"2026-07-23T23:53:43.046467Z","end":"2026-07-23T23:53:43.479839Z","steps":["trace[113936551] 'read index received' (duration: 433.113551ms)","trace[113936551] 'applied index is now lower than readState.Index' (duration: 257.787µs)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:53:43.480107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.622956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.113\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-23T23:53:43.479945Z","caller":"traceutil/trace.go:171","msg":"trace[801792453] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"458.002611ms","start":"2026-07-23T23:53:43.021905Z","end":"2026-07-23T23:53:43.479907Z","steps":["trace[801792453] 'process raft request' (duration: 457.731944ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:53:43.480149Z","caller":"traceutil/trace.go:171","msg":"trace[871989970] range","detail":"{range_begin:/registry/masterleases/199.204.45.113; range_end:; response_count:1; response_revision:799; }","duration":"433.720199ms","start":"2026-07-23T23:53:43.046417Z","end":"2026-07-23T23:53:43.480138Z","steps":["trace[871989970] 'agreement among raft nodes before linearized reading' (duration: 433.560584ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:53:43.480185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:53:43.046399Z","time spent":"433.77767ms","remote":"127.0.0.1:47502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.113\" "} {"level":"warn","ts":"2026-07-23T23:53:43.480221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.845385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-23T23:53:43.480345Z","caller":"traceutil/trace.go:171","msg":"trace[988129485] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:799; }","duration":"322.04424ms","start":"2026-07-23T23:53:43.15827Z","end":"2026-07-23T23:53:43.480314Z","steps":["trace[988129485] 'agreement among raft nodes before linearized reading' (duration: 321.812034ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:53:43.480419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:53:43.158253Z","time spent":"322.146973ms","remote":"127.0.0.1:47926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-07-23T23:53:43.480358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:53:43.021876Z","time spent":"458.384612ms","remote":"127.0.0.1:47660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4025,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T23:53:44.091182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:53:43.562009Z","time spent":"529.163063ms","remote":"127.0.0.1:49618","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-23T23:53:44.091435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:53:43.48135Z","time spent":"610.077522ms","remote":"127.0.0.1:47502","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-23T23:53:44.091436Z","caller":"traceutil/trace.go:171","msg":"trace[753347773] linearizableReadLoop","detail":"{readStateIndex:830; appliedIndex:829; }","duration":"287.59309ms","start":"2026-07-23T23:53:43.803798Z","end":"2026-07-23T23:53:44.091391Z","steps":["trace[753347773] 'read index received' (duration: 255.917646ms)","trace[753347773] 'applied index is now lower than readState.Index' (duration: 31.673314ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:53:44.091672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.844847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:53:44.091722Z","caller":"traceutil/trace.go:171","msg":"trace[1710320271] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:799; }","duration":"287.94724ms","start":"2026-07-23T23:53:43.803761Z","end":"2026-07-23T23:53:44.091709Z","steps":["trace[1710320271] 'agreement among raft nodes before linearized reading' (duration: 287.821976ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:53:44.261178Z","caller":"traceutil/trace.go:171","msg":"trace[864031411] linearizableReadLoop","detail":"{readStateIndex:831; appliedIndex:830; }","duration":"167.872371ms","start":"2026-07-23T23:53:44.093242Z","end":"2026-07-23T23:53:44.261114Z","steps":["trace[864031411] 'read index received' (duration: 167.538732ms)","trace[864031411] 'applied index is now lower than readState.Index' (duration: 332.289µs)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:53:44.261365Z","caller":"traceutil/trace.go:171","msg":"trace[840648286] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"168.56067ms","start":"2026-07-23T23:53:44.092791Z","end":"2026-07-23T23:53:44.261352Z","steps":["trace[840648286] 'process raft request' (duration: 168.016476ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:53:44.261578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.330554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:53:44.261627Z","caller":"traceutil/trace.go:171","msg":"trace[1315188011] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:800; }","duration":"168.397706ms","start":"2026-07-23T23:53:44.093217Z","end":"2026-07-23T23:53:44.261615Z","steps":["trace[1315188011] 'agreement among raft nodes before linearized reading' (duration: 168.260472ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:53:44.26186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.975998ms","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-07-23T23:53:44.262004Z","caller":"traceutil/trace.go:171","msg":"trace[8757931] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:800; }","duration":"148.126332ms","start":"2026-07-23T23:53:44.113796Z","end":"2026-07-23T23:53:44.261922Z","steps":["trace[8757931] 'agreement among raft nodes before linearized reading' (duration: 147.67352ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:54:19.324741Z","caller":"traceutil/trace.go:171","msg":"trace[969747734] linearizableReadLoop","detail":"{readStateIndex:1051; appliedIndex:1050; }","duration":"241.350822ms","start":"2026-07-23T23:54:19.083363Z","end":"2026-07-23T23:54:19.324714Z","steps":["trace[969747734] 'read index received' (duration: 241.326701ms)","trace[969747734] 'applied index is now lower than readState.Index' (duration: 22.721µs)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:54:19.324814Z","caller":"traceutil/trace.go:171","msg":"trace[1259429886] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"242.597456ms","start":"2026-07-23T23:54:19.082194Z","end":"2026-07-23T23:54:19.324791Z","steps":["trace[1259429886] 'process raft request' (duration: 242.310958ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:54:19.324991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.604919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-23T23:54:19.325095Z","caller":"traceutil/trace.go:171","msg":"trace[1915015863] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1013; }","duration":"241.761003ms","start":"2026-07-23T23:54:19.083318Z","end":"2026-07-23T23:54:19.325079Z","steps":["trace[1915015863] 'agreement among raft nodes before linearized reading' (duration: 241.534307ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:54:19.926862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.454162ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-23T23:54:19.927036Z","caller":"traceutil/trace.go:171","msg":"trace[153607730] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"597.586457ms","start":"2026-07-23T23:54:19.329432Z","end":"2026-07-23T23:54:19.927019Z","steps":["trace[153607730] 'process raft request' (duration: 597.535656ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:54:19.927098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:54:19.329407Z","time spent":"597.660949ms","remote":"127.0.0.1:47736","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-07-23T23:54:19.927429Z","caller":"traceutil/trace.go:171","msg":"trace[1429424793] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"806.651632ms","start":"2026-07-23T23:54:19.120748Z","end":"2026-07-23T23:54:19.927399Z","steps":["trace[1429424793] 'process raft request' (duration: 481.577854ms)","trace[1429424793] 'compare' (duration: 324.358618ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:54:19.927422Z","caller":"traceutil/trace.go:171","msg":"trace[505505458] linearizableReadLoop","detail":"{readStateIndex:1052; appliedIndex:1051; }","duration":"602.535032ms","start":"2026-07-23T23:54:19.324826Z","end":"2026-07-23T23:54:19.927361Z","steps":["trace[505505458] 'read index received' (duration: 277.452043ms)","trace[505505458] 'applied index is now lower than readState.Index' (duration: 325.071428ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:54:19.92757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"842.528177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-23T23:54:19.927584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:54:19.120732Z","time spent":"806.773785ms","remote":"127.0.0.1:47736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:54:19.927637Z","caller":"traceutil/trace.go:171","msg":"trace[2007330331] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1015; }","duration":"842.63857ms","start":"2026-07-23T23:54:19.084982Z","end":"2026-07-23T23:54:19.92762Z","steps":["trace[2007330331] 'agreement among raft nodes before linearized reading' (duration: 842.416414ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:54:19.927672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"843.707309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-07-23T23:54:19.927678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:54:19.084937Z","time spent":"842.733472ms","remote":"127.0.0.1:47736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-23T23:54:19.927721Z","caller":"traceutil/trace.go:171","msg":"trace[1290363418] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1015; }","duration":"843.793311ms","start":"2026-07-23T23:54:19.083915Z","end":"2026-07-23T23:54:19.927708Z","steps":["trace[1290363418] 'agreement among raft nodes before linearized reading' (duration: 843.584005ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:54:19.927718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.417807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-23T23:54:19.927787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:54:19.083907Z","time spent":"843.871363ms","remote":"127.0.0.1:47736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-23T23:54:19.927811Z","caller":"traceutil/trace.go:171","msg":"trace[568179400] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1015; }","duration":"197.53934ms","start":"2026-07-23T23:54:19.730255Z","end":"2026-07-23T23:54:19.927794Z","steps":["trace[568179400] 'agreement among raft nodes before linearized reading' (duration: 197.400227ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:54:19.927845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.917477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:54:19.927879Z","caller":"traceutil/trace.go:171","msg":"trace[1374458421] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1015; }","duration":"124.960837ms","start":"2026-07-23T23:54:19.802909Z","end":"2026-07-23T23:54:19.92787Z","steps":["trace[1374458421] 'agreement among raft nodes before linearized reading' (duration: 124.913616ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:54:20.272552Z","caller":"traceutil/trace.go:171","msg":"trace[594250359] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"341.231107ms","start":"2026-07-23T23:54:19.931295Z","end":"2026-07-23T23:54:20.272526Z","steps":["trace[594250359] 'process raft request' (duration: 341.031982ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:54:20.272692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:54:19.931276Z","time spent":"341.34253ms","remote":"127.0.0.1:47736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:54:20.273936Z","caller":"traceutil/trace.go:171","msg":"trace[864408093] linearizableReadLoop","detail":"{readStateIndex:1055; appliedIndex:1053; }","duration":"253.461471ms","start":"2026-07-23T23:54:20.020454Z","end":"2026-07-23T23:54:20.273916Z","steps":["trace[864408093] 'read index received' (duration: 251.849897ms)","trace[864408093] 'applied index is now lower than readState.Index' (duration: 1.610524ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:54:20.274076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.607675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:54:20.274108Z","caller":"traceutil/trace.go:171","msg":"trace[916426977] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1017; }","duration":"253.682707ms","start":"2026-07-23T23:54:20.020416Z","end":"2026-07-23T23:54:20.274098Z","steps":["trace[916426977] 'agreement among raft nodes before linearized reading' (duration: 253.593104ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:54:20.274128Z","caller":"traceutil/trace.go:171","msg":"trace[1482852325] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"341.962517ms","start":"2026-07-23T23:54:19.932135Z","end":"2026-07-23T23:54:20.274097Z","steps":["trace[1482852325] 'process raft request' (duration: 341.603687ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:54:20.274322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:54:19.932122Z","time spent":"342.071409ms","remote":"127.0.0.1:47736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T23:54:42.862321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.525551ms","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-07-23T23:54:42.862419Z","caller":"traceutil/trace.go:171","msg":"trace[703193259] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1090; }","duration":"314.746667ms","start":"2026-07-23T23:54:42.547653Z","end":"2026-07-23T23:54:42.8624Z","steps":["trace[703193259] 'range keys from in-memory index tree' (duration: 314.343916ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:54:42.862465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:54:42.547629Z","time spent":"314.82105ms","remote":"127.0.0.1:47736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-23T23:54:44.370174Z","caller":"traceutil/trace.go:171","msg":"trace[24343623] linearizableReadLoop","detail":"{readStateIndex:1137; appliedIndex:1136; }","duration":"268.556762ms","start":"2026-07-23T23:54:44.101581Z","end":"2026-07-23T23:54:44.370138Z","steps":["trace[24343623] 'read index received' (duration: 268.069079ms)","trace[24343623] 'applied index is now lower than readState.Index' (duration: 486.043µs)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:54:44.37041Z","caller":"traceutil/trace.go:171","msg":"trace[1741048439] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"323.080795ms","start":"2026-07-23T23:54:44.047298Z","end":"2026-07-23T23:54:44.370379Z","steps":["trace[1741048439] 'process raft request' (duration: 322.572561ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:54:44.370524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.931482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-23T23:54:44.370578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:54:44.047259Z","time spent":"323.237889ms","remote":"127.0.0.1:47736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:54:44.370588Z","caller":"traceutil/trace.go:171","msg":"trace[831102477] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1094; }","duration":"269.043985ms","start":"2026-07-23T23:54:44.101526Z","end":"2026-07-23T23:54:44.37057Z","steps":["trace[831102477] 'agreement among raft nodes before linearized reading' (duration: 268.823909ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:54:44.674051Z","caller":"traceutil/trace.go:171","msg":"trace[35971155] linearizableReadLoop","detail":"{readStateIndex:1138; appliedIndex:1137; }","duration":"264.44099ms","start":"2026-07-23T23:54:44.409582Z","end":"2026-07-23T23:54:44.674023Z","steps":["trace[35971155] 'read index received' (duration: 250.856091ms)","trace[35971155] 'applied index is now lower than readState.Index' (duration: 13.583489ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:54:44.674136Z","caller":"traceutil/trace.go:171","msg":"trace[1833236339] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"299.934644ms","start":"2026-07-23T23:54:44.374166Z","end":"2026-07-23T23:54:44.674101Z","steps":["trace[1833236339] 'process raft request' (duration: 286.255092ms)","trace[1833236339] 'compare' (duration: 13.22538ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:54:44.674279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.681466ms","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-07-23T23:54:44.674341Z","caller":"traceutil/trace.go:171","msg":"trace[397435068] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1095; }","duration":"264.776629ms","start":"2026-07-23T23:54:44.40955Z","end":"2026-07-23T23:54:44.674327Z","steps":["trace[397435068] 'agreement among raft nodes before linearized reading' (duration: 264.632965ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:54:44.674426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:54:44.374143Z","time spent":"300.125869ms","remote":"127.0.0.1:47736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T23:54:44.67479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.964198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-23T23:54:44.674871Z","caller":"traceutil/trace.go:171","msg":"trace[410524382] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1095; }","duration":"249.098712ms","start":"2026-07-23T23:54:44.42576Z","end":"2026-07-23T23:54:44.674859Z","steps":["trace[410524382] 'agreement among raft nodes before linearized reading' (duration: 248.914427ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:54:46.069033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.955828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:54:46.069128Z","caller":"traceutil/trace.go:171","msg":"trace[1319303766] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1099; }","duration":"427.121133ms","start":"2026-07-23T23:54:45.641986Z","end":"2026-07-23T23:54:46.069108Z","steps":["trace[1319303766] 'count revisions from in-memory index tree' (duration: 426.863255ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:54:46.069178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:54:45.641963Z","time spent":"427.203155ms","remote":"127.0.0.1:54462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-23T23:54:46.069461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.130321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:54:46.069962Z","caller":"traceutil/trace.go:171","msg":"trace[341403892] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1099; }","duration":"264.684506ms","start":"2026-07-23T23:54:45.805255Z","end":"2026-07-23T23:54:46.06994Z","steps":["trace[341403892] 'range keys from in-memory index tree' (duration: 264.000588ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:54:47.123415Z","caller":"traceutil/trace.go:171","msg":"trace[812256175] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"122.570073ms","start":"2026-07-23T23:54:47.000822Z","end":"2026-07-23T23:54:47.123392Z","steps":["trace[812256175] 'process raft request' (duration: 122.447629ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:54:47.874473Z","caller":"traceutil/trace.go:171","msg":"trace[1370149279] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"329.395116ms","start":"2026-07-23T23:54:47.545041Z","end":"2026-07-23T23:54:47.874436Z","steps":["trace[1370149279] 'process raft request' (duration: 329.093768ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:54:47.874674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:54:47.545025Z","time spent":"329.540619ms","remote":"127.0.0.1:47736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:54:48.534723Z","caller":"traceutil/trace.go:171","msg":"trace[994774241] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"117.32727ms","start":"2026-07-23T23:54:48.417374Z","end":"2026-07-23T23:54:48.534701Z","steps":["trace[994774241] 'process raft request' (duration: 117.217377ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:54:48.534723Z","caller":"traceutil/trace.go:171","msg":"trace[1814987222] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"578.660902ms","start":"2026-07-23T23:54:47.956026Z","end":"2026-07-23T23:54:48.534687Z","steps":["trace[1814987222] 'process raft request' (duration: 508.834884ms)","trace[1814987222] 'compare' (duration: 69.360466ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:54:48.535149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:54:47.955998Z","time spent":"579.064694ms","remote":"127.0.0.1:47736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T23:54:48.840606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.562788ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-23T23:54:48.840716Z","caller":"traceutil/trace.go:171","msg":"trace[384820493] linearizableReadLoop","detail":"{readStateIndex:1151; appliedIndex:1150; }","duration":"140.221382ms","start":"2026-07-23T23:54:48.700476Z","end":"2026-07-23T23:54:48.840698Z","steps":["trace[384820493] 'read index received' (duration: 19.49513ms)","trace[384820493] 'applied index is now lower than readState.Index' (duration: 120.724502ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:54:48.841151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.26534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-23T23:54:48.841268Z","caller":"traceutil/trace.go:171","msg":"trace[14940144] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1107; }","duration":"113.406294ms","start":"2026-07-23T23:54:48.727811Z","end":"2026-07-23T23:54:48.841217Z","steps":["trace[14940144] 'agreement among raft nodes before linearized reading' (duration: 113.007713ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:54:48.841878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.381154ms","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-07-23T23:54:48.841985Z","caller":"traceutil/trace.go:171","msg":"trace[634088403] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1107; }","duration":"141.547989ms","start":"2026-07-23T23:54:48.700413Z","end":"2026-07-23T23:54:48.841961Z","steps":["trace[634088403] 'agreement among raft nodes before linearized reading' (duration: 140.394027ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:54:53.08067Z","caller":"traceutil/trace.go:171","msg":"trace[167558304] transaction","detail":"{read_only:false; response_revision:1123; number_of_response:1; }","duration":"138.347291ms","start":"2026-07-23T23:54:52.942296Z","end":"2026-07-23T23:54:53.080643Z","steps":["trace[167558304] 'process raft request' (duration: 80.531429ms)","trace[167558304] 'compare' (duration: 57.667188ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:54:54.07993Z","caller":"traceutil/trace.go:171","msg":"trace[2027391374] transaction","detail":"{read_only:false; response_revision:1126; number_of_response:1; }","duration":"134.916128ms","start":"2026-07-23T23:54:53.944979Z","end":"2026-07-23T23:54:54.079895Z","steps":["trace[2027391374] 'process raft request' (duration: 134.670501ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:55:03.130846Z","caller":"traceutil/trace.go:171","msg":"trace[467679815] transaction","detail":"{read_only:false; response_revision:1206; number_of_response:1; }","duration":"100.120502ms","start":"2026-07-23T23:55:03.03069Z","end":"2026-07-23T23:55:03.13081Z","steps":["trace[467679815] 'process raft request' (duration: 60.076333ms)","trace[467679815] 'compare' (duration: 39.801772ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:55:03.277087Z","caller":"traceutil/trace.go:171","msg":"trace[1099489945] transaction","detail":"{read_only:false; response_revision:1207; number_of_response:1; }","duration":"142.125874ms","start":"2026-07-23T23:55:03.134939Z","end":"2026-07-23T23:55:03.277064Z","steps":["trace[1099489945] 'process raft request' (duration: 141.999821ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:55:05.045246Z","caller":"traceutil/trace.go:171","msg":"trace[1827081138] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"926.345186ms","start":"2026-07-23T23:55:04.118871Z","end":"2026-07-23T23:55:05.045216Z","steps":["trace[1827081138] 'process raft request' (duration: 926.159851ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:05.045427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:55:04.118838Z","time spent":"926.49168ms","remote":"127.0.0.1:47736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:55:05.260299Z","caller":"traceutil/trace.go:171","msg":"trace[2006970099] linearizableReadLoop","detail":"{readStateIndex:1260; appliedIndex:1258; }","duration":"283.241952ms","start":"2026-07-23T23:55:04.977018Z","end":"2026-07-23T23:55:05.26026Z","steps":["trace[2006970099] 'read index received' (duration: 68.045721ms)","trace[2006970099] 'applied index is now lower than readState.Index' (duration: 215.19425ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:55:05.260464Z","caller":"traceutil/trace.go:171","msg":"trace[1639838189] transaction","detail":"{read_only:false; response_revision:1211; number_of_response:1; }","duration":"643.781294ms","start":"2026-07-23T23:55:04.616671Z","end":"2026-07-23T23:55:05.260452Z","steps":["trace[1639838189] 'process raft request' (duration: 638.956123ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:05.260616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:55:04.616634Z","time spent":"643.860056ms","remote":"127.0.0.1:47736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T23:55:05.260763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.715354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-23T23:55:05.260797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.256195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-23T23:55:05.260858Z","caller":"traceutil/trace.go:171","msg":"trace[418773137] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1211; }","duration":"283.873048ms","start":"2026-07-23T23:55:04.976959Z","end":"2026-07-23T23:55:05.260832Z","steps":["trace[418773137] 'agreement among raft nodes before linearized reading' (duration: 283.57442ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:55:05.260929Z","caller":"traceutil/trace.go:171","msg":"trace[1056654380] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1211; }","duration":"125.41795ms","start":"2026-07-23T23:55:05.135475Z","end":"2026-07-23T23:55:05.260893Z","steps":["trace[1056654380] 'agreement among raft nodes before linearized reading' (duration: 125.05457ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:05.781195Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356766935723651755,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-23T23:55:06.08555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.811978ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:55:06.085653Z","caller":"traceutil/trace.go:171","msg":"trace[862374017] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1211; }","duration":"606.92139ms","start":"2026-07-23T23:55:05.47871Z","end":"2026-07-23T23:55:06.085631Z","steps":["trace[862374017] 'range keys from in-memory index tree' (duration: 606.794097ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:55:06.08587Z","caller":"traceutil/trace.go:171","msg":"trace[1923845742] transaction","detail":"{read_only:false; response_revision:1212; number_of_response:1; }","duration":"819.963363ms","start":"2026-07-23T23:55:05.265876Z","end":"2026-07-23T23:55:06.085839Z","steps":["trace[1923845742] 'process raft request' (duration: 793.216756ms)","trace[1923845742] 'compare' (duration: 26.630514ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:55:06.086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:55:05.265857Z","time spent":"820.075426ms","remote":"127.0.0.1:47736","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-07-23T23:55:06.088562Z","caller":"traceutil/trace.go:171","msg":"trace[321604282] linearizableReadLoop","detail":"{readStateIndex:1262; appliedIndex:1260; }","duration":"807.843663ms","start":"2026-07-23T23:55:05.280696Z","end":"2026-07-23T23:55:06.08854Z","steps":["trace[321604282] 'read index received' (duration: 778.414624ms)","trace[321604282] 'applied index is now lower than readState.Index' (duration: 29.428109ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:55:06.088928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"802.676033ms","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-07-23T23:55:06.088921Z","caller":"traceutil/trace.go:171","msg":"trace[335931002] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"821.520775ms","start":"2026-07-23T23:55:05.267366Z","end":"2026-07-23T23:55:06.088886Z","steps":["trace[335931002] 'process raft request' (duration: 821.012521ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:06.088944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.70706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:55:06.088985Z","caller":"traceutil/trace.go:171","msg":"trace[1594278053] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1213; }","duration":"802.786226ms","start":"2026-07-23T23:55:05.286186Z","end":"2026-07-23T23:55:06.088972Z","steps":["trace[1594278053] 'agreement among raft nodes before linearized reading' (duration: 802.631112ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:55:06.089008Z","caller":"traceutil/trace.go:171","msg":"trace[1825159176] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1213; }","duration":"284.806103ms","start":"2026-07-23T23:55:05.804185Z","end":"2026-07-23T23:55:06.088991Z","steps":["trace[1825159176] 'agreement among raft nodes before linearized reading' (duration: 284.71443ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:06.089018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:55:05.286166Z","time spent":"802.844787ms","remote":"127.0.0.1:47736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-23T23:55:06.089089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:55:05.267343Z","time spent":"821.652769ms","remote":"127.0.0.1:47736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T23:55:06.090037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"809.325013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-23T23:55:06.090122Z","caller":"traceutil/trace.go:171","msg":"trace[318553551] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1213; }","duration":"809.442317ms","start":"2026-07-23T23:55:05.280659Z","end":"2026-07-23T23:55:06.090101Z","steps":["trace[318553551] 'agreement among raft nodes before linearized reading' (duration: 808.08425ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:06.090168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:55:05.280649Z","time spent":"809.510889ms","remote":"127.0.0.1:47736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-23T23:55:18.804749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.763904ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-23T23:55:21.507899Z","caller":"traceutil/trace.go:171","msg":"trace[521301783] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"396.988853ms","start":"2026-07-23T23:55:21.110882Z","end":"2026-07-23T23:55:21.507871Z","steps":["trace[521301783] 'process raft request' (duration: 396.184442ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:21.508054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:55:21.110866Z","time spent":"397.107257ms","remote":"127.0.0.1:47736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:55:21.525773Z","caller":"traceutil/trace.go:171","msg":"trace[1140765386] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"213.926146ms","start":"2026-07-23T23:55:21.311828Z","end":"2026-07-23T23:55:21.525754Z","steps":["trace[1140765386] 'process raft request' (duration: 213.828304ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:22.286971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.937286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-23T23:55:22.28702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.423357ms","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-07-23T23:55:22.287073Z","caller":"traceutil/trace.go:171","msg":"trace[1094894100] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1280; }","duration":"122.09886ms","start":"2026-07-23T23:55:22.164951Z","end":"2026-07-23T23:55:22.28705Z","steps":["trace[1094894100] 'range keys from in-memory index tree' (duration: 121.72493ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:55:22.287096Z","caller":"traceutil/trace.go:171","msg":"trace[159831081] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1280; }","duration":"101.535241ms","start":"2026-07-23T23:55:22.185543Z","end":"2026-07-23T23:55:22.287078Z","steps":["trace[159831081] 'range keys from in-memory index tree' (duration: 101.246593ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:22.287139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.61326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-23T23:55:22.287156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.073224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-23T23:55:22.287252Z","caller":"traceutil/trace.go:171","msg":"trace[1100496794] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1280; }","duration":"106.183267ms","start":"2026-07-23T23:55:22.18105Z","end":"2026-07-23T23:55:22.287233Z","steps":["trace[1100496794] 'range keys from in-memory index tree' (duration: 105.891609ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:55:22.287248Z","caller":"traceutil/trace.go:171","msg":"trace[1147845288] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1280; }","duration":"127.753324ms","start":"2026-07-23T23:55:22.159473Z","end":"2026-07-23T23:55:22.287226Z","steps":["trace[1147845288] 'range keys from in-memory index tree' (duration: 127.411234ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:26.305243Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356766935723651974,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-23T23:55:26.338306Z","caller":"traceutil/trace.go:171","msg":"trace[791266090] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"748.862184ms","start":"2026-07-23T23:55:25.589416Z","end":"2026-07-23T23:55:26.338278Z","steps":["trace[791266090] 'process raft request' (duration: 748.604137ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:26.338476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:55:25.589369Z","time spent":"749.015179ms","remote":"127.0.0.1:47736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:55:26.559719Z","caller":"traceutil/trace.go:171","msg":"trace[327215] linearizableReadLoop","detail":"{readStateIndex:1364; appliedIndex:1362; }","duration":"754.887879ms","start":"2026-07-23T23:55:25.804793Z","end":"2026-07-23T23:55:26.559681Z","steps":["trace[327215] 'read index received' (duration: 533.434408ms)","trace[327215] 'applied index is now lower than readState.Index' (duration: 221.452141ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:55:26.559799Z","caller":"traceutil/trace.go:171","msg":"trace[34733977] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"967.18084ms","start":"2026-07-23T23:55:25.592588Z","end":"2026-07-23T23:55:26.559769Z","steps":["trace[34733977] 'process raft request' (duration: 929.35705ms)","trace[34733977] 'compare' (duration: 37.601264ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:55:26.559995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.291597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-23T23:55:26.559998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"755.184496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:55:26.560083Z","caller":"traceutil/trace.go:171","msg":"trace[1470752371] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1311; }","duration":"755.332181ms","start":"2026-07-23T23:55:25.804731Z","end":"2026-07-23T23:55:26.560064Z","steps":["trace[1470752371] 'agreement among raft nodes before linearized reading' (duration: 755.135876ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:26.560111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.488687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-23T23:55:26.560103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.297821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-23T23:55:26.56015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.508888ms","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":"warn","ts":"2026-07-23T23:55:26.560189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:55:25.592566Z","time spent":"967.288342ms","remote":"127.0.0.1:47736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:55:26.560192Z","caller":"traceutil/trace.go:171","msg":"trace[191654987] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1311; }","duration":"241.445105ms","start":"2026-07-23T23:55:26.318728Z","end":"2026-07-23T23:55:26.560173Z","steps":["trace[191654987] 'agreement among raft nodes before linearized reading' (duration: 241.171308ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:55:26.560223Z","caller":"traceutil/trace.go:171","msg":"trace[1491512377] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1311; }","duration":"241.62585ms","start":"2026-07-23T23:55:26.31858Z","end":"2026-07-23T23:55:26.560206Z","steps":["trace[1491512377] 'agreement among raft nodes before linearized reading' (duration: 241.437945ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:26.56013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:55:25.804698Z","time spent":"755.421133ms","remote":"127.0.0.1:47474","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-07-23T23:55:26.560034Z","caller":"traceutil/trace.go:171","msg":"trace[756757648] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1311; }","duration":"250.383789ms","start":"2026-07-23T23:55:26.309637Z","end":"2026-07-23T23:55:26.560021Z","steps":["trace[756757648] 'agreement among raft nodes before linearized reading' (duration: 250.247355ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:55:26.560139Z","caller":"traceutil/trace.go:171","msg":"trace[554950620] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1311; }","duration":"241.551548ms","start":"2026-07-23T23:55:26.31858Z","end":"2026-07-23T23:55:26.560132Z","steps":["trace[554950620] 'agreement among raft nodes before linearized reading' (duration: 241.474846ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:55:26.761806Z","caller":"traceutil/trace.go:171","msg":"trace[1388528637] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"197.217191ms","start":"2026-07-23T23:55:26.564567Z","end":"2026-07-23T23:55:26.761784Z","steps":["trace[1388528637] 'process raft request' (duration: 183.024414ms)","trace[1388528637] 'compare' (duration: 14.062653ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:55:26.762154Z","caller":"traceutil/trace.go:171","msg":"trace[766542262] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"197.304703ms","start":"2026-07-23T23:55:26.564826Z","end":"2026-07-23T23:55:26.762131Z","steps":["trace[766542262] 'process raft request' (duration: 196.926043ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:55:26.762366Z","caller":"traceutil/trace.go:171","msg":"trace[566217720] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"196.953884ms","start":"2026-07-23T23:55:26.565399Z","end":"2026-07-23T23:55:26.762353Z","steps":["trace[566217720] 'process raft request' (duration: 196.650136ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:55:28.192026Z","caller":"traceutil/trace.go:171","msg":"trace[1741883538] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"242.987387ms","start":"2026-07-23T23:55:27.949013Z","end":"2026-07-23T23:55:28.192Z","steps":["trace[1741883538] 'process raft request' (duration: 242.769251ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:28.489806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.853336ms","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-07-23T23:55:28.4899Z","caller":"traceutil/trace.go:171","msg":"trace[1107163759] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1317; }","duration":"147.9848ms","start":"2026-07-23T23:55:28.341891Z","end":"2026-07-23T23:55:28.489876Z","steps":["trace[1107163759] 'range keys from in-memory index tree' (duration: 147.673172ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:29.298518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.893779ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-23T23:55:29.299037Z","caller":"traceutil/trace.go:171","msg":"trace[502269546] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"733.421003ms","start":"2026-07-23T23:55:28.565579Z","end":"2026-07-23T23:55:29.299Z","steps":["trace[502269546] 'process raft request' (duration: 733.044573ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:55:29.299068Z","caller":"traceutil/trace.go:171","msg":"trace[2029024053] linearizableReadLoop","detail":"{readStateIndex:1373; appliedIndex:1371; }","duration":"708.082264ms","start":"2026-07-23T23:55:28.590905Z","end":"2026-07-23T23:55:29.298987Z","steps":["trace[2029024053] 'read index received' (duration: 21.696001ms)","trace[2029024053] 'applied index is now lower than readState.Index' (duration: 686.384473ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:55:29.299219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:55:28.565549Z","time spent":"733.578848ms","remote":"127.0.0.1:47736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T23:55:29.299241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.31305ms","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-07-23T23:55:29.299324Z","caller":"traceutil/trace.go:171","msg":"trace[426445781] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1319; }","duration":"708.430684ms","start":"2026-07-23T23:55:28.590872Z","end":"2026-07-23T23:55:29.299302Z","steps":["trace[426445781] 'agreement among raft nodes before linearized reading' (duration: 708.199157ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:29.29937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:55:28.59086Z","time spent":"708.501445ms","remote":"127.0.0.1:47736","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-07-23T23:55:29.799276Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356766935723651995,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-23T23:55:30.164251Z","caller":"traceutil/trace.go:171","msg":"trace[1000700751] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"1.213458867s","start":"2026-07-23T23:55:28.950766Z","end":"2026-07-23T23:55:30.164225Z","steps":["trace[1000700751] 'process raft request' (duration: 1.213244131s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:30.164417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:55:28.950741Z","time spent":"1.213604151s","remote":"127.0.0.1:47536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-23T23:55:30.185124Z","caller":"traceutil/trace.go:171","msg":"trace[887964637] linearizableReadLoop","detail":"{readStateIndex:1376; appliedIndex:1373; }","duration":"885.978978ms","start":"2026-07-23T23:55:29.299128Z","end":"2026-07-23T23:55:30.185107Z","steps":["trace[887964637] 'read index received' (duration: 864.944215ms)","trace[887964637] 'applied index is now lower than readState.Index' (duration: 21.033703ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:55:30.18521Z","caller":"traceutil/trace.go:171","msg":"trace[2146138711] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"878.828394ms","start":"2026-07-23T23:55:29.306345Z","end":"2026-07-23T23:55:30.185174Z","steps":["trace[2146138711] 'process raft request' (duration: 878.733832ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:30.185329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:55:29.306322Z","time spent":"878.939687ms","remote":"127.0.0.1:47736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T23:55:30.185404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.127780054s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-07-23T23:55:30.185503Z","caller":"traceutil/trace.go:171","msg":"trace[42531199] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1322; }","duration":"1.128076582s","start":"2026-07-23T23:55:29.057414Z","end":"2026-07-23T23:55:30.18549Z","steps":["trace[42531199] 'agreement among raft nodes before linearized reading' (duration: 1.127789264s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:30.185514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.419024586s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-23T23:55:30.185517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.250972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-23T23:55:30.185611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:55:29.057393Z","time spent":"1.128206805s","remote":"127.0.0.1:47534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":141,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"warn","ts":"2026-07-23T23:55:30.185614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.419746685s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-23T23:55:30.185391Z","caller":"traceutil/trace.go:171","msg":"trace[1210207945] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"899.161148ms","start":"2026-07-23T23:55:29.28618Z","end":"2026-07-23T23:55:30.185341Z","steps":["trace[1210207945] 'process raft request' (duration: 898.804788ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:55:30.185687Z","caller":"traceutil/trace.go:171","msg":"trace[1970877248] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1322; }","duration":"1.419887779s","start":"2026-07-23T23:55:28.765783Z","end":"2026-07-23T23:55:30.185671Z","steps":["trace[1970877248] 'agreement among raft nodes before linearized reading' (duration: 1.419626032s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:30.185751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:55:28.765768Z","time spent":"1.41995477s","remote":"127.0.0.1:47736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-23T23:55:30.185744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"902.621402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-07-23T23:55:30.185777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:55:29.286159Z","time spent":"899.544898ms","remote":"127.0.0.1:47736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:55:30.185781Z","caller":"traceutil/trace.go:171","msg":"trace[903381368] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1322; }","duration":"790.459538ms","start":"2026-07-23T23:55:29.395164Z","end":"2026-07-23T23:55:30.185624Z","steps":["trace[903381368] 'agreement among raft nodes before linearized reading' (duration: 790.238642ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:30.185745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.163062895s","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-07-23T23:55:30.185797Z","caller":"traceutil/trace.go:171","msg":"trace[739413579] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1322; }","duration":"902.706984ms","start":"2026-07-23T23:55:29.283075Z","end":"2026-07-23T23:55:30.185782Z","steps":["trace[739413579] 'agreement among raft nodes before linearized reading' (duration: 902.499478ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:30.185837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:55:29.395144Z","time spent":"790.679523ms","remote":"127.0.0.1:47736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-23T23:55:30.185843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:55:29.283052Z","time spent":"902.783626ms","remote":"127.0.0.1:47736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-07-23T23:55:30.185626Z","caller":"traceutil/trace.go:171","msg":"trace[1948122376] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1322; }","duration":"1.41917917s","start":"2026-07-23T23:55:28.766425Z","end":"2026-07-23T23:55:30.185604Z","steps":["trace[1948122376] 'agreement among raft nodes before linearized reading' (duration: 1.418883582s)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:55:30.185888Z","caller":"traceutil/trace.go:171","msg":"trace[679789319] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1322; }","duration":"1.163172318s","start":"2026-07-23T23:55:29.022658Z","end":"2026-07-23T23:55:30.18583Z","steps":["trace[679789319] 'agreement among raft nodes before linearized reading' (duration: 1.162949762s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:30.185956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.418505561s","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":"warn","ts":"2026-07-23T23:55:30.185967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.339966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:55:30.185998Z","caller":"traceutil/trace.go:171","msg":"trace[960015747] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1322; }","duration":"381.390896ms","start":"2026-07-23T23:55:29.804598Z","end":"2026-07-23T23:55:30.185989Z","steps":["trace[960015747] 'agreement among raft nodes before linearized reading' (duration: 381.346615ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:55:30.185998Z","caller":"traceutil/trace.go:171","msg":"trace[487506703] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1322; }","duration":"1.418554612s","start":"2026-07-23T23:55:28.767429Z","end":"2026-07-23T23:55:30.185984Z","steps":["trace[487506703] 'agreement among raft nodes before linearized reading' (duration: 1.41846408s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:30.1859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:55:28.76641Z","time spent":"1.419468597s","remote":"127.0.0.1:47736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-23T23:55:30.186023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:55:29.804584Z","time spent":"381.433198ms","remote":"127.0.0.1:47474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-23T23:55:30.186033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:55:28.767422Z","time spent":"1.418605013s","remote":"127.0.0.1:47736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-23T23:55:30.185994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:55:29.022592Z","time spent":"1.163344192s","remote":"127.0.0.1:47736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":10,"response size":30,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-07-23T23:55:30.186229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.1272733s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:126396"} {"level":"info","ts":"2026-07-23T23:55:30.186277Z","caller":"traceutil/trace.go:171","msg":"trace[2137306869] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1322; }","duration":"1.127334441s","start":"2026-07-23T23:55:29.05893Z","end":"2026-07-23T23:55:30.186265Z","steps":["trace[2137306869] 'agreement among raft nodes before linearized reading' (duration: 1.127132255s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:30.186309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:55:29.058916Z","time spent":"1.127386523s","remote":"127.0.0.1:47520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":126419,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"info","ts":"2026-07-23T23:55:30.534623Z","caller":"traceutil/trace.go:171","msg":"trace[2089394617] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"343.059073ms","start":"2026-07-23T23:55:30.191543Z","end":"2026-07-23T23:55:30.534602Z","steps":["trace[2089394617] 'process raft request' (duration: 342.774056ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:30.534749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:55:30.191524Z","time spent":"343.165306ms","remote":"127.0.0.1:47736","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-07-23T23:55:30.534941Z","caller":"traceutil/trace.go:171","msg":"trace[1309036637] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"343.104624ms","start":"2026-07-23T23:55:30.191808Z","end":"2026-07-23T23:55:30.534913Z","steps":["trace[1309036637] 'process raft request' (duration: 342.704033ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:30.535093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:55:30.191785Z","time spent":"343.222557ms","remote":"127.0.0.1:47736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:55:30.535283Z","caller":"traceutil/trace.go:171","msg":"trace[845705048] linearizableReadLoop","detail":"{readStateIndex:1378; appliedIndex:1376; }","duration":"343.497005ms","start":"2026-07-23T23:55:30.191771Z","end":"2026-07-23T23:55:30.535268Z","steps":["trace[845705048] 'read index received' (duration: 16.849839ms)","trace[845705048] 'applied index is now lower than readState.Index' (duration: 326.645915ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:55:30.535385Z","caller":"traceutil/trace.go:171","msg":"trace[1056639842] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"342.60442ms","start":"2026-07-23T23:55:30.192768Z","end":"2026-07-23T23:55:30.535373Z","steps":["trace[1056639842] 'process raft request' (duration: 342.442426ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:30.535454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:55:30.192761Z","time spent":"342.657762ms","remote":"127.0.0.1:47736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:55:30.535588Z","caller":"traceutil/trace.go:171","msg":"trace[2004760642] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"343.615138ms","start":"2026-07-23T23:55:30.191963Z","end":"2026-07-23T23:55:30.535578Z","steps":["trace[2004760642] 'process raft request' (duration: 342.59604ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:30.535603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.859725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:126396"} {"level":"info","ts":"2026-07-23T23:55:30.535636Z","caller":"traceutil/trace.go:171","msg":"trace[309670809] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1327; }","duration":"343.919286ms","start":"2026-07-23T23:55:30.191706Z","end":"2026-07-23T23:55:30.535626Z","steps":["trace[309670809] 'agreement among raft nodes before linearized reading' (duration: 343.757222ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:30.535643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:55:30.191957Z","time spent":"343.658949ms","remote":"127.0.0.1:47736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":493,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T23:55:30.535672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:55:30.1917Z","time spent":"343.964977ms","remote":"127.0.0.1:47520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":126419,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"info","ts":"2026-07-23T23:55:30.565931Z","caller":"traceutil/trace.go:171","msg":"trace[857036723] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"267.562847ms","start":"2026-07-23T23:55:30.298348Z","end":"2026-07-23T23:55:30.565911Z","steps":["trace[857036723] 'process raft request' (duration: 267.493225ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:30.566225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.136499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-07-23T23:55:30.566305Z","caller":"traceutil/trace.go:171","msg":"trace[338388771] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1328; }","duration":"374.263723ms","start":"2026-07-23T23:55:30.192023Z","end":"2026-07-23T23:55:30.566286Z","steps":["trace[338388771] 'agreement among raft nodes before linearized reading' (duration: 373.938234ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:30.566359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:55:30.192008Z","time spent":"374.341735ms","remote":"127.0.0.1:47534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":141,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"info","ts":"2026-07-23T23:55:31.439518Z","caller":"traceutil/trace.go:171","msg":"trace[1640506728] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"135.190222ms","start":"2026-07-23T23:55:31.304303Z","end":"2026-07-23T23:55:31.439493Z","steps":["trace[1640506728] 'process raft request' (duration: 135.032888ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:55:34.39715Z","caller":"traceutil/trace.go:171","msg":"trace[1698097576] linearizableReadLoop","detail":"{readStateIndex:1417; appliedIndex:1416; }","duration":"195.837493ms","start":"2026-07-23T23:55:34.201283Z","end":"2026-07-23T23:55:34.397121Z","steps":["trace[1698097576] 'read index received' (duration: 195.580626ms)","trace[1698097576] 'applied index is now lower than readState.Index' (duration: 255.517µs)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:55:34.397297Z","caller":"traceutil/trace.go:171","msg":"trace[1686970048] transaction","detail":"{read_only:false; response_revision:1362; number_of_response:1; }","duration":"217.145403ms","start":"2026-07-23T23:55:34.180137Z","end":"2026-07-23T23:55:34.397283Z","steps":["trace[1686970048] 'process raft request' (duration: 216.789794ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:34.39743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.501954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-23T23:55:34.397515Z","caller":"traceutil/trace.go:171","msg":"trace[916873061] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1362; }","duration":"177.620097ms","start":"2026-07-23T23:55:34.219878Z","end":"2026-07-23T23:55:34.397498Z","steps":["trace[916873061] 'agreement among raft nodes before linearized reading' (duration: 177.440422ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:34.397665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.390019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-23T23:55:34.39769Z","caller":"traceutil/trace.go:171","msg":"trace[337487518] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1362; }","duration":"196.44613ms","start":"2026-07-23T23:55:34.201235Z","end":"2026-07-23T23:55:34.397682Z","steps":["trace[337487518] 'agreement among raft nodes before linearized reading' (duration: 196.372708ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:35.04999Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356766935723652077,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-23T23:55:35.174087Z","caller":"traceutil/trace.go:171","msg":"trace[1587077225] linearizableReadLoop","detail":"{readStateIndex:1419; appliedIndex:1417; }","duration":"624.889217ms","start":"2026-07-23T23:55:34.549174Z","end":"2026-07-23T23:55:35.174064Z","steps":["trace[1587077225] 'read index received' (duration: 618.131843ms)","trace[1587077225] 'applied index is now lower than readState.Index' (duration: 6.756114ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:55:35.174131Z","caller":"traceutil/trace.go:171","msg":"trace[470470375] transaction","detail":"{read_only:false; response_revision:1363; number_of_response:1; }","duration":"772.433535ms","start":"2026-07-23T23:55:34.401658Z","end":"2026-07-23T23:55:35.174092Z","steps":["trace[470470375] 'process raft request' (duration: 765.702811ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:55:35.174208Z","caller":"traceutil/trace.go:171","msg":"trace[1449740689] transaction","detail":"{read_only:false; response_revision:1364; number_of_response:1; }","duration":"771.632293ms","start":"2026-07-23T23:55:34.402551Z","end":"2026-07-23T23:55:35.174184Z","steps":["trace[1449740689] 'process raft request' (duration: 771.435078ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:35.174318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.129843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-23T23:55:35.174351Z","caller":"traceutil/trace.go:171","msg":"trace[1409492929] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1364; }","duration":"625.243366ms","start":"2026-07-23T23:55:34.549098Z","end":"2026-07-23T23:55:35.174341Z","steps":["trace[1409492929] 'agreement among raft nodes before linearized reading' (duration: 625.100483ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:35.17436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:55:34.401626Z","time spent":"772.61148ms","remote":"127.0.0.1:47736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T23:55:35.17483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:55:34.54907Z","time spent":"625.303948ms","remote":"127.0.0.1:47736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-23T23:55:35.174878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.677817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-23T23:55:35.174915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.189175ms","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-07-23T23:55:35.174925Z","caller":"traceutil/trace.go:171","msg":"trace[519327865] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1364; }","duration":"369.766779ms","start":"2026-07-23T23:55:34.805145Z","end":"2026-07-23T23:55:35.174912Z","steps":["trace[519327865] 'agreement among raft nodes before linearized reading' (duration: 369.685137ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:35.175668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:55:34.805122Z","time spent":"370.53219ms","remote":"127.0.0.1:47474","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-07-23T23:55:35.175488Z","caller":"traceutil/trace.go:171","msg":"trace[762279603] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1364; }","duration":"625.798111ms","start":"2026-07-23T23:55:34.549673Z","end":"2026-07-23T23:55:35.175471Z","steps":["trace[762279603] 'agreement among raft nodes before linearized reading' (duration: 625.063411ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:35.175221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.976011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-23T23:55:35.17577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:55:34.549653Z","time spent":"626.078039ms","remote":"127.0.0.1:47736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-23T23:55:35.17578Z","caller":"traceutil/trace.go:171","msg":"trace[1250623051] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1364; }","duration":"516.557296ms","start":"2026-07-23T23:55:34.659208Z","end":"2026-07-23T23:55:35.175766Z","steps":["trace[1250623051] 'agreement among raft nodes before linearized reading' (duration: 515.937179ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:35.175818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:55:34.659193Z","time spent":"516.614178ms","remote":"127.0.0.1:47736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-23T23:55:35.175369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:55:34.402522Z","time spent":"772.503427ms","remote":"127.0.0.1:47736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:55:35.337522Z","caller":"traceutil/trace.go:171","msg":"trace[1523573988] transaction","detail":"{read_only:false; response_revision:1366; number_of_response:1; }","duration":"157.1573ms","start":"2026-07-23T23:55:35.180337Z","end":"2026-07-23T23:55:35.337494Z","steps":["trace[1523573988] 'process raft request' (duration: 156.907663ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:55:35.337942Z","caller":"traceutil/trace.go:171","msg":"trace[1546811635] transaction","detail":"{read_only:false; response_revision:1367; number_of_response:1; }","duration":"157.501749ms","start":"2026-07-23T23:55:35.180396Z","end":"2026-07-23T23:55:35.337898Z","steps":["trace[1546811635] 'process raft request' (duration: 157.055947ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:35.338048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.669319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:55:35.338109Z","caller":"traceutil/trace.go:171","msg":"trace[1185738955] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1367; }","duration":"112.759211ms","start":"2026-07-23T23:55:35.225334Z","end":"2026-07-23T23:55:35.338093Z","steps":["trace[1185738955] 'agreement among raft nodes before linearized reading' (duration: 112.624728ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:55:35.338522Z","caller":"traceutil/trace.go:171","msg":"trace[1708803398] linearizableReadLoop","detail":"{readStateIndex:1422; appliedIndex:1419; }","duration":"112.478234ms","start":"2026-07-23T23:55:35.22534Z","end":"2026-07-23T23:55:35.337818Z","steps":["trace[1708803398] 'read index received' (duration: 6.620261ms)","trace[1708803398] 'applied index is now lower than readState.Index' (duration: 105.854003ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:55:45.624178Z","caller":"traceutil/trace.go:171","msg":"trace[1886786623] transaction","detail":"{read_only:false; response_revision:1415; number_of_response:1; }","duration":"133.62961ms","start":"2026-07-23T23:55:45.49042Z","end":"2026-07-23T23:55:45.62405Z","steps":["trace[1886786623] 'process raft request' (duration: 133.303641ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:56:45.266186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.125793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:56:45.266311Z","caller":"traceutil/trace.go:171","msg":"trace[744015412] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1685; }","duration":"341.321298ms","start":"2026-07-23T23:56:44.924942Z","end":"2026-07-23T23:56:45.266263Z","steps":["trace[744015412] 'count revisions from in-memory index tree' (duration: 341.02063ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:56:45.266368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:56:44.924924Z","time spent":"341.430011ms","remote":"127.0.0.1:51950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-23T23:56:45.267394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.365241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:56:45.267454Z","caller":"traceutil/trace.go:171","msg":"trace[171174038] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1685; }","duration":"150.709871ms","start":"2026-07-23T23:56:45.116719Z","end":"2026-07-23T23:56:45.267429Z","steps":["trace[171174038] 'count revisions from in-memory index tree' (duration: 148.845119ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:56:45.681084Z","caller":"traceutil/trace.go:171","msg":"trace[336005726] transaction","detail":"{read_only:false; response_revision:1686; number_of_response:1; }","duration":"130.058347ms","start":"2026-07-23T23:56:45.550944Z","end":"2026-07-23T23:56:45.681002Z","steps":["trace[336005726] 'process raft request' (duration: 129.796299ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:56:45.916334Z","caller":"traceutil/trace.go:171","msg":"trace[576512451] transaction","detail":"{read_only:false; response_revision:1690; number_of_response:1; }","duration":"148.999794ms","start":"2026-07-23T23:56:45.767315Z","end":"2026-07-23T23:56:45.916315Z","steps":["trace[576512451] 'process raft request' (duration: 147.985336ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:56:46.008365Z","caller":"traceutil/trace.go:171","msg":"trace[1165771956] linearizableReadLoop","detail":"{readStateIndex:1760; appliedIndex:1758; }","duration":"202.571694ms","start":"2026-07-23T23:56:45.805752Z","end":"2026-07-23T23:56:46.008324Z","steps":["trace[1165771956] 'read index received' (duration: 109.460615ms)","trace[1165771956] 'applied index is now lower than readState.Index' (duration: 93.109999ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:56:46.008393Z","caller":"traceutil/trace.go:171","msg":"trace[814801782] transaction","detail":"{read_only:false; response_revision:1691; number_of_response:1; }","duration":"239.749938ms","start":"2026-07-23T23:56:45.768612Z","end":"2026-07-23T23:56:46.008362Z","steps":["trace[814801782] 'process raft request' (duration: 239.528962ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:56:46.008563Z","caller":"traceutil/trace.go:171","msg":"trace[1995153603] transaction","detail":"{read_only:false; response_revision:1692; number_of_response:1; }","duration":"106.833913ms","start":"2026-07-23T23:56:45.90171Z","end":"2026-07-23T23:56:46.008544Z","steps":["trace[1995153603] 'process raft request' (duration: 106.571036ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:56:46.008764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.957846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-07-23T23:56:46.008856Z","caller":"traceutil/trace.go:171","msg":"trace[1919551169] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1692; }","duration":"203.14934ms","start":"2026-07-23T23:56:45.805688Z","end":"2026-07-23T23:56:46.008837Z","steps":["trace[1919551169] 'agreement among raft nodes before linearized reading' (duration: 202.76236ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:57:20.032702Z","caller":"traceutil/trace.go:171","msg":"trace[133258345] transaction","detail":"{read_only:false; response_revision:1845; number_of_response:1; }","duration":"101.986061ms","start":"2026-07-23T23:57:19.930691Z","end":"2026-07-23T23:57:20.032677Z","steps":["trace[133258345] 'process raft request' (duration: 101.94115ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:57:20.03278Z","caller":"traceutil/trace.go:171","msg":"trace[1528081615] transaction","detail":"{read_only:false; response_revision:1844; number_of_response:1; }","duration":"102.162296ms","start":"2026-07-23T23:57:19.930574Z","end":"2026-07-23T23:57:20.032736Z","steps":["trace[1528081615] 'process raft request' (duration: 73.398621ms)","trace[1528081615] 'compare' (duration: 28.532848ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:57:48.649944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.859685ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-23T23:57:48.65006Z","caller":"traceutil/trace.go:171","msg":"trace[1271821433] linearizableReadLoop","detail":"{readStateIndex:2059; appliedIndex:2058; }","duration":"150.366708ms","start":"2026-07-23T23:57:48.499675Z","end":"2026-07-23T23:57:48.650042Z","steps":["trace[1271821433] 'read index received' (duration: 29.101µs)","trace[1271821433] 'applied index is now lower than readState.Index' (duration: 150.335956ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:57:48.650295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.594324ms","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-07-23T23:57:48.650376Z","caller":"traceutil/trace.go:171","msg":"trace[526442789] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1977; }","duration":"150.722787ms","start":"2026-07-23T23:57:48.499634Z","end":"2026-07-23T23:57:48.650357Z","steps":["trace[526442789] 'agreement among raft nodes before linearized reading' (duration: 150.502631ms)"],"step_count":1}