{"level":"warn","ts":"2026-05-05T07:46:32.443669Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T07:46:32.443747Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.53:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.53:2380","--initial-cluster=instance=https://199.204.45.53:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.53:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.53:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-05T07:46:32.443811Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T07:46:32.443817Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-05-05T07:46:32.443831Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-05T07:46:32.444665Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"]} {"level":"info","ts":"2026-05-05T07:46:32.444735Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.53:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-05T07:46:32.453906Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.202633ms"} {"level":"info","ts":"2026-05-05T07:46:32.467803Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c47ca1a7b1fe506b","cluster-id":"95bd21e158fabf07"} {"level":"info","ts":"2026-05-05T07:46:32.467879Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=()"} {"level":"info","ts":"2026-05-05T07:46:32.467905Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 0"} {"level":"info","ts":"2026-05-05T07:46:32.467919Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c47ca1a7b1fe506b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-05T07:46:32.467937Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 1"} {"level":"info","ts":"2026-05-05T07:46:32.467971Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"warn","ts":"2026-05-05T07:46:32.483528Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-05T07:46:32.486105Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-05T07:46:32.487719Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-05T07:46:32.490838Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c47ca1a7b1fe506b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-05T07:46:32.491059Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c47ca1a7b1fe506b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-05T07:46:32.491256Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-05T07:46:32.491301Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-05T07:46:32.49136Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-05T07:46:32.494393Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"info","ts":"2026-05-05T07:46:32.494818Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","added-peer-id":"c47ca1a7b1fe506b","added-peer-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-05-05T07:46:32.496727Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-05T07:46:32.496805Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-05-05T07:46:32.496836Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-05-05T07:46:32.496942Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c47ca1a7b1fe506b","initial-advertise-peer-urls":["https://199.204.45.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-05T07:46:32.496982Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-05T07:46:33.27338Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b is starting a new election at term 1"} {"level":"info","ts":"2026-05-05T07:46:33.273428Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became pre-candidate at term 1"} {"level":"info","ts":"2026-05-05T07:46:33.273465Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgPreVoteResp from c47ca1a7b1fe506b at term 1"} {"level":"info","ts":"2026-05-05T07:46:33.273479Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became candidate at term 2"} {"level":"info","ts":"2026-05-05T07:46:33.27349Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgVoteResp from c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-05-05T07:46:33.2735Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became leader at term 2"} {"level":"info","ts":"2026-05-05T07:46:33.27351Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c47ca1a7b1fe506b elected leader c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-05-05T07:46:33.292938Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c47ca1a7b1fe506b","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.53:2379]}","request-path":"/0/members/c47ca1a7b1fe506b/attributes","cluster-id":"95bd21e158fabf07","publish-timeout":"7s"} {"level":"info","ts":"2026-05-05T07:46:33.293193Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T07:46:33.293377Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T07:46:33.293488Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-05T07:46:33.293504Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-05T07:46:33.293727Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T07:46:33.294981Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T07:46:33.295034Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T07:46:33.295054Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T07:46:33.295591Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.53:2379"} {"level":"info","ts":"2026-05-05T07:46:33.295745Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-05T07:46:34.751242Z","caller":"traceutil/trace.go:171","msg":"trace[1543913895] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"100.659518ms","start":"2026-05-05T07:46:34.650549Z","end":"2026-05-05T07:46:34.751208Z","steps":["trace[1543913895] 'process raft request' (duration: 100.51073ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:46:34.751231Z","caller":"traceutil/trace.go:171","msg":"trace[1591037551] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"106.468404ms","start":"2026-05-05T07:46:34.644735Z","end":"2026-05-05T07:46:34.751203Z","steps":["trace[1591037551] 'process raft request' (duration: 53.571579ms)","trace[1591037551] 'compare' (duration: 52.563218ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:46:34.751651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.695564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T07:46:34.751827Z","caller":"traceutil/trace.go:171","msg":"trace[265929928] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:12; }","duration":"109.02997ms","start":"2026-05-05T07:46:34.642747Z","end":"2026-05-05T07:46:34.751777Z","steps":["trace[265929928] 'agreement among raft nodes before linearized reading' (duration: 108.554094ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:46:34.75165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.302028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T07:46:34.752304Z","caller":"traceutil/trace.go:171","msg":"trace[1253879859] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:12; }","duration":"111.107121ms","start":"2026-05-05T07:46:34.641177Z","end":"2026-05-05T07:46:34.752284Z","steps":["trace[1253879859] 'agreement among raft nodes before linearized reading' (duration: 110.15438ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:46:34.917652Z","caller":"traceutil/trace.go:171","msg":"trace[1777181561] transaction","detail":"{read_only:false; response_revision:36; number_of_response:1; }","duration":"106.107888ms","start":"2026-05-05T07:46:34.811497Z","end":"2026-05-05T07:46:34.917605Z","steps":["trace[1777181561] 'process raft request' (duration: 87.860909ms)","trace[1777181561] 'compare' (duration: 17.959722ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:46:34.917912Z","caller":"traceutil/trace.go:171","msg":"trace[1112755531] linearizableReadLoop","detail":"{readStateIndex:40; appliedIndex:39; }","duration":"106.339466ms","start":"2026-05-05T07:46:34.811532Z","end":"2026-05-05T07:46:34.917872Z","steps":["trace[1112755531] 'read index received' (duration: 19.115311ms)","trace[1112755531] 'applied index is now lower than readState.Index' (duration: 87.220995ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:46:34.918069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.512174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-node-lease/\" range_end:\"/registry/resourcequotas/kube-node-lease0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T07:46:34.91815Z","caller":"traceutil/trace.go:171","msg":"trace[926224005] range","detail":"{range_begin:/registry/resourcequotas/kube-node-lease/; range_end:/registry/resourcequotas/kube-node-lease0; response_count:0; response_revision:37; }","duration":"106.627823ms","start":"2026-05-05T07:46:34.811503Z","end":"2026-05-05T07:46:34.918131Z","steps":["trace[926224005] 'agreement among raft nodes before linearized reading' (duration: 106.476265ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:46:34.918217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.264403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T07:46:34.918305Z","caller":"traceutil/trace.go:171","msg":"trace[689148294] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:37; }","duration":"101.464181ms","start":"2026-05-05T07:46:34.816824Z","end":"2026-05-05T07:46:34.918288Z","steps":["trace[689148294] 'agreement among raft nodes before linearized reading' (duration: 101.251603ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:47:01.282692Z","caller":"traceutil/trace.go:171","msg":"trace[510359829] linearizableReadLoop","detail":"{readStateIndex:520; appliedIndex:519; }","duration":"273.647005ms","start":"2026-05-05T07:47:01.009019Z","end":"2026-05-05T07:47:01.282666Z","steps":["trace[510359829] 'read index received' (duration: 273.554384ms)","trace[510359829] 'applied index is now lower than readState.Index' (duration: 91.621µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:47:01.282836Z","caller":"traceutil/trace.go:171","msg":"trace[650292077] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"293.106981ms","start":"2026-05-05T07:47:00.989613Z","end":"2026-05-05T07:47:01.28272Z","steps":["trace[650292077] 'process raft request' (duration: 292.881347ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:47:01.282888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.835229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-05T07:47:01.282984Z","caller":"traceutil/trace.go:171","msg":"trace[334256034] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:504; }","duration":"273.977542ms","start":"2026-05-05T07:47:01.008985Z","end":"2026-05-05T07:47:01.282963Z","steps":["trace[334256034] 'agreement among raft nodes before linearized reading' (duration: 273.775348ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:47:01.432629Z","caller":"traceutil/trace.go:171","msg":"trace[2093588932] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"398.912133ms","start":"2026-05-05T07:47:01.033674Z","end":"2026-05-05T07:47:01.432586Z","steps":["trace[2093588932] 'process raft request' (duration: 398.700699ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:47:01.432676Z","caller":"traceutil/trace.go:171","msg":"trace[110509972] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"389.076118ms","start":"2026-05-05T07:47:01.043535Z","end":"2026-05-05T07:47:01.432612Z","steps":["trace[110509972] 'process raft request' (duration: 388.968896ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:47:01.432777Z","caller":"traceutil/trace.go:171","msg":"trace[1547843586] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"377.514863ms","start":"2026-05-05T07:47:01.055173Z","end":"2026-05-05T07:47:01.432687Z","steps":["trace[1547843586] 'process raft request' (duration: 377.36449ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:47:01.432886Z","caller":"traceutil/trace.go:171","msg":"trace[926637914] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"367.896861ms","start":"2026-05-05T07:47:01.064962Z","end":"2026-05-05T07:47:01.432858Z","steps":["trace[926637914] 'process raft request' (duration: 367.649177ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:47:01.432941Z","caller":"traceutil/trace.go:171","msg":"trace[612915516] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"367.632146ms","start":"2026-05-05T07:47:01.065293Z","end":"2026-05-05T07:47:01.432925Z","steps":["trace[612915516] 'process raft request' (duration: 367.359071ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:47:01.432995Z","caller":"traceutil/trace.go:171","msg":"trace[993730019] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"366.987005ms","start":"2026-05-05T07:47:01.066Z","end":"2026-05-05T07:47:01.432987Z","steps":["trace[993730019] 'process raft request' (duration: 366.887914ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:47:01.433041Z","caller":"traceutil/trace.go:171","msg":"trace[841058116] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"355.504691ms","start":"2026-05-05T07:47:01.077528Z","end":"2026-05-05T07:47:01.433033Z","steps":["trace[841058116] 'process raft request' (duration: 355.409589ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:47:01.433021Z","caller":"traceutil/trace.go:171","msg":"trace[509111391] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"367.548755ms","start":"2026-05-05T07:47:01.065387Z","end":"2026-05-05T07:47:01.432935Z","steps":["trace[509111391] 'process raft request' (duration: 367.301451ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:47:01.433124Z","caller":"traceutil/trace.go:171","msg":"trace[1957484519] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"354.061096ms","start":"2026-05-05T07:47:01.079049Z","end":"2026-05-05T07:47:01.43311Z","steps":["trace[1957484519] 'process raft request' (duration: 353.940663ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:47:01.433059Z","caller":"traceutil/trace.go:171","msg":"trace[1585114083] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"377.461021ms","start":"2026-05-05T07:47:01.055298Z","end":"2026-05-05T07:47:01.432759Z","steps":["trace[1585114083] 'process raft request' (duration: 377.277428ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:47:01.433261Z","caller":"traceutil/trace.go:171","msg":"trace[272740322] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"341.642785ms","start":"2026-05-05T07:47:01.091606Z","end":"2026-05-05T07:47:01.433248Z","steps":["trace[272740322] 'process raft request' (duration: 341.438081ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:47:01.433272Z","caller":"traceutil/trace.go:171","msg":"trace[134605302] linearizableReadLoop","detail":"{readStateIndex:532; appliedIndex:520; }","duration":"150.482006ms","start":"2026-05-05T07:47:01.28277Z","end":"2026-05-05T07:47:01.433252Z","steps":["trace[134605302] 'read index received' (duration: 149.634411ms)","trace[134605302] 'applied index is now lower than readState.Index' (duration: 846.395µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:47:01.433289Z","caller":"traceutil/trace.go:171","msg":"trace[1722197542] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"245.041206ms","start":"2026-05-05T07:47:01.188235Z","end":"2026-05-05T07:47:01.433276Z","steps":["trace[1722197542] 'process raft request' (duration: 244.874053ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:47:01.433423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.123444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T07:47:01.433482Z","caller":"traceutil/trace.go:171","msg":"trace[2093275536] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:516; }","duration":"211.224626ms","start":"2026-05-05T07:47:01.22224Z","end":"2026-05-05T07:47:01.433464Z","steps":["trace[2093275536] 'agreement among raft nodes before linearized reading' (duration: 211.036972ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:47:01.433576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.471396ms","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-05-05T07:47:01.433671Z","caller":"traceutil/trace.go:171","msg":"trace[839526124] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:516; }","duration":"420.66207ms","start":"2026-05-05T07:47:01.012993Z","end":"2026-05-05T07:47:01.433655Z","steps":["trace[839526124] 'agreement among raft nodes before linearized reading' (duration: 420.384785ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:47:01.433748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:47:01.012974Z","time spent":"420.733421ms","remote":"127.0.0.1:41336","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\" "} {"level":"warn","ts":"2026-05-05T07:47:01.433725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:47:01.043519Z","time spent":"389.299062ms","remote":"127.0.0.1:41100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T07:47:01.433725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:47:01.033655Z","time spent":"399.097266ms","remote":"127.0.0.1:41100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T07:47:01.433932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:47:01.055135Z","time spent":"377.751277ms","remote":"127.0.0.1:41100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T07:47:01.434209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:47:01.064944Z","time spent":"367.981783ms","remote":"127.0.0.1:41100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T07:47:01.434381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:47:01.065277Z","time spent":"367.687358ms","remote":"127.0.0.1:41100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T07:47:01.434475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:47:01.065993Z","time spent":"367.019266ms","remote":"127.0.0.1:41100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T07:47:01.434611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:47:01.077496Z","time spent":"355.562723ms","remote":"127.0.0.1:41100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T07:47:01.43475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:47:01.065366Z","time spent":"367.758409ms","remote":"127.0.0.1:41100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T07:47:01.434906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:47:01.079036Z","time spent":"354.125676ms","remote":"127.0.0.1:41100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T07:47:01.435055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:47:01.055285Z","time spent":"377.963791ms","remote":"127.0.0.1:41100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T07:47:01.435213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:47:01.091591Z","time spent":"341.698866ms","remote":"127.0.0.1:41100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T07:47:01.43588Z","caller":"traceutil/trace.go:171","msg":"trace[1033900302] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"148.834067ms","start":"2026-05-05T07:47:01.287023Z","end":"2026-05-05T07:47:01.435857Z","steps":["trace[1033900302] 'process raft request' (duration: 148.695374ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:47:02.15175Z","caller":"traceutil/trace.go:171","msg":"trace[1985789712] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"134.864387ms","start":"2026-05-05T07:47:02.01687Z","end":"2026-05-05T07:47:02.151734Z","steps":["trace[1985789712] 'process raft request' (duration: 134.765046ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:47:02.575504Z","caller":"traceutil/trace.go:171","msg":"trace[1267626547] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"116.492681ms","start":"2026-05-05T07:47:02.458986Z","end":"2026-05-05T07:47:02.575479Z","steps":["trace[1267626547] 'process raft request' (duration: 116.310418ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:47:02.646315Z","caller":"traceutil/trace.go:171","msg":"trace[693474669] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"186.368983ms","start":"2026-05-05T07:47:02.459933Z","end":"2026-05-05T07:47:02.646302Z","steps":["trace[693474669] 'process raft request' (duration: 185.887175ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:47:03.131495Z","caller":"traceutil/trace.go:171","msg":"trace[157670290] linearizableReadLoop","detail":"{readStateIndex:600; appliedIndex:599; }","duration":"118.292384ms","start":"2026-05-05T07:47:03.013184Z","end":"2026-05-05T07:47:03.131476Z","steps":["trace[157670290] 'read index received' (duration: 117.946867ms)","trace[157670290] 'applied index is now lower than readState.Index' (duration: 344.847µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:47:03.131564Z","caller":"traceutil/trace.go:171","msg":"trace[1333523054] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"120.053145ms","start":"2026-05-05T07:47:03.011478Z","end":"2026-05-05T07:47:03.131531Z","steps":["trace[1333523054] 'process raft request' (duration: 119.80218ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:47:03.131745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.545578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoyproxies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:570105"} {"level":"info","ts":"2026-05-05T07:47:03.131768Z","caller":"traceutil/trace.go:171","msg":"trace[287532079] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/envoyproxies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:582; }","duration":"118.609479ms","start":"2026-05-05T07:47:03.013151Z","end":"2026-05-05T07:47:03.131761Z","steps":["trace[287532079] 'agreement among raft nodes before linearized reading' (duration: 118.393745ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:47:03.64859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.157201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-05T07:47:03.648674Z","caller":"traceutil/trace.go:171","msg":"trace[1387125874] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:583; }","duration":"172.313544ms","start":"2026-05-05T07:47:03.476342Z","end":"2026-05-05T07:47:03.648656Z","steps":["trace[1387125874] 'range keys from in-memory index tree' (duration: 171.987128ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:47:03.648945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.27102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-05T07:47:03.648977Z","caller":"traceutil/trace.go:171","msg":"trace[1062254617] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:583; }","duration":"201.33552ms","start":"2026-05-05T07:47:03.447631Z","end":"2026-05-05T07:47:03.648967Z","steps":["trace[1062254617] 'range keys from in-memory index tree' (duration: 201.202978ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:47:08.467531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.927435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T07:47:08.467616Z","caller":"traceutil/trace.go:171","msg":"trace[1261349560] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:612; }","duration":"245.060557ms","start":"2026-05-05T07:47:08.222537Z","end":"2026-05-05T07:47:08.467597Z","steps":["trace[1261349560] 'range keys from in-memory index tree' (duration: 244.854994ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:47:08.49059Z","caller":"traceutil/trace.go:171","msg":"trace[1338975801] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"250.055336ms","start":"2026-05-05T07:47:08.240505Z","end":"2026-05-05T07:47:08.49056Z","steps":["trace[1338975801] 'process raft request' (duration: 249.459596ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:47:09.43644Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794899030166837499,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T07:47:09.628925Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.072078493s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-05T07:47:09.759437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.777918ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-05T07:47:09.759539Z","caller":"traceutil/trace.go:171","msg":"trace[176279811] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"1.202798337s","start":"2026-05-05T07:47:08.556721Z","end":"2026-05-05T07:47:09.759519Z","steps":["trace[176279811] 'process raft request' (duration: 1.072735014s)","trace[176279811] 'compare' (duration: 129.557724ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:47:09.759592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:47:08.556699Z","time spent":"1.202871888s","remote":"127.0.0.1:41574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T07:47:10.037452Z","caller":"traceutil/trace.go:171","msg":"trace[1289730211] linearizableReadLoop","detail":"{readStateIndex:634; appliedIndex:632; }","duration":"1.101551927s","start":"2026-05-05T07:47:08.935868Z","end":"2026-05-05T07:47:10.037419Z","steps":["trace[1289730211] 'read index received' (duration: 693.535752ms)","trace[1289730211] 'applied index is now lower than readState.Index' (duration: 408.014795ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:47:10.037755Z","caller":"traceutil/trace.go:171","msg":"trace[1568099481] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"1.15072081s","start":"2026-05-05T07:47:08.886833Z","end":"2026-05-05T07:47:10.037554Z","steps":["trace[1568099481] 'process raft request' (duration: 1.099305765s)","trace[1568099481] 'compare' (duration: 50.943197ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:47:10.037799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.101899863s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-htn4p\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-05-05T07:47:10.037886Z","caller":"traceutil/trace.go:171","msg":"trace[712507279] range","detail":"{range_begin:/registry/pods/kube-system/cilium-htn4p; range_end:; response_count:1; response_revision:615; }","duration":"1.102025845s","start":"2026-05-05T07:47:08.93584Z","end":"2026-05-05T07:47:10.037866Z","steps":["trace[712507279] 'agreement among raft nodes before linearized reading' (duration: 1.101861652s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:47:10.037925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:47:08.935825Z","time spent":"1.102091005s","remote":"127.0.0.1:41264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-htn4p\" "} {"level":"warn","ts":"2026-05-05T07:47:10.037955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.47431ms","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-05-05T07:47:10.038011Z","caller":"traceutil/trace.go:171","msg":"trace[1577100618] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:615; }","duration":"319.553311ms","start":"2026-05-05T07:47:09.718441Z","end":"2026-05-05T07:47:10.037995Z","steps":["trace[1577100618] 'agreement among raft nodes before linearized reading' (duration: 319.379538ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:47:10.038034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.485379ms","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-05-05T07:47:10.038106Z","caller":"traceutil/trace.go:171","msg":"trace[1334739717] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:615; }","duration":"716.601161ms","start":"2026-05-05T07:47:09.321485Z","end":"2026-05-05T07:47:10.038086Z","steps":["trace[1334739717] 'agreement among raft nodes before linearized reading' (duration: 716.411108ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:47:10.038116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.056932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T07:47:10.038146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:47:09.321465Z","time spent":"716.674772ms","remote":"127.0.0.1:41336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-05T07:47:10.038158Z","caller":"traceutil/trace.go:171","msg":"trace[2007129858] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:615; }","duration":"265.138213ms","start":"2026-05-05T07:47:09.773006Z","end":"2026-05-05T07:47:10.038144Z","steps":["trace[2007129858] 'agreement among raft nodes before linearized reading' (duration: 265.076732ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:47:10.038191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:47:08.886792Z","time spent":"1.151075707s","remote":"127.0.0.1:41336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T07:47:10.038054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:47:09.718431Z","time spent":"319.611003ms","remote":"127.0.0.1:41336","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\" "} {"level":"warn","ts":"2026-05-05T07:47:10.038573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"816.562169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T07:47:10.038665Z","caller":"traceutil/trace.go:171","msg":"trace[770797716] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:615; }","duration":"816.698141ms","start":"2026-05-05T07:47:09.221942Z","end":"2026-05-05T07:47:10.03864Z","steps":["trace[770797716] 'agreement among raft nodes before linearized reading' (duration: 816.530708ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:47:10.038716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:47:09.22192Z","time spent":"816.788712ms","remote":"127.0.0.1:41028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T07:47:10.038973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.08151041s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-05T07:47:10.039032Z","caller":"traceutil/trace.go:171","msg":"trace[1981375273] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:615; }","duration":"1.081607602s","start":"2026-05-05T07:47:08.957408Z","end":"2026-05-05T07:47:10.039016Z","steps":["trace[1981375273] 'agreement among raft nodes before linearized reading' (duration: 1.080342799s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:47:10.039068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:47:08.957386Z","time spent":"1.081673913s","remote":"127.0.0.1:41336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T07:47:10.038401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.672904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-05T07:47:10.039783Z","caller":"traceutil/trace.go:171","msg":"trace[1051807488] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:615; }","duration":"325.080159ms","start":"2026-05-05T07:47:09.714686Z","end":"2026-05-05T07:47:10.039766Z","steps":["trace[1051807488] 'agreement among raft nodes before linearized reading' (duration: 323.640304ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:47:10.039869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:47:09.714666Z","time spent":"325.191371ms","remote":"127.0.0.1:41336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-05T07:47:15.319144Z","caller":"traceutil/trace.go:171","msg":"trace[893685653] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"190.841033ms","start":"2026-05-05T07:47:15.128267Z","end":"2026-05-05T07:47:15.319108Z","steps":["trace[893685653] 'process raft request' (duration: 190.471287ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:47:21.829013Z","caller":"traceutil/trace.go:171","msg":"trace[1517573545] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"142.536055ms","start":"2026-05-05T07:47:21.686458Z","end":"2026-05-05T07:47:21.828994Z","steps":["trace[1517573545] 'process raft request' (duration: 142.391972ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:47:27.15476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"857.192102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-05T07:47:27.154832Z","caller":"traceutil/trace.go:171","msg":"trace[848570565] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:780; }","duration":"857.313904ms","start":"2026-05-05T07:47:26.297503Z","end":"2026-05-05T07:47:27.154816Z","steps":["trace[848570565] 'range keys from in-memory index tree' (duration: 856.965937ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:47:27.154873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:47:26.297479Z","time spent":"857.387645ms","remote":"127.0.0.1:41336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T07:47:27.155112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"934.15819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T07:47:27.155203Z","caller":"traceutil/trace.go:171","msg":"trace[513894120] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:780; }","duration":"934.281712ms","start":"2026-05-05T07:47:26.220905Z","end":"2026-05-05T07:47:27.155187Z","steps":["trace[513894120] 'range keys from in-memory index tree' (duration: 934.077748ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:47:27.155219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"872.694118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.53\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-05-05T07:47:27.155236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:47:26.220885Z","time spent":"934.344233ms","remote":"127.0.0.1:41028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-05T07:47:27.15525Z","caller":"traceutil/trace.go:171","msg":"trace[502097614] range","detail":"{range_begin:/registry/masterleases/199.204.45.53; range_end:; response_count:1; response_revision:780; }","duration":"872.756268ms","start":"2026-05-05T07:47:26.282487Z","end":"2026-05-05T07:47:27.155243Z","steps":["trace[502097614] 'range keys from in-memory index tree' (duration: 872.538465ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:47:27.155271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:47:26.282471Z","time spent":"872.79547ms","remote":"127.0.0.1:41088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.53\" "} {"level":"warn","ts":"2026-05-05T07:47:27.155454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"934.68499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-05T07:47:27.155482Z","caller":"traceutil/trace.go:171","msg":"trace[2016407393] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:780; }","duration":"934.73915ms","start":"2026-05-05T07:47:26.220734Z","end":"2026-05-05T07:47:27.155473Z","steps":["trace[2016407393] 'range keys from in-memory index tree' (duration: 934.535636ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:47:27.155505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:47:26.220719Z","time spent":"934.781401ms","remote":"127.0.0.1:41336","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-05-05T07:47:27.155549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"973.816345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-05T07:47:27.155519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"940.744917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-05T07:47:27.155578Z","caller":"traceutil/trace.go:171","msg":"trace[76015640] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:780; }","duration":"973.868656ms","start":"2026-05-05T07:47:26.181703Z","end":"2026-05-05T07:47:27.155572Z","steps":["trace[76015640] 'range keys from in-memory index tree' (duration: 973.733274ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:47:27.155601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:47:26.181688Z","time spent":"973.908747ms","remote":"127.0.0.1:41336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-05T07:47:27.155612Z","caller":"traceutil/trace.go:171","msg":"trace[1761355854] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:780; }","duration":"940.88387ms","start":"2026-05-05T07:47:26.214702Z","end":"2026-05-05T07:47:27.155586Z","steps":["trace[1761355854] 'range keys from in-memory index tree' (duration: 940.520313ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:47:27.155676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:47:26.214682Z","time spent":"940.983022ms","remote":"127.0.0.1:41336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-05T07:47:27.17323Z","caller":"traceutil/trace.go:171","msg":"trace[998038455] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"129.92584ms","start":"2026-05-05T07:47:27.04329Z","end":"2026-05-05T07:47:27.173216Z","steps":["trace[998038455] 'process raft request' (duration: 129.815128ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:47:27.454899Z","caller":"traceutil/trace.go:171","msg":"trace[772363459] linearizableReadLoop","detail":"{readStateIndex:805; appliedIndex:803; }","duration":"298.153612ms","start":"2026-05-05T07:47:27.156726Z","end":"2026-05-05T07:47:27.45488Z","steps":["trace[772363459] 'read index received' (duration: 16.331091ms)","trace[772363459] 'applied index is now lower than readState.Index' (duration: 281.821691ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:47:27.455036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.288794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T07:47:27.455087Z","caller":"traceutil/trace.go:171","msg":"trace[243041761] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:785; }","duration":"298.372166ms","start":"2026-05-05T07:47:27.156701Z","end":"2026-05-05T07:47:27.455073Z","steps":["trace[243041761] 'agreement among raft nodes before linearized reading' (duration: 298.268874ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:47:27.455531Z","caller":"traceutil/trace.go:171","msg":"trace[1580329550] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"295.988993ms","start":"2026-05-05T07:47:27.15953Z","end":"2026-05-05T07:47:27.455519Z","steps":["trace[1580329550] 'process raft request' (duration: 295.158498ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:47:27.455714Z","caller":"traceutil/trace.go:171","msg":"trace[443246076] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"294.598198ms","start":"2026-05-05T07:47:27.161106Z","end":"2026-05-05T07:47:27.455704Z","steps":["trace[443246076] 'process raft request' (duration: 293.680361ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:47:27.455802Z","caller":"traceutil/trace.go:171","msg":"trace[1870418505] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"294.662399ms","start":"2026-05-05T07:47:27.161132Z","end":"2026-05-05T07:47:27.455794Z","steps":["trace[1870418505] 'process raft request' (duration: 293.685142ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:47:27.455974Z","caller":"traceutil/trace.go:171","msg":"trace[2032449195] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"294.69522ms","start":"2026-05-05T07:47:27.161244Z","end":"2026-05-05T07:47:27.455939Z","steps":["trace[2032449195] 'process raft request' (duration: 293.602521ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:47:34.829946Z","caller":"traceutil/trace.go:171","msg":"trace[1116806659] transaction","detail":"{read_only:false; response_revision:854; number_of_response:1; }","duration":"111.613623ms","start":"2026-05-05T07:47:34.718305Z","end":"2026-05-05T07:47:34.829918Z","steps":["trace[1116806659] 'process raft request' (duration: 111.368323ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:47:34.829939Z","caller":"traceutil/trace.go:171","msg":"trace[72085019] transaction","detail":"{read_only:false; response_revision:855; number_of_response:1; }","duration":"104.276214ms","start":"2026-05-05T07:47:34.725639Z","end":"2026-05-05T07:47:34.829915Z","steps":["trace[72085019] 'process raft request' (duration: 104.1783ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:47:41.720988Z","caller":"traceutil/trace.go:171","msg":"trace[1733597230] linearizableReadLoop","detail":"{readStateIndex:941; appliedIndex:940; }","duration":"194.569526ms","start":"2026-05-05T07:47:41.526394Z","end":"2026-05-05T07:47:41.720964Z","steps":["trace[1733597230] 'read index received' (duration: 194.453491ms)","trace[1733597230] 'applied index is now lower than readState.Index' (duration: 114.935µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:47:41.721196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.788184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-05T07:47:41.721253Z","caller":"traceutil/trace.go:171","msg":"trace[1933685288] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:914; }","duration":"194.908848ms","start":"2026-05-05T07:47:41.526337Z","end":"2026-05-05T07:47:41.721246Z","steps":["trace[1933685288] 'agreement among raft nodes before linearized reading' (duration: 194.724231ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:47:41.721511Z","caller":"traceutil/trace.go:171","msg":"trace[1634474856] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"206.040721ms","start":"2026-05-05T07:47:41.514968Z","end":"2026-05-05T07:47:41.721009Z","steps":["trace[1634474856] 'process raft request' (duration: 205.829613ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:47:41.80061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.905748ms","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-05-05T07:47:41.800666Z","caller":"traceutil/trace.go:171","msg":"trace[1739737759] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:915; }","duration":"264.987082ms","start":"2026-05-05T07:47:41.535665Z","end":"2026-05-05T07:47:41.800652Z","steps":["trace[1739737759] 'agreement among raft nodes before linearized reading' (duration: 264.841846ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:47:41.800831Z","caller":"traceutil/trace.go:171","msg":"trace[30690535] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"221.324452ms","start":"2026-05-05T07:47:41.579495Z","end":"2026-05-05T07:47:41.800819Z","steps":["trace[30690535] 'process raft request' (duration: 220.914036ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:47:41.800991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.517916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T07:47:41.801027Z","caller":"traceutil/trace.go:171","msg":"trace[152791771] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:915; }","duration":"203.568607ms","start":"2026-05-05T07:47:41.59745Z","end":"2026-05-05T07:47:41.801019Z","steps":["trace[152791771] 'agreement among raft nodes before linearized reading' (duration: 203.516846ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:47:41.801194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.308354ms","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-05-05T07:47:41.801226Z","caller":"traceutil/trace.go:171","msg":"trace[613931985] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:915; }","duration":"260.380517ms","start":"2026-05-05T07:47:41.540838Z","end":"2026-05-05T07:47:41.801219Z","steps":["trace[613931985] 'agreement among raft nodes before linearized reading' (duration: 260.298234ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:47:41.801435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.282261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-05T07:47:41.801469Z","caller":"traceutil/trace.go:171","msg":"trace[1040901457] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:915; }","duration":"261.334623ms","start":"2026-05-05T07:47:41.540127Z","end":"2026-05-05T07:47:41.801461Z","steps":["trace[1040901457] 'agreement among raft nodes before linearized reading' (duration: 261.231049ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:47:41.853394Z","caller":"traceutil/trace.go:171","msg":"trace[1185903319] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"128.360239ms","start":"2026-05-05T07:47:41.725004Z","end":"2026-05-05T07:47:41.853365Z","steps":["trace[1185903319] 'process raft request' (duration: 128.058308ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:47:41.853512Z","caller":"traceutil/trace.go:171","msg":"trace[215267758] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"127.988814ms","start":"2026-05-05T07:47:41.725486Z","end":"2026-05-05T07:47:41.853475Z","steps":["trace[215267758] 'process raft request' (duration: 127.759046ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:47:41.853622Z","caller":"traceutil/trace.go:171","msg":"trace[319609601] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"126.29156ms","start":"2026-05-05T07:47:41.727317Z","end":"2026-05-05T07:47:41.853609Z","steps":["trace[319609601] 'process raft request' (duration: 126.083832ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:47:41.85355Z","caller":"traceutil/trace.go:171","msg":"trace[1687743263] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"126.994917ms","start":"2026-05-05T07:47:41.726518Z","end":"2026-05-05T07:47:41.853513Z","steps":["trace[1687743263] 'process raft request' (duration: 126.776368ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:48:02.208252Z","caller":"traceutil/trace.go:171","msg":"trace[64010133] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"123.694231ms","start":"2026-05-05T07:48:02.084531Z","end":"2026-05-05T07:48:02.208225Z","steps":["trace[64010133] 'process raft request' (duration: 123.65115ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:48:02.208316Z","caller":"traceutil/trace.go:171","msg":"trace[1631727870] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"124.44736ms","start":"2026-05-05T07:48:02.08383Z","end":"2026-05-05T07:48:02.208278Z","steps":["trace[1631727870] 'process raft request' (duration: 113.928521ms)","trace[1631727870] 'compare' (duration: 10.27994ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:48:02.208334Z","caller":"traceutil/trace.go:171","msg":"trace[946228859] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"124.244502ms","start":"2026-05-05T07:48:02.084067Z","end":"2026-05-05T07:48:02.208303Z","steps":["trace[946228859] 'process raft request' (duration: 124.078906ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:49:07.091584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.454039ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T07:49:07.091823Z","caller":"traceutil/trace.go:171","msg":"trace[1055705046] linearizableReadLoop","detail":"{readStateIndex:1257; appliedIndex:1255; }","duration":"349.358973ms","start":"2026-05-05T07:49:06.742447Z","end":"2026-05-05T07:49:07.091806Z","steps":["trace[1055705046] 'read index received' (duration: 27.431µs)","trace[1055705046] 'applied index is now lower than readState.Index' (duration: 349.330402ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:49:07.092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.553767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-05T07:49:07.092035Z","caller":"traceutil/trace.go:171","msg":"trace[811776357] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1210; }","duration":"349.612919ms","start":"2026-05-05T07:49:06.742412Z","end":"2026-05-05T07:49:07.092025Z","steps":["trace[811776357] 'agreement among raft nodes before linearized reading' (duration: 349.443315ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:49:07.09207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:49:06.742396Z","time spent":"349.66583ms","remote":"127.0.0.1:41336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-05T07:49:07.092068Z","caller":"traceutil/trace.go:171","msg":"trace[2035913551] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"471.198392ms","start":"2026-05-05T07:49:06.620838Z","end":"2026-05-05T07:49:07.092037Z","steps":["trace[2035913551] 'process raft request' (duration: 470.874334ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:49:07.092066Z","caller":"traceutil/trace.go:171","msg":"trace[1908049841] transaction","detail":"{read_only:false; response_revision:1209; number_of_response:1; }","duration":"472.180686ms","start":"2026-05-05T07:49:06.619854Z","end":"2026-05-05T07:49:07.092035Z","steps":["trace[1908049841] 'process raft request' (duration: 36.170855ms)","trace[1908049841] 'compare' (duration: 435.193701ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:49:07.092223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:49:06.619838Z","time spent":"472.303449ms","remote":"127.0.0.1:41336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T07:49:07.092249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:49:06.620832Z","time spent":"471.309675ms","remote":"127.0.0.1:41336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T07:49:56.941063Z","caller":"traceutil/trace.go:171","msg":"trace[1496900327] transaction","detail":"{read_only:false; response_revision:1372; number_of_response:1; }","duration":"128.998436ms","start":"2026-05-05T07:49:56.811983Z","end":"2026-05-05T07:49:56.940981Z","steps":["trace[1496900327] 'process raft request' (duration: 127.519582ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:49:57.084988Z","caller":"traceutil/trace.go:171","msg":"trace[1384843252] transaction","detail":"{read_only:false; response_revision:1373; number_of_response:1; }","duration":"138.920106ms","start":"2026-05-05T07:49:56.946039Z","end":"2026-05-05T07:49:57.084959Z","steps":["trace[1384843252] 'process raft request' (duration: 136.734485ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:49:57.543353Z","caller":"traceutil/trace.go:171","msg":"trace[1664570654] linearizableReadLoop","detail":"{readStateIndex:1431; appliedIndex:1430; }","duration":"130.9148ms","start":"2026-05-05T07:49:57.412409Z","end":"2026-05-05T07:49:57.543323Z","steps":["trace[1664570654] 'read index received' (duration: 130.712475ms)","trace[1664570654] 'applied index is now lower than readState.Index' (duration: 201.335µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:49:57.54338Z","caller":"traceutil/trace.go:171","msg":"trace[53029664] transaction","detail":"{read_only:false; response_revision:1374; number_of_response:1; }","duration":"145.4673ms","start":"2026-05-05T07:49:57.397891Z","end":"2026-05-05T07:49:57.543359Z","steps":["trace[53029664] 'process raft request' (duration: 145.291336ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:49:57.543579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.151615ms","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-05-05T07:49:57.543587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.105342ms","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-05-05T07:49:57.543618Z","caller":"traceutil/trace.go:171","msg":"trace[1081230752] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1374; }","duration":"131.241537ms","start":"2026-05-05T07:49:57.412366Z","end":"2026-05-05T07:49:57.543608Z","steps":["trace[1081230752] 'agreement among raft nodes before linearized reading' (duration: 131.085984ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:49:57.543637Z","caller":"traceutil/trace.go:171","msg":"trace[730535678] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1374; }","duration":"127.187674ms","start":"2026-05-05T07:49:57.416435Z","end":"2026-05-05T07:49:57.543623Z","steps":["trace[730535678] 'agreement among raft nodes before linearized reading' (duration: 127.051751ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:50:03.044114Z","caller":"traceutil/trace.go:171","msg":"trace[1456848957] transaction","detail":"{read_only:false; response_revision:1390; number_of_response:1; }","duration":"200.312167ms","start":"2026-05-05T07:50:02.843778Z","end":"2026-05-05T07:50:03.04409Z","steps":["trace[1456848957] 'process raft request' (duration: 200.163934ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:50:11.398147Z","caller":"traceutil/trace.go:171","msg":"trace[805456292] linearizableReadLoop","detail":"{readStateIndex:1476; appliedIndex:1475; }","duration":"175.900527ms","start":"2026-05-05T07:50:11.222223Z","end":"2026-05-05T07:50:11.398123Z","steps":["trace[805456292] 'read index received' (duration: 175.846306ms)","trace[805456292] 'applied index is now lower than readState.Index' (duration: 52.971µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:50:11.398292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.05296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T07:50:11.398273Z","caller":"traceutil/trace.go:171","msg":"trace[1656149488] transaction","detail":"{read_only:false; response_revision:1417; number_of_response:1; }","duration":"232.727751ms","start":"2026-05-05T07:50:11.165511Z","end":"2026-05-05T07:50:11.398239Z","steps":["trace[1656149488] 'process raft request' (duration: 232.480805ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:50:11.398325Z","caller":"traceutil/trace.go:171","msg":"trace[172315791] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1417; }","duration":"176.144592ms","start":"2026-05-05T07:50:11.222171Z","end":"2026-05-05T07:50:11.398316Z","steps":["trace[172315791] 'agreement among raft nodes before linearized reading' (duration: 176.061721ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:50:11.898836Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794899030166839455,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T07:50:12.389315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.816877ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T07:50:12.389446Z","caller":"traceutil/trace.go:171","msg":"trace[1293967948] transaction","detail":"{read_only:false; response_revision:1418; number_of_response:1; }","duration":"1.102328536s","start":"2026-05-05T07:50:11.287098Z","end":"2026-05-05T07:50:12.389427Z","steps":["trace[1293967948] 'process raft request' (duration: 658.305374ms)","trace[1293967948] 'compare' (duration: 443.692234ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:50:12.389509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:50:11.287075Z","time spent":"1.102401418s","remote":"127.0.0.1:41336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T07:50:12.813408Z","caller":"traceutil/trace.go:171","msg":"trace[2132984805] linearizableReadLoop","detail":"{readStateIndex:1478; appliedIndex:1476; }","duration":"1.415170973s","start":"2026-05-05T07:50:11.398212Z","end":"2026-05-05T07:50:12.813383Z","steps":["trace[2132984805] 'read index received' (duration: 547.196076ms)","trace[2132984805] 'applied index is now lower than readState.Index' (duration: 867.973317ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:50:12.813741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.590982308s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T07:50:12.813888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.086361683s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-05T07:50:12.81387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.305286103s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T07:50:12.813909Z","caller":"traceutil/trace.go:171","msg":"trace[2112746705] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1418; }","duration":"1.086407895s","start":"2026-05-05T07:50:11.727495Z","end":"2026-05-05T07:50:12.813903Z","steps":["trace[2112746705] 'agreement among raft nodes before linearized reading' (duration: 1.086309733s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:50:12.813899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.475126409s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-05T07:50:12.813914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"920.078711ms","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-05-05T07:50:12.813942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:50:11.72748Z","time spent":"1.086456056s","remote":"127.0.0.1:41336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-05T07:50:12.813956Z","caller":"traceutil/trace.go:171","msg":"trace[66844035] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1418; }","duration":"1.305423366s","start":"2026-05-05T07:50:11.508504Z","end":"2026-05-05T07:50:12.813928Z","steps":["trace[66844035] 'agreement among raft nodes before linearized reading' (duration: 1.305259913s)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:50:12.813975Z","caller":"traceutil/trace.go:171","msg":"trace[725519759] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1418; }","duration":"920.164432ms","start":"2026-05-05T07:50:11.893792Z","end":"2026-05-05T07:50:12.813956Z","steps":["trace[725519759] 'agreement among raft nodes before linearized reading' (duration: 919.999528ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:50:12.813964Z","caller":"traceutil/trace.go:171","msg":"trace[1687364775] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1418; }","duration":"1.475233082s","start":"2026-05-05T07:50:11.338716Z","end":"2026-05-05T07:50:12.813949Z","steps":["trace[1687364775] 'agreement among raft nodes before linearized reading' (duration: 1.474989716s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:50:12.814005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:50:11.893782Z","time spent":"920.217444ms","remote":"127.0.0.1:41336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T07:50:12.813979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"942.960313ms","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-05-05T07:50:12.814007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:50:11.508487Z","time spent":"1.305505888s","remote":"127.0.0.1:43104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":31,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-05T07:50:12.814055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:50:11.338698Z","time spent":"1.475319034s","remote":"127.0.0.1:41336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-05T07:50:12.814054Z","caller":"traceutil/trace.go:171","msg":"trace[1523869126] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1418; }","duration":"943.076726ms","start":"2026-05-05T07:50:11.870959Z","end":"2026-05-05T07:50:12.814035Z","steps":["trace[1523869126] 'agreement among raft nodes before linearized reading' (duration: 942.83754ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:50:12.814097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:50:11.870942Z","time spent":"943.146947ms","remote":"127.0.0.1:41336","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\" "} {"level":"info","ts":"2026-05-05T07:50:12.813778Z","caller":"traceutil/trace.go:171","msg":"trace[690681649] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1418; }","duration":"1.59104275s","start":"2026-05-05T07:50:11.222719Z","end":"2026-05-05T07:50:12.813762Z","steps":["trace[690681649] 'agreement among raft nodes before linearized reading' (duration: 1.590976848s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:50:12.814223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:50:11.222707Z","time spent":"1.59150728s","remote":"127.0.0.1:41044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-05T07:50:37.055756Z","caller":"traceutil/trace.go:171","msg":"trace[1496301068] linearizableReadLoop","detail":"{readStateIndex:1564; appliedIndex:1563; }","duration":"117.051707ms","start":"2026-05-05T07:50:36.938682Z","end":"2026-05-05T07:50:37.055734Z","steps":["trace[1496301068] 'read index received' (duration: 116.837232ms)","trace[1496301068] 'applied index is now lower than readState.Index' (duration: 213.625µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:50:37.05613Z","caller":"traceutil/trace.go:171","msg":"trace[518056705] transaction","detail":"{read_only:false; response_revision:1499; number_of_response:1; }","duration":"128.967905ms","start":"2026-05-05T07:50:36.927112Z","end":"2026-05-05T07:50:37.05608Z","steps":["trace[518056705] 'process raft request' (duration: 128.502074ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:50:37.0562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.634297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-05T07:50:37.056195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.498608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-05T07:50:37.056247Z","caller":"traceutil/trace.go:171","msg":"trace[216922787] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1499; }","duration":"107.718049ms","start":"2026-05-05T07:50:36.948516Z","end":"2026-05-05T07:50:37.056234Z","steps":["trace[216922787] 'agreement among raft nodes before linearized reading' (duration: 107.579656ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:50:37.056258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.817605ms","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-05-05T07:50:37.056295Z","caller":"traceutil/trace.go:171","msg":"trace[1278056005] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1499; }","duration":"117.64102ms","start":"2026-05-05T07:50:36.938636Z","end":"2026-05-05T07:50:37.056277Z","steps":["trace[1278056005] 'agreement among raft nodes before linearized reading' (duration: 117.321723ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:50:37.056345Z","caller":"traceutil/trace.go:171","msg":"trace[1255207059] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1499; }","duration":"108.958038ms","start":"2026-05-05T07:50:36.947368Z","end":"2026-05-05T07:50:37.056326Z","steps":["trace[1255207059] 'agreement among raft nodes before linearized reading' (duration: 108.701182ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:50:37.239189Z","caller":"traceutil/trace.go:171","msg":"trace[1529525930] transaction","detail":"{read_only:false; response_revision:1500; number_of_response:1; }","duration":"178.15971ms","start":"2026-05-05T07:50:37.061Z","end":"2026-05-05T07:50:37.239159Z","steps":["trace[1529525930] 'process raft request' (duration: 163.897248ms)","trace[1529525930] 'compare' (duration: 14.134329ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:50:37.245277Z","caller":"traceutil/trace.go:171","msg":"trace[1474483158] transaction","detail":"{read_only:false; response_revision:1501; number_of_response:1; }","duration":"184.195301ms","start":"2026-05-05T07:50:37.061035Z","end":"2026-05-05T07:50:37.24523Z","steps":["trace[1474483158] 'process raft request' (duration: 183.966135ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:50:37.245311Z","caller":"traceutil/trace.go:171","msg":"trace[979919645] transaction","detail":"{read_only:false; response_revision:1502; number_of_response:1; }","duration":"184.193481ms","start":"2026-05-05T07:50:37.061095Z","end":"2026-05-05T07:50:37.245288Z","steps":["trace[979919645] 'process raft request' (duration: 184.025757ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:50:39.393889Z","caller":"traceutil/trace.go:171","msg":"trace[244725905] transaction","detail":"{read_only:false; response_revision:1506; number_of_response:1; }","duration":"146.762579ms","start":"2026-05-05T07:50:39.246659Z","end":"2026-05-05T07:50:39.393422Z","steps":["trace[244725905] 'process raft request' (duration: 146.514713ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:50:39.393405Z","caller":"traceutil/trace.go:171","msg":"trace[453141525] linearizableReadLoop","detail":"{readStateIndex:1571; appliedIndex:1570; }","duration":"143.888222ms","start":"2026-05-05T07:50:39.249489Z","end":"2026-05-05T07:50:39.393378Z","steps":["trace[453141525] 'read index received' (duration: 143.612545ms)","trace[453141525] 'applied index is now lower than readState.Index' (duration: 274.067µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:50:39.394325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.434244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-05T07:50:39.394386Z","caller":"traceutil/trace.go:171","msg":"trace[1043388971] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1506; }","duration":"144.511716ms","start":"2026-05-05T07:50:39.249846Z","end":"2026-05-05T07:50:39.394357Z","steps":["trace[1043388971] 'agreement among raft nodes before linearized reading' (duration: 144.362912ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:50:39.395117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.624042ms","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-05-05T07:50:39.395204Z","caller":"traceutil/trace.go:171","msg":"trace[1750733872] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1506; }","duration":"145.734285ms","start":"2026-05-05T07:50:39.249435Z","end":"2026-05-05T07:50:39.395169Z","steps":["trace[1750733872] 'agreement among raft nodes before linearized reading' (duration: 145.160491ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:50:39.692911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.745867ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T07:50:39.693068Z","caller":"traceutil/trace.go:171","msg":"trace[1591526500] linearizableReadLoop","detail":"{readStateIndex:1573; appliedIndex:1571; }","duration":"159.998187ms","start":"2026-05-05T07:50:39.533057Z","end":"2026-05-05T07:50:39.693055Z","steps":["trace[1591526500] 'read index received' (duration: 155.287468ms)","trace[1591526500] 'applied index is now lower than readState.Index' (duration: 4.710099ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:50:39.693156Z","caller":"traceutil/trace.go:171","msg":"trace[759894744] transaction","detail":"{read_only:false; response_revision:1508; number_of_response:1; }","duration":"292.124285ms","start":"2026-05-05T07:50:39.401Z","end":"2026-05-05T07:50:39.693125Z","steps":["trace[759894744] 'process raft request' (duration: 291.993312ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:50:39.693223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.169001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-05T07:50:39.693262Z","caller":"traceutil/trace.go:171","msg":"trace[1364986428] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1508; }","duration":"160.247813ms","start":"2026-05-05T07:50:39.533005Z","end":"2026-05-05T07:50:39.693253Z","steps":["trace[1364986428] 'agreement among raft nodes before linearized reading' (duration: 160.098379ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:50:39.693299Z","caller":"traceutil/trace.go:171","msg":"trace[1928433972] transaction","detail":"{read_only:false; response_revision:1507; number_of_response:1; }","duration":"292.408611ms","start":"2026-05-05T07:50:39.400854Z","end":"2026-05-05T07:50:39.693263Z","steps":["trace[1928433972] 'process raft request' (duration: 100.227455ms)","trace[1928433972] 'compare' (duration: 191.297695ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:50:39.908158Z","caller":"traceutil/trace.go:171","msg":"trace[56159153] transaction","detail":"{read_only:false; response_revision:1509; number_of_response:1; }","duration":"211.116297ms","start":"2026-05-05T07:50:39.697013Z","end":"2026-05-05T07:50:39.908129Z","steps":["trace[56159153] 'process raft request' (duration: 111.484186ms)","trace[56159153] 'compare' (duration: 99.509058ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:50:43.894652Z","caller":"traceutil/trace.go:171","msg":"trace[35100701] transaction","detail":"{read_only:false; response_revision:1520; number_of_response:1; }","duration":"185.358945ms","start":"2026-05-05T07:50:43.709268Z","end":"2026-05-05T07:50:43.894627Z","steps":["trace[35100701] 'process raft request' (duration: 185.17374ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:50:43.896474Z","caller":"traceutil/trace.go:171","msg":"trace[189245317] transaction","detail":"{read_only:false; response_revision:1521; number_of_response:1; }","duration":"186.00856ms","start":"2026-05-05T07:50:43.71041Z","end":"2026-05-05T07:50:43.896419Z","steps":["trace[189245317] 'process raft request' (duration: 185.694742ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:50:52.807052Z","caller":"traceutil/trace.go:171","msg":"trace[1807824426] transaction","detail":"{read_only:false; response_revision:1601; number_of_response:1; }","duration":"187.849704ms","start":"2026-05-05T07:50:52.619179Z","end":"2026-05-05T07:50:52.807028Z","steps":["trace[1807824426] 'process raft request' (duration: 187.704911ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:50:54.850227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"814.634316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-05T07:50:54.850322Z","caller":"traceutil/trace.go:171","msg":"trace[508053799] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1604; }","duration":"814.775269ms","start":"2026-05-05T07:50:54.035529Z","end":"2026-05-05T07:50:54.850304Z","steps":["trace[508053799] 'range keys from in-memory index tree' (duration: 814.452412ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:50:54.850366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:50:54.035509Z","time spent":"814.846181ms","remote":"127.0.0.1:41336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-05T07:50:54.850376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.457159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T07:50:54.850423Z","caller":"traceutil/trace.go:171","msg":"trace[609358394] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1604; }","duration":"280.519131ms","start":"2026-05-05T07:50:54.569893Z","end":"2026-05-05T07:50:54.850412Z","steps":["trace[609358394] 'count revisions from in-memory index tree' (duration: 280.402728ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:50:54.850604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"812.159936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-05T07:50:54.850644Z","caller":"traceutil/trace.go:171","msg":"trace[2135526022] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1604; }","duration":"812.242089ms","start":"2026-05-05T07:50:54.038392Z","end":"2026-05-05T07:50:54.850634Z","steps":["trace[2135526022] 'range keys from in-memory index tree' (duration: 812.037924ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:50:54.85077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:50:54.03837Z","time spent":"812.391662ms","remote":"127.0.0.1:41336","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-05-05T07:50:54.850824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.715588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T07:50:54.850868Z","caller":"traceutil/trace.go:171","msg":"trace[398731338] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1604; }","duration":"629.003935ms","start":"2026-05-05T07:50:54.221851Z","end":"2026-05-05T07:50:54.850855Z","steps":["trace[398731338] 'range keys from in-memory index tree' (duration: 628.661937ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:50:54.850903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:50:54.221837Z","time spent":"629.056406ms","remote":"127.0.0.1:41028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T07:51:07.227393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.142109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-05-05T07:51:07.227413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.750524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-05T07:51:07.22745Z","caller":"traceutil/trace.go:171","msg":"trace[549478254] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1646; }","duration":"262.231022ms","start":"2026-05-05T07:51:06.965206Z","end":"2026-05-05T07:51:07.227438Z","steps":["trace[549478254] 'range keys from in-memory index tree' (duration: 262.028417ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:51:07.227301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.860178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T07:51:07.227468Z","caller":"traceutil/trace.go:171","msg":"trace[1616514403] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1646; }","duration":"262.849446ms","start":"2026-05-05T07:51:06.964605Z","end":"2026-05-05T07:51:07.227454Z","steps":["trace[1616514403] 'range keys from in-memory index tree' (duration: 262.617221ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:51:07.22751Z","caller":"traceutil/trace.go:171","msg":"trace[1297683594] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1646; }","duration":"216.139304ms","start":"2026-05-05T07:51:07.011349Z","end":"2026-05-05T07:51:07.227489Z","steps":["trace[1297683594] 'count revisions from in-memory index tree' (duration: 215.765405ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:51:07.347164Z","caller":"traceutil/trace.go:171","msg":"trace[1128454605] transaction","detail":"{read_only:false; response_revision:1647; number_of_response:1; }","duration":"115.980827ms","start":"2026-05-05T07:51:07.231131Z","end":"2026-05-05T07:51:07.347112Z","steps":["trace[1128454605] 'process raft request' (duration: 115.798002ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:51:08.069489Z","caller":"traceutil/trace.go:171","msg":"trace[306907495] linearizableReadLoop","detail":"{readStateIndex:1721; appliedIndex:1719; }","duration":"797.948646ms","start":"2026-05-05T07:51:07.271516Z","end":"2026-05-05T07:51:08.069465Z","steps":["trace[306907495] 'read index received' (duration: 75.375652ms)","trace[306907495] 'applied index is now lower than readState.Index' (duration: 722.571984ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:51:08.069581Z","caller":"traceutil/trace.go:171","msg":"trace[1112041929] transaction","detail":"{read_only:false; response_revision:1648; number_of_response:1; }","duration":"837.985757ms","start":"2026-05-05T07:51:07.231558Z","end":"2026-05-05T07:51:08.069544Z","steps":["trace[1112041929] 'process raft request' (duration: 837.743272ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:51:08.069764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:51:07.231544Z","time spent":"838.11964ms","remote":"127.0.0.1:41336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T07:51:08.069814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.207692ms","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":"warn","ts":"2026-05-05T07:51:08.069851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.715359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-05-05T07:51:08.069784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.664531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-05T07:51:08.069855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"798.292774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-05T07:51:08.06993Z","caller":"traceutil/trace.go:171","msg":"trace[2048924128] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1648; }","duration":"711.856526ms","start":"2026-05-05T07:51:07.358059Z","end":"2026-05-05T07:51:08.069916Z","steps":["trace[2048924128] 'agreement among raft nodes before linearized reading' (duration: 711.661732ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:51:08.069884Z","caller":"traceutil/trace.go:171","msg":"trace[779132874] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1648; }","duration":"524.78046ms","start":"2026-05-05T07:51:07.545095Z","end":"2026-05-05T07:51:08.069875Z","steps":["trace[779132874] 'agreement among raft nodes before linearized reading' (duration: 524.641797ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:51:08.069989Z","caller":"traceutil/trace.go:171","msg":"trace[696765973] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1648; }","duration":"798.483738ms","start":"2026-05-05T07:51:07.271477Z","end":"2026-05-05T07:51:08.069961Z","steps":["trace[696765973] 'agreement among raft nodes before linearized reading' (duration: 798.099319ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:51:08.070069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:51:07.54508Z","time spent":"524.956805ms","remote":"127.0.0.1:41336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T07:51:08.070055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:51:07.358046Z","time spent":"711.971069ms","remote":"127.0.0.1:41344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":5,"response size":31,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-05-05T07:51:08.070133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:51:07.271434Z","time spent":"798.655543ms","remote":"127.0.0.1:41336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-05T07:51:08.069886Z","caller":"traceutil/trace.go:171","msg":"trace[822865243] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1648; }","duration":"354.323335ms","start":"2026-05-05T07:51:07.715545Z","end":"2026-05-05T07:51:08.069868Z","steps":["trace[822865243] 'agreement among raft nodes before linearized reading' (duration: 354.190212ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:51:08.070266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:51:07.71553Z","time spent":"354.711984ms","remote":"127.0.0.1:41426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-05-05T07:51:08.906517Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794899030166840026,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T07:51:09.143523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"999.027217ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T07:51:09.143748Z","caller":"traceutil/trace.go:171","msg":"trace[2008333741] linearizableReadLoop","detail":"{readStateIndex:1724; appliedIndex:1721; }","duration":"738.21946ms","start":"2026-05-05T07:51:08.405508Z","end":"2026-05-05T07:51:09.143728Z","steps":["trace[2008333741] 'read index received' (duration: 639.509187ms)","trace[2008333741] 'applied index is now lower than readState.Index' (duration: 98.709263ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:51:09.143838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"738.326443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T07:51:09.143871Z","caller":"traceutil/trace.go:171","msg":"trace[1739786299] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1651; }","duration":"738.396674ms","start":"2026-05-05T07:51:08.405461Z","end":"2026-05-05T07:51:09.143858Z","steps":["trace[1739786299] 'agreement among raft nodes before linearized reading' (duration: 738.325042ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:51:09.143905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:51:08.405442Z","time spent":"738.454136ms","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"info","ts":"2026-05-05T07:51:09.143961Z","caller":"traceutil/trace.go:171","msg":"trace[1114777316] transaction","detail":"{read_only:false; response_revision:1649; number_of_response:1; }","duration":"1.065973816s","start":"2026-05-05T07:51:08.077952Z","end":"2026-05-05T07:51:09.143926Z","steps":["trace[1114777316] 'process raft request' (duration: 66.433117ms)","trace[1114777316] 'compare' (duration: 998.673029ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:51:09.144217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:51:08.077937Z","time spent":"1.066190971s","remote":"127.0.0.1:41336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T07:51:09.144335Z","caller":"traceutil/trace.go:171","msg":"trace[250616749] transaction","detail":"{read_only:false; response_revision:1650; number_of_response:1; }","duration":"1.066046038s","start":"2026-05-05T07:51:08.078275Z","end":"2026-05-05T07:51:09.144321Z","steps":["trace[250616749] 'process raft request' (duration: 1.065345971s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:51:09.144546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:51:08.078253Z","time spent":"1.06614783s","remote":"127.0.0.1:41336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T07:51:09.144869Z","caller":"traceutil/trace.go:171","msg":"trace[1092648551] transaction","detail":"{read_only:false; response_revision:1651; number_of_response:1; }","duration":"1.065442233s","start":"2026-05-05T07:51:08.078599Z","end":"2026-05-05T07:51:09.144041Z","steps":["trace[1092648551] 'process raft request' (duration: 1.065096055s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:51:09.144938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:51:08.078593Z","time spent":"1.066310364s","remote":"127.0.0.1:41336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T07:51:10.454851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"971.002143ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T07:51:10.45497Z","caller":"traceutil/trace.go:171","msg":"trace[1901363957] linearizableReadLoop","detail":"{readStateIndex:1725; appliedIndex:1724; }","duration":"1.235295764s","start":"2026-05-05T07:51:09.219654Z","end":"2026-05-05T07:51:10.45495Z","steps":["trace[1901363957] 'read index received' (duration: 264.071126ms)","trace[1901363957] 'applied index is now lower than readState.Index' (duration: 971.223078ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:51:10.455127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.235476568s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T07:51:10.455181Z","caller":"traceutil/trace.go:171","msg":"trace[650230448] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1652; }","duration":"1.23555518s","start":"2026-05-05T07:51:09.219615Z","end":"2026-05-05T07:51:10.45517Z","steps":["trace[650230448] 'agreement among raft nodes before linearized reading' (duration: 1.235462968s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:51:10.455209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.104848999s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-05-05T07:51:10.455222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:51:09.219599Z","time spent":"1.235614911s","remote":"127.0.0.1:41028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-05T07:51:10.455251Z","caller":"traceutil/trace.go:171","msg":"trace[418691162] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1652; }","duration":"1.104916791s","start":"2026-05-05T07:51:09.350324Z","end":"2026-05-05T07:51:10.45524Z","steps":["trace[418691162] 'agreement among raft nodes before linearized reading' (duration: 1.104746817s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:51:10.455284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:51:09.350308Z","time spent":"1.104968722s","remote":"127.0.0.1:41336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-05T07:51:10.455657Z","caller":"traceutil/trace.go:171","msg":"trace[1938797969] transaction","detail":"{read_only:false; response_revision:1652; number_of_response:1; }","duration":"1.502583957s","start":"2026-05-05T07:51:08.952453Z","end":"2026-05-05T07:51:10.455037Z","steps":["trace[1938797969] 'process raft request' (duration: 531.297157ms)","trace[1938797969] 'compare' (duration: 970.555073ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:51:10.455751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.395776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-05T07:51:10.45583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:51:08.952434Z","time spent":"1.503287515s","remote":"127.0.0.1:41336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T07:51:10.455829Z","caller":"traceutil/trace.go:171","msg":"trace[1520195092] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1652; }","duration":"381.504498ms","start":"2026-05-05T07:51:10.074303Z","end":"2026-05-05T07:51:10.455808Z","steps":["trace[1520195092] 'agreement among raft nodes before linearized reading' (duration: 381.260092ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:51:10.455886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:51:10.074291Z","time spent":"381.583029ms","remote":"127.0.0.1:41336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-05T07:51:10.699183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.980995ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T07:51:10.699326Z","caller":"traceutil/trace.go:171","msg":"trace[138809099] transaction","detail":"{read_only:false; response_revision:1653; number_of_response:1; }","duration":"240.236513ms","start":"2026-05-05T07:51:10.459062Z","end":"2026-05-05T07:51:10.699298Z","steps":["trace[138809099] 'process raft request' (duration: 102.045892ms)","trace[138809099] 'compare' (duration: 137.826062ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:51:10.717833Z","caller":"traceutil/trace.go:171","msg":"trace[2113949455] linearizableReadLoop","detail":"{readStateIndex:1727; appliedIndex:1725; }","duration":"254.395473ms","start":"2026-05-05T07:51:10.463415Z","end":"2026-05-05T07:51:10.71781Z","steps":["trace[2113949455] 'read index received' (duration: 97.703968ms)","trace[2113949455] 'applied index is now lower than readState.Index' (duration: 156.690645ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:51:10.717959Z","caller":"traceutil/trace.go:171","msg":"trace[300663837] transaction","detail":"{read_only:false; response_revision:1654; number_of_response:1; }","duration":"258.635765ms","start":"2026-05-05T07:51:10.459309Z","end":"2026-05-05T07:51:10.717945Z","steps":["trace[300663837] 'process raft request' (duration: 258.359288ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:51:10.71813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.705711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T07:51:10.718136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.492016ms","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":"info","ts":"2026-05-05T07:51:10.718162Z","caller":"traceutil/trace.go:171","msg":"trace[732342234] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1654; }","duration":"254.778673ms","start":"2026-05-05T07:51:10.463376Z","end":"2026-05-05T07:51:10.718155Z","steps":["trace[732342234] 'agreement among raft nodes before linearized reading' (duration: 254.718831ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:51:10.718184Z","caller":"traceutil/trace.go:171","msg":"trace[118744033] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1654; }","duration":"135.592998ms","start":"2026-05-05T07:51:10.582579Z","end":"2026-05-05T07:51:10.718172Z","steps":["trace[118744033] 'agreement among raft nodes before linearized reading' (duration: 135.474475ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:51:11.602802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.589738ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-05-05T07:51:16.897291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.719531ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T07:51:16.89765Z","caller":"traceutil/trace.go:171","msg":"trace[1566824511] linearizableReadLoop","detail":"{readStateIndex:1784; appliedIndex:1783; }","duration":"177.173666ms","start":"2026-05-05T07:51:16.720454Z","end":"2026-05-05T07:51:16.897628Z","steps":["trace[1566824511] 'read index received' (duration: 39.43µs)","trace[1566824511] 'applied index is now lower than readState.Index' (duration: 177.132036ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:51:16.897788Z","caller":"traceutil/trace.go:171","msg":"trace[961048682] transaction","detail":"{read_only:false; response_revision:1709; number_of_response:1; }","duration":"426.536179ms","start":"2026-05-05T07:51:16.471224Z","end":"2026-05-05T07:51:16.89776Z","steps":["trace[961048682] 'process raft request' (duration: 69.247305ms)","trace[961048682] 'compare' (duration: 356.604618ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:51:16.897957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.484825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-05T07:51:16.897994Z","caller":"traceutil/trace.go:171","msg":"trace[299566651] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1709; }","duration":"177.572596ms","start":"2026-05-05T07:51:16.720413Z","end":"2026-05-05T07:51:16.897986Z","steps":["trace[299566651] 'agreement among raft nodes before linearized reading' (duration: 177.421122ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:51:16.897984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:51:16.471202Z","time spent":"426.709263ms","remote":"127.0.0.1:41336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T07:51:16.898227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.357008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-05T07:51:16.898341Z","caller":"traceutil/trace.go:171","msg":"trace[957539290] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1709; }","duration":"159.503912ms","start":"2026-05-05T07:51:16.738819Z","end":"2026-05-05T07:51:16.898323Z","steps":["trace[957539290] 'agreement among raft nodes before linearized reading' (duration: 159.275356ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:51:17.681668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.782102ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T07:51:17.681992Z","caller":"traceutil/trace.go:171","msg":"trace[1633303009] linearizableReadLoop","detail":"{readStateIndex:1786; appliedIndex:1784; }","duration":"497.674969ms","start":"2026-05-05T07:51:17.184299Z","end":"2026-05-05T07:51:17.681974Z","steps":["trace[1633303009] 'read index received' (duration: 66.495048ms)","trace[1633303009] 'applied index is now lower than readState.Index' (duration: 431.178701ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:51:17.682167Z","caller":"traceutil/trace.go:171","msg":"trace[1753484636] transaction","detail":"{read_only:false; response_revision:1710; number_of_response:1; }","duration":"781.269383ms","start":"2026-05-05T07:51:16.900883Z","end":"2026-05-05T07:51:17.682153Z","steps":["trace[1753484636] 'process raft request' (duration: 349.941458ms)","trace[1753484636] 'compare' (duration: 430.70127ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:51:17.682299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:51:16.900857Z","time spent":"781.339865ms","remote":"127.0.0.1:41336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T07:51:17.682445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.149241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-05T07:51:17.682482Z","caller":"traceutil/trace.go:171","msg":"trace[949638082] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1711; }","duration":"498.219692ms","start":"2026-05-05T07:51:17.184254Z","end":"2026-05-05T07:51:17.682474Z","steps":["trace[949638082] 'agreement among raft nodes before linearized reading' (duration: 498.080029ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:51:17.682502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:51:17.184237Z","time spent":"498.260334ms","remote":"127.0.0.1:41336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T07:51:17.682622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.835426ms","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-05-05T07:51:17.68272Z","caller":"traceutil/trace.go:171","msg":"trace[81975745] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1711; }","duration":"448.972759ms","start":"2026-05-05T07:51:17.233727Z","end":"2026-05-05T07:51:17.6827Z","steps":["trace[81975745] 'agreement among raft nodes before linearized reading' (duration: 448.699543ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:51:17.682792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:51:17.23371Z","time spent":"449.069231ms","remote":"127.0.0.1:41336","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\" "} {"level":"info","ts":"2026-05-05T07:51:17.682923Z","caller":"traceutil/trace.go:171","msg":"trace[1618666693] transaction","detail":"{read_only:false; response_revision:1711; number_of_response:1; }","duration":"780.524516ms","start":"2026-05-05T07:51:16.902045Z","end":"2026-05-05T07:51:17.682569Z","steps":["trace[1618666693] 'process raft request' (duration: 779.719957ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:51:17.683016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:51:16.902018Z","time spent":"780.931035ms","remote":"127.0.0.1:41336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T07:51:17.68332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.307545ms","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-05-05T07:51:17.684062Z","caller":"traceutil/trace.go:171","msg":"trace[1120442161] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1711; }","duration":"447.096694ms","start":"2026-05-05T07:51:17.236951Z","end":"2026-05-05T07:51:17.684048Z","steps":["trace[1120442161] 'agreement among raft nodes before linearized reading' (duration: 445.720371ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:51:17.684116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:51:17.236933Z","time spent":"447.161415ms","remote":"127.0.0.1:41336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T07:51:18.218232Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794899030166840152,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-05T07:51:18.34454Z","caller":"traceutil/trace.go:171","msg":"trace[138980812] transaction","detail":"{read_only:false; response_revision:1713; number_of_response:1; }","duration":"656.524547ms","start":"2026-05-05T07:51:17.687987Z","end":"2026-05-05T07:51:18.344512Z","steps":["trace[138980812] 'process raft request' (duration: 656.413904ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:51:18.344708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:51:17.687962Z","time spent":"656.658389ms","remote":"127.0.0.1:41336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T07:51:18.344899Z","caller":"traceutil/trace.go:171","msg":"trace[1345388287] transaction","detail":"{read_only:false; response_revision:1712; number_of_response:1; }","duration":"656.903005ms","start":"2026-05-05T07:51:17.687961Z","end":"2026-05-05T07:51:18.344864Z","steps":["trace[1345388287] 'process raft request' (duration: 601.915504ms)","trace[1345388287] 'compare' (duration: 54.10076ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:51:18.345764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:51:17.687931Z","time spent":"657.09506ms","remote":"127.0.0.1:41336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T07:51:18.357742Z","caller":"traceutil/trace.go:171","msg":"trace[414834386] linearizableReadLoop","detail":"{readStateIndex:1789; appliedIndex:1786; }","duration":"640.158493ms","start":"2026-05-05T07:51:17.717565Z","end":"2026-05-05T07:51:18.357723Z","steps":["trace[414834386] 'read index received' (duration: 572.321523ms)","trace[414834386] 'applied index is now lower than readState.Index' (duration: 67.83615ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:51:18.357798Z","caller":"traceutil/trace.go:171","msg":"trace[1625526288] transaction","detail":"{read_only:false; response_revision:1714; number_of_response:1; }","duration":"669.703883ms","start":"2026-05-05T07:51:17.68807Z","end":"2026-05-05T07:51:18.357774Z","steps":["trace[1625526288] 'process raft request' (duration: 669.510249ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:51:18.357903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:51:17.688033Z","time spent":"669.811055ms","remote":"127.0.0.1:41336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T07:51:18.357946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.370968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:1948"} {"level":"info","ts":"2026-05-05T07:51:18.357973Z","caller":"traceutil/trace.go:171","msg":"trace[1716655695] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1714; }","duration":"640.45375ms","start":"2026-05-05T07:51:17.717511Z","end":"2026-05-05T07:51:18.357965Z","steps":["trace[1716655695] 'agreement among raft nodes before linearized reading' (duration: 640.328487ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:51:18.358005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:51:17.717488Z","time spent":"640.510842ms","remote":"127.0.0.1:41264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":1972,"request content":"key:\"/registry/pods/default/test-pvc-pod\" "} {"level":"warn","ts":"2026-05-05T07:51:18.768802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.978051ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-05T07:51:18.769118Z","caller":"traceutil/trace.go:171","msg":"trace[567400785] linearizableReadLoop","detail":"{readStateIndex:1790; appliedIndex:1789; }","duration":"411.286114ms","start":"2026-05-05T07:51:18.357814Z","end":"2026-05-05T07:51:18.7691Z","steps":["trace[567400785] 'read index received' (duration: 159.841821ms)","trace[567400785] 'applied index is now lower than readState.Index' (duration: 251.442253ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:51:18.769201Z","caller":"traceutil/trace.go:171","msg":"trace[1822812113] transaction","detail":"{read_only:false; response_revision:1715; number_of_response:1; }","duration":"455.402714ms","start":"2026-05-05T07:51:18.31378Z","end":"2026-05-05T07:51:18.769182Z","steps":["trace[1822812113] 'process raft request' (duration: 203.971322ms)","trace[1822812113] 'compare' (duration: 250.825407ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:51:18.769223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"871.581274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T07:51:18.769254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.872467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T07:51:18.769255Z","caller":"traceutil/trace.go:171","msg":"trace[191986084] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1715; }","duration":"871.658146ms","start":"2026-05-05T07:51:17.897586Z","end":"2026-05-05T07:51:18.769245Z","steps":["trace[191986084] 'agreement among raft nodes before linearized reading' (duration: 871.564504ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:51:18.769274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:51:18.313762Z","time spent":"455.480955ms","remote":"127.0.0.1:41132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T07:51:18.769295Z","caller":"traceutil/trace.go:171","msg":"trace[1738620378] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1715; }","duration":"545.943059ms","start":"2026-05-05T07:51:18.223341Z","end":"2026-05-05T07:51:18.769285Z","steps":["trace[1738620378] 'agreement among raft nodes before linearized reading' (duration: 545.880878ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:51:18.769304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:51:17.897567Z","time spent":"871.726507ms","remote":"127.0.0.1:42934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-05-05T07:51:18.769348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:51:18.223323Z","time spent":"545.994731ms","remote":"127.0.0.1:41028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T07:51:19.261376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.409151ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-05T07:51:19.261473Z","caller":"traceutil/trace.go:171","msg":"trace[1844979060] linearizableReadLoop","detail":"{readStateIndex:1792; appliedIndex:1791; }","duration":"215.061488ms","start":"2026-05-05T07:51:19.046396Z","end":"2026-05-05T07:51:19.261457Z","steps":["trace[1844979060] 'read index received' (duration: 37.481µs)","trace[1844979060] 'applied index is now lower than readState.Index' (duration: 215.020347ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:51:19.261572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.200922ms","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-05-05T07:51:19.261605Z","caller":"traceutil/trace.go:171","msg":"trace[1415089882] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1717; }","duration":"215.272323ms","start":"2026-05-05T07:51:19.046323Z","end":"2026-05-05T07:51:19.261596Z","steps":["trace[1415089882] 'agreement among raft nodes before linearized reading' (duration: 215.17838ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:51:19.261767Z","caller":"traceutil/trace.go:171","msg":"trace[1907750540] transaction","detail":"{read_only:false; response_revision:1717; number_of_response:1; }","duration":"293.593795ms","start":"2026-05-05T07:51:18.968146Z","end":"2026-05-05T07:51:19.26174Z","steps":["trace[1907750540] 'process raft request' (duration: 51.731053ms)","trace[1907750540] 'compare' (duration: 241.225417ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:51:19.783983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.941704ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-05T07:51:19.784063Z","caller":"traceutil/trace.go:171","msg":"trace[112384485] transaction","detail":"{read_only:false; response_revision:1718; number_of_response:1; }","duration":"518.655544ms","start":"2026-05-05T07:51:19.265394Z","end":"2026-05-05T07:51:19.784049Z","steps":["trace[112384485] 'process raft request' (duration: 239.584017ms)","trace[112384485] 'compare' (duration: 278.744059ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:51:19.784327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:51:19.265371Z","time spent":"518.93258ms","remote":"127.0.0.1:41132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":780,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T07:51:19.784814Z","caller":"traceutil/trace.go:171","msg":"trace[1731578309] linearizableReadLoop","detail":"{readStateIndex:1794; appliedIndex:1793; }","duration":"189.76072ms","start":"2026-05-05T07:51:19.595044Z","end":"2026-05-05T07:51:19.784804Z","steps":["trace[1731578309] 'read index received' (duration: 189.531324ms)","trace[1731578309] 'applied index is now lower than readState.Index' (duration: 228.836µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:51:19.784864Z","caller":"traceutil/trace.go:171","msg":"trace[1797130786] transaction","detail":"{read_only:false; response_revision:1719; number_of_response:1; }","duration":"517.482305ms","start":"2026-05-05T07:51:19.267375Z","end":"2026-05-05T07:51:19.784857Z","steps":["trace[1797130786] 'process raft request' (duration: 517.233359ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:51:19.784997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:51:19.267355Z","time spent":"517.586678ms","remote":"127.0.0.1:41336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":492,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T07:51:19.785086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.037947ms","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":"info","ts":"2026-05-05T07:51:19.785144Z","caller":"traceutil/trace.go:171","msg":"trace[658564908] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1719; }","duration":"190.17912ms","start":"2026-05-05T07:51:19.594952Z","end":"2026-05-05T07:51:19.785131Z","steps":["trace[658564908] 'agreement among raft nodes before linearized reading' (duration: 189.984385ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:51:20.613833Z","caller":"traceutil/trace.go:171","msg":"trace[634166096] transaction","detail":"{read_only:false; response_revision:1726; number_of_response:1; }","duration":"261.326669ms","start":"2026-05-05T07:51:20.352483Z","end":"2026-05-05T07:51:20.61381Z","steps":["trace[634166096] 'process raft request' (duration: 261.187616ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:51:20.880032Z","caller":"traceutil/trace.go:171","msg":"trace[1686186068] linearizableReadLoop","detail":"{readStateIndex:1802; appliedIndex:1800; }","duration":"519.266487ms","start":"2026-05-05T07:51:20.360746Z","end":"2026-05-05T07:51:20.880012Z","steps":["trace[1686186068] 'read index received' (duration: 252.987508ms)","trace[1686186068] 'applied index is now lower than readState.Index' (duration: 266.278129ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:51:20.880118Z","caller":"traceutil/trace.go:171","msg":"trace[494423600] transaction","detail":"{read_only:false; response_revision:1727; number_of_response:1; }","duration":"526.529341ms","start":"2026-05-05T07:51:20.353566Z","end":"2026-05-05T07:51:20.880096Z","steps":["trace[494423600] 'process raft request' (duration: 526.317506ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:51:20.880206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.443791ms","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-05-05T07:51:20.880239Z","caller":"traceutil/trace.go:171","msg":"trace[813362223] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1727; }","duration":"519.510903ms","start":"2026-05-05T07:51:20.360719Z","end":"2026-05-05T07:51:20.88023Z","steps":["trace[813362223] 'agreement among raft nodes before linearized reading' (duration: 519.3735ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:51:20.880268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:51:20.360708Z","time spent":"519.552614ms","remote":"127.0.0.1:41336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T07:51:20.880356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:51:20.353534Z","time spent":"526.688375ms","remote":"127.0.0.1:41336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T07:51:21.089485Z","caller":"traceutil/trace.go:171","msg":"trace[1303960171] transaction","detail":"{read_only:false; response_revision:1729; number_of_response:1; }","duration":"171.469481ms","start":"2026-05-05T07:51:20.917993Z","end":"2026-05-05T07:51:21.089463Z","steps":["trace[1303960171] 'process raft request' (duration: 162.837563ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:51:26.20488Z","caller":"traceutil/trace.go:171","msg":"trace[1070388824] transaction","detail":"{read_only:false; response_revision:1784; number_of_response:1; }","duration":"128.56436ms","start":"2026-05-05T07:51:26.076275Z","end":"2026-05-05T07:51:26.204839Z","steps":["trace[1070388824] 'process raft request' (duration: 128.509669ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:51:26.205083Z","caller":"traceutil/trace.go:171","msg":"trace[469472296] transaction","detail":"{read_only:false; response_revision:1783; number_of_response:1; }","duration":"135.552488ms","start":"2026-05-05T07:51:26.069498Z","end":"2026-05-05T07:51:26.20505Z","steps":["trace[469472296] 'process raft request' (duration: 108.59177ms)","trace[469472296] 'compare' (duration: 26.536848ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:51:26.89068Z","caller":"traceutil/trace.go:171","msg":"trace[1907585524] transaction","detail":"{read_only:false; response_revision:1789; number_of_response:1; }","duration":"106.777046ms","start":"2026-05-05T07:51:26.783868Z","end":"2026-05-05T07:51:26.890645Z","steps":["trace[1907585524] 'process raft request' (duration: 106.314055ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:51:27.30965Z","caller":"traceutil/trace.go:171","msg":"trace[1921266697] transaction","detail":"{read_only:false; response_revision:1793; number_of_response:1; }","duration":"117.972965ms","start":"2026-05-05T07:51:27.191639Z","end":"2026-05-05T07:51:27.309612Z","steps":["trace[1921266697] 'process raft request' (duration: 94.839639ms)","trace[1921266697] 'compare' (duration: 22.962102ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:51:41.96233Z","caller":"traceutil/trace.go:171","msg":"trace[1096594484] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"135.012133ms","start":"2026-05-05T07:51:41.8273Z","end":"2026-05-05T07:51:41.962312Z","steps":["trace[1096594484] 'process raft request' (duration: 134.89676ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:51:44.621221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.98572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-05T07:51:44.621292Z","caller":"traceutil/trace.go:171","msg":"trace[2079589976] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1880; }","duration":"310.090491ms","start":"2026-05-05T07:51:44.31118Z","end":"2026-05-05T07:51:44.621271Z","steps":["trace[2079589976] 'range keys from in-memory index tree' (duration: 309.774464ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:51:44.621321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:51:44.311162Z","time spent":"310.152554ms","remote":"127.0.0.1:41336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-05T07:51:45.077298Z","caller":"traceutil/trace.go:171","msg":"trace[280848132] linearizableReadLoop","detail":"{readStateIndex:1961; appliedIndex:1960; }","duration":"240.52379ms","start":"2026-05-05T07:51:44.836747Z","end":"2026-05-05T07:51:45.077271Z","steps":["trace[280848132] 'read index received' (duration: 240.310835ms)","trace[280848132] 'applied index is now lower than readState.Index' (duration: 211.995µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:51:45.077407Z","caller":"traceutil/trace.go:171","msg":"trace[1210651646] transaction","detail":"{read_only:false; response_revision:1881; number_of_response:1; }","duration":"452.582006ms","start":"2026-05-05T07:51:44.624784Z","end":"2026-05-05T07:51:45.077366Z","steps":["trace[1210651646] 'process raft request' (duration: 452.301789ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:51:45.077575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:51:44.624768Z","time spent":"452.72233ms","remote":"127.0.0.1:41336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T07:51:45.077674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.195223ms","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":"info","ts":"2026-05-05T07:51:45.077754Z","caller":"traceutil/trace.go:171","msg":"trace[1439789731] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1881; }","duration":"161.320877ms","start":"2026-05-05T07:51:44.916414Z","end":"2026-05-05T07:51:45.077735Z","steps":["trace[1439789731] 'agreement among raft nodes before linearized reading' (duration: 161.06856ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:51:45.077762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.964351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-05-05T07:51:45.077805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.048823ms","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-05-05T07:51:45.077844Z","caller":"traceutil/trace.go:171","msg":"trace[1993750561] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1881; }","duration":"159.098754ms","start":"2026-05-05T07:51:44.918723Z","end":"2026-05-05T07:51:45.077822Z","steps":["trace[1993750561] 'agreement among raft nodes before linearized reading' (duration: 158.905799ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:51:45.07785Z","caller":"traceutil/trace.go:171","msg":"trace[1642959977] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1881; }","duration":"241.162836ms","start":"2026-05-05T07:51:44.836673Z","end":"2026-05-05T07:51:45.077836Z","steps":["trace[1642959977] 'agreement among raft nodes before linearized reading' (duration: 240.767186ms)"],"step_count":1}