{"level":"warn","ts":"2026-03-19T21:50:03.611285Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-19T21:50:03.611369Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.62: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://162.253.55.62:2380","--initial-cluster=instance=https://162.253.55.62:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.62:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.62: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-03-19T21:50:03.611437Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-19T21:50:03.611453Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.62:2380"]} {"level":"info","ts":"2026-03-19T21:50:03.611476Z","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-03-19T21:50:03.612076Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"]} {"level":"info","ts":"2026-03-19T21:50:03.612166Z","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://162.253.55.62:2380"],"listen-peer-urls":["https://162.253.55.62:2380"],"advertise-client-urls":["https://162.253.55.62:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.62: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-03-19T21:50:03.615863Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.478245ms"} {"level":"info","ts":"2026-03-19T21:50:03.622524Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"791a7e25b533cd26","cluster-id":"be484d2748da72cd"} {"level":"info","ts":"2026-03-19T21:50:03.622597Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=()"} {"level":"info","ts":"2026-03-19T21:50:03.622621Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became follower at term 0"} {"level":"info","ts":"2026-03-19T21:50:03.622647Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 791a7e25b533cd26 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-19T21:50:03.622661Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became follower at term 1"} {"level":"info","ts":"2026-03-19T21:50:03.622701Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=(8726425928402717990)"} {"level":"warn","ts":"2026-03-19T21:50:03.627486Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-19T21:50:03.628862Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-19T21:50:03.630049Z","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-03-19T21:50:03.633221Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"791a7e25b533cd26","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-19T21:50:03.63449Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"791a7e25b533cd26","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-19T21:50:03.635085Z","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-03-19T21:50:03.635184Z","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-03-19T21:50:03.635215Z","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-03-19T21:50:03.635988Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=(8726425928402717990)"} {"level":"info","ts":"2026-03-19T21:50:03.638495Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"be484d2748da72cd","local-member-id":"791a7e25b533cd26","added-peer-id":"791a7e25b533cd26","added-peer-peer-urls":["https://162.253.55.62:2380"]} {"level":"info","ts":"2026-03-19T21:50:03.639166Z","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-03-19T21:50:03.639329Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"791a7e25b533cd26","initial-advertise-peer-urls":["https://162.253.55.62:2380"],"listen-peer-urls":["https://162.253.55.62:2380"],"advertise-client-urls":["https://162.253.55.62:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-19T21:50:03.639351Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-19T21:50:03.6394Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.62:2380"} {"level":"info","ts":"2026-03-19T21:50:03.63941Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.62:2380"} {"level":"info","ts":"2026-03-19T21:50:04.222869Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 is starting a new election at term 1"} {"level":"info","ts":"2026-03-19T21:50:04.222907Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-19T21:50:04.222926Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 received MsgPreVoteResp from 791a7e25b533cd26 at term 1"} {"level":"info","ts":"2026-03-19T21:50:04.222936Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became candidate at term 2"} {"level":"info","ts":"2026-03-19T21:50:04.222943Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 received MsgVoteResp from 791a7e25b533cd26 at term 2"} {"level":"info","ts":"2026-03-19T21:50:04.222952Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became leader at term 2"} {"level":"info","ts":"2026-03-19T21:50:04.222958Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 791a7e25b533cd26 elected leader 791a7e25b533cd26 at term 2"} {"level":"info","ts":"2026-03-19T21:50:04.223949Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"791a7e25b533cd26","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.62:2379]}","request-path":"/0/members/791a7e25b533cd26/attributes","cluster-id":"be484d2748da72cd","publish-timeout":"7s"} {"level":"info","ts":"2026-03-19T21:50:04.223986Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-19T21:50:04.224135Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T21:50:04.224257Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-19T21:50:04.225258Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-19T21:50:04.225306Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-19T21:50:04.22543Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"be484d2748da72cd","local-member-id":"791a7e25b533cd26","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T21:50:04.225521Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T21:50:04.225539Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T21:50:04.227022Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-19T21:50:04.2276Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.62:2379"} {"level":"info","ts":"2026-03-19T21:50:23.761901Z","caller":"traceutil/trace.go:171","msg":"trace[1468977830] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"104.868362ms","start":"2026-03-19T21:50:23.657007Z","end":"2026-03-19T21:50:23.761875Z","steps":["trace[1468977830] 'process raft request' (duration: 48.50427ms)","trace[1468977830] 'compare' (duration: 56.245459ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:50:23.953987Z","caller":"traceutil/trace.go:171","msg":"trace[1269836767] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"166.849157ms","start":"2026-03-19T21:50:23.787112Z","end":"2026-03-19T21:50:23.953961Z","steps":["trace[1269836767] 'process raft request' (duration: 160.70801ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:50:29.907701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.673584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-19T21:50:29.907773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.46711ms","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-03-19T21:50:29.907864Z","caller":"traceutil/trace.go:171","msg":"trace[1386988257] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:462; }","duration":"115.615674ms","start":"2026-03-19T21:50:29.792227Z","end":"2026-03-19T21:50:29.907843Z","steps":["trace[1386988257] 'range keys from in-memory index tree' (duration: 115.257544ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:50:29.90781Z","caller":"traceutil/trace.go:171","msg":"trace[1223443989] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:462; }","duration":"133.86497ms","start":"2026-03-19T21:50:29.773926Z","end":"2026-03-19T21:50:29.907791Z","steps":["trace[1223443989] 'range keys from in-memory index tree' (duration: 133.549041ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:50:29.907707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.122759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-19T21:50:29.908231Z","caller":"traceutil/trace.go:171","msg":"trace[1304062455] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:462; }","duration":"233.677974ms","start":"2026-03-19T21:50:29.674526Z","end":"2026-03-19T21:50:29.908204Z","steps":["trace[1304062455] 'range keys from in-memory index tree' (duration: 233.009646ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:50:30.626729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.771807ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T21:50:30.627262Z","caller":"traceutil/trace.go:171","msg":"trace[1236601217] linearizableReadLoop","detail":"{readStateIndex:481; appliedIndex:480; }","duration":"654.682862ms","start":"2026-03-19T21:50:29.97253Z","end":"2026-03-19T21:50:30.627213Z","steps":["trace[1236601217] 'read index received' (duration: 39.578846ms)","trace[1236601217] 'applied index is now lower than readState.Index' (duration: 615.101946ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:50:30.627306Z","caller":"traceutil/trace.go:171","msg":"trace[1176514970] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"712.106163ms","start":"2026-03-19T21:50:29.915149Z","end":"2026-03-19T21:50:30.627255Z","steps":["trace[1176514970] 'process raft request' (duration: 586.661092ms)","trace[1176514970] 'compare' (duration: 119.453809ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T21:50:30.62736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.829526ms","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-03-19T21:50:30.627383Z","caller":"traceutil/trace.go:171","msg":"trace[52105735] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:464; }","duration":"654.892908ms","start":"2026-03-19T21:50:29.972482Z","end":"2026-03-19T21:50:30.627375Z","steps":["trace[52105735] 'agreement among raft nodes before linearized reading' (duration: 654.830646ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:50:30.62741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:50:29.97241Z","time spent":"654.994921ms","remote":"127.0.0.1:42256","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-03-19T21:50:30.627494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:50:29.915129Z","time spent":"712.255867ms","remote":"127.0.0.1:42256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T21:50:30.627968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.213192ms","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-03-19T21:50:30.628053Z","caller":"traceutil/trace.go:171","msg":"trace[1164388519] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:464; }","duration":"635.311206ms","start":"2026-03-19T21:50:29.992719Z","end":"2026-03-19T21:50:30.62803Z","steps":["trace[1164388519] 'agreement among raft nodes before linearized reading' (duration: 635.167292ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:50:30.628104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:50:29.992711Z","time spent":"635.379577ms","remote":"127.0.0.1:42256","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-03-19T21:50:34.409951Z","caller":"traceutil/trace.go:171","msg":"trace[1198968276] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"380.496906ms","start":"2026-03-19T21:50:34.029432Z","end":"2026-03-19T21:50:34.409929Z","steps":["trace[1198968276] 'process raft request' (duration: 380.357092ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:50:34.410117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:50:34.029423Z","time spent":"380.617889ms","remote":"127.0.0.1:42256","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-03-19T21:50:40.722848Z","caller":"traceutil/trace.go:171","msg":"trace[1420558397] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"286.448579ms","start":"2026-03-19T21:50:40.436373Z","end":"2026-03-19T21:50:40.722822Z","steps":["trace[1420558397] 'process raft request' (duration: 285.891184ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:50:41.025424Z","caller":"traceutil/trace.go:171","msg":"trace[1396524866] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"297.130889ms","start":"2026-03-19T21:50:40.728262Z","end":"2026-03-19T21:50:41.025393Z","steps":["trace[1396524866] 'process raft request' (duration: 297.003956ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:50:41.025709Z","caller":"traceutil/trace.go:171","msg":"trace[1169519210] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"297.438738ms","start":"2026-03-19T21:50:40.728215Z","end":"2026-03-19T21:50:41.025654Z","steps":["trace[1169519210] 'process raft request' (duration: 198.980691ms)","trace[1169519210] 'compare' (duration: 97.865901ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:50:41.64386Z","caller":"traceutil/trace.go:171","msg":"trace[1077210195] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"540.25222ms","start":"2026-03-19T21:50:41.103583Z","end":"2026-03-19T21:50:41.643836Z","steps":["trace[1077210195] 'process raft request' (duration: 540.111216ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:50:41.644169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:50:41.103552Z","time spent":"540.400334ms","remote":"127.0.0.1:42194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T21:50:43.48389Z","caller":"traceutil/trace.go:171","msg":"trace[528919344] linearizableReadLoop","detail":"{readStateIndex:551; appliedIndex:550; }","duration":"454.295183ms","start":"2026-03-19T21:50:43.029569Z","end":"2026-03-19T21:50:43.483864Z","steps":["trace[528919344] 'read index received' (duration: 393.284415ms)","trace[528919344] 'applied index is now lower than readState.Index' (duration: 61.009628ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:50:43.483958Z","caller":"traceutil/trace.go:171","msg":"trace[2031248802] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"615.899767ms","start":"2026-03-19T21:50:42.868029Z","end":"2026-03-19T21:50:43.483929Z","steps":["trace[2031248802] 'process raft request' (duration: 554.785075ms)","trace[2031248802] 'compare' (duration: 60.920087ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T21:50:43.484153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.995005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-19T21:50:43.484146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:50:42.868001Z","time spent":"616.020861ms","remote":"127.0.0.1:42256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T21:50:43.484197Z","caller":"traceutil/trace.go:171","msg":"trace[1470139257] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:531; }","duration":"454.101648ms","start":"2026-03-19T21:50:43.030084Z","end":"2026-03-19T21:50:43.484185Z","steps":["trace[1470139257] 'agreement among raft nodes before linearized reading' (duration: 453.943204ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:50:43.484167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.57292ms","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-03-19T21:50:43.484234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:50:43.030058Z","time spent":"454.168669ms","remote":"127.0.0.1:42256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-19T21:50:43.484281Z","caller":"traceutil/trace.go:171","msg":"trace[942278569] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:531; }","duration":"454.739225ms","start":"2026-03-19T21:50:43.029513Z","end":"2026-03-19T21:50:43.484252Z","steps":["trace[942278569] 'agreement among raft nodes before linearized reading' (duration: 454.448317ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:50:43.484345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:50:43.029492Z","time spent":"454.840068ms","remote":"127.0.0.1:42256","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-03-19T21:50:44.228557Z","caller":"traceutil/trace.go:171","msg":"trace[1562133356] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"129.280765ms","start":"2026-03-19T21:50:44.099255Z","end":"2026-03-19T21:50:44.228536Z","steps":["trace[1562133356] 'process raft request' (duration: 129.08328ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:50:44.233206Z","caller":"traceutil/trace.go:171","msg":"trace[830818014] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"119.396317ms","start":"2026-03-19T21:50:44.113799Z","end":"2026-03-19T21:50:44.233196Z","steps":["trace[830818014] 'process raft request' (duration: 119.274804ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:50:45.706302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.312536ms","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-03-19T21:50:45.706373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.814954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T21:50:45.706407Z","caller":"traceutil/trace.go:171","msg":"trace[440797652] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:547; }","duration":"211.430659ms","start":"2026-03-19T21:50:45.494958Z","end":"2026-03-19T21:50:45.706388Z","steps":["trace[440797652] 'range keys from in-memory index tree' (duration: 211.210673ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:50:45.706443Z","caller":"traceutil/trace.go:171","msg":"trace[1751887581] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:0; response_revision:547; }","duration":"491.911886ms","start":"2026-03-19T21:50:45.214513Z","end":"2026-03-19T21:50:45.706425Z","steps":["trace[1751887581] 'range keys from in-memory index tree' (duration: 491.716521ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:50:45.706492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:50:45.214505Z","time spent":"491.978147ms","remote":"127.0.0.1:42256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-19T21:50:45.706256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.107164ms","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-03-19T21:50:45.70663Z","caller":"traceutil/trace.go:171","msg":"trace[1029291188] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:547; }","duration":"217.514325ms","start":"2026-03-19T21:50:45.489061Z","end":"2026-03-19T21:50:45.706575Z","steps":["trace[1029291188] 'range keys from in-memory index tree' (duration: 216.920078ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:50:45.706798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.38711ms","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-03-19T21:50:45.706848Z","caller":"traceutil/trace.go:171","msg":"trace[758156175] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:547; }","duration":"207.458181ms","start":"2026-03-19T21:50:45.499376Z","end":"2026-03-19T21:50:45.706834Z","steps":["trace[758156175] 'range keys from in-memory index tree' (duration: 206.67191ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:50:46.685728Z","caller":"traceutil/trace.go:171","msg":"trace[2024139066] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"184.704012ms","start":"2026-03-19T21:50:46.501001Z","end":"2026-03-19T21:50:46.685705Z","steps":["trace[2024139066] 'process raft request' (duration: 184.536058ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:50:46.714893Z","caller":"traceutil/trace.go:171","msg":"trace[818128697] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"191.205399ms","start":"2026-03-19T21:50:46.523665Z","end":"2026-03-19T21:50:46.71487Z","steps":["trace[818128697] 'process raft request' (duration: 191.072246ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:50:49.142796Z","caller":"traceutil/trace.go:171","msg":"trace[1573738224] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"112.569391ms","start":"2026-03-19T21:50:49.030199Z","end":"2026-03-19T21:50:49.142768Z","steps":["trace[1573738224] 'process raft request' (duration: 111.218664ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:50:49.905936Z","caller":"traceutil/trace.go:171","msg":"trace[1472758955] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"552.784561ms","start":"2026-03-19T21:50:49.353122Z","end":"2026-03-19T21:50:49.905906Z","steps":["trace[1472758955] 'process raft request' (duration: 484.69554ms)","trace[1472758955] 'compare' (duration: 67.414253ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:50:49.906175Z","caller":"traceutil/trace.go:171","msg":"trace[685669001] linearizableReadLoop","detail":"{readStateIndex:633; appliedIndex:632; }","duration":"383.624951ms","start":"2026-03-19T21:50:49.522528Z","end":"2026-03-19T21:50:49.906153Z","steps":["trace[685669001] 'read index received' (duration: 315.215281ms)","trace[685669001] 'applied index is now lower than readState.Index' (duration: 68.40745ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T21:50:49.906141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:50:49.353095Z","time spent":"552.919195ms","remote":"127.0.0.1:42098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-19T21:50:49.906372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.832167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"warn","ts":"2026-03-19T21:50:49.906408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.89507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-03-19T21:50:49.90644Z","caller":"traceutil/trace.go:171","msg":"trace[904630121] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:613; }","duration":"183.977952ms","start":"2026-03-19T21:50:49.722451Z","end":"2026-03-19T21:50:49.906429Z","steps":["trace[904630121] 'agreement among raft nodes before linearized reading' (duration: 183.816088ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:50:49.906438Z","caller":"traceutil/trace.go:171","msg":"trace[469322169] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:613; }","duration":"383.94109ms","start":"2026-03-19T21:50:49.52248Z","end":"2026-03-19T21:50:49.906421Z","steps":["trace[469322169] 'agreement among raft nodes before linearized reading' (duration: 383.766916ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:50:49.906474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:50:49.522462Z","time spent":"384.006042ms","remote":"127.0.0.1:42098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":24267,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"warn","ts":"2026-03-19T21:50:49.906545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.44807ms","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-03-19T21:50:49.906546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.190503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-19T21:50:49.90659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.074763ms","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-03-19T21:50:49.906599Z","caller":"traceutil/trace.go:171","msg":"trace[1475991280] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:613; }","duration":"179.562383ms","start":"2026-03-19T21:50:49.727024Z","end":"2026-03-19T21:50:49.906586Z","steps":["trace[1475991280] 'agreement among raft nodes before linearized reading' (duration: 179.320396ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:50:49.906639Z","caller":"traceutil/trace.go:171","msg":"trace[381006681] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:613; }","duration":"179.298166ms","start":"2026-03-19T21:50:49.72732Z","end":"2026-03-19T21:50:49.906618Z","steps":["trace[381006681] 'agreement among raft nodes before linearized reading' (duration: 178.989027ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:50:49.906654Z","caller":"traceutil/trace.go:171","msg":"trace[1714143399] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:613; }","duration":"167.181676ms","start":"2026-03-19T21:50:49.739454Z","end":"2026-03-19T21:50:49.906635Z","steps":["trace[1714143399] 'agreement among raft nodes before linearized reading' (duration: 167.009851ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:50:49.90672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.685193ms","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-03-19T21:50:49.906817Z","caller":"traceutil/trace.go:171","msg":"trace[1643646124] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:613; }","duration":"180.798457ms","start":"2026-03-19T21:50:49.725966Z","end":"2026-03-19T21:50:49.906764Z","steps":["trace[1643646124] 'agreement among raft nodes before linearized reading' (duration: 180.56756ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:50:50.422133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.007491ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T21:50:50.42252Z","caller":"traceutil/trace.go:171","msg":"trace[989766544] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"509.188696ms","start":"2026-03-19T21:50:49.913306Z","end":"2026-03-19T21:50:50.422495Z","steps":["trace[989766544] 'process raft request' (duration: 508.917379ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:50:50.422541Z","caller":"traceutil/trace.go:171","msg":"trace[2043674766] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"509.265348ms","start":"2026-03-19T21:50:49.913251Z","end":"2026-03-19T21:50:50.422516Z","steps":["trace[2043674766] 'process raft request' (duration: 398.798234ms)","trace[2043674766] 'compare' (duration: 109.889838ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:50:50.422586Z","caller":"traceutil/trace.go:171","msg":"trace[375047453] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"406.216885ms","start":"2026-03-19T21:50:50.016356Z","end":"2026-03-19T21:50:50.422573Z","steps":["trace[375047453] 'process raft request' (duration: 406.039061ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:50:50.422633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:50:49.913243Z","time spent":"509.34428ms","remote":"127.0.0.1:42256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T21:50:50.422668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:50:50.016328Z","time spent":"406.302648ms","remote":"127.0.0.1:42098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188471,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T21:50:50.422858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:50:49.913303Z","time spent":"509.269398ms","remote":"127.0.0.1:42256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T21:50:50.422856Z","caller":"traceutil/trace.go:171","msg":"trace[2137115409] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:635; }","duration":"496.773128ms","start":"2026-03-19T21:50:49.926024Z","end":"2026-03-19T21:50:50.422797Z","steps":["trace[2137115409] 'read index received' (duration: 386.1366ms)","trace[2137115409] 'applied index is now lower than readState.Index' (duration: 110.631648ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:50:50.422856Z","caller":"traceutil/trace.go:171","msg":"trace[959135425] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"508.201159ms","start":"2026-03-19T21:50:49.914592Z","end":"2026-03-19T21:50:50.422793Z","steps":["trace[959135425] 'process raft request' (duration: 507.677965ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:50:50.423111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:50:49.914573Z","time spent":"508.398814ms","remote":"127.0.0.1:42256","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-03-19T21:50:50.423164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.021488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-03-19T21:50:50.423348Z","caller":"traceutil/trace.go:171","msg":"trace[1538312702] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:618; }","duration":"204.200143ms","start":"2026-03-19T21:50:50.21913Z","end":"2026-03-19T21:50:50.42333Z","steps":["trace[1538312702] 'agreement among raft nodes before linearized reading' (duration: 203.975547ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:50:50.423218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.161612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188252"} {"level":"info","ts":"2026-03-19T21:50:50.423433Z","caller":"traceutil/trace.go:171","msg":"trace[1778696489] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:618; }","duration":"300.414209ms","start":"2026-03-19T21:50:50.123Z","end":"2026-03-19T21:50:50.423414Z","steps":["trace[1778696489] 'agreement among raft nodes before linearized reading' (duration: 300.097171ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:50:50.423247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.19389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"warn","ts":"2026-03-19T21:50:50.423491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:50:50.122979Z","time spent":"300.497161ms","remote":"127.0.0.1:42098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":1,"response size":188275,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-03-19T21:50:50.423503Z","caller":"traceutil/trace.go:171","msg":"trace[765571884] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:618; }","duration":"497.481257ms","start":"2026-03-19T21:50:49.925996Z","end":"2026-03-19T21:50:50.423478Z","steps":["trace[765571884] 'agreement among raft nodes before linearized reading' (duration: 497.088346ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:50:50.423566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:50:49.925987Z","time spent":"497.55961ms","remote":"127.0.0.1:42098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4356,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"warn","ts":"2026-03-19T21:50:50.423295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.434913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-bzrh7\" ","response":"range_response_count:1 size:18512"} {"level":"info","ts":"2026-03-19T21:50:50.423775Z","caller":"traceutil/trace.go:171","msg":"trace[1912702471] range","detail":"{range_begin:/registry/pods/kube-system/cilium-bzrh7; range_end:; response_count:1; response_revision:618; }","duration":"263.934757ms","start":"2026-03-19T21:50:50.159826Z","end":"2026-03-19T21:50:50.423761Z","steps":["trace[1912702471] 'agreement among raft nodes before linearized reading' (duration: 263.413773ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:50:50.829517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.639299ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T21:50:50.829669Z","caller":"traceutil/trace.go:171","msg":"trace[1941015675] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:639; }","duration":"373.876066ms","start":"2026-03-19T21:50:50.455766Z","end":"2026-03-19T21:50:50.829642Z","steps":["trace[1941015675] 'read index received' (duration: 206.120865ms)","trace[1941015675] 'applied index is now lower than readState.Index' (duration: 167.752191ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:50:50.829836Z","caller":"traceutil/trace.go:171","msg":"trace[1187040953] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"390.996942ms","start":"2026-03-19T21:50:50.43877Z","end":"2026-03-19T21:50:50.829767Z","steps":["trace[1187040953] 'process raft request' (duration: 222.969533ms)","trace[1187040953] 'compare' (duration: 167.391441ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T21:50:50.829955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:50:50.438737Z","time spent":"391.174697ms","remote":"127.0.0.1:42194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18694,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T21:50:50.830169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.38507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188486"} {"level":"info","ts":"2026-03-19T21:50:50.830251Z","caller":"traceutil/trace.go:171","msg":"trace[1215179923] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:619; }","duration":"374.514223ms","start":"2026-03-19T21:50:50.455716Z","end":"2026-03-19T21:50:50.83023Z","steps":["trace[1215179923] 'agreement among raft nodes before linearized reading' (duration: 374.183604ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:50:50.830293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:50:50.455696Z","time spent":"374.588276ms","remote":"127.0.0.1:42098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":1,"response size":188509,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-03-19T21:50:50.850139Z","caller":"traceutil/trace.go:171","msg":"trace[1765507732] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"354.817928ms","start":"2026-03-19T21:50:50.495288Z","end":"2026-03-19T21:50:50.850106Z","steps":["trace[1765507732] 'process raft request' (duration: 354.429917ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:50:50.850254Z","caller":"traceutil/trace.go:171","msg":"trace[1960367716] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"294.793816ms","start":"2026-03-19T21:50:50.55543Z","end":"2026-03-19T21:50:50.850224Z","steps":["trace[1960367716] 'process raft request' (duration: 294.611181ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:50:50.850367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:50:50.495271Z","time spent":"354.953062ms","remote":"127.0.0.1:42098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T21:50:51.037227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.96419ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T21:50:51.037367Z","caller":"traceutil/trace.go:171","msg":"trace[1930616008] linearizableReadLoop","detail":"{readStateIndex:643; appliedIndex:640; }","duration":"207.648736ms","start":"2026-03-19T21:50:50.829702Z","end":"2026-03-19T21:50:51.037351Z","steps":["trace[1930616008] 'read index received' (duration: 19.985633ms)","trace[1930616008] 'applied index is now lower than readState.Index' (duration: 187.662223ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:50:51.037475Z","caller":"traceutil/trace.go:171","msg":"trace[902976762] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"301.154659ms","start":"2026-03-19T21:50:50.736301Z","end":"2026-03-19T21:50:51.037455Z","steps":["trace[902976762] 'process raft request' (duration: 197.899892ms)","trace[902976762] 'compare' (duration: 102.833515ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T21:50:51.037538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:50:50.736275Z","time spent":"301.233741ms","remote":"127.0.0.1:42190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4514,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T21:50:51.037617Z","caller":"traceutil/trace.go:171","msg":"trace[792651865] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"183.093319ms","start":"2026-03-19T21:50:50.85451Z","end":"2026-03-19T21:50:51.037604Z","steps":["trace[792651865] 'process raft request' (duration: 182.809591ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:50:51.037633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.103875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-19T21:50:51.03768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.151453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T21:50:51.03768Z","caller":"traceutil/trace.go:171","msg":"trace[1867034154] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:622; }","duration":"398.186417ms","start":"2026-03-19T21:50:50.639479Z","end":"2026-03-19T21:50:51.037666Z","steps":["trace[1867034154] 'agreement among raft nodes before linearized reading' (duration: 398.043803ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:50:51.037719Z","caller":"traceutil/trace.go:171","msg":"trace[895000875] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:622; }","duration":"264.212115ms","start":"2026-03-19T21:50:50.773497Z","end":"2026-03-19T21:50:51.03771Z","steps":["trace[895000875] 'agreement among raft nodes before linearized reading' (duration: 264.04526ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:50:51.037725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:50:50.639458Z","time spent":"398.258109ms","remote":"127.0.0.1:35706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-03-19T21:50:51.037701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.689494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T21:50:51.037779Z","caller":"traceutil/trace.go:171","msg":"trace[521876243] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:0; response_revision:623; }","duration":"397.809657ms","start":"2026-03-19T21:50:50.639952Z","end":"2026-03-19T21:50:51.037762Z","steps":["trace[521876243] 'agreement among raft nodes before linearized reading' (duration: 397.622142ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:50:51.037833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:50:50.639941Z","time spent":"397.883759ms","remote":"127.0.0.1:35706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodes/instance\" limit:500 "} {"level":"warn","ts":"2026-03-19T21:50:51.037841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.370781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-19T21:50:51.037949Z","caller":"traceutil/trace.go:171","msg":"trace[348518109] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:623; }","duration":"142.546396ms","start":"2026-03-19T21:50:50.895381Z","end":"2026-03-19T21:50:51.037927Z","steps":["trace[348518109] 'agreement among raft nodes before linearized reading' (duration: 142.280889ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:50:51.037891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.542556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T21:50:51.038055Z","caller":"traceutil/trace.go:171","msg":"trace[518086407] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:623; }","duration":"398.733342ms","start":"2026-03-19T21:50:50.639306Z","end":"2026-03-19T21:50:51.038039Z","steps":["trace[518086407] 'agreement among raft nodes before linearized reading' (duration: 398.549947ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:50:51.038096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:50:50.63929Z","time spent":"398.794084ms","remote":"127.0.0.1:35706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 "} {"level":"info","ts":"2026-03-19T21:50:51.188831Z","caller":"traceutil/trace.go:171","msg":"trace[387611917] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"140.464529ms","start":"2026-03-19T21:50:51.048341Z","end":"2026-03-19T21:50:51.188806Z","steps":["trace[387611917] 'process raft request' (duration: 131.223288ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:50:51.335526Z","caller":"traceutil/trace.go:171","msg":"trace[1767158875] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"141.804405ms","start":"2026-03-19T21:50:51.193692Z","end":"2026-03-19T21:50:51.335496Z","steps":["trace[1767158875] 'process raft request' (duration: 141.496077ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:50:51.33562Z","caller":"traceutil/trace.go:171","msg":"trace[412611822] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"141.58718ms","start":"2026-03-19T21:50:51.193934Z","end":"2026-03-19T21:50:51.335521Z","steps":["trace[412611822] 'process raft request' (duration: 141.474677ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:50:51.391121Z","caller":"traceutil/trace.go:171","msg":"trace[127327282] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"142.881585ms","start":"2026-03-19T21:50:51.248218Z","end":"2026-03-19T21:50:51.391099Z","steps":["trace[127327282] 'process raft request' (duration: 142.770782ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:51:04.400628Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.27977103s","expected-duration":"1s"} {"level":"info","ts":"2026-03-19T21:51:04.40113Z","caller":"traceutil/trace.go:171","msg":"trace[1662136449] linearizableReadLoop","detail":"{readStateIndex:744; appliedIndex:743; }","duration":"414.869292ms","start":"2026-03-19T21:51:03.986236Z","end":"2026-03-19T21:51:04.401105Z","steps":["trace[1662136449] 'read index received' (duration: 414.515592ms)","trace[1662136449] 'applied index is now lower than readState.Index' (duration: 352.88µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:51:04.401259Z","caller":"traceutil/trace.go:171","msg":"trace[1914869848] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"1.280460688s","start":"2026-03-19T21:51:03.120762Z","end":"2026-03-19T21:51:04.401222Z","steps":["trace[1914869848] 'process raft request' (duration: 1.280237712s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:51:04.401344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.095818ms","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-03-19T21:51:04.401367Z","caller":"traceutil/trace.go:171","msg":"trace[1084525679] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:718; }","duration":"415.1694ms","start":"2026-03-19T21:51:03.98619Z","end":"2026-03-19T21:51:04.40136Z","steps":["trace[1084525679] 'agreement among raft nodes before linearized reading' (duration: 415.025446ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:51:04.401389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:51:03.986173Z","time spent":"415.21131ms","remote":"127.0.0.1:42256","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-03-19T21:51:04.401441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:51:03.120724Z","time spent":"1.280625782s","remote":"127.0.0.1:42256","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-03-19T21:51:04.587061Z","caller":"traceutil/trace.go:171","msg":"trace[779005617] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"181.270399ms","start":"2026-03-19T21:51:04.40576Z","end":"2026-03-19T21:51:04.587031Z","steps":["trace[779005617] 'process raft request' (duration: 125.097592ms)","trace[779005617] 'compare' (duration: 55.90461ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T21:51:07.092001Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782675484906449907,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-19T21:51:07.303338Z","caller":"traceutil/trace.go:171","msg":"trace[184609135] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"893.434833ms","start":"2026-03-19T21:51:06.409884Z","end":"2026-03-19T21:51:07.303319Z","steps":["trace[184609135] 'process raft request' (duration: 893.32419ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:51:07.303347Z","caller":"traceutil/trace.go:171","msg":"trace[518885232] linearizableReadLoop","detail":"{readStateIndex:749; appliedIndex:749; }","duration":"711.855407ms","start":"2026-03-19T21:51:06.591462Z","end":"2026-03-19T21:51:07.303317Z","steps":["trace[518885232] 'read index received' (duration: 711.839156ms)","trace[518885232] 'applied index is now lower than readState.Index' (duration: 13.341µs)"],"step_count":2} {"level":"warn","ts":"2026-03-19T21:51:07.303445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:51:06.409869Z","time spent":"893.516426ms","remote":"127.0.0.1:42256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T21:51:07.303657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"712.172975ms","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-03-19T21:51:07.303715Z","caller":"traceutil/trace.go:171","msg":"trace[1938459930] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:723; }","duration":"712.273858ms","start":"2026-03-19T21:51:06.591422Z","end":"2026-03-19T21:51:07.303696Z","steps":["trace[1938459930] 'agreement among raft nodes before linearized reading' (duration: 712.059192ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:51:07.30376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:51:06.591408Z","time spent":"712.341761ms","remote":"127.0.0.1:42256","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":"info","ts":"2026-03-19T21:51:07.414174Z","caller":"traceutil/trace.go:171","msg":"trace[1643554679] linearizableReadLoop","detail":"{readStateIndex:750; appliedIndex:749; }","duration":"110.726211ms","start":"2026-03-19T21:51:07.30343Z","end":"2026-03-19T21:51:07.414157Z","steps":["trace[1643554679] 'read index received' (duration: 27.9565ms)","trace[1643554679] 'applied index is now lower than readState.Index' (duration: 82.768831ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:51:07.414209Z","caller":"traceutil/trace.go:171","msg":"trace[902560269] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"188.016042ms","start":"2026-03-19T21:51:07.226155Z","end":"2026-03-19T21:51:07.414171Z","steps":["trace[902560269] 'process raft request' (duration: 105.160699ms)","trace[902560269] 'compare' (duration: 82.698439ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T21:51:07.414441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"735.693535ms","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-03-19T21:51:07.414426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"735.611582ms","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-03-19T21:51:07.414479Z","caller":"traceutil/trace.go:171","msg":"trace[1733790065] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:725; }","duration":"735.796387ms","start":"2026-03-19T21:51:06.678672Z","end":"2026-03-19T21:51:07.414468Z","steps":["trace[1733790065] 'agreement among raft nodes before linearized reading' (duration: 735.574761ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:51:07.414494Z","caller":"traceutil/trace.go:171","msg":"trace[590827946] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:725; }","duration":"735.743246ms","start":"2026-03-19T21:51:06.678735Z","end":"2026-03-19T21:51:07.414478Z","steps":["trace[590827946] 'agreement among raft nodes before linearized reading' (duration: 735.50767ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:51:07.414504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:51:06.678649Z","time spent":"735.849979ms","remote":"127.0.0.1:42256","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-03-19T21:51:07.414525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:51:06.678702Z","time spent":"735.816879ms","remote":"127.0.0.1:42256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-19T21:51:07.414531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.058443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-19T21:51:07.414611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"765.175397ms","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-03-19T21:51:07.414612Z","caller":"traceutil/trace.go:171","msg":"trace[662019006] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:725; }","duration":"719.206957ms","start":"2026-03-19T21:51:06.695387Z","end":"2026-03-19T21:51:07.414594Z","steps":["trace[662019006] 'agreement among raft nodes before linearized reading' (duration: 718.832386ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:51:07.414633Z","caller":"traceutil/trace.go:171","msg":"trace[1682435525] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:725; }","duration":"765.259699ms","start":"2026-03-19T21:51:06.649368Z","end":"2026-03-19T21:51:07.414627Z","steps":["trace[1682435525] 'agreement among raft nodes before linearized reading' (duration: 764.864928ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:51:07.41465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:51:06.649345Z","time spent":"765.30113ms","remote":"127.0.0.1:42256","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-03-19T21:51:07.414656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:51:06.695363Z","time spent":"719.281359ms","remote":"127.0.0.1:42148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":4,"response size":30,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-03-19T21:51:07.414989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.038477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.62\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-19T21:51:07.415017Z","caller":"traceutil/trace.go:171","msg":"trace[1113800839] range","detail":"{range_begin:/registry/masterleases/162.253.55.62; range_end:; response_count:1; response_revision:725; }","duration":"240.094019ms","start":"2026-03-19T21:51:07.174915Z","end":"2026-03-19T21:51:07.415009Z","steps":["trace[1113800839] 'agreement among raft nodes before linearized reading' (duration: 240.026277ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:51:07.415974Z","caller":"traceutil/trace.go:171","msg":"trace[1448658682] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"109.140898ms","start":"2026-03-19T21:51:07.306817Z","end":"2026-03-19T21:51:07.415958Z","steps":["trace[1448658682] 'process raft request' (duration: 107.311728ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:51:07.416008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.189542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T21:51:07.416084Z","caller":"traceutil/trace.go:171","msg":"trace[206852267] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:725; }","duration":"642.326387ms","start":"2026-03-19T21:51:06.773739Z","end":"2026-03-19T21:51:07.416065Z","steps":["trace[206852267] 'agreement among raft nodes before linearized reading' (duration: 642.197373ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:51:07.416133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:51:06.773714Z","time spent":"642.410348ms","remote":"127.0.0.1:42024","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-03-19T21:51:07.735233Z","caller":"traceutil/trace.go:171","msg":"trace[424886569] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"313.115033ms","start":"2026-03-19T21:51:07.422096Z","end":"2026-03-19T21:51:07.735211Z","steps":["trace[424886569] 'process raft request' (duration: 312.946039ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:51:07.735388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:51:07.422081Z","time spent":"313.225407ms","remote":"127.0.0.1:42074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T21:51:10.199446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.545597ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T21:51:10.199529Z","caller":"traceutil/trace.go:171","msg":"trace[467538072] linearizableReadLoop","detail":"{readStateIndex:758; appliedIndex:757; }","duration":"693.601756ms","start":"2026-03-19T21:51:09.505915Z","end":"2026-03-19T21:51:10.199516Z","steps":["trace[467538072] 'read index received' (duration: 109.830623ms)","trace[467538072] 'applied index is now lower than readState.Index' (duration: 583.770083ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:51:10.199551Z","caller":"traceutil/trace.go:171","msg":"trace[1789956504] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"777.330265ms","start":"2026-03-19T21:51:09.422191Z","end":"2026-03-19T21:51:10.199521Z","steps":["trace[1789956504] 'process raft request' (duration: 193.644294ms)","trace[1789956504] 'compare' (duration: 583.080964ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T21:51:10.199675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"693.7422ms","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-03-19T21:51:10.19971Z","caller":"traceutil/trace.go:171","msg":"trace[1009758711] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:731; }","duration":"693.816733ms","start":"2026-03-19T21:51:09.505882Z","end":"2026-03-19T21:51:10.199699Z","steps":["trace[1009758711] 'agreement among raft nodes before linearized reading' (duration: 693.683779ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:51:10.199708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:51:09.422164Z","time spent":"777.458789ms","remote":"127.0.0.1:42256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T21:51:10.199735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:51:09.505868Z","time spent":"693.860093ms","remote":"127.0.0.1:42256","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-03-19T21:51:10.607249Z","caller":"traceutil/trace.go:171","msg":"trace[119302034] linearizableReadLoop","detail":"{readStateIndex:759; appliedIndex:758; }","duration":"407.678014ms","start":"2026-03-19T21:51:10.199545Z","end":"2026-03-19T21:51:10.607223Z","steps":["trace[119302034] 'read index received' (duration: 332.938848ms)","trace[119302034] 'applied index is now lower than readState.Index' (duration: 74.738186ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:51:10.60735Z","caller":"traceutil/trace.go:171","msg":"trace[1874497667] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"407.933701ms","start":"2026-03-19T21:51:10.199394Z","end":"2026-03-19T21:51:10.607328Z","steps":["trace[1874497667] 'process raft request' (duration: 333.109053ms)","trace[1874497667] 'compare' (duration: 74.588912ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T21:51:10.607395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"833.749675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T21:51:10.607436Z","caller":"traceutil/trace.go:171","msg":"trace[257326187] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:732; }","duration":"833.839037ms","start":"2026-03-19T21:51:09.773588Z","end":"2026-03-19T21:51:10.607427Z","steps":["trace[257326187] 'agreement among raft nodes before linearized reading' (duration: 833.728924ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:51:10.607453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:51:10.199378Z","time spent":"408.025635ms","remote":"127.0.0.1:42190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5169,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T21:51:10.607464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:51:09.77357Z","time spent":"833.887799ms","remote":"127.0.0.1:42028","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-03-19T21:51:10.607488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.101421984s","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-03-19T21:51:10.60753Z","caller":"traceutil/trace.go:171","msg":"trace[1884208644] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:732; }","duration":"1.101484686s","start":"2026-03-19T21:51:09.506035Z","end":"2026-03-19T21:51:10.607519Z","steps":["trace[1884208644] 'agreement among raft nodes before linearized reading' (duration: 1.101294331s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:51:10.607529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.101283701s","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-03-19T21:51:10.607571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:51:09.505965Z","time spent":"1.101596869s","remote":"127.0.0.1:42256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-19T21:51:10.607612Z","caller":"traceutil/trace.go:171","msg":"trace[1089451957] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:732; }","duration":"1.101395133s","start":"2026-03-19T21:51:09.5062Z","end":"2026-03-19T21:51:10.607595Z","steps":["trace[1089451957] 'agreement among raft nodes before linearized reading' (duration: 1.101184307s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:51:10.607657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:51:09.506193Z","time spent":"1.101455996s","remote":"127.0.0.1:42256","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-03-19T21:51:10.693001Z","caller":"traceutil/trace.go:171","msg":"trace[1735990912] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"490.183498ms","start":"2026-03-19T21:51:10.202796Z","end":"2026-03-19T21:51:10.69298Z","steps":["trace[1735990912] 'process raft request' (duration: 489.993762ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:51:10.693112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:51:10.202778Z","time spent":"490.27487ms","remote":"127.0.0.1:42256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T21:51:10.693207Z","caller":"traceutil/trace.go:171","msg":"trace[490904097] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"265.151555ms","start":"2026-03-19T21:51:10.428049Z","end":"2026-03-19T21:51:10.6932Z","steps":["trace[490904097] 'process raft request' (duration: 264.867517ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:51:29.05853Z","caller":"traceutil/trace.go:171","msg":"trace[164328162] linearizableReadLoop","detail":"{readStateIndex:823; appliedIndex:822; }","duration":"156.710203ms","start":"2026-03-19T21:51:28.901789Z","end":"2026-03-19T21:51:29.058499Z","steps":["trace[164328162] 'read index received' (duration: 102.461877ms)","trace[164328162] 'applied index is now lower than readState.Index' (duration: 54.246916ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:51:29.058587Z","caller":"traceutil/trace.go:171","msg":"trace[919021560] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"163.248675ms","start":"2026-03-19T21:51:28.895314Z","end":"2026-03-19T21:51:29.058562Z","steps":["trace[919021560] 'process raft request' (duration: 108.979888ms)","trace[919021560] 'compare' (duration: 54.094212ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T21:51:29.058679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.860338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T21:51:29.058719Z","caller":"traceutil/trace.go:171","msg":"trace[870331101] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:791; }","duration":"156.92706ms","start":"2026-03-19T21:51:28.90178Z","end":"2026-03-19T21:51:29.058707Z","steps":["trace[870331101] 'agreement among raft nodes before linearized reading' (duration: 156.835987ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:51:31.502317Z","caller":"traceutil/trace.go:171","msg":"trace[1245866111] linearizableReadLoop","detail":"{readStateIndex:829; appliedIndex:828; }","duration":"357.720343ms","start":"2026-03-19T21:51:31.144569Z","end":"2026-03-19T21:51:31.502289Z","steps":["trace[1245866111] 'read index received' (duration: 332.102174ms)","trace[1245866111] 'applied index is now lower than readState.Index' (duration: 25.616729ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:51:31.502487Z","caller":"traceutil/trace.go:171","msg":"trace[2096454713] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"424.832506ms","start":"2026-03-19T21:51:31.077617Z","end":"2026-03-19T21:51:31.50245Z","steps":["trace[2096454713] 'process raft request' (duration: 399.032741ms)","trace[2096454713] 'compare' (duration: 25.494346ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T21:51:31.502639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:51:31.077598Z","time spent":"424.966379ms","remote":"127.0.0.1:42256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T21:51:31.50267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.46803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-19T21:51:31.502669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.997861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-19T21:51:31.502784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.862671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T21:51:31.502819Z","caller":"traceutil/trace.go:171","msg":"trace[594205279] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:797; }","duration":"358.271459ms","start":"2026-03-19T21:51:31.144524Z","end":"2026-03-19T21:51:31.502795Z","steps":["trace[594205279] 'agreement among raft nodes before linearized reading' (duration: 357.95465ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:51:31.502874Z","caller":"traceutil/trace.go:171","msg":"trace[427952049] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:797; }","duration":"112.982554ms","start":"2026-03-19T21:51:31.389858Z","end":"2026-03-19T21:51:31.50284Z","steps":["trace[427952049] 'agreement among raft nodes before linearized reading' (duration: 112.860111ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:51:31.502898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:51:31.14451Z","time spent":"358.373312ms","remote":"127.0.0.1:42356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"info","ts":"2026-03-19T21:51:31.502937Z","caller":"traceutil/trace.go:171","msg":"trace[644610548] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:797; }","duration":"152.626064ms","start":"2026-03-19T21:51:31.350145Z","end":"2026-03-19T21:51:31.502771Z","steps":["trace[644610548] 'agreement among raft nodes before linearized reading' (duration: 152.46817ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:51:33.552678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"908.989816ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-19T21:51:33.552785Z","caller":"traceutil/trace.go:171","msg":"trace[1648135550] linearizableReadLoop","detail":"{readStateIndex:831; appliedIndex:830; }","duration":"1.22298036s","start":"2026-03-19T21:51:32.329786Z","end":"2026-03-19T21:51:33.552766Z","steps":["trace[1648135550] 'read index received' (duration: 313.909902ms)","trace[1648135550] 'applied index is now lower than readState.Index' (duration: 909.069078ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T21:51:33.553014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.788037ms","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-03-19T21:51:33.553014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.520955ms","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-03-19T21:51:33.553015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.223230097s","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-03-19T21:51:33.55305Z","caller":"traceutil/trace.go:171","msg":"trace[1660266334] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:798; }","duration":"660.865259ms","start":"2026-03-19T21:51:32.892171Z","end":"2026-03-19T21:51:33.553036Z","steps":["trace[1660266334] 'agreement among raft nodes before linearized reading' (duration: 660.740886ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:51:33.553062Z","caller":"traceutil/trace.go:171","msg":"trace[1793461852] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:798; }","duration":"412.613059ms","start":"2026-03-19T21:51:33.140431Z","end":"2026-03-19T21:51:33.553044Z","steps":["trace[1793461852] 'agreement among raft nodes before linearized reading' (duration: 412.499745ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:51:33.553064Z","caller":"traceutil/trace.go:171","msg":"trace[846601060] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:798; }","duration":"1.223322681s","start":"2026-03-19T21:51:32.329729Z","end":"2026-03-19T21:51:33.553051Z","steps":["trace[846601060] 'agreement among raft nodes before linearized reading' (duration: 1.223131625s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:51:33.553082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:51:32.892137Z","time spent":"660.937792ms","remote":"127.0.0.1:42256","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-03-19T21:51:33.55309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:51:33.140403Z","time spent":"412.67996ms","remote":"127.0.0.1:42256","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-03-19T21:51:33.553099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:51:32.329708Z","time spent":"1.223381442s","remote":"127.0.0.1:42256","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-03-19T21:51:33.553143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.707307ms","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-03-19T21:51:33.553229Z","caller":"traceutil/trace.go:171","msg":"trace[138424548] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:798; }","duration":"617.801249ms","start":"2026-03-19T21:51:32.935407Z","end":"2026-03-19T21:51:33.553208Z","steps":["trace[138424548] 'agreement among raft nodes before linearized reading' (duration: 617.557062ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:51:33.553267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:51:32.935332Z","time spent":"617.926473ms","remote":"127.0.0.1:42256","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-03-19T21:51:33.722786Z","caller":"traceutil/trace.go:171","msg":"trace[1924995375] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"165.316926ms","start":"2026-03-19T21:51:33.557443Z","end":"2026-03-19T21:51:33.72276Z","steps":["trace[1924995375] 'process raft request' (duration: 165.106019ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:51:33.722951Z","caller":"traceutil/trace.go:171","msg":"trace[1510085801] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"165.44828ms","start":"2026-03-19T21:51:33.557483Z","end":"2026-03-19T21:51:33.722931Z","steps":["trace[1510085801] 'process raft request' (duration: 165.231003ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:51:33.723079Z","caller":"traceutil/trace.go:171","msg":"trace[1195231799] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"165.50672ms","start":"2026-03-19T21:51:33.557556Z","end":"2026-03-19T21:51:33.723063Z","steps":["trace[1195231799] 'process raft request' (duration: 165.315285ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:51:33.723421Z","caller":"traceutil/trace.go:171","msg":"trace[133586326] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"165.693077ms","start":"2026-03-19T21:51:33.557686Z","end":"2026-03-19T21:51:33.723379Z","steps":["trace[133586326] 'process raft request' (duration: 165.330826ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:51:35.979792Z","caller":"traceutil/trace.go:171","msg":"trace[1130351812] linearizableReadLoop","detail":"{readStateIndex:837; appliedIndex:836; }","duration":"252.491215ms","start":"2026-03-19T21:51:35.727272Z","end":"2026-03-19T21:51:35.979764Z","steps":["trace[1130351812] 'read index received' (duration: 252.32478ms)","trace[1130351812] 'applied index is now lower than readState.Index' (duration: 165.195µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:51:35.979834Z","caller":"traceutil/trace.go:171","msg":"trace[1564848360] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"358.96773ms","start":"2026-03-19T21:51:35.620832Z","end":"2026-03-19T21:51:35.9798Z","steps":["trace[1564848360] 'process raft request' (duration: 358.767074ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:51:35.980028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:51:35.620815Z","time spent":"359.103033ms","remote":"127.0.0.1:42256","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-03-19T21:51:35.980043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.789139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-19T21:51:35.98006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.568568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-19T21:51:35.980052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.764924ms","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-03-19T21:51:35.980105Z","caller":"traceutil/trace.go:171","msg":"trace[1356601449] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:804; }","duration":"205.915273ms","start":"2026-03-19T21:51:35.774171Z","end":"2026-03-19T21:51:35.980086Z","steps":["trace[1356601449] 'agreement among raft nodes before linearized reading' (duration: 205.80008ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:51:35.980125Z","caller":"traceutil/trace.go:171","msg":"trace[883358251] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:804; }","duration":"252.881947ms","start":"2026-03-19T21:51:35.727226Z","end":"2026-03-19T21:51:35.980108Z","steps":["trace[883358251] 'agreement among raft nodes before linearized reading' (duration: 252.668021ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:51:35.980184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.593809ms","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-03-19T21:51:35.980109Z","caller":"traceutil/trace.go:171","msg":"trace[1805427520] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:804; }","duration":"251.64066ms","start":"2026-03-19T21:51:35.728454Z","end":"2026-03-19T21:51:35.980095Z","steps":["trace[1805427520] 'agreement among raft nodes before linearized reading' (duration: 251.518537ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:51:35.980209Z","caller":"traceutil/trace.go:171","msg":"trace[398293398] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:804; }","duration":"251.634251ms","start":"2026-03-19T21:51:35.728567Z","end":"2026-03-19T21:51:35.980201Z","steps":["trace[398293398] 'agreement among raft nodes before linearized reading' (duration: 251.563088ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:51:35.980344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.354792ms","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-03-19T21:51:35.980454Z","caller":"traceutil/trace.go:171","msg":"trace[273121207] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:804; }","duration":"251.491866ms","start":"2026-03-19T21:51:35.72894Z","end":"2026-03-19T21:51:35.980432Z","steps":["trace[273121207] 'agreement among raft nodes before linearized reading' (duration: 251.170957ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:51:35.980964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.737771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T21:51:35.981021Z","caller":"traceutil/trace.go:171","msg":"trace[1876791034] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:804; }","duration":"200.847284ms","start":"2026-03-19T21:51:35.780159Z","end":"2026-03-19T21:51:35.981007Z","steps":["trace[1876791034] 'agreement among raft nodes before linearized reading' (duration: 200.71322ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:51:37.274995Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782675484906450142,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-19T21:51:37.394916Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.409279151s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-19T21:51:37.502027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.32722ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T21:51:37.502266Z","caller":"traceutil/trace.go:171","msg":"trace[200771392] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"1.516605569s","start":"2026-03-19T21:51:35.985639Z","end":"2026-03-19T21:51:37.502244Z","steps":["trace[200771392] 'process raft request' (duration: 1.516478576s)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:51:37.502248Z","caller":"traceutil/trace.go:171","msg":"trace[840177531] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"1.516676132s","start":"2026-03-19T21:51:35.98554Z","end":"2026-03-19T21:51:37.502216Z","steps":["trace[840177531] 'process raft request' (duration: 1.409665772s)","trace[840177531] 'compare' (duration: 105.64639ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:51:37.5025Z","caller":"traceutil/trace.go:171","msg":"trace[625744811] linearizableReadLoop","detail":"{readStateIndex:841; appliedIndex:837; }","duration":"727.811568ms","start":"2026-03-19T21:51:36.774672Z","end":"2026-03-19T21:51:37.502484Z","steps":["trace[625744811] 'read index received' (duration: 620.525061ms)","trace[625744811] 'applied index is now lower than readState.Index' (duration: 107.285597ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:51:37.502509Z","caller":"traceutil/trace.go:171","msg":"trace[1147959643] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"1.516755344s","start":"2026-03-19T21:51:35.985724Z","end":"2026-03-19T21:51:37.50248Z","steps":["trace[1147959643] 'process raft request' (duration: 1.516482156s)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:51:37.502611Z","caller":"traceutil/trace.go:171","msg":"trace[1248789169] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"1.516769075s","start":"2026-03-19T21:51:35.985832Z","end":"2026-03-19T21:51:37.502601Z","steps":["trace[1248789169] 'process raft request' (duration: 1.516575109s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:51:37.502664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:51:35.985717Z","time spent":"1.516871738s","remote":"127.0.0.1:42256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T21:51:37.502683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:51:35.985824Z","time spent":"1.516815866s","remote":"127.0.0.1:42256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T21:51:37.502568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:51:35.985522Z","time spent":"1.51695463s","remote":"127.0.0.1:42256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T21:51:37.502785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.374127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.62\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-03-19T21:51:37.502816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"728.150178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T21:51:37.502826Z","caller":"traceutil/trace.go:171","msg":"trace[196587383] range","detail":"{range_begin:/registry/masterleases/162.253.55.62; range_end:; response_count:1; response_revision:808; }","duration":"326.466299ms","start":"2026-03-19T21:51:37.176347Z","end":"2026-03-19T21:51:37.502814Z","steps":["trace[196587383] 'agreement among raft nodes before linearized reading' (duration: 326.326895ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:51:37.50285Z","caller":"traceutil/trace.go:171","msg":"trace[1959462287] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:808; }","duration":"728.22153ms","start":"2026-03-19T21:51:36.774619Z","end":"2026-03-19T21:51:37.502841Z","steps":["trace[1959462287] 'agreement among raft nodes before linearized reading' (duration: 728.169458ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:51:37.502884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:51:36.774596Z","time spent":"728.279102ms","remote":"127.0.0.1:42028","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-03-19T21:51:37.502886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:51:37.176317Z","time spent":"326.555742ms","remote":"127.0.0.1:42074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/162.253.55.62\" "} {"level":"warn","ts":"2026-03-19T21:51:37.502377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:51:35.985614Z","time spent":"1.516701232s","remote":"127.0.0.1:42256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T21:51:37.734602Z","caller":"traceutil/trace.go:171","msg":"trace[305758293] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"180.573582ms","start":"2026-03-19T21:51:37.553994Z","end":"2026-03-19T21:51:37.734568Z","steps":["trace[305758293] 'process raft request' (duration: 139.348476ms)","trace[305758293] 'compare' (duration: 40.937058ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:51:47.701148Z","caller":"traceutil/trace.go:171","msg":"trace[74623336] transaction","detail":"{read_only:false; response_revision:871; number_of_response:1; }","duration":"106.854475ms","start":"2026-03-19T21:51:47.594248Z","end":"2026-03-19T21:51:47.701103Z","steps":["trace[74623336] 'process raft request' (duration: 100.370195ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:51:47.701781Z","caller":"traceutil/trace.go:171","msg":"trace[1439192040] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"106.729702ms","start":"2026-03-19T21:51:47.595009Z","end":"2026-03-19T21:51:47.701739Z","steps":["trace[1439192040] 'process raft request' (duration: 105.689161ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:51:47.705306Z","caller":"traceutil/trace.go:171","msg":"trace[1494063176] transaction","detail":"{read_only:false; response_revision:873; number_of_response:1; }","duration":"105.519296ms","start":"2026-03-19T21:51:47.599757Z","end":"2026-03-19T21:51:47.705277Z","steps":["trace[1494063176] 'process raft request' (duration: 101.831238ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:51:47.706884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.384474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:6 size:363682"} {"level":"info","ts":"2026-03-19T21:51:47.70702Z","caller":"traceutil/trace.go:171","msg":"trace[1486250284] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:873; }","duration":"116.543409ms","start":"2026-03-19T21:51:47.590428Z","end":"2026-03-19T21:51:47.706972Z","steps":["trace[1486250284] 'agreement among raft nodes before linearized reading' (duration: 114.299083ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:51:47.744257Z","caller":"traceutil/trace.go:171","msg":"trace[1993447793] transaction","detail":"{read_only:false; response_revision:874; number_of_response:1; }","duration":"120.082982ms","start":"2026-03-19T21:51:47.624132Z","end":"2026-03-19T21:51:47.744214Z","steps":["trace[1993447793] 'process raft request' (duration: 119.855075ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:51:47.744305Z","caller":"traceutil/trace.go:171","msg":"trace[1762242356] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"119.346601ms","start":"2026-03-19T21:51:47.624932Z","end":"2026-03-19T21:51:47.744278Z","steps":["trace[1762242356] 'process raft request' (duration: 119.249918ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:51:47.744364Z","caller":"traceutil/trace.go:171","msg":"trace[1649329309] transaction","detail":"{read_only:false; response_revision:875; number_of_response:1; }","duration":"120.072462ms","start":"2026-03-19T21:51:47.624253Z","end":"2026-03-19T21:51:47.744325Z","steps":["trace[1649329309] 'process raft request' (duration: 119.873416ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:51:47.744666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.918413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/ceph-csi-rbd-provisioner\" ","response":"range_response_count:1 size:10872"} {"level":"info","ts":"2026-03-19T21:51:47.744717Z","caller":"traceutil/trace.go:171","msg":"trace[432449558] range","detail":"{range_begin:/registry/deployments/kube-system/ceph-csi-rbd-provisioner; range_end:; response_count:1; response_revision:876; }","duration":"113.018005ms","start":"2026-03-19T21:51:47.631683Z","end":"2026-03-19T21:51:47.744701Z","steps":["trace[432449558] 'agreement among raft nodes before linearized reading' (duration: 112.788398ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:51:47.907898Z","caller":"traceutil/trace.go:171","msg":"trace[1418273826] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"129.771255ms","start":"2026-03-19T21:51:47.778082Z","end":"2026-03-19T21:51:47.907853Z","steps":["trace[1418273826] 'process raft request' (duration: 125.015156ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:51:47.942882Z","caller":"traceutil/trace.go:171","msg":"trace[2048428505] linearizableReadLoop","detail":"{readStateIndex:922; appliedIndex:920; }","duration":"129.91449ms","start":"2026-03-19T21:51:47.812929Z","end":"2026-03-19T21:51:47.942844Z","steps":["trace[2048428505] 'read index received' (duration: 90.295861ms)","trace[2048428505] 'applied index is now lower than readState.Index' (duration: 39.617289ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:51:47.943045Z","caller":"traceutil/trace.go:171","msg":"trace[1781765399] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"159.243758ms","start":"2026-03-19T21:51:47.783705Z","end":"2026-03-19T21:51:47.942949Z","steps":["trace[1781765399] 'process raft request' (duration: 158.97151ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:51:47.943389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.430545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:6 size:363682"} {"level":"info","ts":"2026-03-19T21:51:47.943478Z","caller":"traceutil/trace.go:171","msg":"trace[757417190] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:884; }","duration":"130.565479ms","start":"2026-03-19T21:51:47.812868Z","end":"2026-03-19T21:51:47.943433Z","steps":["trace[757417190] 'agreement among raft nodes before linearized reading' (duration: 130.24654ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:51:49.89563Z","caller":"traceutil/trace.go:171","msg":"trace[199253779] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"123.521433ms","start":"2026-03-19T21:51:49.772082Z","end":"2026-03-19T21:51:49.895604Z","steps":["trace[199253779] 'process raft request' (duration: 29.04035ms)","trace[199253779] 'compare' (duration: 94.328969ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:51:49.916696Z","caller":"traceutil/trace.go:171","msg":"trace[1642103786] linearizableReadLoop","detail":"{readStateIndex:931; appliedIndex:929; }","duration":"142.122137ms","start":"2026-03-19T21:51:49.774531Z","end":"2026-03-19T21:51:49.916653Z","steps":["trace[1642103786] 'read index received' (duration: 26.616088ms)","trace[1642103786] 'applied index is now lower than readState.Index' (duration: 115.504919ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T21:51:49.916852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.294512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T21:51:49.916888Z","caller":"traceutil/trace.go:171","msg":"trace[579849389] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:894; }","duration":"142.363124ms","start":"2026-03-19T21:51:49.774515Z","end":"2026-03-19T21:51:49.916878Z","steps":["trace[579849389] 'agreement among raft nodes before linearized reading' (duration: 142.263461ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:51:49.917862Z","caller":"traceutil/trace.go:171","msg":"trace[542581106] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"144.555438ms","start":"2026-03-19T21:51:49.773298Z","end":"2026-03-19T21:51:49.917854Z","steps":["trace[542581106] 'process raft request' (duration: 143.208179ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:51:49.91797Z","caller":"traceutil/trace.go:171","msg":"trace[1127381856] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"141.986423ms","start":"2026-03-19T21:51:49.775978Z","end":"2026-03-19T21:51:49.917964Z","steps":["trace[1127381856] 'process raft request' (duration: 140.639314ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:51:50.411865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.223015ms","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-03-19T21:51:50.412202Z","caller":"traceutil/trace.go:171","msg":"trace[1428449358] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:894; }","duration":"308.595796ms","start":"2026-03-19T21:51:50.103569Z","end":"2026-03-19T21:51:50.412165Z","steps":["trace[1428449358] 'range keys from in-memory index tree' (duration: 308.016529ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:51:50.41231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:51:50.103555Z","time spent":"308.699529ms","remote":"127.0.0.1:42256","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-03-19T21:51:50.5515Z","caller":"traceutil/trace.go:171","msg":"trace[378769702] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"134.461513ms","start":"2026-03-19T21:51:50.41701Z","end":"2026-03-19T21:51:50.551471Z","steps":["trace[378769702] 'process raft request' (duration: 133.911177ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:51:51.434902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.763858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T21:51:51.434993Z","caller":"traceutil/trace.go:171","msg":"trace[2072650362] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:895; }","duration":"660.912642ms","start":"2026-03-19T21:51:50.774059Z","end":"2026-03-19T21:51:51.434972Z","steps":["trace[2072650362] 'range keys from in-memory index tree' (duration: 660.680045ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:51:51.435035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:51:50.774039Z","time spent":"660.986843ms","remote":"127.0.0.1:42028","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-03-19T21:51:52.792068Z","caller":"traceutil/trace.go:171","msg":"trace[82402479] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"867.306088ms","start":"2026-03-19T21:51:51.924734Z","end":"2026-03-19T21:51:52.79204Z","steps":["trace[82402479] 'process raft request' (duration: 867.145073ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:51:52.792248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:51:51.924711Z","time spent":"867.452982ms","remote":"127.0.0.1:42256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T21:51:53.938218Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.146337148s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-19T21:51:54.773252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999892764s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-03-19T21:51:54.773396Z","caller":"traceutil/trace.go:171","msg":"trace[523911365] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00008615s","start":"2026-03-19T21:51:52.773288Z","end":"2026-03-19T21:51:54.773374Z","steps":["trace[523911365] 'agreement among raft nodes before linearized reading' (duration: 1.999905825s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:51:54.773457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:51:52.773269Z","time spent":"2.000168142s","remote":"127.0.0.1:42028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-19T21:51:54.909485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"970.931389ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T21:51:54.909748Z","caller":"traceutil/trace.go:171","msg":"trace[132711884] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"2.984462011s","start":"2026-03-19T21:51:51.925241Z","end":"2026-03-19T21:51:54.909703Z","steps":["trace[132711884] 'process raft request' (duration: 2.013224684s)","trace[132711884] 'compare' (duration: 970.808765ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T21:51:54.909941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:51:51.925232Z","time spent":"2.984607315s","remote":"127.0.0.1:42256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T21:51:54.909762Z","caller":"traceutil/trace.go:171","msg":"trace[1184859252] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"2.834495745s","start":"2026-03-19T21:51:52.075241Z","end":"2026-03-19T21:51:54.909737Z","steps":["trace[1184859252] 'process raft request' (duration: 2.834363722s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:51:54.910247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:51:52.075213Z","time spent":"2.834952279s","remote":"127.0.0.1:42256","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-03-19T21:51:54.912565Z","caller":"traceutil/trace.go:171","msg":"trace[411185381] linearizableReadLoop","detail":"{readStateIndex:938; appliedIndex:935; }","duration":"2.501817604s","start":"2026-03-19T21:51:52.410727Z","end":"2026-03-19T21:51:54.912545Z","steps":["trace[411185381] 'read index received' (duration: 381.189039ms)","trace[411185381] 'applied index is now lower than readState.Index' (duration: 2.120627025s)"],"step_count":2} {"level":"warn","ts":"2026-03-19T21:51:54.912699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.50196004s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T21:51:54.912728Z","caller":"traceutil/trace.go:171","msg":"trace[1730208681] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:900; }","duration":"2.502033722s","start":"2026-03-19T21:51:52.410685Z","end":"2026-03-19T21:51:54.912719Z","steps":["trace[1730208681] 'agreement among raft nodes before linearized reading' (duration: 2.50197391s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:51:54.912758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:51:52.410665Z","time spent":"2.502083882s","remote":"127.0.0.1:42448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-03-19T21:51:54.912913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.358022139s","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-03-19T21:51:54.912956Z","caller":"traceutil/trace.go:171","msg":"trace[904968757] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:900; }","duration":"2.358091111s","start":"2026-03-19T21:51:52.554853Z","end":"2026-03-19T21:51:54.912944Z","steps":["trace[904968757] 'agreement among raft nodes before linearized reading' (duration: 2.357947687s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:51:54.912979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:51:52.554835Z","time spent":"2.358139222s","remote":"127.0.0.1:42256","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":"warn","ts":"2026-03-19T21:51:54.913011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.204748ms","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":"warn","ts":"2026-03-19T21:51:54.913009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.050401754s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-19T21:51:54.9129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.814997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-19T21:51:54.913086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.705886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T21:51:54.913085Z","caller":"traceutil/trace.go:171","msg":"trace[2117913219] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:900; }","duration":"556.046704ms","start":"2026-03-19T21:51:54.357027Z","end":"2026-03-19T21:51:54.913073Z","steps":["trace[2117913219] 'agreement among raft nodes before linearized reading' (duration: 555.808427ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:51:54.913122Z","caller":"traceutil/trace.go:171","msg":"trace[148825146] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:900; }","duration":"138.803249ms","start":"2026-03-19T21:51:54.774311Z","end":"2026-03-19T21:51:54.913114Z","steps":["trace[148825146] 'agreement among raft nodes before linearized reading' (duration: 138.756548ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:51:54.91312Z","caller":"traceutil/trace.go:171","msg":"trace[160310484] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:900; }","duration":"1.050497496s","start":"2026-03-19T21:51:53.86255Z","end":"2026-03-19T21:51:54.913048Z","steps":["trace[160310484] 'agreement among raft nodes before linearized reading' (duration: 1.050319361s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:51:54.913132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:51:54.357007Z","time spent":"556.113316ms","remote":"127.0.0.1:42324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":14,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-03-19T21:51:54.913161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:51:53.862518Z","time spent":"1.05063412s","remote":"127.0.0.1:42256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-19T21:51:54.913187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.088139097s","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-03-19T21:51:54.913211Z","caller":"traceutil/trace.go:171","msg":"trace[610107696] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:900; }","duration":"1.088195989s","start":"2026-03-19T21:51:53.825007Z","end":"2026-03-19T21:51:54.913203Z","steps":["trace[610107696] 'agreement among raft nodes before linearized reading' (duration: 1.088153157s)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:51:54.913049Z","caller":"traceutil/trace.go:171","msg":"trace[298471427] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:900; }","duration":"117.26087ms","start":"2026-03-19T21:51:54.795775Z","end":"2026-03-19T21:51:54.913036Z","steps":["trace[298471427] 'agreement among raft nodes before linearized reading' (duration: 117.166637ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:51:54.913232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:51:53.824982Z","time spent":"1.08824169s","remote":"127.0.0.1:42438","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-03-19T21:51:54.913427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"992.19134ms","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-03-19T21:51:54.913462Z","caller":"traceutil/trace.go:171","msg":"trace[1885052833] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:900; }","duration":"992.283993ms","start":"2026-03-19T21:51:53.921167Z","end":"2026-03-19T21:51:54.913451Z","steps":["trace[1885052833] 'agreement among raft nodes before linearized reading' (duration: 992.1694ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:51:54.913489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:51:53.921133Z","time spent":"992.349525ms","remote":"127.0.0.1:42256","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-03-19T21:52:11.51091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.619048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-03-19T21:52:11.510976Z","caller":"traceutil/trace.go:171","msg":"trace[1072150803] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:946; }","duration":"312.733622ms","start":"2026-03-19T21:52:11.198229Z","end":"2026-03-19T21:52:11.510963Z","steps":["trace[1072150803] 'range keys from in-memory index tree' (duration: 312.442483ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:11.510999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:11.19821Z","time spent":"312.784083ms","remote":"127.0.0.1:42256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-19T21:52:11.51098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.570646ms","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-03-19T21:52:11.511073Z","caller":"traceutil/trace.go:171","msg":"trace[1545749391] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:946; }","duration":"312.67697ms","start":"2026-03-19T21:52:11.198363Z","end":"2026-03-19T21:52:11.51104Z","steps":["trace[1545749391] 'range keys from in-memory index tree' (duration: 312.410592ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:11.511113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:11.198357Z","time spent":"312.747542ms","remote":"127.0.0.1:42256","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":"info","ts":"2026-03-19T21:52:11.511117Z","caller":"traceutil/trace.go:171","msg":"trace[581516877] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"311.145088ms","start":"2026-03-19T21:52:11.199957Z","end":"2026-03-19T21:52:11.511102Z","steps":["trace[581516877] 'process raft request' (duration: 311.077826ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:52:11.511224Z","caller":"traceutil/trace.go:171","msg":"trace[1363404464] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"311.391084ms","start":"2026-03-19T21:52:11.199827Z","end":"2026-03-19T21:52:11.511218Z","steps":["trace[1363404464] 'process raft request' (duration: 309.304166ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:11.51122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:11.199942Z","time spent":"311.213189ms","remote":"127.0.0.1:42256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T21:52:11.51126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:11.199812Z","time spent":"311.425285ms","remote":"127.0.0.1:42256","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-03-19T21:52:11.950563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.993489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-19T21:52:11.950663Z","caller":"traceutil/trace.go:171","msg":"trace[416114243] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:958; }","duration":"110.170765ms","start":"2026-03-19T21:52:11.840472Z","end":"2026-03-19T21:52:11.950643Z","steps":["trace[416114243] 'count revisions from in-memory index tree' (duration: 109.847046ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:52:13.759693Z","caller":"traceutil/trace.go:171","msg":"trace[987857058] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"241.68694ms","start":"2026-03-19T21:52:13.517984Z","end":"2026-03-19T21:52:13.759671Z","steps":["trace[987857058] 'process raft request' (duration: 241.549016ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:52:13.996214Z","caller":"traceutil/trace.go:171","msg":"trace[1130306760] linearizableReadLoop","detail":"{readStateIndex:1013; appliedIndex:1011; }","duration":"475.903591ms","start":"2026-03-19T21:52:13.520287Z","end":"2026-03-19T21:52:13.996191Z","steps":["trace[1130306760] 'read index received' (duration: 239.478408ms)","trace[1130306760] 'applied index is now lower than readState.Index' (duration: 236.423923ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:52:13.996439Z","caller":"traceutil/trace.go:171","msg":"trace[2024481023] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"477.108544ms","start":"2026-03-19T21:52:13.519304Z","end":"2026-03-19T21:52:13.996413Z","steps":["trace[2024481023] 'process raft request' (duration: 476.819416ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:52:13.996619Z","caller":"traceutil/trace.go:171","msg":"trace[1042101579] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"478.515934ms","start":"2026-03-19T21:52:13.518043Z","end":"2026-03-19T21:52:13.996559Z","steps":["trace[1042101579] 'process raft request' (duration: 477.906977ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:13.99668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.546156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-19T21:52:13.996731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.440826ms","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-03-19T21:52:13.996735Z","caller":"traceutil/trace.go:171","msg":"trace[679931429] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:970; }","duration":"211.645869ms","start":"2026-03-19T21:52:13.785075Z","end":"2026-03-19T21:52:13.996721Z","steps":["trace[679931429] 'agreement among raft nodes before linearized reading' (duration: 211.517015ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:52:13.996766Z","caller":"traceutil/trace.go:171","msg":"trace[2123885470] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:970; }","duration":"476.516078ms","start":"2026-03-19T21:52:13.52024Z","end":"2026-03-19T21:52:13.996756Z","steps":["trace[2123885470] 'agreement among raft nodes before linearized reading' (duration: 476.320842ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:13.996789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:13.520231Z","time spent":"476.553949ms","remote":"127.0.0.1:42256","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-03-19T21:52:13.996852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:13.518028Z","time spent":"478.708149ms","remote":"127.0.0.1:42256","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-03-19T21:52:13.996757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.372324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-03-19T21:52:13.996988Z","caller":"traceutil/trace.go:171","msg":"trace[825596960] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:970; }","duration":"476.631491ms","start":"2026-03-19T21:52:13.520348Z","end":"2026-03-19T21:52:13.996979Z","steps":["trace[825596960] 'agreement among raft nodes before linearized reading' (duration: 476.152058ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:13.997015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:13.520334Z","time spent":"476.673532ms","remote":"127.0.0.1:42256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-19T21:52:13.996624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:13.519282Z","time spent":"477.215398ms","remote":"127.0.0.1:42194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11371,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T21:52:14.309418Z","caller":"traceutil/trace.go:171","msg":"trace[878480684] linearizableReadLoop","detail":"{readStateIndex:1018; appliedIndex:1014; }","duration":"239.359685ms","start":"2026-03-19T21:52:14.07004Z","end":"2026-03-19T21:52:14.309399Z","steps":["trace[878480684] 'read index received' (duration: 199.326714ms)","trace[878480684] 'applied index is now lower than readState.Index' (duration: 40.031581ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:52:14.309435Z","caller":"traceutil/trace.go:171","msg":"trace[1760443492] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"305.652371ms","start":"2026-03-19T21:52:14.003747Z","end":"2026-03-19T21:52:14.309399Z","steps":["trace[1760443492] 'process raft request' (duration: 305.504707ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:52:14.309535Z","caller":"traceutil/trace.go:171","msg":"trace[673753206] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"303.527172ms","start":"2026-03-19T21:52:14.005988Z","end":"2026-03-19T21:52:14.309515Z","steps":["trace[673753206] 'process raft request' (duration: 303.329137ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:14.309561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.512119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T21:52:14.3096Z","caller":"traceutil/trace.go:171","msg":"trace[78161571] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:975; }","duration":"239.603611ms","start":"2026-03-19T21:52:14.069986Z","end":"2026-03-19T21:52:14.309589Z","steps":["trace[78161571] 'agreement among raft nodes before linearized reading' (duration: 239.523429ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:14.309602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:14.003724Z","time spent":"305.793845ms","remote":"127.0.0.1:42256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T21:52:14.309638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:14.005982Z","time spent":"303.598504ms","remote":"127.0.0.1:42458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9375,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T21:52:14.309464Z","caller":"traceutil/trace.go:171","msg":"trace[528101819] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"305.688972ms","start":"2026-03-19T21:52:14.003756Z","end":"2026-03-19T21:52:14.309445Z","steps":["trace[528101819] 'process raft request' (duration: 305.530748ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:14.30973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:14.003732Z","time spent":"305.982721ms","remote":"127.0.0.1:42270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1507,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T21:52:14.309389Z","caller":"traceutil/trace.go:171","msg":"trace[9041570] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"305.659902ms","start":"2026-03-19T21:52:14.003693Z","end":"2026-03-19T21:52:14.309353Z","steps":["trace[9041570] 'process raft request' (duration: 265.735753ms)","trace[9041570] 'compare' (duration: 39.718433ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T21:52:14.309997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:14.003672Z","time spent":"306.219797ms","remote":"127.0.0.1:42184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":914,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T21:52:15.886676Z","caller":"traceutil/trace.go:171","msg":"trace[2142813402] linearizableReadLoop","detail":"{readStateIndex:1020; appliedIndex:1019; }","duration":"122.45985ms","start":"2026-03-19T21:52:15.76419Z","end":"2026-03-19T21:52:15.88665Z","steps":["trace[2142813402] 'read index received' (duration: 122.368737ms)","trace[2142813402] 'applied index is now lower than readState.Index' (duration: 89.933µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:52:15.88705Z","caller":"traceutil/trace.go:171","msg":"trace[1515618462] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"261.286918ms","start":"2026-03-19T21:52:15.625731Z","end":"2026-03-19T21:52:15.887017Z","steps":["trace[1515618462] 'process raft request' (duration: 260.784294ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:15.887488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.277823ms","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-03-19T21:52:15.887591Z","caller":"traceutil/trace.go:171","msg":"trace[2047209581] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:977; }","duration":"123.423907ms","start":"2026-03-19T21:52:15.764149Z","end":"2026-03-19T21:52:15.887573Z","steps":["trace[2047209581] 'agreement among raft nodes before linearized reading' (duration: 123.202011ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:15.888015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.581862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T21:52:15.888837Z","caller":"traceutil/trace.go:171","msg":"trace[1874298233] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:977; }","duration":"114.457026ms","start":"2026-03-19T21:52:15.774366Z","end":"2026-03-19T21:52:15.888823Z","steps":["trace[1874298233] 'agreement among raft nodes before linearized reading' (duration: 113.613162ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:16.451393Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782675484906450543,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-19T21:52:16.589853Z","caller":"traceutil/trace.go:171","msg":"trace[1022202347] linearizableReadLoop","detail":"{readStateIndex:1021; appliedIndex:1020; }","duration":"639.374189ms","start":"2026-03-19T21:52:15.950451Z","end":"2026-03-19T21:52:16.589825Z","steps":["trace[1022202347] 'read index received' (duration: 639.171813ms)","trace[1022202347] 'applied index is now lower than readState.Index' (duration: 201.376µs)"],"step_count":2} {"level":"warn","ts":"2026-03-19T21:52:16.590096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.609455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-19T21:52:16.590189Z","caller":"traceutil/trace.go:171","msg":"trace[1972411543] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:978; }","duration":"639.76742ms","start":"2026-03-19T21:52:15.950398Z","end":"2026-03-19T21:52:16.590166Z","steps":["trace[1972411543] 'agreement among raft nodes before linearized reading' (duration: 639.592905ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:16.590213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.146435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-19T21:52:16.590258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:15.950377Z","time spent":"639.864083ms","remote":"127.0.0.1:42132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":15,"response size":30,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-03-19T21:52:16.590256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.134879ms","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":"warn","ts":"2026-03-19T21:52:16.590316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.101444ms","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-03-19T21:52:16.590443Z","caller":"traceutil/trace.go:171","msg":"trace[1527012395] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:978; }","duration":"587.381012ms","start":"2026-03-19T21:52:16.003047Z","end":"2026-03-19T21:52:16.590428Z","steps":["trace[1527012395] 'agreement among raft nodes before linearized reading' (duration: 586.997211ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:16.590514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:16.002992Z","time spent":"587.506796ms","remote":"127.0.0.1:42256","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-03-19T21:52:16.590361Z","caller":"traceutil/trace.go:171","msg":"trace[1367641449] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:978; }","duration":"535.240012ms","start":"2026-03-19T21:52:16.055068Z","end":"2026-03-19T21:52:16.590308Z","steps":["trace[1367641449] 'agreement among raft nodes before linearized reading' (duration: 535.052576ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:52:16.59027Z","caller":"traceutil/trace.go:171","msg":"trace[24292715] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:978; }","duration":"276.254298ms","start":"2026-03-19T21:52:16.314003Z","end":"2026-03-19T21:52:16.590258Z","steps":["trace[24292715] 'agreement among raft nodes before linearized reading' (duration: 276.087983ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:52:16.590767Z","caller":"traceutil/trace.go:171","msg":"trace[1690647867] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"698.30406ms","start":"2026-03-19T21:52:15.892436Z","end":"2026-03-19T21:52:16.59074Z","steps":["trace[1690647867] 'process raft request' (duration: 697.24953ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:16.591373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:15.892408Z","time spent":"698.881086ms","remote":"127.0.0.1:42256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T21:52:16.59175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:16.055039Z","time spent":"536.671782ms","remote":"127.0.0.1:42256","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-03-19T21:52:16.89546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.852275ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T21:52:16.895717Z","caller":"traceutil/trace.go:171","msg":"trace[73586848] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"300.430786ms","start":"2026-03-19T21:52:16.595264Z","end":"2026-03-19T21:52:16.895695Z","steps":["trace[73586848] 'process raft request' (duration: 300.309622ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:52:16.895722Z","caller":"traceutil/trace.go:171","msg":"trace[426560901] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"300.546769ms","start":"2026-03-19T21:52:16.595117Z","end":"2026-03-19T21:52:16.895663Z","steps":["trace[426560901] 'process raft request' (duration: 159.408255ms)","trace[426560901] 'compare' (duration: 140.736002ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T21:52:16.895814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:16.595251Z","time spent":"300.514027ms","remote":"127.0.0.1:42256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T21:52:16.895879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:16.595096Z","time spent":"300.694762ms","remote":"127.0.0.1:42256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T21:52:16.896055Z","caller":"traceutil/trace.go:171","msg":"trace[541839640] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"287.563134ms","start":"2026-03-19T21:52:16.608481Z","end":"2026-03-19T21:52:16.896044Z","steps":["trace[541839640] 'process raft request' (duration: 287.166273ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:52:16.895998Z","caller":"traceutil/trace.go:171","msg":"trace[707155614] linearizableReadLoop","detail":"{readStateIndex:1024; appliedIndex:1021; }","duration":"121.833883ms","start":"2026-03-19T21:52:16.774147Z","end":"2026-03-19T21:52:16.895981Z","steps":["trace[707155614] 'read index received' (duration: 115.609318ms)","trace[707155614] 'applied index is now lower than readState.Index' (duration: 6.223365ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T21:52:16.896265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.11771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T21:52:16.896294Z","caller":"traceutil/trace.go:171","msg":"trace[1280186452] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:981; }","duration":"122.186782ms","start":"2026-03-19T21:52:16.774098Z","end":"2026-03-19T21:52:16.896284Z","steps":["trace[1280186452] 'agreement among raft nodes before linearized reading' (duration: 122.074599ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:17.418896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.052577ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-03-19T21:52:17.644078Z","caller":"traceutil/trace.go:171","msg":"trace[1922597267] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"309.421978ms","start":"2026-03-19T21:52:17.334625Z","end":"2026-03-19T21:52:17.644047Z","steps":["trace[1922597267] 'process raft request' (duration: 272.588736ms)","trace[1922597267] 'compare' (duration: 36.732999ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T21:52:17.644191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:17.334605Z","time spent":"309.542731ms","remote":"127.0.0.1:42116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":891,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-19T21:52:17.671387Z","caller":"traceutil/trace.go:171","msg":"trace[561773356] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"251.381951ms","start":"2026-03-19T21:52:17.419978Z","end":"2026-03-19T21:52:17.67136Z","steps":["trace[561773356] 'process raft request' (duration: 251.262418ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:52:18.930106Z","caller":"traceutil/trace.go:171","msg":"trace[1762461692] linearizableReadLoop","detail":"{readStateIndex:1029; appliedIndex:1028; }","duration":"156.495813ms","start":"2026-03-19T21:52:18.77359Z","end":"2026-03-19T21:52:18.930086Z","steps":["trace[1762461692] 'read index received' (duration: 156.36917ms)","trace[1762461692] 'applied index is now lower than readState.Index' (duration: 125.613µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:52:18.930228Z","caller":"traceutil/trace.go:171","msg":"trace[52676557] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"331.051273ms","start":"2026-03-19T21:52:18.599163Z","end":"2026-03-19T21:52:18.930214Z","steps":["trace[52676557] 'process raft request' (duration: 330.789916ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:18.930382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:18.599136Z","time spent":"331.117265ms","remote":"127.0.0.1:42256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T21:52:18.930655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.07327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T21:52:18.930698Z","caller":"traceutil/trace.go:171","msg":"trace[1840110970] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:985; }","duration":"157.152622ms","start":"2026-03-19T21:52:18.773533Z","end":"2026-03-19T21:52:18.930686Z","steps":["trace[1840110970] 'agreement among raft nodes before linearized reading' (duration: 157.08332ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:19.482567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.880872ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T21:52:19.482878Z","caller":"traceutil/trace.go:171","msg":"trace[1500807294] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"547.343912ms","start":"2026-03-19T21:52:18.935502Z","end":"2026-03-19T21:52:19.482846Z","steps":["trace[1500807294] 'process raft request' (duration: 439.09747ms)","trace[1500807294] 'compare' (duration: 107.162592ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T21:52:19.483056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:18.935477Z","time spent":"547.496237ms","remote":"127.0.0.1:42256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T21:52:19.48307Z","caller":"traceutil/trace.go:171","msg":"trace[785984858] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"544.317807ms","start":"2026-03-19T21:52:18.938733Z","end":"2026-03-19T21:52:19.483051Z","steps":["trace[785984858] 'process raft request' (duration: 544.198724ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:52:19.483158Z","caller":"traceutil/trace.go:171","msg":"trace[2110286697] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"544.330417ms","start":"2026-03-19T21:52:18.938733Z","end":"2026-03-19T21:52:19.483063Z","steps":["trace[2110286697] 'process raft request' (duration: 543.934476ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:52:19.48321Z","caller":"traceutil/trace.go:171","msg":"trace[1292104037] linearizableReadLoop","detail":"{readStateIndex:1032; appliedIndex:1029; }","duration":"295.171538ms","start":"2026-03-19T21:52:19.188019Z","end":"2026-03-19T21:52:19.48319Z","steps":["trace[1292104037] 'read index received' (duration: 186.656228ms)","trace[1292104037] 'applied index is now lower than readState.Index' (duration: 108.5143ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T21:52:19.483216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:18.938716Z","time spent":"544.429831ms","remote":"127.0.0.1:42256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T21:52:19.483337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:18.938716Z","time spent":"544.530173ms","remote":"127.0.0.1:42256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T21:52:19.483397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.365584ms","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-03-19T21:52:19.483457Z","caller":"traceutil/trace.go:171","msg":"trace[1293964986] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:989; }","duration":"295.459186ms","start":"2026-03-19T21:52:19.187983Z","end":"2026-03-19T21:52:19.483442Z","steps":["trace[1293964986] 'agreement among raft nodes before linearized reading' (duration: 295.267691ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:52:19.483498Z","caller":"traceutil/trace.go:171","msg":"trace[1860347161] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"189.014835ms","start":"2026-03-19T21:52:19.294463Z","end":"2026-03-19T21:52:19.483478Z","steps":["trace[1860347161] 'process raft request' (duration: 188.531671ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:52:19.674066Z","caller":"traceutil/trace.go:171","msg":"trace[1831836587] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"186.013091ms","start":"2026-03-19T21:52:19.488025Z","end":"2026-03-19T21:52:19.674038Z","steps":["trace[1831836587] 'process raft request' (duration: 154.59131ms)","trace[1831836587] 'compare' (duration: 31.230585ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T21:52:20.036904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.355215ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-19T21:52:20.03703Z","caller":"traceutil/trace.go:171","msg":"trace[613672364] linearizableReadLoop","detail":"{readStateIndex:1035; appliedIndex:1034; }","duration":"263.566863ms","start":"2026-03-19T21:52:19.773441Z","end":"2026-03-19T21:52:20.037008Z","steps":["trace[613672364] 'read index received' (duration: 24.028453ms)","trace[613672364] 'applied index is now lower than readState.Index' (duration: 239.53654ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:52:20.037158Z","caller":"traceutil/trace.go:171","msg":"trace[83057588] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"318.164322ms","start":"2026-03-19T21:52:19.71895Z","end":"2026-03-19T21:52:20.037114Z","steps":["trace[83057588] 'process raft request' (duration: 78.520429ms)","trace[83057588] 'compare' (duration: 239.211251ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T21:52:20.037201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.747318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-19T21:52:20.037254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.114023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T21:52:20.037284Z","caller":"traceutil/trace.go:171","msg":"trace[1203827225] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:991; }","duration":"263.867421ms","start":"2026-03-19T21:52:19.7734Z","end":"2026-03-19T21:52:20.037267Z","steps":["trace[1203827225] 'agreement among raft nodes before linearized reading' (duration: 263.721667ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:52:20.037314Z","caller":"traceutil/trace.go:171","msg":"trace[708111853] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:991; }","duration":"186.239927ms","start":"2026-03-19T21:52:19.85106Z","end":"2026-03-19T21:52:20.0373Z","steps":["trace[708111853] 'agreement among raft nodes before linearized reading' (duration: 186.117363ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:20.037343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:19.718925Z","time spent":"318.315986ms","remote":"127.0.0.1:42116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":787,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-19T21:52:20.413139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.702032ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-19T21:52:20.41321Z","caller":"traceutil/trace.go:171","msg":"trace[2068918079] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"369.569962ms","start":"2026-03-19T21:52:20.043629Z","end":"2026-03-19T21:52:20.413199Z","steps":["trace[2068918079] 'process raft request' (duration: 158.765827ms)","trace[2068918079] 'compare' (duration: 210.555358ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T21:52:20.413243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:20.04361Z","time spent":"369.620723ms","remote":"127.0.0.1:42256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-19T21:52:21.05735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.428414ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-19T21:52:21.057758Z","caller":"traceutil/trace.go:171","msg":"trace[963865878] linearizableReadLoop","detail":"{readStateIndex:1037; appliedIndex:1036; }","duration":"641.215662ms","start":"2026-03-19T21:52:20.416525Z","end":"2026-03-19T21:52:21.05774Z","steps":["trace[963865878] 'read index received' (duration: 173.307275ms)","trace[963865878] 'applied index is now lower than readState.Index' (duration: 467.906456ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:52:21.057824Z","caller":"traceutil/trace.go:171","msg":"trace[1495637887] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"910.342469ms","start":"2026-03-19T21:52:20.147449Z","end":"2026-03-19T21:52:21.057791Z","steps":["trace[1495637887] 'process raft request' (duration: 442.408071ms)","trace[1495637887] 'compare' (duration: 467.123935ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T21:52:21.057954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.420427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"warn","ts":"2026-03-19T21:52:21.057977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:20.147426Z","time spent":"910.470963ms","remote":"127.0.0.1:42116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":787,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-19T21:52:21.057995Z","caller":"traceutil/trace.go:171","msg":"trace[957714158] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:993; }","duration":"641.503809ms","start":"2026-03-19T21:52:20.416478Z","end":"2026-03-19T21:52:21.057982Z","steps":["trace[957714158] 'agreement among raft nodes before linearized reading' (duration: 641.333295ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:21.058028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:20.416459Z","time spent":"641.562101ms","remote":"127.0.0.1:42256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-03-19T21:52:21.558656Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782675484906450581,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-19T21:52:21.828739Z","caller":"traceutil/trace.go:171","msg":"trace[2135582665] linearizableReadLoop","detail":"{readStateIndex:1040; appliedIndex:1037; }","duration":"770.936063ms","start":"2026-03-19T21:52:21.057788Z","end":"2026-03-19T21:52:21.828724Z","steps":["trace[2135582665] 'read index received' (duration: 723.089874ms)","trace[2135582665] 'applied index is now lower than readState.Index' (duration: 47.845189ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:52:21.82882Z","caller":"traceutil/trace.go:171","msg":"trace[2053367543] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"764.606046ms","start":"2026-03-19T21:52:21.064207Z","end":"2026-03-19T21:52:21.828813Z","steps":["trace[2053367543] 'process raft request' (duration: 764.473402ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:21.828888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:21.064194Z","time spent":"764.640898ms","remote":"127.0.0.1:42256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T21:52:21.829025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"894.248118ms","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-03-19T21:52:21.829048Z","caller":"traceutil/trace.go:171","msg":"trace[1797509151] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:996; }","duration":"894.271258ms","start":"2026-03-19T21:52:20.934771Z","end":"2026-03-19T21:52:21.829042Z","steps":["trace[1797509151] 'agreement among raft nodes before linearized reading' (duration: 894.189986ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:21.829066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:20.93471Z","time spent":"894.352271ms","remote":"127.0.0.1:42256","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-03-19T21:52:21.829175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.055354371s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T21:52:21.829161Z","caller":"traceutil/trace.go:171","msg":"trace[1103769580] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"1.41159608s","start":"2026-03-19T21:52:20.417459Z","end":"2026-03-19T21:52:21.829056Z","steps":["trace[1103769580] 'process raft request' (duration: 1.36340554s)","trace[1103769580] 'compare' (duration: 47.411118ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T21:52:21.829282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:20.417445Z","time spent":"1.411778944s","remote":"127.0.0.1:42116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":772,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-19T21:52:21.829315Z","caller":"traceutil/trace.go:171","msg":"trace[972205169] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"767.547039ms","start":"2026-03-19T21:52:21.061762Z","end":"2026-03-19T21:52:21.829309Z","steps":["trace[972205169] 'process raft request' (duration: 766.88047ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:52:21.829196Z","caller":"traceutil/trace.go:171","msg":"trace[1381455245] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:996; }","duration":"1.055414123s","start":"2026-03-19T21:52:20.773777Z","end":"2026-03-19T21:52:21.829191Z","steps":["trace[1381455245] 'agreement among raft nodes before linearized reading' (duration: 1.055384542s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:21.829416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.391882ms","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-03-19T21:52:21.829437Z","caller":"traceutil/trace.go:171","msg":"trace[20457927] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:996; }","duration":"341.433722ms","start":"2026-03-19T21:52:21.487999Z","end":"2026-03-19T21:52:21.829432Z","steps":["trace[20457927] 'agreement among raft nodes before linearized reading' (duration: 341.375831ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:21.829454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:21.487984Z","time spent":"341.466954ms","remote":"127.0.0.1:42256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-19T21:52:21.829429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:20.773756Z","time spent":"1.055640278s","remote":"127.0.0.1:42028","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-03-19T21:52:21.829351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:21.061741Z","time spent":"767.59285ms","remote":"127.0.0.1:42116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":835,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-19T21:52:21.82959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.792513ms","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-03-19T21:52:21.829636Z","caller":"traceutil/trace.go:171","msg":"trace[1571941566] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:996; }","duration":"341.868785ms","start":"2026-03-19T21:52:21.487756Z","end":"2026-03-19T21:52:21.829625Z","steps":["trace[1571941566] 'agreement among raft nodes before linearized reading' (duration: 341.729071ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:21.829673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:21.487739Z","time spent":"341.926337ms","remote":"127.0.0.1:42256","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-03-19T21:52:21.829893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.238258ms","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-03-19T21:52:21.829301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.511815ms","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-03-19T21:52:21.830071Z","caller":"traceutil/trace.go:171","msg":"trace[1245129600] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:996; }","duration":"342.307688ms","start":"2026-03-19T21:52:21.487742Z","end":"2026-03-19T21:52:21.83005Z","steps":["trace[1245129600] 'agreement among raft nodes before linearized reading' (duration: 341.502645ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:21.830126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:21.487722Z","time spent":"342.388699ms","remote":"127.0.0.1:42256","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-03-19T21:52:21.829971Z","caller":"traceutil/trace.go:171","msg":"trace[433869308] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:996; }","duration":"150.362352ms","start":"2026-03-19T21:52:21.679591Z","end":"2026-03-19T21:52:21.829954Z","steps":["trace[433869308] 'agreement among raft nodes before linearized reading' (duration: 149.92691ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:22.52049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.325574ms","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-03-19T21:52:22.520614Z","caller":"traceutil/trace.go:171","msg":"trace[1188132558] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:997; }","duration":"319.456649ms","start":"2026-03-19T21:52:22.201135Z","end":"2026-03-19T21:52:22.520592Z","steps":["trace[1188132558] 'range keys from in-memory index tree' (duration: 319.310014ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:22.520768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.786798ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T21:52:22.521235Z","caller":"traceutil/trace.go:171","msg":"trace[1090798949] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"687.261901ms","start":"2026-03-19T21:52:21.833944Z","end":"2026-03-19T21:52:22.521206Z","steps":["trace[1090798949] 'process raft request' (duration: 687.038145ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:22.521376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:21.833927Z","time spent":"687.375305ms","remote":"127.0.0.1:42256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T21:52:22.521175Z","caller":"traceutil/trace.go:171","msg":"trace[1696139649] linearizableReadLoop","detail":"{readStateIndex:1045; appliedIndex:1040; }","duration":"683.502846ms","start":"2026-03-19T21:52:21.837649Z","end":"2026-03-19T21:52:22.521152Z","steps":["trace[1696139649] 'read index received' (duration: 15.178486ms)","trace[1696139649] 'applied index is now lower than readState.Index' (duration: 668.32306ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:52:22.52151Z","caller":"traceutil/trace.go:171","msg":"trace[711051968] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"687.319112ms","start":"2026-03-19T21:52:21.834086Z","end":"2026-03-19T21:52:22.521405Z","steps":["trace[711051968] 'process raft request' (duration: 686.976103ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:52:22.521594Z","caller":"traceutil/trace.go:171","msg":"trace[1703687766] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"687.834077ms","start":"2026-03-19T21:52:21.833735Z","end":"2026-03-19T21:52:22.521569Z","steps":["trace[1703687766] 'process raft request' (duration: 191.152494ms)","trace[1703687766] 'compare' (duration: 495.671095ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:52:22.521712Z","caller":"traceutil/trace.go:171","msg":"trace[2142999812] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"686.632424ms","start":"2026-03-19T21:52:21.83504Z","end":"2026-03-19T21:52:22.521672Z","steps":["trace[2142999812] 'process raft request' (duration: 686.065858ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:22.521834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.171465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-19T21:52:22.521895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:21.835031Z","time spent":"686.759547ms","remote":"127.0.0.1:42256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T21:52:22.521904Z","caller":"traceutil/trace.go:171","msg":"trace[1559374220] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1001; }","duration":"684.262667ms","start":"2026-03-19T21:52:21.837625Z","end":"2026-03-19T21:52:22.521888Z","steps":["trace[1559374220] 'agreement among raft nodes before linearized reading' (duration: 684.146324ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:22.521912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:21.833718Z","time spent":"687.995592ms","remote":"127.0.0.1:42256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T21:52:22.521955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:21.837616Z","time spent":"684.326278ms","remote":"127.0.0.1:42028","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-03-19T21:52:22.52239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:21.833722Z","time spent":"687.91335ms","remote":"127.0.0.1:42256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T21:52:23.432242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.494492ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-03-19T21:52:24.88527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.642749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T21:52:24.885389Z","caller":"traceutil/trace.go:171","msg":"trace[293742180] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1008; }","duration":"281.800763ms","start":"2026-03-19T21:52:24.603571Z","end":"2026-03-19T21:52:24.885372Z","steps":["trace[293742180] 'range keys from in-memory index tree' (duration: 281.533055ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:24.885514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.587266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T21:52:24.885599Z","caller":"traceutil/trace.go:171","msg":"trace[96425907] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1008; }","duration":"111.718529ms","start":"2026-03-19T21:52:24.773859Z","end":"2026-03-19T21:52:24.885577Z","steps":["trace[96425907] 'range keys from in-memory index tree' (duration: 111.522093ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:52:26.047447Z","caller":"traceutil/trace.go:171","msg":"trace[413788661] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"122.860632ms","start":"2026-03-19T21:52:25.924562Z","end":"2026-03-19T21:52:26.047422Z","steps":["trace[413788661] 'process raft request' (duration: 122.721208ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:53:38.280176Z","caller":"traceutil/trace.go:171","msg":"trace[683117658] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"118.887077ms","start":"2026-03-19T21:53:38.161262Z","end":"2026-03-19T21:53:38.280149Z","steps":["trace[683117658] 'process raft request' (duration: 118.757553ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:55:28.238579Z","caller":"traceutil/trace.go:171","msg":"trace[1363065949] linearizableReadLoop","detail":"{readStateIndex:1890; appliedIndex:1889; }","duration":"111.83549ms","start":"2026-03-19T21:55:28.126719Z","end":"2026-03-19T21:55:28.238555Z","steps":["trace[1363065949] 'read index received' (duration: 111.636534ms)","trace[1363065949] 'applied index is now lower than readState.Index' (duration: 197.835µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:55:28.2387Z","caller":"traceutil/trace.go:171","msg":"trace[885327270] transaction","detail":"{read_only:false; response_revision:1807; number_of_response:1; }","duration":"113.089304ms","start":"2026-03-19T21:55:28.125571Z","end":"2026-03-19T21:55:28.238661Z","steps":["trace[885327270] 'process raft request' (duration: 112.812617ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:55:28.238944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.132928ms","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-03-19T21:55:28.239025Z","caller":"traceutil/trace.go:171","msg":"trace[861147762] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1807; }","duration":"112.311543ms","start":"2026-03-19T21:55:28.126692Z","end":"2026-03-19T21:55:28.239004Z","steps":["trace[861147762] 'agreement among raft nodes before linearized reading' (duration: 111.994214ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:55:38.117926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.227659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-19T21:55:38.118031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-03-19T21:55:38.118119Z","caller":"traceutil/trace.go:171","msg":"trace[573531695] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1851; }","duration":"166.112294ms","start":"2026-03-19T21:55:37.951986Z","end":"2026-03-19T21:55:38.118098Z","steps":["trace[573531695] 'range keys from in-memory index tree' (duration: 165.763895ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:55:38.118234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.724543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-03-19T21:55:38.118051Z","caller":"traceutil/trace.go:171","msg":"trace[563761162] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1851; }","duration":"344.414174ms","start":"2026-03-19T21:55:37.77361Z","end":"2026-03-19T21:55:38.118024Z","steps":["trace[563761162] 'range keys from in-memory index tree' (duration: 344.146577ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:55:38.118317Z","caller":"traceutil/trace.go:171","msg":"trace[897979475] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1851; }","duration":"494.856767ms","start":"2026-03-19T21:55:37.623442Z","end":"2026-03-19T21:55:38.118299Z","steps":["trace[897979475] 'range keys from in-memory index tree' (duration: 494.526718ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:55:38.118382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:55:37.773594Z","time spent":"344.770915ms","remote":"127.0.0.1:42028","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-03-19T21:55:38.118395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:55:37.623422Z","time spent":"494.96282ms","remote":"127.0.0.1:42256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}