{"level":"warn","ts":"2026-05-07T03:18:29.923272Z","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-07T03:18:29.923383Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.246: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.246:2380","--initial-cluster=instance=https://199.204.45.246:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.246:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.246: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-07T03:18:29.923636Z","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-07T03:18:29.923656Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.246:2380"]} {"level":"info","ts":"2026-05-07T03:18:29.92372Z","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-07T03:18:29.924899Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"]} {"level":"info","ts":"2026-05-07T03:18:29.925577Z","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.246:2380"],"listen-peer-urls":["https://199.204.45.246:2380"],"advertise-client-urls":["https://199.204.45.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.246: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-07T03:18:30.037973Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"112.003548ms"} {"level":"info","ts":"2026-05-07T03:18:30.101681Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ab97322ebdd79664","cluster-id":"87bc89380c3dc2c"} {"level":"info","ts":"2026-05-07T03:18:30.10177Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=()"} {"level":"info","ts":"2026-05-07T03:18:30.101818Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became follower at term 0"} {"level":"info","ts":"2026-05-07T03:18:30.101838Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ab97322ebdd79664 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-07T03:18:30.101847Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became follower at term 1"} {"level":"info","ts":"2026-05-07T03:18:30.101927Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=(12364406478303893092)"} {"level":"warn","ts":"2026-05-07T03:18:30.284261Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-07T03:18:30.287514Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-07T03:18:30.29105Z","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-07T03:18:30.293289Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ab97322ebdd79664","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-07T03:18:30.297191Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ab97322ebdd79664","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-07T03:18:30.297254Z","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-07T03:18:30.297369Z","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-07T03:18:30.297416Z","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-07T03:18:30.297758Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=(12364406478303893092)"} {"level":"info","ts":"2026-05-07T03:18:30.297777Z","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-07T03:18:30.297895Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"87bc89380c3dc2c","local-member-id":"ab97322ebdd79664","added-peer-id":"ab97322ebdd79664","added-peer-peer-urls":["https://199.204.45.246:2380"]} {"level":"info","ts":"2026-05-07T03:18:30.297944Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.246:2380"} {"level":"info","ts":"2026-05-07T03:18:30.297968Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.246:2380"} {"level":"info","ts":"2026-05-07T03:18:30.297981Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ab97322ebdd79664","initial-advertise-peer-urls":["https://199.204.45.246:2380"],"listen-peer-urls":["https://199.204.45.246:2380"],"advertise-client-urls":["https://199.204.45.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-07T03:18:30.298015Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-07T03:18:30.302263Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 is starting a new election at term 1"} {"level":"info","ts":"2026-05-07T03:18:30.302344Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-07T03:18:30.302384Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 received MsgPreVoteResp from ab97322ebdd79664 at term 1"} {"level":"info","ts":"2026-05-07T03:18:30.302414Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became candidate at term 2"} {"level":"info","ts":"2026-05-07T03:18:30.302442Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 received MsgVoteResp from ab97322ebdd79664 at term 2"} {"level":"info","ts":"2026-05-07T03:18:30.302467Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became leader at term 2"} {"level":"info","ts":"2026-05-07T03:18:30.302487Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ab97322ebdd79664 elected leader ab97322ebdd79664 at term 2"} {"level":"info","ts":"2026-05-07T03:18:30.303529Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ab97322ebdd79664","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.246:2379]}","request-path":"/0/members/ab97322ebdd79664/attributes","cluster-id":"87bc89380c3dc2c","publish-timeout":"7s"} {"level":"info","ts":"2026-05-07T03:18:30.303679Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T03:18:30.303705Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-07T03:18:30.303807Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-07T03:18:30.304545Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-07T03:18:30.304569Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-07T03:18:30.306303Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"87bc89380c3dc2c","local-member-id":"ab97322ebdd79664","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T03:18:30.306408Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T03:18:30.30644Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T03:18:30.306526Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-07T03:18:30.306642Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.246:2379"} {"level":"info","ts":"2026-05-07T03:18:33.665467Z","caller":"traceutil/trace.go:171","msg":"trace[1149587870] transaction","detail":"{read_only:false; response_revision:201; number_of_response:1; }","duration":"101.626456ms","start":"2026-05-07T03:18:33.563812Z","end":"2026-05-07T03:18:33.665439Z","steps":["trace[1149587870] 'process raft request' (duration: 64.622754ms)","trace[1149587870] 'compare' (duration: 36.762071ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:18:34.34834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.730157ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:18:34.348523Z","caller":"traceutil/trace.go:171","msg":"trace[1626582917] linearizableReadLoop","detail":"{readStateIndex:211; appliedIndex:209; }","duration":"635.298463ms","start":"2026-05-07T03:18:33.713209Z","end":"2026-05-07T03:18:34.348507Z","steps":["trace[1626582917] 'read index received' (duration: 46.81191ms)","trace[1626582917] 'applied index is now lower than readState.Index' (duration: 588.485683ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:18:34.348735Z","caller":"traceutil/trace.go:171","msg":"trace[1661738480] transaction","detail":"{read_only:false; response_revision:205; number_of_response:1; }","duration":"641.813143ms","start":"2026-05-07T03:18:33.706878Z","end":"2026-05-07T03:18:34.348691Z","steps":["trace[1661738480] 'process raft request' (duration: 300.122574ms)","trace[1661738480] 'compare' (duration: 340.605631ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:18:34.348958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.68941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/statefulset-controller\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T03:18:34.348944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:18:33.706858Z","time spent":"641.97086ms","remote":"127.0.0.1:36442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:18:34.349061Z","caller":"traceutil/trace.go:171","msg":"trace[425250785] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/statefulset-controller; range_end:; response_count:0; response_revision:206; }","duration":"635.859478ms","start":"2026-05-07T03:18:33.713172Z","end":"2026-05-07T03:18:34.349032Z","steps":["trace[425250785] 'agreement among raft nodes before linearized reading' (duration: 635.529333ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:18:34.349109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:18:33.713158Z","time spent":"635.940911ms","remote":"127.0.0.1:36384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/statefulset-controller\" "} {"level":"info","ts":"2026-05-07T03:18:34.349318Z","caller":"traceutil/trace.go:171","msg":"trace[703193811] transaction","detail":"{read_only:false; response_revision:206; number_of_response:1; }","duration":"620.323573ms","start":"2026-05-07T03:18:33.728916Z","end":"2026-05-07T03:18:34.349239Z","steps":["trace[703193811] 'process raft request' (duration: 619.533618ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:18:34.349072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.25903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-05-07T03:18:34.349544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:18:33.728901Z","time spent":"620.547743ms","remote":"127.0.0.1:36268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1342,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T03:18:34.349618Z","caller":"traceutil/trace.go:171","msg":"trace[1839249793] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:206; }","duration":"594.994943ms","start":"2026-05-07T03:18:33.754606Z","end":"2026-05-07T03:18:34.349601Z","steps":["trace[1839249793] 'agreement among raft nodes before linearized reading' (duration: 594.131225ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:18:34.349688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:18:33.754587Z","time spent":"595.086997ms","remote":"127.0.0.1:36442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-07T03:18:34.553594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.89375ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:18:34.553812Z","caller":"traceutil/trace.go:171","msg":"trace[921596944] transaction","detail":"{read_only:false; response_revision:209; number_of_response:1; }","duration":"198.039462ms","start":"2026-05-07T03:18:34.355751Z","end":"2026-05-07T03:18:34.55379Z","steps":["trace[921596944] 'process raft request' (duration: 197.939658ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:18:34.553861Z","caller":"traceutil/trace.go:171","msg":"trace[1505512308] linearizableReadLoop","detail":"{readStateIndex:215; appliedIndex:213; }","duration":"166.799055ms","start":"2026-05-07T03:18:34.387053Z","end":"2026-05-07T03:18:34.553852Z","steps":["trace[1505512308] 'read index received' (duration: 57.855641ms)","trace[1505512308] 'applied index is now lower than readState.Index' (duration: 108.942424ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:18:34.553946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.891229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:18:34.553976Z","caller":"traceutil/trace.go:171","msg":"trace[1472382779] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:210; }","duration":"166.961652ms","start":"2026-05-07T03:18:34.387006Z","end":"2026-05-07T03:18:34.553968Z","steps":["trace[1472382779] 'agreement among raft nodes before linearized reading' (duration: 166.894309ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:18:34.553812Z","caller":"traceutil/trace.go:171","msg":"trace[1314334778] transaction","detail":"{read_only:false; response_revision:210; number_of_response:1; }","duration":"104.843213ms","start":"2026-05-07T03:18:34.448956Z","end":"2026-05-07T03:18:34.553799Z","steps":["trace[1314334778] 'process raft request' (duration: 104.791461ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:18:34.554116Z","caller":"traceutil/trace.go:171","msg":"trace[464389143] transaction","detail":"{read_only:false; response_revision:208; number_of_response:1; }","duration":"199.999299ms","start":"2026-05-07T03:18:34.354041Z","end":"2026-05-07T03:18:34.554041Z","steps":["trace[464389143] 'process raft request' (duration: 93.562465ms)","trace[464389143] 'compare' (duration: 105.773945ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:18:34.769545Z","caller":"traceutil/trace.go:171","msg":"trace[64172033] transaction","detail":"{read_only:false; response_revision:212; number_of_response:1; }","duration":"134.22796ms","start":"2026-05-07T03:18:34.635291Z","end":"2026-05-07T03:18:34.769519Z","steps":["trace[64172033] 'process raft request' (duration: 105.191169ms)","trace[64172033] 'compare' (duration: 28.834512ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:18:34.769982Z","caller":"traceutil/trace.go:171","msg":"trace[741564828] transaction","detail":"{read_only:false; response_revision:213; number_of_response:1; }","duration":"133.941266ms","start":"2026-05-07T03:18:34.636006Z","end":"2026-05-07T03:18:34.769947Z","steps":["trace[741564828] 'process raft request' (duration: 133.453925ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:18:40.280981Z","caller":"traceutil/trace.go:171","msg":"trace[1442847050] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"312.621019ms","start":"2026-05-07T03:18:39.968329Z","end":"2026-05-07T03:18:40.28095Z","steps":["trace[1442847050] 'process raft request' (duration: 312.404609ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:18:40.281147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:18:39.968307Z","time spent":"312.768965ms","remote":"127.0.0.1:36234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":777,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T03:18:40.812238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.422889ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:18:40.812338Z","caller":"traceutil/trace.go:171","msg":"trace[1472931649] linearizableReadLoop","detail":"{readStateIndex:358; appliedIndex:357; }","duration":"438.022121ms","start":"2026-05-07T03:18:40.3743Z","end":"2026-05-07T03:18:40.812322Z","steps":["trace[1472931649] 'read index received' (duration: 45.771µs)","trace[1472931649] 'applied index is now lower than readState.Index' (duration: 437.97535ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:18:40.812551Z","caller":"traceutil/trace.go:171","msg":"trace[1752533823] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"525.515325ms","start":"2026-05-07T03:18:40.287022Z","end":"2026-05-07T03:18:40.812538Z","steps":["trace[1752533823] 'process raft request' (duration: 76.7152ms)","trace[1752533823] 'compare' (duration: 448.288124ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:18:40.812632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.860462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-07T03:18:40.812724Z","caller":"traceutil/trace.go:171","msg":"trace[1761491231] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:345; }","duration":"239.997707ms","start":"2026-05-07T03:18:40.572707Z","end":"2026-05-07T03:18:40.812705Z","steps":["trace[1761491231] 'agreement among raft nodes before linearized reading' (duration: 239.837991ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:18:40.812746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.448529ms","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-07T03:18:40.812782Z","caller":"traceutil/trace.go:171","msg":"trace[1354031290] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:345; }","duration":"438.519471ms","start":"2026-05-07T03:18:40.37425Z","end":"2026-05-07T03:18:40.81277Z","steps":["trace[1354031290] 'agreement among raft nodes before linearized reading' (duration: 438.460959ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:18:40.812805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:18:40.374231Z","time spent":"438.568133ms","remote":"127.0.0.1:36442","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-07T03:18:40.812677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:18:40.286995Z","time spent":"525.615429ms","remote":"127.0.0.1:36442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:18:41.298205Z","caller":"traceutil/trace.go:171","msg":"trace[1791413965] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"163.245578ms","start":"2026-05-07T03:18:41.134928Z","end":"2026-05-07T03:18:41.298174Z","steps":["trace[1791413965] 'process raft request' (duration: 163.102822ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:18:45.391016Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836960328104532295,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-07T03:18:45.58781Z","caller":"traceutil/trace.go:171","msg":"trace[1908638360] linearizableReadLoop","detail":"{readStateIndex:379; appliedIndex:378; }","duration":"696.928646ms","start":"2026-05-07T03:18:44.890854Z","end":"2026-05-07T03:18:45.587783Z","steps":["trace[1908638360] 'read index received' (duration: 696.696007ms)","trace[1908638360] 'applied index is now lower than readState.Index' (duration: 231.399µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:18:45.587839Z","caller":"traceutil/trace.go:171","msg":"trace[1834384754] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"751.149641ms","start":"2026-05-07T03:18:44.836657Z","end":"2026-05-07T03:18:45.587807Z","steps":["trace[1834384754] 'process raft request' (duration: 750.953713ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:18:45.588095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:18:44.836645Z","time spent":"751.37655ms","remote":"127.0.0.1:36442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:18:45.588155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.58045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"warn","ts":"2026-05-07T03:18:45.588185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.072521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-07T03:18:45.588154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.265139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-07T03:18:45.588262Z","caller":"traceutil/trace.go:171","msg":"trace[75776768] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:364; }","duration":"696.177095ms","start":"2026-05-07T03:18:44.892066Z","end":"2026-05-07T03:18:45.588244Z","steps":["trace[75776768] 'agreement among raft nodes before linearized reading' (duration: 696.03431ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:18:45.588262Z","caller":"traceutil/trace.go:171","msg":"trace[1120696599] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:364; }","duration":"511.730796ms","start":"2026-05-07T03:18:45.076506Z","end":"2026-05-07T03:18:45.588237Z","steps":["trace[1120696599] 'agreement among raft nodes before linearized reading' (duration: 511.532169ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:18:45.588294Z","caller":"traceutil/trace.go:171","msg":"trace[244779049] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:364; }","duration":"697.428275ms","start":"2026-05-07T03:18:44.890849Z","end":"2026-05-07T03:18:45.588278Z","steps":["trace[244779049] 'agreement among raft nodes before linearized reading' (duration: 697.100282ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:18:45.588314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:18:44.892052Z","time spent":"696.253458ms","remote":"127.0.0.1:36442","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-07T03:18:45.588321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:18:45.076482Z","time spent":"511.825539ms","remote":"127.0.0.1:36362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4349,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-05-07T03:18:45.588098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.365574ms","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-07T03:18:45.588346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:18:44.890808Z","time spent":"697.524878ms","remote":"127.0.0.1:36442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-07T03:18:45.58844Z","caller":"traceutil/trace.go:171","msg":"trace[157937408] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:364; }","duration":"696.742908ms","start":"2026-05-07T03:18:44.891653Z","end":"2026-05-07T03:18:45.588396Z","steps":["trace[157937408] 'agreement among raft nodes before linearized reading' (duration: 696.300521ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:18:45.58857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:18:44.891629Z","time spent":"696.894374ms","remote":"127.0.0.1:36442","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-07T03:18:46.150913Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836960328104532298,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T03:18:46.710641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.914752ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:18:46.71078Z","caller":"traceutil/trace.go:171","msg":"trace[1651461913] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"1.117527835s","start":"2026-05-07T03:18:45.593231Z","end":"2026-05-07T03:18:46.710759Z","steps":["trace[1651461913] 'process raft request' (duration: 983.404665ms)","trace[1651461913] 'compare' (duration: 133.716805ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:18:46.710917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:18:45.593204Z","time spent":"1.117620579s","remote":"127.0.0.1:36442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:18:46.767543Z","caller":"traceutil/trace.go:171","msg":"trace[2029604667] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"1.13041966s","start":"2026-05-07T03:18:45.637094Z","end":"2026-05-07T03:18:46.767513Z","steps":["trace[2029604667] 'process raft request' (duration: 1.130287135s)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:18:46.767634Z","caller":"traceutil/trace.go:171","msg":"trace[1247857325] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"1.173839413s","start":"2026-05-07T03:18:45.593784Z","end":"2026-05-07T03:18:46.767623Z","steps":["trace[1247857325] 'process raft request' (duration: 1.173407517s)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:18:46.767577Z","caller":"traceutil/trace.go:171","msg":"trace[2144530186] linearizableReadLoop","detail":"{readStateIndex:384; appliedIndex:379; }","duration":"1.117724338s","start":"2026-05-07T03:18:45.649838Z","end":"2026-05-07T03:18:46.767563Z","steps":["trace[2144530186] 'read index received' (duration: 926.808692ms)","trace[2144530186] 'applied index is now lower than readState.Index' (duration: 190.914926ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:18:46.767776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.117926715s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T03:18:46.76784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:18:45.593765Z","time spent":"1.1740025s","remote":"127.0.0.1:36442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:18:46.767848Z","caller":"traceutil/trace.go:171","msg":"trace[1944765993] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:369; }","duration":"1.11805235s","start":"2026-05-07T03:18:45.649782Z","end":"2026-05-07T03:18:46.767834Z","steps":["trace[1944765993] 'agreement among raft nodes before linearized reading' (duration: 1.117931585s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:18:46.767878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:18:45.637076Z","time spent":"1.130549724s","remote":"127.0.0.1:36362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4311,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:18:46.767966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:18:45.649764Z","time spent":"1.118191756s","remote":"127.0.0.1:36184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-07T03:18:46.767537Z","caller":"traceutil/trace.go:171","msg":"trace[1692038801] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"1.173665157s","start":"2026-05-07T03:18:45.593839Z","end":"2026-05-07T03:18:46.767504Z","steps":["trace[1692038801] 'process raft request' (duration: 1.173466689s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:18:46.768159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:18:45.593822Z","time spent":"1.17430435s","remote":"127.0.0.1:36442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:18:46.768174Z","caller":"traceutil/trace.go:171","msg":"trace[578031271] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"641.347647ms","start":"2026-05-07T03:18:46.126792Z","end":"2026-05-07T03:18:46.768139Z","steps":["trace[578031271] 'process raft request' (duration: 640.65647ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:18:46.768325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:18:46.126753Z","time spent":"641.497623ms","remote":"127.0.0.1:36442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:18:55.066963Z","caller":"traceutil/trace.go:171","msg":"trace[1949441686] linearizableReadLoop","detail":"{readStateIndex:435; appliedIndex:434; }","duration":"270.592786ms","start":"2026-05-07T03:18:54.796351Z","end":"2026-05-07T03:18:55.066944Z","steps":["trace[1949441686] 'read index received' (duration: 270.453521ms)","trace[1949441686] 'applied index is now lower than readState.Index' (duration: 138.695µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:18:55.067047Z","caller":"traceutil/trace.go:171","msg":"trace[1646502089] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"324.301827ms","start":"2026-05-07T03:18:54.742736Z","end":"2026-05-07T03:18:55.067037Z","steps":["trace[1646502089] 'process raft request' (duration: 324.10684ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:18:55.067121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:18:54.742719Z","time spent":"324.345768ms","remote":"127.0.0.1:36442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:18:55.067213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.867665ms","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-07T03:18:55.067246Z","caller":"traceutil/trace.go:171","msg":"trace[1718593638] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:417; }","duration":"270.922727ms","start":"2026-05-07T03:18:54.796316Z","end":"2026-05-07T03:18:55.067238Z","steps":["trace[1718593638] 'agreement among raft nodes before linearized reading' (duration: 270.874895ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:18:55.067364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.837949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-07T03:18:55.067411Z","caller":"traceutil/trace.go:171","msg":"trace[1643335797] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:417; }","duration":"267.88598ms","start":"2026-05-07T03:18:54.799512Z","end":"2026-05-07T03:18:55.067398Z","steps":["trace[1643335797] 'agreement among raft nodes before linearized reading' (duration: 267.811488ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:18:55.681625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.552365ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:18:55.681785Z","caller":"traceutil/trace.go:171","msg":"trace[1072225798] linearizableReadLoop","detail":"{readStateIndex:437; appliedIndex:435; }","duration":"159.33937ms","start":"2026-05-07T03:18:55.52243Z","end":"2026-05-07T03:18:55.68177Z","steps":["trace[1072225798] 'read index received' (duration: 33.031µs)","trace[1072225798] 'applied index is now lower than readState.Index' (duration: 159.305449ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:18:55.681876Z","caller":"traceutil/trace.go:171","msg":"trace[2096765386] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"608.024706ms","start":"2026-05-07T03:18:55.07384Z","end":"2026-05-07T03:18:55.681865Z","steps":["trace[2096765386] 'process raft request' (duration: 186.096038ms)","trace[2096765386] 'compare' (duration: 421.444402ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:18:55.681938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:18:55.073817Z","time spent":"608.091589ms","remote":"127.0.0.1:36442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:18:55.682052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.595969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:18:55.682163Z","caller":"traceutil/trace.go:171","msg":"trace[889750570] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:419; }","duration":"159.744504ms","start":"2026-05-07T03:18:55.522397Z","end":"2026-05-07T03:18:55.682142Z","steps":["trace[889750570] 'agreement among raft nodes before linearized reading' (duration: 159.469955ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:18:55.682264Z","caller":"traceutil/trace.go:171","msg":"trace[167995648] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"608.220183ms","start":"2026-05-07T03:18:55.074026Z","end":"2026-05-07T03:18:55.682246Z","steps":["trace[167995648] 'process raft request' (duration: 607.688004ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:18:55.682382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:18:55.07399Z","time spent":"608.326577ms","remote":"127.0.0.1:36442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:18:56.836246Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.150332672s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-07T03:18:57.571636Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836960328104532403,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T03:18:57.900199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.063744924s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:18:57.900364Z","caller":"traceutil/trace.go:171","msg":"trace[1196568276] linearizableReadLoop","detail":"{readStateIndex:439; appliedIndex:437; }","duration":"829.569192ms","start":"2026-05-07T03:18:57.070785Z","end":"2026-05-07T03:18:57.900354Z","steps":["trace[1196568276] 'read index received' (duration: 774.545776ms)","trace[1196568276] 'applied index is now lower than readState.Index' (duration: 55.020626ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:18:57.9005Z","caller":"traceutil/trace.go:171","msg":"trace[643833138] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"2.214662555s","start":"2026-05-07T03:18:55.685831Z","end":"2026-05-07T03:18:57.900493Z","steps":["trace[643833138] 'process raft request' (duration: 1.15058331s)","trace[643833138] 'compare' (duration: 1.062840753s)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:18:57.900536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:18:55.685812Z","time spent":"2.214707216s","remote":"127.0.0.1:36442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:18:57.900638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.108853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:18:57.90067Z","caller":"traceutil/trace.go:171","msg":"trace[1317070156] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"1.055083336s","start":"2026-05-07T03:18:56.845561Z","end":"2026-05-07T03:18:57.900645Z","steps":["trace[1317070156] 'process raft request' (duration: 1.054740304s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:18:57.900697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.650936ms","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-07T03:18:57.900722Z","caller":"traceutil/trace.go:171","msg":"trace[134767953] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:421; }","duration":"214.709068ms","start":"2026-05-07T03:18:57.686006Z","end":"2026-05-07T03:18:57.900715Z","steps":["trace[134767953] 'agreement among raft nodes before linearized reading' (duration: 214.671277ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:18:57.900721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.626416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-07T03:18:57.900774Z","caller":"traceutil/trace.go:171","msg":"trace[1920714379] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:421; }","duration":"214.694467ms","start":"2026-05-07T03:18:57.686062Z","end":"2026-05-07T03:18:57.900757Z","steps":["trace[1920714379] 'agreement among raft nodes before linearized reading' (duration: 214.583794ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:18:57.900694Z","caller":"traceutil/trace.go:171","msg":"trace[2075013750] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:421; }","duration":"369.215577ms","start":"2026-05-07T03:18:57.531467Z","end":"2026-05-07T03:18:57.900683Z","steps":["trace[2075013750] 'agreement among raft nodes before linearized reading' (duration: 369.110923ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:18:57.900828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:18:56.845536Z","time spent":"1.05520298s","remote":"127.0.0.1:36442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:18:57.900854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:18:57.531453Z","time spent":"369.394192ms","remote":"127.0.0.1:36184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T03:18:57.900637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"829.856032ms","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-07T03:18:57.900957Z","caller":"traceutil/trace.go:171","msg":"trace[1653093845] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:421; }","duration":"830.203423ms","start":"2026-05-07T03:18:57.070745Z","end":"2026-05-07T03:18:57.900949Z","steps":["trace[1653093845] 'agreement among raft nodes before linearized reading' (duration: 829.861461ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:18:57.900981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:18:57.07073Z","time spent":"830.244155ms","remote":"127.0.0.1:36442","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-07T03:18:58.189684Z","caller":"traceutil/trace.go:171","msg":"trace[969076249] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"285.505371ms","start":"2026-05-07T03:18:57.904158Z","end":"2026-05-07T03:18:58.189664Z","steps":["trace[969076249] 'process raft request' (duration: 215.198214ms)","trace[969076249] 'compare' (duration: 70.220084ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:18:58.210064Z","caller":"traceutil/trace.go:171","msg":"trace[762740711] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"304.55497ms","start":"2026-05-07T03:18:57.905493Z","end":"2026-05-07T03:18:58.210048Z","steps":["trace[762740711] 'process raft request' (duration: 304.361893ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:18:58.210422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:18:57.905485Z","time spent":"304.652123ms","remote":"127.0.0.1:36442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:18:58.210857Z","caller":"traceutil/trace.go:171","msg":"trace[1094078730] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"305.778052ms","start":"2026-05-07T03:18:57.90422Z","end":"2026-05-07T03:18:58.209998Z","steps":["trace[1094078730] 'process raft request' (duration: 305.550663ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:18:58.211004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:18:57.904196Z","time spent":"306.745843ms","remote":"127.0.0.1:36442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:19:00.396666Z","caller":"traceutil/trace.go:171","msg":"trace[158251055] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"177.961991ms","start":"2026-05-07T03:19:00.218683Z","end":"2026-05-07T03:19:00.396645Z","steps":["trace[158251055] 'process raft request' (duration: 173.36488ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:02.731388Z","caller":"traceutil/trace.go:171","msg":"trace[578693846] linearizableReadLoop","detail":"{readStateIndex:451; appliedIndex:450; }","duration":"330.776819ms","start":"2026-05-07T03:19:02.400573Z","end":"2026-05-07T03:19:02.73135Z","steps":["trace[578693846] 'read index received' (duration: 330.54657ms)","trace[578693846] 'applied index is now lower than readState.Index' (duration: 229.099µs)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:19:02.731565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.044557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-07T03:19:02.731604Z","caller":"traceutil/trace.go:171","msg":"trace[604109726] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:432; }","duration":"331.15506ms","start":"2026-05-07T03:19:02.400439Z","end":"2026-05-07T03:19:02.731594Z","steps":["trace[604109726] 'agreement among raft nodes before linearized reading' (duration: 331.057587ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:02.731638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:02.40042Z","time spent":"331.210543ms","remote":"127.0.0.1:36442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-07T03:19:02.7316Z","caller":"traceutil/trace.go:171","msg":"trace[1303832044] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"397.616695ms","start":"2026-05-07T03:19:02.33381Z","end":"2026-05-07T03:19:02.731427Z","steps":["trace[1303832044] 'process raft request' (duration: 397.398727ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:02.731844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:02.333783Z","time spent":"397.916515ms","remote":"127.0.0.1:36442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:19:02.732058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.475334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:19:02.732123Z","caller":"traceutil/trace.go:171","msg":"trace[2114338366] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:432; }","duration":"201.767223ms","start":"2026-05-07T03:19:02.53034Z","end":"2026-05-07T03:19:02.732107Z","steps":["trace[2114338366] 'agreement among raft nodes before linearized reading' (duration: 201.388071ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:05.774592Z","caller":"traceutil/trace.go:171","msg":"trace[1301217808] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"293.460776ms","start":"2026-05-07T03:19:05.48111Z","end":"2026-05-07T03:19:05.774571Z","steps":["trace[1301217808] 'process raft request' (duration: 292.774595ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:05.780441Z","caller":"traceutil/trace.go:171","msg":"trace[1454456257] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"276.457147ms","start":"2026-05-07T03:19:05.503967Z","end":"2026-05-07T03:19:05.780424Z","steps":["trace[1454456257] 'process raft request' (duration: 276.161438ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:06.406446Z","caller":"traceutil/trace.go:171","msg":"trace[1040865910] linearizableReadLoop","detail":"{readStateIndex:590; appliedIndex:589; }","duration":"404.597816ms","start":"2026-05-07T03:19:06.001804Z","end":"2026-05-07T03:19:06.406402Z","steps":["trace[1040865910] 'read index received' (duration: 400.342585ms)","trace[1040865910] 'applied index is now lower than readState.Index' (duration: 4.254431ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:19:06.406499Z","caller":"traceutil/trace.go:171","msg":"trace[981447123] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"427.444827ms","start":"2026-05-07T03:19:05.979012Z","end":"2026-05-07T03:19:06.406457Z","steps":["trace[981447123] 'process raft request' (duration: 423.861757ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:06.406574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.75346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-07T03:19:06.406604Z","caller":"traceutil/trace.go:171","msg":"trace[1195633612] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:564; }","duration":"404.820273ms","start":"2026-05-07T03:19:06.001777Z","end":"2026-05-07T03:19:06.406597Z","steps":["trace[1195633612] 'agreement among raft nodes before linearized reading' (duration: 404.7498ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:06.406674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:06.001765Z","time spent":"404.903386ms","remote":"127.0.0.1:36442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-07T03:19:06.406725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:05.978993Z","time spent":"427.598091ms","remote":"127.0.0.1:36196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:19:06.417674Z","caller":"traceutil/trace.go:171","msg":"trace[1904441628] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"371.208386ms","start":"2026-05-07T03:19:06.046445Z","end":"2026-05-07T03:19:06.417654Z","steps":["trace[1904441628] 'process raft request' (duration: 370.982339ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:06.417719Z","caller":"traceutil/trace.go:171","msg":"trace[849359874] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"112.843536ms","start":"2026-05-07T03:19:06.304842Z","end":"2026-05-07T03:19:06.417685Z","steps":["trace[849359874] 'process raft request' (duration: 112.775634ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:06.417762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:06.046429Z","time spent":"371.292969ms","remote":"127.0.0.1:36196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":207462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T03:19:06.417758Z","caller":"traceutil/trace.go:171","msg":"trace[453374784] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"314.063166ms","start":"2026-05-07T03:19:06.103658Z","end":"2026-05-07T03:19:06.417722Z","steps":["trace[453374784] 'process raft request' (duration: 313.916392ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:06.417773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.982294ms","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-07T03:19:06.417858Z","caller":"traceutil/trace.go:171","msg":"trace[226531432] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:567; }","duration":"179.080567ms","start":"2026-05-07T03:19:06.238758Z","end":"2026-05-07T03:19:06.417839Z","steps":["trace[226531432] 'agreement among raft nodes before linearized reading' (duration: 178.911042ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:06.417872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:06.103635Z","time spent":"314.18424ms","remote":"127.0.0.1:36196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T03:19:06.417898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.938221ms","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-07T03:19:06.417928Z","caller":"traceutil/trace.go:171","msg":"trace[509513952] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:567; }","duration":"176.995494ms","start":"2026-05-07T03:19:06.240922Z","end":"2026-05-07T03:19:06.417918Z","steps":["trace[509513952] 'agreement among raft nodes before linearized reading' (duration: 176.917371ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:07.195199Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836960328104532661,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T03:19:07.696089Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836960328104532661,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T03:19:08.089955Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.414006146s","expected-duration":"1s"} {"level":"info","ts":"2026-05-07T03:19:08.109793Z","caller":"traceutil/trace.go:171","msg":"trace[1364490008] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:605; }","duration":"1.4151979s","start":"2026-05-07T03:19:06.694571Z","end":"2026-05-07T03:19:08.109769Z","steps":["trace[1364490008] 'read index received' (duration: 1.395484085s)","trace[1364490008] 'applied index is now lower than readState.Index' (duration: 19.712655ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:19:08.10981Z","caller":"traceutil/trace.go:171","msg":"trace[1167833084] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"1.433954327s","start":"2026-05-07T03:19:06.675833Z","end":"2026-05-07T03:19:08.109787Z","steps":["trace[1167833084] 'process raft request' (duration: 1.414318765s)","trace[1167833084] 'compare' (duration: 19.21219ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:19:08.109999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.415411487s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoyextensionpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:74769"} {"level":"info","ts":"2026-05-07T03:19:08.110052Z","caller":"traceutil/trace.go:171","msg":"trace[456214579] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/envoyextensionpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:580; }","duration":"1.41550195s","start":"2026-05-07T03:19:06.694537Z","end":"2026-05-07T03:19:08.110039Z","steps":["trace[456214579] 'agreement among raft nodes before linearized reading' (duration: 1.415350454s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:08.110081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:06.694524Z","time spent":"1.415549252s","remote":"127.0.0.1:36196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":103,"response count":1,"response size":74792,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoyextensionpolicies.gateway.envoyproxy.io\" "} {"level":"warn","ts":"2026-05-07T03:19:08.110146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:06.675805Z","time spent":"1.434106662s","remote":"127.0.0.1:36196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5571,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:19:08.11114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.410138833s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:19:08.111204Z","caller":"traceutil/trace.go:171","msg":"trace[995327274] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:581; }","duration":"1.410236526s","start":"2026-05-07T03:19:06.700952Z","end":"2026-05-07T03:19:08.111188Z","steps":["trace[995327274] 'agreement among raft nodes before linearized reading' (duration: 1.410099152s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:08.11124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:06.700937Z","time spent":"1.410293158s","remote":"127.0.0.1:36196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-05-07T03:19:08.11184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.485795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-05-07T03:19:08.111894Z","caller":"traceutil/trace.go:171","msg":"trace[895226573] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:581; }","duration":"210.563488ms","start":"2026-05-07T03:19:07.901318Z","end":"2026-05-07T03:19:08.111882Z","steps":["trace[895226573] 'agreement among raft nodes before linearized reading' (duration: 210.489316ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:08.112039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.209460809s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:19:08.112087Z","caller":"traceutil/trace.go:171","msg":"trace[1346168941] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:581; }","duration":"1.209549752s","start":"2026-05-07T03:19:06.902524Z","end":"2026-05-07T03:19:08.112074Z","steps":["trace[1346168941] 'agreement among raft nodes before linearized reading' (duration: 1.209440479s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:08.112113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:06.902504Z","time spent":"1.209602444s","remote":"127.0.0.1:36196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-05-07T03:19:08.112156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.396581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5168"} {"level":"info","ts":"2026-05-07T03:19:08.112203Z","caller":"traceutil/trace.go:171","msg":"trace[664549716] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:581; }","duration":"410.461853ms","start":"2026-05-07T03:19:07.70172Z","end":"2026-05-07T03:19:08.112182Z","steps":["trace[664549716] 'agreement among raft nodes before linearized reading' (duration: 410.346409ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:08.112243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:07.701702Z","time spent":"410.529914ms","remote":"127.0.0.1:36196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":5191,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"warn","ts":"2026-05-07T03:19:08.112265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.322575745s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-07T03:19:08.11229Z","caller":"traceutil/trace.go:171","msg":"trace[2050861265] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:581; }","duration":"1.322638957s","start":"2026-05-07T03:19:06.789644Z","end":"2026-05-07T03:19:08.112283Z","steps":["trace[2050861265] 'agreement among raft nodes before linearized reading' (duration: 1.322541594s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:08.112311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:06.789621Z","time spent":"1.322685688s","remote":"127.0.0.1:36442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-07T03:19:08.112357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.359958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:19:08.112383Z","caller":"traceutil/trace.go:171","msg":"trace[1837972083] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:581; }","duration":"590.382818ms","start":"2026-05-07T03:19:07.521992Z","end":"2026-05-07T03:19:08.112375Z","steps":["trace[1837972083] 'agreement among raft nodes before linearized reading' (duration: 590.344327ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:08.1124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:07.521936Z","time spent":"590.460801ms","remote":"127.0.0.1:36184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T03:19:08.112025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.009375636s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-05-07T03:19:08.112457Z","caller":"traceutil/trace.go:171","msg":"trace[1956312132] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:581; }","duration":"1.009884982s","start":"2026-05-07T03:19:07.102561Z","end":"2026-05-07T03:19:08.112446Z","steps":["trace[1956312132] 'agreement among raft nodes before linearized reading' (duration: 1.009285294s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:08.112491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:07.102545Z","time spent":"1.009937163s","remote":"127.0.0.1:36196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4356,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"warn","ts":"2026-05-07T03:19:08.11257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.40336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-05-07T03:19:08.112602Z","caller":"traceutil/trace.go:171","msg":"trace[822880345] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:581; }","duration":"611.457712ms","start":"2026-05-07T03:19:07.501136Z","end":"2026-05-07T03:19:08.112594Z","steps":["trace[822880345] 'agreement among raft nodes before linearized reading' (duration: 611.375149ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:08.112623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:07.501124Z","time spent":"611.494103ms","remote":"127.0.0.1:36196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4106,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"warn","ts":"2026-05-07T03:19:08.112746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"810.219824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"warn","ts":"2026-05-07T03:19:08.112757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.001228538s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-05-07T03:19:08.112777Z","caller":"traceutil/trace.go:171","msg":"trace[239495072] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:581; }","duration":"810.289696ms","start":"2026-05-07T03:19:07.30248Z","end":"2026-05-07T03:19:08.11277Z","steps":["trace[239495072] 'agreement among raft nodes before linearized reading' (duration: 810.202433ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:08.112799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:07.302461Z","time spent":"810.333097ms","remote":"127.0.0.1:36196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":1,"response size":10400,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"info","ts":"2026-05-07T03:19:08.112797Z","caller":"traceutil/trace.go:171","msg":"trace[1479119569] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:581; }","duration":"1.0012879s","start":"2026-05-07T03:19:07.111498Z","end":"2026-05-07T03:19:08.112785Z","steps":["trace[1479119569] 'agreement among raft nodes before linearized reading' (duration: 1.001178595s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:08.11283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:07.11149Z","time spent":"1.001332642s","remote":"127.0.0.1:36442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-07T03:19:11.939444Z","caller":"traceutil/trace.go:171","msg":"trace[1331669341] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:640; }","duration":"416.955505ms","start":"2026-05-07T03:19:11.522458Z","end":"2026-05-07T03:19:11.939413Z","steps":["trace[1331669341] 'read index received' (duration: 414.69661ms)","trace[1331669341] 'applied index is now lower than readState.Index' (duration: 2.258225ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:19:11.939503Z","caller":"traceutil/trace.go:171","msg":"trace[1179624033] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"849.302254ms","start":"2026-05-07T03:19:11.090182Z","end":"2026-05-07T03:19:11.939484Z","steps":["trace[1179624033] 'process raft request' (duration: 847.80827ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:11.939594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.153043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:19:11.939669Z","caller":"traceutil/trace.go:171","msg":"trace[1544186098] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:614; }","duration":"417.284486ms","start":"2026-05-07T03:19:11.522365Z","end":"2026-05-07T03:19:11.939649Z","steps":["trace[1544186098] 'agreement among raft nodes before linearized reading' (duration: 417.157482ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:11.939718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:11.522342Z","time spent":"417.365219ms","remote":"127.0.0.1:36184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T03:19:11.939885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:11.090163Z","time spent":"849.516311ms","remote":"127.0.0.1:36256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036157,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:19:14.753757Z","caller":"traceutil/trace.go:171","msg":"trace[1989965512] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"159.102851ms","start":"2026-05-07T03:19:14.594608Z","end":"2026-05-07T03:19:14.75371Z","steps":["trace[1989965512] 'process raft request' (duration: 158.193595ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:14.753803Z","caller":"traceutil/trace.go:171","msg":"trace[731963205] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"158.908725ms","start":"2026-05-07T03:19:14.594868Z","end":"2026-05-07T03:19:14.753777Z","steps":["trace[731963205] 'process raft request' (duration: 158.776981ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:14.754199Z","caller":"traceutil/trace.go:171","msg":"trace[1777923025] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"158.500693ms","start":"2026-05-07T03:19:14.595672Z","end":"2026-05-07T03:19:14.754173Z","steps":["trace[1777923025] 'process raft request' (duration: 158.41094ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:14.754292Z","caller":"traceutil/trace.go:171","msg":"trace[1086617019] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"159.000306ms","start":"2026-05-07T03:19:14.595264Z","end":"2026-05-07T03:19:14.754264Z","steps":["trace[1086617019] 'process raft request' (duration: 158.447151ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:16.581312Z","caller":"traceutil/trace.go:171","msg":"trace[371455053] linearizableReadLoop","detail":"{readStateIndex:674; appliedIndex:673; }","duration":"421.313935ms","start":"2026-05-07T03:19:16.159972Z","end":"2026-05-07T03:19:16.581286Z","steps":["trace[371455053] 'read index received' (duration: 421.11791ms)","trace[371455053] 'applied index is now lower than readState.Index' (duration: 194.555µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:19:16.581425Z","caller":"traceutil/trace.go:171","msg":"trace[865897053] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"1.36250059s","start":"2026-05-07T03:19:15.218891Z","end":"2026-05-07T03:19:16.581391Z","steps":["trace[865897053] 'process raft request' (duration: 1.36218067s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:16.581568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.565344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-05-07T03:19:16.581633Z","caller":"traceutil/trace.go:171","msg":"trace[1394729424] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:646; }","duration":"421.693667ms","start":"2026-05-07T03:19:16.159924Z","end":"2026-05-07T03:19:16.581618Z","steps":["trace[1394729424] 'agreement among raft nodes before linearized reading' (duration: 421.504792ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:16.581682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:16.159901Z","time spent":"421.767709ms","remote":"127.0.0.1:36442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-07T03:19:16.581937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.521553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-07T03:19:16.582027Z","caller":"traceutil/trace.go:171","msg":"trace[68035912] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:646; }","duration":"421.607634ms","start":"2026-05-07T03:19:16.160379Z","end":"2026-05-07T03:19:16.581986Z","steps":["trace[68035912] 'agreement among raft nodes before linearized reading' (duration: 421.127091ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:16.582076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:16.16037Z","time spent":"421.693498ms","remote":"127.0.0.1:36442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-07T03:19:16.582177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:15.218871Z","time spent":"1.363187738s","remote":"127.0.0.1:36362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:19:17.088708Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836960328104532901,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-07T03:19:17.498875Z","caller":"traceutil/trace.go:171","msg":"trace[352850308] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"913.647716ms","start":"2026-05-07T03:19:16.585199Z","end":"2026-05-07T03:19:17.498847Z","steps":["trace[352850308] 'process raft request' (duration: 913.221784ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:17.499082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:16.585172Z","time spent":"913.78009ms","remote":"127.0.0.1:36442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:19:17.563849Z","caller":"traceutil/trace.go:171","msg":"trace[2003431299] linearizableReadLoop","detail":"{readStateIndex:677; appliedIndex:674; }","duration":"975.80671ms","start":"2026-05-07T03:19:16.588019Z","end":"2026-05-07T03:19:17.563826Z","steps":["trace[2003431299] 'read index received' (duration: 910.801647ms)","trace[2003431299] 'applied index is now lower than readState.Index' (duration: 65.004423ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:19:17.563909Z","caller":"traceutil/trace.go:171","msg":"trace[122873582] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"976.89522ms","start":"2026-05-07T03:19:16.586964Z","end":"2026-05-07T03:19:17.563859Z","steps":["trace[122873582] 'process raft request' (duration: 976.733186ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:17.563927Z","caller":"traceutil/trace.go:171","msg":"trace[1355502097] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"976.138309ms","start":"2026-05-07T03:19:16.587764Z","end":"2026-05-07T03:19:17.563902Z","steps":["trace[1355502097] 'process raft request' (duration: 976.035456ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:17.564088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"976.050297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-hdc68\" ","response":"range_response_count:1 size:17149"} {"level":"info","ts":"2026-05-07T03:19:17.564114Z","caller":"traceutil/trace.go:171","msg":"trace[228639142] range","detail":"{range_begin:/registry/pods/kube-system/cilium-hdc68; range_end:; response_count:1; response_revision:649; }","duration":"976.111838ms","start":"2026-05-07T03:19:16.587995Z","end":"2026-05-07T03:19:17.564107Z","steps":["trace[228639142] 'agreement among raft nodes before linearized reading' (duration: 975.969494ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:17.564115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:16.586943Z","time spent":"977.075144ms","remote":"127.0.0.1:36442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:19:17.564134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:16.587975Z","time spent":"976.154299ms","remote":"127.0.0.1:36362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17172,"request content":"key:\"/registry/pods/kube-system/cilium-hdc68\" "} {"level":"warn","ts":"2026-05-07T03:19:17.564224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:16.587745Z","time spent":"976.259052ms","remote":"127.0.0.1:36442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:19:17.564409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"971.950603ms","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-07T03:19:17.564526Z","caller":"traceutil/trace.go:171","msg":"trace[90736502] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:649; }","duration":"972.092207ms","start":"2026-05-07T03:19:16.592407Z","end":"2026-05-07T03:19:17.564499Z","steps":["trace[90736502] 'agreement among raft nodes before linearized reading' (duration: 971.817569ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:17.564579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:16.592399Z","time spent":"972.169248ms","remote":"127.0.0.1:36442","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-07T03:19:17.564426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"807.45936ms","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-07T03:19:17.564718Z","caller":"traceutil/trace.go:171","msg":"trace[1911179906] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:649; }","duration":"807.77875ms","start":"2026-05-07T03:19:16.756925Z","end":"2026-05-07T03:19:17.564704Z","steps":["trace[1911179906] 'agreement among raft nodes before linearized reading' (duration: 807.326217ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:17.564774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:16.75691Z","time spent":"807.833631ms","remote":"127.0.0.1:36442","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-07T03:19:17.682763Z","caller":"traceutil/trace.go:171","msg":"trace[1824113812] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"113.41809ms","start":"2026-05-07T03:19:17.569302Z","end":"2026-05-07T03:19:17.68272Z","steps":["trace[1824113812] 'process raft request' (duration: 113.317877ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:19.064881Z","caller":"traceutil/trace.go:171","msg":"trace[1770676172] linearizableReadLoop","detail":"{readStateIndex:681; appliedIndex:679; }","duration":"542.733631ms","start":"2026-05-07T03:19:18.522119Z","end":"2026-05-07T03:19:19.064853Z","steps":["trace[1770676172] 'read index received' (duration: 454.511537ms)","trace[1770676172] 'applied index is now lower than readState.Index' (duration: 88.220884ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:19:19.065022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.881394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:19:19.065072Z","caller":"traceutil/trace.go:171","msg":"trace[2054366027] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:652; }","duration":"542.977667ms","start":"2026-05-07T03:19:18.522082Z","end":"2026-05-07T03:19:19.06506Z","steps":["trace[2054366027] 'agreement among raft nodes before linearized reading' (duration: 542.878935ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:19.065122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:18.522067Z","time spent":"543.043629ms","remote":"127.0.0.1:36184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-07T03:19:19.065232Z","caller":"traceutil/trace.go:171","msg":"trace[1725952859] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"633.141389ms","start":"2026-05-07T03:19:18.432072Z","end":"2026-05-07T03:19:19.065213Z","steps":["trace[1725952859] 'process raft request' (duration: 632.623096ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:19.065362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:18.432056Z","time spent":"633.227022ms","remote":"127.0.0.1:36442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:19:20.028588Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836960328104532913,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T03:19:20.528688Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836960328104532913,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T03:19:21.028843Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836960328104532913,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T03:19:21.5278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000062287s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-07T03:19:21.563668Z","caller":"traceutil/trace.go:171","msg":"trace[905027787] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.03594799s","start":"2026-05-07T03:19:19.527668Z","end":"2026-05-07T03:19:21.563616Z","steps":["trace[905027787] 'agreement among raft nodes before linearized reading' (duration: 2.000074987s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:21.529349Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836960328104532913,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T03:19:21.564071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:19.527662Z","time spent":"2.036313999s","remote":"127.0.0.1:36184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T03:19:22.064816Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836960328104532913,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T03:19:22.565014Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836960328104532913,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T03:19:23.065683Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836960328104532913,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T03:19:23.550076Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"4.025923858s","expected-duration":"1s"} {"level":"info","ts":"2026-05-07T03:19:23.550503Z","caller":"traceutil/trace.go:171","msg":"trace[1529304223] linearizableReadLoop","detail":"{readStateIndex:682; appliedIndex:681; }","duration":"4.022789873s","start":"2026-05-07T03:19:19.527693Z","end":"2026-05-07T03:19:23.550483Z","steps":["trace[1529304223] 'read index received' (duration: 4.022579267s)","trace[1529304223] 'applied index is now lower than readState.Index' (duration: 209.806µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:19:23.550602Z","caller":"traceutil/trace.go:171","msg":"trace[1962270010] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"4.026559165s","start":"2026-05-07T03:19:19.524036Z","end":"2026-05-07T03:19:23.550595Z","steps":["trace[1962270010] 'process raft request' (duration: 4.026275637s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:23.55075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:19.524012Z","time spent":"4.026643786s","remote":"127.0.0.1:36442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:19:23.563263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.994373982s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-05-07T03:19:23.563295Z","caller":"traceutil/trace.go:171","msg":"trace[470966124] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:654; }","duration":"3.994445953s","start":"2026-05-07T03:19:19.568842Z","end":"2026-05-07T03:19:23.563288Z","steps":["trace[470966124] 'agreement among raft nodes before linearized reading' (duration: 3.994354961s)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:23.563264Z","caller":"traceutil/trace.go:171","msg":"trace[335354639] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"398.139091ms","start":"2026-05-07T03:19:23.165098Z","end":"2026-05-07T03:19:23.563237Z","steps":["trace[335354639] 'process raft request' (duration: 398.048969ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:23.563313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:19.568821Z","time spent":"3.994488514s","remote":"127.0.0.1:36442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-07T03:19:23.563345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.717952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.246\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-07T03:19:23.563404Z","caller":"traceutil/trace.go:171","msg":"trace[378114117] range","detail":"{range_begin:/registry/masterleases/199.204.45.246; range_end:; response_count:1; response_revision:654; }","duration":"237.803374ms","start":"2026-05-07T03:19:23.325588Z","end":"2026-05-07T03:19:23.563392Z","steps":["trace[378114117] 'agreement among raft nodes before linearized reading' (duration: 237.691521ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:23.563397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:23.165072Z","time spent":"398.254173ms","remote":"127.0.0.1:36442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:19:23.563459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.877504399s","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-07T03:19:23.563482Z","caller":"traceutil/trace.go:171","msg":"trace[944030174] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:654; }","duration":"3.87756194s","start":"2026-05-07T03:19:19.685915Z","end":"2026-05-07T03:19:23.563477Z","steps":["trace[944030174] 'agreement among raft nodes before linearized reading' (duration: 3.877491408s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:23.563495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:19.685897Z","time spent":"3.877594571s","remote":"127.0.0.1:36442","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-07T03:19:23.563252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.998848946s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:19:23.563589Z","caller":"traceutil/trace.go:171","msg":"trace[631482624] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:654; }","duration":"1.999228276s","start":"2026-05-07T03:19:21.564354Z","end":"2026-05-07T03:19:23.563582Z","steps":["trace[631482624] 'agreement among raft nodes before linearized reading' (duration: 1.998851266s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:23.563611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:21.564336Z","time spent":"1.999268806s","remote":"127.0.0.1:36180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T03:19:23.563646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.994386871s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-07T03:19:23.563679Z","caller":"traceutil/trace.go:171","msg":"trace[749114124] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:654; }","duration":"3.994454263s","start":"2026-05-07T03:19:19.569215Z","end":"2026-05-07T03:19:23.563669Z","steps":["trace[749114124] 'agreement among raft nodes before linearized reading' (duration: 3.99433568s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:23.563718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:19.569204Z","time spent":"3.994507684s","remote":"127.0.0.1:36442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-07T03:19:23.563727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.896008929s","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-07T03:19:23.56376Z","caller":"traceutil/trace.go:171","msg":"trace[567091076] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:654; }","duration":"3.896087921s","start":"2026-05-07T03:19:19.667663Z","end":"2026-05-07T03:19:23.563751Z","steps":["trace[567091076] 'agreement among raft nodes before linearized reading' (duration: 3.895986609s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:23.563797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:19.667637Z","time spent":"3.896152863s","remote":"127.0.0.1:36442","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-07T03:19:23.936643Z","caller":"traceutil/trace.go:171","msg":"trace[1480349126] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"368.370304ms","start":"2026-05-07T03:19:23.568249Z","end":"2026-05-07T03:19:23.93662Z","steps":["trace[1480349126] 'process raft request' (duration: 368.141488ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:23.936649Z","caller":"traceutil/trace.go:171","msg":"trace[1116276792] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"368.23305ms","start":"2026-05-07T03:19:23.568393Z","end":"2026-05-07T03:19:23.936626Z","steps":["trace[1116276792] 'process raft request' (duration: 368.109657ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:23.93667Z","caller":"traceutil/trace.go:171","msg":"trace[1090371468] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"369.406022ms","start":"2026-05-07T03:19:23.56724Z","end":"2026-05-07T03:19:23.936646Z","steps":["trace[1090371468] 'process raft request' (duration: 349.682028ms)","trace[1090371468] 'compare' (duration: 18.989775ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:19:23.936665Z","caller":"traceutil/trace.go:171","msg":"trace[95945210] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"367.232745ms","start":"2026-05-07T03:19:23.569406Z","end":"2026-05-07T03:19:23.936639Z","steps":["trace[95945210] 'process raft request' (duration: 367.144282ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:23.93677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:23.568384Z","time spent":"368.323743ms","remote":"127.0.0.1:36442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:19:23.936778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:23.567218Z","time spent":"369.500085ms","remote":"127.0.0.1:36442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:19:23.936781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:23.568231Z","time spent":"368.471717ms","remote":"127.0.0.1:36234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T03:19:23.936896Z","caller":"traceutil/trace.go:171","msg":"trace[1648238622] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"366.437614ms","start":"2026-05-07T03:19:23.570417Z","end":"2026-05-07T03:19:23.936854Z","steps":["trace[1648238622] 'process raft request' (duration: 366.187068ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:23.936945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.502166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:19:23.937049Z","caller":"traceutil/trace.go:171","msg":"trace[1339799854] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:659; }","duration":"366.62833ms","start":"2026-05-07T03:19:23.570411Z","end":"2026-05-07T03:19:23.937039Z","steps":["trace[1339799854] 'agreement among raft nodes before linearized reading' (duration: 366.318942ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:23.936707Z","caller":"traceutil/trace.go:171","msg":"trace[1993452968] linearizableReadLoop","detail":"{readStateIndex:688; appliedIndex:683; }","duration":"366.26034ms","start":"2026-05-07T03:19:23.570433Z","end":"2026-05-07T03:19:23.936693Z","steps":["trace[1993452968] 'read index received' (duration: 28.052821ms)","trace[1993452968] 'applied index is now lower than readState.Index' (duration: 338.203499ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:19:23.937132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:23.570404Z","time spent":"366.719712ms","remote":"127.0.0.1:36184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T03:19:23.937373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:23.57038Z","time spent":"366.624429ms","remote":"127.0.0.1:36442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:19:23.937658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:23.5694Z","time spent":"367.376069ms","remote":"127.0.0.1:36442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:19:23.941225Z","caller":"traceutil/trace.go:171","msg":"trace[1213621480] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"298.769273ms","start":"2026-05-07T03:19:23.642431Z","end":"2026-05-07T03:19:23.9412Z","steps":["trace[1213621480] 'process raft request' (duration: 298.599288ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:24.55264Z","caller":"traceutil/trace.go:171","msg":"trace[988363622] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"304.332144ms","start":"2026-05-07T03:19:24.248278Z","end":"2026-05-07T03:19:24.55261Z","steps":["trace[988363622] 'process raft request' (duration: 270.556753ms)","trace[988363622] 'compare' (duration: 33.311469ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:19:24.553053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:24.248257Z","time spent":"304.477497ms","remote":"127.0.0.1:36362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17232,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:19:25.470065Z","caller":"traceutil/trace.go:171","msg":"trace[1920018166] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"216.687679ms","start":"2026-05-07T03:19:25.253348Z","end":"2026-05-07T03:19:25.470036Z","steps":["trace[1920018166] 'process raft request' (duration: 216.491904ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:25.723895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.037465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:19:25.724009Z","caller":"traceutil/trace.go:171","msg":"trace[355251641] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:663; }","duration":"202.173389ms","start":"2026-05-07T03:19:25.521788Z","end":"2026-05-07T03:19:25.723961Z","steps":["trace[355251641] 'range keys from in-memory index tree' (duration: 201.961354ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:25.724215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.059292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-07T03:19:25.724318Z","caller":"traceutil/trace.go:171","msg":"trace[1825225728] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:663; }","duration":"169.228646ms","start":"2026-05-07T03:19:25.555061Z","end":"2026-05-07T03:19:25.72429Z","steps":["trace[1825225728] 'range keys from in-memory index tree' (duration: 168.790755ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:26.074535Z","caller":"traceutil/trace.go:171","msg":"trace[287018427] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"408.266651ms","start":"2026-05-07T03:19:25.666246Z","end":"2026-05-07T03:19:26.074512Z","steps":["trace[287018427] 'process raft request' (duration: 408.129017ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:26.074664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.061247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T03:19:26.074679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:25.666224Z","time spent":"408.394064ms","remote":"127.0.0.1:36234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":700,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T03:19:26.074556Z","caller":"traceutil/trace.go:171","msg":"trace[223208845] linearizableReadLoop","detail":"{readStateIndex:694; appliedIndex:694; }","duration":"348.935114ms","start":"2026-05-07T03:19:25.725601Z","end":"2026-05-07T03:19:26.074536Z","steps":["trace[223208845] 'read index received' (duration: 348.919474ms)","trace[223208845] 'applied index is now lower than readState.Index' (duration: 13.95µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:19:26.074726Z","caller":"traceutil/trace.go:171","msg":"trace[1824090054] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:664; }","duration":"349.143369ms","start":"2026-05-07T03:19:25.725553Z","end":"2026-05-07T03:19:26.074697Z","steps":["trace[1824090054] 'agreement among raft nodes before linearized reading' (duration: 349.042957ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:26.074773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:25.725468Z","time spent":"349.293903ms","remote":"127.0.0.1:36180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-07T03:19:26.282917Z","caller":"traceutil/trace.go:171","msg":"trace[697234385] linearizableReadLoop","detail":"{readStateIndex:695; appliedIndex:694; }","duration":"208.130462ms","start":"2026-05-07T03:19:26.074763Z","end":"2026-05-07T03:19:26.282893Z","steps":["trace[697234385] 'read index received' (duration: 205.158337ms)","trace[697234385] 'applied index is now lower than readState.Index' (duration: 2.970455ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:19:26.2831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.525196ms","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-07T03:19:26.283154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.648058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-07T03:19:26.283167Z","caller":"traceutil/trace.go:171","msg":"trace[623176124] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:665; }","duration":"341.639679ms","start":"2026-05-07T03:19:25.94151Z","end":"2026-05-07T03:19:26.28315Z","steps":["trace[623176124] 'agreement among raft nodes before linearized reading' (duration: 341.450044ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:26.283206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:25.94149Z","time spent":"341.70894ms","remote":"127.0.0.1:36442","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-07T03:19:26.283336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.520421ms","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-07T03:19:26.283394Z","caller":"traceutil/trace.go:171","msg":"trace[54211478] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:665; }","duration":"342.617654ms","start":"2026-05-07T03:19:25.940759Z","end":"2026-05-07T03:19:26.283376Z","steps":["trace[54211478] 'agreement among raft nodes before linearized reading' (duration: 342.476861ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:26.283431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:25.940736Z","time spent":"342.686456ms","remote":"127.0.0.1:36442","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-07T03:19:26.283506Z","caller":"traceutil/trace.go:171","msg":"trace[1747997470] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"556.181234ms","start":"2026-05-07T03:19:25.727294Z","end":"2026-05-07T03:19:26.283475Z","steps":["trace[1747997470] 'process raft request' (duration: 552.644634ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:26.283193Z","caller":"traceutil/trace.go:171","msg":"trace[997187034] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:665; }","duration":"341.724581ms","start":"2026-05-07T03:19:25.941455Z","end":"2026-05-07T03:19:26.283179Z","steps":["trace[997187034] 'agreement among raft nodes before linearized reading' (duration: 341.553686ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:26.283692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:25.727264Z","time spent":"556.284327ms","remote":"127.0.0.1:36442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:19:26.283703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.765177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-05-07T03:19:26.283728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:25.941441Z","time spent":"342.244074ms","remote":"127.0.0.1:36442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-07T03:19:26.283787Z","caller":"traceutil/trace.go:171","msg":"trace[631578446] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:665; }","duration":"342.9117ms","start":"2026-05-07T03:19:25.940856Z","end":"2026-05-07T03:19:26.283767Z","steps":["trace[631578446] 'agreement among raft nodes before linearized reading' (duration: 342.633613ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:26.283845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:25.940829Z","time spent":"342.999533ms","remote":"127.0.0.1:36442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-07T03:19:28.629969Z","caller":"traceutil/trace.go:171","msg":"trace[2112172027] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"284.523365ms","start":"2026-05-07T03:19:28.345417Z","end":"2026-05-07T03:19:28.629941Z","steps":["trace[2112172027] 'process raft request' (duration: 284.31717ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:28.630244Z","caller":"traceutil/trace.go:171","msg":"trace[439504666] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"284.037134ms","start":"2026-05-07T03:19:28.346128Z","end":"2026-05-07T03:19:28.630165Z","steps":["trace[439504666] 'process raft request' (duration: 283.764187ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:28.766967Z","caller":"traceutil/trace.go:171","msg":"trace[2051745650] linearizableReadLoop","detail":"{readStateIndex:713; appliedIndex:710; }","duration":"244.213424ms","start":"2026-05-07T03:19:28.522728Z","end":"2026-05-07T03:19:28.766941Z","steps":["trace[2051745650] 'read index received' (duration: 107.015231ms)","trace[2051745650] 'applied index is now lower than readState.Index' (duration: 137.192743ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:19:28.76699Z","caller":"traceutil/trace.go:171","msg":"trace[581866125] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"222.947064ms","start":"2026-05-07T03:19:28.544008Z","end":"2026-05-07T03:19:28.766955Z","steps":["trace[581866125] 'process raft request' (duration: 222.79327ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:28.767141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.405509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:19:28.767178Z","caller":"traceutil/trace.go:171","msg":"trace[111445685] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:683; }","duration":"244.494661ms","start":"2026-05-07T03:19:28.522674Z","end":"2026-05-07T03:19:28.767168Z","steps":["trace[111445685] 'agreement among raft nodes before linearized reading' (duration: 244.364598ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:30.664455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.952807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:19:30.664554Z","caller":"traceutil/trace.go:171","msg":"trace[1909400139] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:695; }","duration":"144.114431ms","start":"2026-05-07T03:19:30.520418Z","end":"2026-05-07T03:19:30.664533Z","steps":["trace[1909400139] 'range keys from in-memory index tree' (duration: 143.858416ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:33.826639Z","caller":"traceutil/trace.go:171","msg":"trace[2082995640] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"318.726231ms","start":"2026-05-07T03:19:33.507891Z","end":"2026-05-07T03:19:33.826617Z","steps":["trace[2082995640] 'process raft request' (duration: 267.539094ms)","trace[2082995640] 'compare' (duration: 50.947291ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:19:33.826754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:33.507872Z","time spent":"318.824893ms","remote":"127.0.0.1:33432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1697,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:19:33.828266Z","caller":"traceutil/trace.go:171","msg":"trace[1555177348] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"175.812102ms","start":"2026-05-07T03:19:33.65243Z","end":"2026-05-07T03:19:33.828243Z","steps":["trace[1555177348] 'process raft request' (duration: 175.679198ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:34.331012Z","caller":"traceutil/trace.go:171","msg":"trace[131058298] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"123.266007ms","start":"2026-05-07T03:19:34.207713Z","end":"2026-05-07T03:19:34.330979Z","steps":["trace[131058298] 'process raft request' (duration: 123.123063ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:34.4548Z","caller":"traceutil/trace.go:171","msg":"trace[1444252738] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"111.51963ms","start":"2026-05-07T03:19:34.343255Z","end":"2026-05-07T03:19:34.454775Z","steps":["trace[1444252738] 'process raft request' (duration: 12.622587ms)","trace[1444252738] 'compare' (duration: 98.594596ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:19:34.913999Z","caller":"traceutil/trace.go:171","msg":"trace[105783636] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"139.09187ms","start":"2026-05-07T03:19:34.774877Z","end":"2026-05-07T03:19:34.913969Z","steps":["trace[105783636] 'process raft request' (duration: 125.763046ms)","trace[105783636] 'compare' (duration: 13.135529ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:19:35.777475Z","caller":"traceutil/trace.go:171","msg":"trace[1870860455] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"108.796564ms","start":"2026-05-07T03:19:35.668648Z","end":"2026-05-07T03:19:35.777445Z","steps":["trace[1870860455] 'process raft request' (duration: 108.611589ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:36.265947Z","caller":"traceutil/trace.go:171","msg":"trace[1218796635] linearizableReadLoop","detail":"{readStateIndex:758; appliedIndex:757; }","duration":"413.456921ms","start":"2026-05-07T03:19:35.852462Z","end":"2026-05-07T03:19:36.265919Z","steps":["trace[1218796635] 'read index received' (duration: 407.222937ms)","trace[1218796635] 'applied index is now lower than readState.Index' (duration: 6.232594ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:19:36.266008Z","caller":"traceutil/trace.go:171","msg":"trace[1322013567] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"470.773861ms","start":"2026-05-07T03:19:35.795201Z","end":"2026-05-07T03:19:36.265975Z","steps":["trace[1322013567] 'process raft request' (duration: 464.558828ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:36.266138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.648515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T03:19:36.26619Z","caller":"traceutil/trace.go:171","msg":"trace[503201786] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:725; }","duration":"413.717737ms","start":"2026-05-07T03:19:35.852456Z","end":"2026-05-07T03:19:36.266173Z","steps":["trace[503201786] 'agreement among raft nodes before linearized reading' (duration: 413.615825ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:36.266209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:35.79518Z","time spent":"470.930016ms","remote":"127.0.0.1:36362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:19:36.266239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:35.852406Z","time spent":"413.817989ms","remote":"127.0.0.1:36698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":30,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-05-07T03:19:36.266211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.562185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-07T03:19:36.2663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.411371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T03:19:36.266302Z","caller":"traceutil/trace.go:171","msg":"trace[2119553279] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:725; }","duration":"256.701638ms","start":"2026-05-07T03:19:36.009578Z","end":"2026-05-07T03:19:36.266279Z","steps":["trace[2119553279] 'agreement among raft nodes before linearized reading' (duration: 256.526104ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:36.266351Z","caller":"traceutil/trace.go:171","msg":"trace[2132333004] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:725; }","duration":"111.480153ms","start":"2026-05-07T03:19:36.154854Z","end":"2026-05-07T03:19:36.266335Z","steps":["trace[2132333004] 'agreement among raft nodes before linearized reading' (duration: 111.392871ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:36.564299Z","caller":"traceutil/trace.go:171","msg":"trace[370818046] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"288.702451ms","start":"2026-05-07T03:19:36.275545Z","end":"2026-05-07T03:19:36.564248Z","steps":["trace[370818046] 'process raft request' (duration: 288.543368ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:36.734893Z","caller":"traceutil/trace.go:171","msg":"trace[1894222302] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"457.481113ms","start":"2026-05-07T03:19:36.277352Z","end":"2026-05-07T03:19:36.734833Z","steps":["trace[1894222302] 'process raft request' (duration: 453.432329ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:36.735125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.800895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-07T03:19:36.735214Z","caller":"traceutil/trace.go:171","msg":"trace[790528702] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:727; }","duration":"276.899127ms","start":"2026-05-07T03:19:36.458293Z","end":"2026-05-07T03:19:36.735192Z","steps":["trace[790528702] 'agreement among raft nodes before linearized reading' (duration: 276.659392ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:36.735367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:36.277339Z","time spent":"457.678717ms","remote":"127.0.0.1:36664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14465,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:19:36.736201Z","caller":"traceutil/trace.go:171","msg":"trace[1253311243] linearizableReadLoop","detail":"{readStateIndex:760; appliedIndex:758; }","duration":"276.406175ms","start":"2026-05-07T03:19:36.458304Z","end":"2026-05-07T03:19:36.73471Z","steps":["trace[1253311243] 'read index received' (duration: 105.74013ms)","trace[1253311243] 'applied index is now lower than readState.Index' (duration: 170.664055ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:19:36.765121Z","caller":"traceutil/trace.go:171","msg":"trace[1160980485] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"195.160752ms","start":"2026-05-07T03:19:36.569928Z","end":"2026-05-07T03:19:36.765088Z","steps":["trace[1160980485] 'process raft request' (duration: 195.04086ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:36.765188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.186969ms","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-07T03:19:36.765171Z","caller":"traceutil/trace.go:171","msg":"trace[31838801] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"195.587772ms","start":"2026-05-07T03:19:36.569553Z","end":"2026-05-07T03:19:36.765141Z","steps":["trace[31838801] 'process raft request' (duration: 195.294475ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:36.765278Z","caller":"traceutil/trace.go:171","msg":"trace[793658046] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:730; }","duration":"257.307902ms","start":"2026-05-07T03:19:36.507951Z","end":"2026-05-07T03:19:36.765259Z","steps":["trace[793658046] 'agreement among raft nodes before linearized reading' (duration: 257.120038ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:36.765411Z","caller":"traceutil/trace.go:171","msg":"trace[1108781684] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"192.963702ms","start":"2026-05-07T03:19:36.572435Z","end":"2026-05-07T03:19:36.765399Z","steps":["trace[1108781684] 'process raft request' (duration: 192.572872ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:36.765519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.036813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:19:36.765601Z","caller":"traceutil/trace.go:171","msg":"trace[1999605941] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:730; }","duration":"240.179836ms","start":"2026-05-07T03:19:36.5254Z","end":"2026-05-07T03:19:36.76558Z","steps":["trace[1999605941] 'agreement among raft nodes before linearized reading' (duration: 240.043903ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:36.765671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.581958ms","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-07T03:19:36.765719Z","caller":"traceutil/trace.go:171","msg":"trace[1710805516] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:730; }","duration":"225.65417ms","start":"2026-05-07T03:19:36.540054Z","end":"2026-05-07T03:19:36.765708Z","steps":["trace[1710805516] 'agreement among raft nodes before linearized reading' (duration: 225.526177ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:39.995566Z","caller":"traceutil/trace.go:171","msg":"trace[201714219] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"486.376598ms","start":"2026-05-07T03:19:39.509158Z","end":"2026-05-07T03:19:39.995535Z","steps":["trace[201714219] 'process raft request' (duration: 486.145442ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:39.995729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:39.509127Z","time spent":"486.51568ms","remote":"127.0.0.1:36442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:19:43.300765Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836960328104533153,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T03:19:43.801246Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836960328104533153,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T03:19:44.301662Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836960328104533153,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T03:19:44.641001Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.293793202s","expected-duration":"1s"} {"level":"info","ts":"2026-05-07T03:19:44.641546Z","caller":"traceutil/trace.go:171","msg":"trace[347395413] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"2.294489426s","start":"2026-05-07T03:19:42.347029Z","end":"2026-05-07T03:19:44.641519Z","steps":["trace[347395413] 'process raft request' (duration: 2.294337013s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:44.641705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:42.347007Z","time spent":"2.29461425s","remote":"127.0.0.1:36362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:19:44.686205Z","caller":"traceutil/trace.go:171","msg":"trace[1240831491] linearizableReadLoop","detail":"{readStateIndex:812; appliedIndex:810; }","duration":"1.886473561s","start":"2026-05-07T03:19:42.799701Z","end":"2026-05-07T03:19:44.686175Z","steps":["trace[1240831491] 'read index received' (duration: 1.841539838s)","trace[1240831491] 'applied index is now lower than readState.Index' (duration: 44.932163ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:19:44.686352Z","caller":"traceutil/trace.go:171","msg":"trace[469608091] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"725.823878ms","start":"2026-05-07T03:19:43.960475Z","end":"2026-05-07T03:19:44.686299Z","steps":["trace[469608091] 'process raft request' (duration: 708.476632ms)","trace[469608091] 'compare' (duration: 17.096091ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:19:44.686516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.886792158s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-07T03:19:44.686575Z","caller":"traceutil/trace.go:171","msg":"trace[281930695] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:777; }","duration":"1.886911041s","start":"2026-05-07T03:19:42.799645Z","end":"2026-05-07T03:19:44.686556Z","steps":["trace[281930695] 'agreement among raft nodes before linearized reading' (duration: 1.886720796s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:44.686565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:43.960448Z","time spent":"726.002052ms","remote":"127.0.0.1:36442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:19:44.686624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:42.799618Z","time spent":"1.886993763s","remote":"127.0.0.1:36442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-07T03:19:44.686572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.831709955s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-07T03:19:44.68674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.823194477s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-05-07T03:19:44.686789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.358828011s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.246\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-07T03:19:44.686844Z","caller":"traceutil/trace.go:171","msg":"trace[924403308] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:777; }","duration":"1.82335015s","start":"2026-05-07T03:19:42.863476Z","end":"2026-05-07T03:19:44.686826Z","steps":["trace[924403308] 'agreement among raft nodes before linearized reading' (duration: 1.823172646s)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:44.686884Z","caller":"traceutil/trace.go:171","msg":"trace[1030576970] range","detail":"{range_begin:/registry/masterleases/199.204.45.246; range_end:; response_count:1; response_revision:777; }","duration":"1.358939734s","start":"2026-05-07T03:19:43.327908Z","end":"2026-05-07T03:19:44.686848Z","steps":["trace[1030576970] 'agreement among raft nodes before linearized reading' (duration: 1.358605967s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:44.686919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:42.863466Z","time spent":"1.823432612s","remote":"127.0.0.1:36442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-07T03:19:44.686792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.820491907s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-07T03:19:44.68695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:43.327891Z","time spent":"1.359047886s","remote":"127.0.0.1:36188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.246\" "} {"level":"info","ts":"2026-05-07T03:19:44.686989Z","caller":"traceutil/trace.go:171","msg":"trace[1113248017] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:777; }","duration":"1.820715172s","start":"2026-05-07T03:19:42.866253Z","end":"2026-05-07T03:19:44.686968Z","steps":["trace[1113248017] 'agreement among raft nodes before linearized reading' (duration: 1.820374025s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:44.687061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:42.866234Z","time spent":"1.820805585s","remote":"127.0.0.1:36442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-07T03:19:44.686764Z","caller":"traceutil/trace.go:171","msg":"trace[1253882939] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:777; }","duration":"1.831929239s","start":"2026-05-07T03:19:42.854806Z","end":"2026-05-07T03:19:44.686735Z","steps":["trace[1253882939] 'agreement among raft nodes before linearized reading' (duration: 1.831571852s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:44.687144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.165119578s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:19:44.687214Z","caller":"traceutil/trace.go:171","msg":"trace[283678732] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:777; }","duration":"1.165191719s","start":"2026-05-07T03:19:43.522004Z","end":"2026-05-07T03:19:44.687196Z","steps":["trace[283678732] 'agreement among raft nodes before linearized reading' (duration: 1.164422893s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:44.68724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.743322378s","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-07T03:19:44.687419Z","caller":"traceutil/trace.go:171","msg":"trace[1535872695] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:777; }","duration":"1.743685885s","start":"2026-05-07T03:19:42.943719Z","end":"2026-05-07T03:19:44.687405Z","steps":["trace[1535872695] 'agreement among raft nodes before linearized reading' (duration: 1.742747105s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:44.687323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:43.52195Z","time spent":"1.165304941s","remote":"127.0.0.1:36184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T03:19:44.687491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:42.943701Z","time spent":"1.743774897s","remote":"127.0.0.1:36442","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-07T03:19:44.687358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:42.854789Z","time spent":"1.83238884s","remote":"127.0.0.1:36442","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-07T03:19:48.769311Z","caller":"traceutil/trace.go:171","msg":"trace[2065846965] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"105.864271ms","start":"2026-05-07T03:19:48.662909Z","end":"2026-05-07T03:19:48.768773Z","steps":["trace[2065846965] 'process raft request' (duration: 98.781163ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:49.002586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.980387ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:19:49.002712Z","caller":"traceutil/trace.go:171","msg":"trace[1618945663] linearizableReadLoop","detail":"{readStateIndex:836; appliedIndex:835; }","duration":"186.387494ms","start":"2026-05-07T03:19:48.816304Z","end":"2026-05-07T03:19:49.002692Z","steps":["trace[1618945663] 'read index received' (duration: 68.241614ms)","trace[1618945663] 'applied index is now lower than readState.Index' (duration: 118.14407ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:19:49.002839Z","caller":"traceutil/trace.go:171","msg":"trace[1518693762] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"192.468431ms","start":"2026-05-07T03:19:48.810348Z","end":"2026-05-07T03:19:49.002816Z","steps":["trace[1518693762] 'process raft request' (duration: 74.177147ms)","trace[1518693762] 'compare' (duration: 117.803624ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:19:49.003158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.841593ms","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-07T03:19:49.003215Z","caller":"traceutil/trace.go:171","msg":"trace[1308683861] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:799; }","duration":"186.938585ms","start":"2026-05-07T03:19:48.816263Z","end":"2026-05-07T03:19:49.003201Z","steps":["trace[1308683861] 'agreement among raft nodes before linearized reading' (duration: 186.663759ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:49.003259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.206058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-07T03:19:49.003309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.202739ms","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-07T03:19:49.003323Z","caller":"traceutil/trace.go:171","msg":"trace[1635789507] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:799; }","duration":"185.310211ms","start":"2026-05-07T03:19:48.817994Z","end":"2026-05-07T03:19:49.003304Z","steps":["trace[1635789507] 'agreement among raft nodes before linearized reading' (duration: 185.083676ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:49.003341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.082836ms","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-07T03:19:49.003353Z","caller":"traceutil/trace.go:171","msg":"trace[1803374659] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:799; }","duration":"185.28722ms","start":"2026-05-07T03:19:48.818052Z","end":"2026-05-07T03:19:49.003339Z","steps":["trace[1803374659] 'agreement among raft nodes before linearized reading' (duration: 185.164458ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:49.003395Z","caller":"traceutil/trace.go:171","msg":"trace[1414888799] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:799; }","duration":"185.140607ms","start":"2026-05-07T03:19:48.818239Z","end":"2026-05-07T03:19:49.00338Z","steps":["trace[1414888799] 'agreement among raft nodes before linearized reading' (duration: 184.973724ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:49.003498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.439423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-07T03:19:49.003523Z","caller":"traceutil/trace.go:171","msg":"trace[1356195473] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:799; }","duration":"184.485223ms","start":"2026-05-07T03:19:48.81903Z","end":"2026-05-07T03:19:49.003515Z","steps":["trace[1356195473] 'agreement among raft nodes before linearized reading' (duration: 184.412912ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:49.216899Z","caller":"traceutil/trace.go:171","msg":"trace[1477009085] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"160.427132ms","start":"2026-05-07T03:19:49.056401Z","end":"2026-05-07T03:19:49.216828Z","steps":["trace[1477009085] 'process raft request' (duration: 160.319729ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:49.356732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.672475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:116"} {"level":"info","ts":"2026-05-07T03:19:49.356824Z","caller":"traceutil/trace.go:171","msg":"trace[1141989409] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:808; }","duration":"135.819308ms","start":"2026-05-07T03:19:49.220985Z","end":"2026-05-07T03:19:49.356804Z","steps":["trace[1141989409] 'range keys from in-memory index tree' (duration: 135.547103ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:49.625924Z","caller":"traceutil/trace.go:171","msg":"trace[385976641] linearizableReadLoop","detail":"{readStateIndex:846; appliedIndex:845; }","duration":"102.875012ms","start":"2026-05-07T03:19:49.523024Z","end":"2026-05-07T03:19:49.625899Z","steps":["trace[385976641] 'read index received' (duration: 102.600637ms)","trace[385976641] 'applied index is now lower than readState.Index' (duration: 273.535µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:19:49.625985Z","caller":"traceutil/trace.go:171","msg":"trace[1473147937] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"266.469621ms","start":"2026-05-07T03:19:49.359474Z","end":"2026-05-07T03:19:49.625943Z","steps":["trace[1473147937] 'process raft request' (duration: 266.262947ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:49.626058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.020175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:19:49.626132Z","caller":"traceutil/trace.go:171","msg":"trace[1660089745] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:809; }","duration":"103.147308ms","start":"2026-05-07T03:19:49.522974Z","end":"2026-05-07T03:19:49.626122Z","steps":["trace[1660089745] 'agreement among raft nodes before linearized reading' (duration: 103.018355ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:49.761547Z","caller":"traceutil/trace.go:171","msg":"trace[1332478181] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"133.337148ms","start":"2026-05-07T03:19:49.628174Z","end":"2026-05-07T03:19:49.761511Z","steps":["trace[1332478181] 'process raft request' (duration: 54.219369ms)","trace[1332478181] 'compare' (duration: 78.991506ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:19:50.313501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.266512ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:19:50.313602Z","caller":"traceutil/trace.go:171","msg":"trace[512985340] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"547.005187ms","start":"2026-05-07T03:19:49.766579Z","end":"2026-05-07T03:19:50.313584Z","steps":["trace[512985340] 'process raft request' (duration: 130.591281ms)","trace[512985340] 'compare' (duration: 415.789843ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:19:50.313734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:49.766559Z","time spent":"547.073568ms","remote":"127.0.0.1:36456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1329,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T03:19:50.404119Z","caller":"traceutil/trace.go:171","msg":"trace[230484791] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"634.509494ms","start":"2026-05-07T03:19:49.769585Z","end":"2026-05-07T03:19:50.404095Z","steps":["trace[230484791] 'process raft request' (duration: 634.455743ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:50.404472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:49.76956Z","time spent":"634.791851ms","remote":"127.0.0.1:36640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4761,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T03:19:50.404142Z","caller":"traceutil/trace.go:171","msg":"trace[1588464731] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"637.019007ms","start":"2026-05-07T03:19:49.767086Z","end":"2026-05-07T03:19:50.404105Z","steps":["trace[1588464731] 'process raft request' (duration: 636.753531ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:50.405269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:49.767078Z","time spent":"638.146771ms","remote":"127.0.0.1:36334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":860,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T03:19:50.753449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.762105ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:19:50.753709Z","caller":"traceutil/trace.go:171","msg":"trace[1159042141] linearizableReadLoop","detail":"{readStateIndex:852; appliedIndex:850; }","duration":"230.161048ms","start":"2026-05-07T03:19:50.523534Z","end":"2026-05-07T03:19:50.753696Z","steps":["trace[1159042141] 'read index received' (duration: 6.081006ms)","trace[1159042141] 'applied index is now lower than readState.Index' (duration: 224.079272ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:19:50.75377Z","caller":"traceutil/trace.go:171","msg":"trace[1125816541] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"343.212595ms","start":"2026-05-07T03:19:50.410532Z","end":"2026-05-07T03:19:50.753745Z","steps":["trace[1125816541] 'process raft request' (duration: 343.117003ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:50.753873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.343362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:19:50.75391Z","caller":"traceutil/trace.go:171","msg":"trace[1556428783] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:815; }","duration":"230.481594ms","start":"2026-05-07T03:19:50.523421Z","end":"2026-05-07T03:19:50.753903Z","steps":["trace[1556428783] 'agreement among raft nodes before linearized reading' (duration: 230.380232ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:50.753913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:50.410514Z","time spent":"343.323977ms","remote":"127.0.0.1:36666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4982,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T03:19:50.754136Z","caller":"traceutil/trace.go:171","msg":"trace[653104095] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"555.045443ms","start":"2026-05-07T03:19:50.199058Z","end":"2026-05-07T03:19:50.754103Z","steps":["trace[653104095] 'process raft request' (duration: 330.549073ms)","trace[653104095] 'compare' (duration: 223.642973ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:19:50.754485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:50.199033Z","time spent":"555.175327ms","remote":"127.0.0.1:36442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:19:51.230818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.34484ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:19:51.230914Z","caller":"traceutil/trace.go:171","msg":"trace[283641287] linearizableReadLoop","detail":"{readStateIndex:853; appliedIndex:852; }","duration":"469.864327ms","start":"2026-05-07T03:19:50.761036Z","end":"2026-05-07T03:19:51.2309Z","steps":["trace[283641287] 'read index received' (duration: 98.321843ms)","trace[283641287] 'applied index is now lower than readState.Index' (duration: 371.541394ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:19:51.23097Z","caller":"traceutil/trace.go:171","msg":"trace[1334472394] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"470.674924ms","start":"2026-05-07T03:19:50.760287Z","end":"2026-05-07T03:19:51.230962Z","steps":["trace[1334472394] 'process raft request' (duration: 99.12758ms)","trace[1334472394] 'compare' (duration: 371.245448ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:19:51.231011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:50.760273Z","time spent":"470.720725ms","remote":"127.0.0.1:36234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":731,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T03:19:51.231202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.160453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/envoy-gateway-system/\" range_end:\"/registry/limitranges/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:19:51.231232Z","caller":"traceutil/trace.go:171","msg":"trace[1179136557] range","detail":"{range_begin:/registry/limitranges/envoy-gateway-system/; range_end:/registry/limitranges/envoy-gateway-system0; response_count:0; response_revision:816; }","duration":"470.233844ms","start":"2026-05-07T03:19:50.760988Z","end":"2026-05-07T03:19:51.231222Z","steps":["trace[1179136557] 'agreement among raft nodes before linearized reading' (duration: 470.165993ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:51.231255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:50.760969Z","time spent":"470.281095ms","remote":"127.0.0.1:36312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/envoy-gateway-system/\" range_end:\"/registry/limitranges/envoy-gateway-system0\" "} {"level":"info","ts":"2026-05-07T03:19:51.264417Z","caller":"traceutil/trace.go:171","msg":"trace[1972799428] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"501.634769ms","start":"2026-05-07T03:19:50.762758Z","end":"2026-05-07T03:19:51.264393Z","steps":["trace[1972799428] 'process raft request' (duration: 501.363453ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:51.264704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:50.762745Z","time spent":"501.895014ms","remote":"127.0.0.1:36640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5251,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:19:51.266933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.149981ms","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-07T03:19:51.266947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.868506ms","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-07T03:19:51.266998Z","caller":"traceutil/trace.go:171","msg":"trace[200169915] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:818; }","duration":"210.232192ms","start":"2026-05-07T03:19:51.056748Z","end":"2026-05-07T03:19:51.26698Z","steps":["trace[200169915] 'agreement among raft nodes before linearized reading' (duration: 209.996107ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:51.267045Z","caller":"traceutil/trace.go:171","msg":"trace[1339771107] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:818; }","duration":"250.007079ms","start":"2026-05-07T03:19:51.017Z","end":"2026-05-07T03:19:51.267007Z","steps":["trace[1339771107] 'agreement among raft nodes before linearized reading' (duration: 249.724423ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:51.267327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.56483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-07T03:19:51.267389Z","caller":"traceutil/trace.go:171","msg":"trace[114440774] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:818; }","duration":"210.664691ms","start":"2026-05-07T03:19:51.056708Z","end":"2026-05-07T03:19:51.267372Z","steps":["trace[114440774] 'agreement among raft nodes before linearized reading' (duration: 210.492248ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:51.267727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.273644ms","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-07T03:19:51.267804Z","caller":"traceutil/trace.go:171","msg":"trace[1949657810] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:818; }","duration":"211.380066ms","start":"2026-05-07T03:19:51.056408Z","end":"2026-05-07T03:19:51.267788Z","steps":["trace[1949657810] 'agreement among raft nodes before linearized reading' (duration: 211.150351ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:51.267729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.015749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-07T03:19:51.267955Z","caller":"traceutil/trace.go:171","msg":"trace[1358820242] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:818; }","duration":"212.232163ms","start":"2026-05-07T03:19:51.055671Z","end":"2026-05-07T03:19:51.267903Z","steps":["trace[1358820242] 'agreement among raft nodes before linearized reading' (duration: 211.960637ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:51.268408Z","caller":"traceutil/trace.go:171","msg":"trace[46642504] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"432.58718ms","start":"2026-05-07T03:19:50.834643Z","end":"2026-05-07T03:19:51.26723Z","steps":["trace[46642504] 'process raft request' (duration: 430.410635ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:51.268699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:50.834614Z","time spent":"433.933369ms","remote":"127.0.0.1:36256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036286,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:19:51.583623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.963173ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:19:51.583748Z","caller":"traceutil/trace.go:171","msg":"trace[1850405491] linearizableReadLoop","detail":"{readStateIndex:856; appliedIndex:855; }","duration":"311.313895ms","start":"2026-05-07T03:19:51.27241Z","end":"2026-05-07T03:19:51.583723Z","steps":["trace[1850405491] 'read index received' (duration: 108.158349ms)","trace[1850405491] 'applied index is now lower than readState.Index' (duration: 203.153116ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:19:51.583801Z","caller":"traceutil/trace.go:171","msg":"trace[518210346] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"347.927967ms","start":"2026-05-07T03:19:51.235831Z","end":"2026-05-07T03:19:51.583759Z","steps":["trace[518210346] 'process raft request' (duration: 144.7522ms)","trace[518210346] 'compare' (duration: 202.80153ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:19:51.583971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:51.235806Z","time spent":"348.08058ms","remote":"127.0.0.1:36362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4434,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T03:19:51.584088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.658822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:1 size:5266"} {"level":"info","ts":"2026-05-07T03:19:51.584144Z","caller":"traceutil/trace.go:171","msg":"trace[95617715] range","detail":"{range_begin:/registry/deployments/envoy-gateway-system/envoy-gateway; range_end:; response_count:1; response_revision:819; }","duration":"311.752185ms","start":"2026-05-07T03:19:51.272375Z","end":"2026-05-07T03:19:51.584127Z","steps":["trace[95617715] 'agreement among raft nodes before linearized reading' (duration: 311.453669ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:51.584206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:51.272352Z","time spent":"311.839046ms","remote":"127.0.0.1:36640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":5289,"request content":"key:\"/registry/deployments/envoy-gateway-system/envoy-gateway\" "} {"level":"info","ts":"2026-05-07T03:19:51.612496Z","caller":"traceutil/trace.go:171","msg":"trace[281316643] transaction","detail":"{read_only:false; response_revision:822; number_of_response:1; }","duration":"338.8482ms","start":"2026-05-07T03:19:51.273625Z","end":"2026-05-07T03:19:51.612474Z","steps":["trace[281316643] 'process raft request' (duration: 338.787959ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:51.612519Z","caller":"traceutil/trace.go:171","msg":"trace[2104828290] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"339.092115ms","start":"2026-05-07T03:19:51.273393Z","end":"2026-05-07T03:19:51.612485Z","steps":["trace[2104828290] 'process raft request' (duration: 338.877331ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:51.612647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:51.273596Z","time spent":"338.970833ms","remote":"127.0.0.1:36442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:19:51.612678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:51.273362Z","time spent":"339.22974ms","remote":"127.0.0.1:36442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:19:51.612821Z","caller":"traceutil/trace.go:171","msg":"trace[72408950] transaction","detail":"{read_only:false; response_revision:821; number_of_response:1; }","duration":"338.989984ms","start":"2026-05-07T03:19:51.273585Z","end":"2026-05-07T03:19:51.612574Z","steps":["trace[72408950] 'process raft request' (duration: 338.799439ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:51.613003Z","caller":"traceutil/trace.go:171","msg":"trace[167625096] transaction","detail":"{read_only:false; response_revision:823; number_of_response:1; }","duration":"337.505103ms","start":"2026-05-07T03:19:51.27548Z","end":"2026-05-07T03:19:51.612985Z","steps":["trace[167625096] 'process raft request' (duration: 336.962912ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:51.613106Z","caller":"traceutil/trace.go:171","msg":"trace[362126292] transaction","detail":"{read_only:false; response_revision:824; number_of_response:1; }","duration":"337.562745ms","start":"2026-05-07T03:19:51.275534Z","end":"2026-05-07T03:19:51.613097Z","steps":["trace[362126292] 'process raft request' (duration: 337.31434ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:51.613101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:51.275472Z","time spent":"337.581586ms","remote":"127.0.0.1:36442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:19:51.613197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:51.27553Z","time spent":"337.638186ms","remote":"127.0.0.1:36442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:19:51.613355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:51.273571Z","time spent":"339.336812ms","remote":"127.0.0.1:36442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:19:51.614647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.292231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:5 size:1050260"} {"level":"info","ts":"2026-05-07T03:19:51.61473Z","caller":"traceutil/trace.go:171","msg":"trace[2036385745] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:5; response_revision:824; }","duration":"225.429644ms","start":"2026-05-07T03:19:51.389278Z","end":"2026-05-07T03:19:51.614707Z","steps":["trace[2036385745] 'agreement among raft nodes before linearized reading' (duration: 223.882213ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:51.777745Z","caller":"traceutil/trace.go:171","msg":"trace[2127962685] transaction","detail":"{read_only:false; response_revision:825; number_of_response:1; }","duration":"188.402275ms","start":"2026-05-07T03:19:51.589319Z","end":"2026-05-07T03:19:51.777721Z","steps":["trace[2127962685] 'process raft request' (duration: 129.557208ms)","trace[2127962685] 'compare' (duration: 58.577581ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:19:51.777813Z","caller":"traceutil/trace.go:171","msg":"trace[2010021831] transaction","detail":"{read_only:false; response_revision:826; number_of_response:1; }","duration":"187.527196ms","start":"2026-05-07T03:19:51.590262Z","end":"2026-05-07T03:19:51.777789Z","steps":["trace[2010021831] 'process raft request' (duration: 187.336312ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:51.777924Z","caller":"traceutil/trace.go:171","msg":"trace[1730026432] transaction","detail":"{read_only:false; response_revision:828; number_of_response:1; }","duration":"184.584586ms","start":"2026-05-07T03:19:51.593321Z","end":"2026-05-07T03:19:51.777905Z","steps":["trace[1730026432] 'process raft request' (duration: 184.418802ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:51.777986Z","caller":"traceutil/trace.go:171","msg":"trace[198629022] transaction","detail":"{read_only:false; response_revision:827; number_of_response:1; }","duration":"185.759611ms","start":"2026-05-07T03:19:51.592217Z","end":"2026-05-07T03:19:51.777977Z","steps":["trace[198629022] 'process raft request' (duration: 185.475164ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:51.944349Z","caller":"traceutil/trace.go:171","msg":"trace[2072349114] linearizableReadLoop","detail":"{readStateIndex:868; appliedIndex:867; }","duration":"103.738318ms","start":"2026-05-07T03:19:51.840584Z","end":"2026-05-07T03:19:51.944323Z","steps":["trace[2072349114] 'read index received' (duration: 82.954592ms)","trace[2072349114] 'applied index is now lower than readState.Index' (duration: 20.782606ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:19:51.944544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.941603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T03:19:51.944588Z","caller":"traceutil/trace.go:171","msg":"trace[2137745446] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:831; }","duration":"104.056755ms","start":"2026-05-07T03:19:51.840522Z","end":"2026-05-07T03:19:51.944578Z","steps":["trace[2137745446] 'agreement among raft nodes before linearized reading' (duration: 103.893532ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:51.944792Z","caller":"traceutil/trace.go:171","msg":"trace[1080466223] transaction","detail":"{read_only:false; response_revision:831; number_of_response:1; }","duration":"110.865494ms","start":"2026-05-07T03:19:51.833889Z","end":"2026-05-07T03:19:51.944755Z","steps":["trace[1080466223] 'process raft request' (duration: 89.658899ms)","trace[1080466223] 'compare' (duration: 20.636903ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:19:51.956696Z","caller":"traceutil/trace.go:171","msg":"trace[561256395] transaction","detail":"{read_only:false; response_revision:832; number_of_response:1; }","duration":"103.263779ms","start":"2026-05-07T03:19:51.853408Z","end":"2026-05-07T03:19:51.956672Z","steps":["trace[561256395] 'process raft request' (duration: 103.114116ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:52.793863Z","caller":"traceutil/trace.go:171","msg":"trace[2141412432] linearizableReadLoop","detail":"{readStateIndex:871; appliedIndex:870; }","duration":"274.179061ms","start":"2026-05-07T03:19:52.51966Z","end":"2026-05-07T03:19:52.79384Z","steps":["trace[2141412432] 'read index received' (duration: 222.605012ms)","trace[2141412432] 'applied index is now lower than readState.Index' (duration: 51.572869ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:19:52.793933Z","caller":"traceutil/trace.go:171","msg":"trace[650136226] transaction","detail":"{read_only:false; response_revision:834; number_of_response:1; }","duration":"341.297408ms","start":"2026-05-07T03:19:52.452615Z","end":"2026-05-07T03:19:52.793913Z","steps":["trace[650136226] 'process raft request' (duration: 289.610686ms)","trace[650136226] 'compare' (duration: 51.072019ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:19:52.79403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:52.452595Z","time spent":"341.387629ms","remote":"127.0.0.1:33176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2783,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T03:19:52.794153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.451982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/eg\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:19:52.794192Z","caller":"traceutil/trace.go:171","msg":"trace[1683286895] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/eg; range_end:; response_count:0; response_revision:834; }","duration":"178.529213ms","start":"2026-05-07T03:19:52.615652Z","end":"2026-05-07T03:19:52.794182Z","steps":["trace[1683286895] 'agreement among raft nodes before linearized reading' (duration: 178.432701ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:52.794226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.523498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:19:52.794643Z","caller":"traceutil/trace.go:171","msg":"trace[2030793496] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:834; }","duration":"274.968767ms","start":"2026-05-07T03:19:52.519651Z","end":"2026-05-07T03:19:52.79462Z","steps":["trace[2030793496] 'agreement among raft nodes before linearized reading' (duration: 274.483397ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:55.750716Z","caller":"traceutil/trace.go:171","msg":"trace[1524795947] transaction","detail":"{read_only:false; response_revision:865; number_of_response:1; }","duration":"112.648603ms","start":"2026-05-07T03:19:55.638047Z","end":"2026-05-07T03:19:55.750696Z","steps":["trace[1524795947] 'process raft request' (duration: 112.610912ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:55.750942Z","caller":"traceutil/trace.go:171","msg":"trace[222627090] transaction","detail":"{read_only:false; response_revision:864; number_of_response:1; }","duration":"116.084381ms","start":"2026-05-07T03:19:55.634848Z","end":"2026-05-07T03:19:55.750932Z","steps":["trace[222627090] 'process raft request' (duration: 115.775725ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:55.750958Z","caller":"traceutil/trace.go:171","msg":"trace[1934393200] transaction","detail":"{read_only:false; response_revision:863; number_of_response:1; }","duration":"116.208183ms","start":"2026-05-07T03:19:55.63472Z","end":"2026-05-07T03:19:55.750929Z","steps":["trace[1934393200] 'process raft request' (duration: 115.861176ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:55.7513Z","caller":"traceutil/trace.go:171","msg":"trace[2034598914] transaction","detail":"{read_only:false; response_revision:862; number_of_response:1; }","duration":"116.667732ms","start":"2026-05-07T03:19:55.634623Z","end":"2026-05-07T03:19:55.75129Z","steps":["trace[2034598914] 'process raft request' (duration: 110.771945ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:57.241719Z","caller":"traceutil/trace.go:171","msg":"trace[1905354402] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"167.878443ms","start":"2026-05-07T03:19:57.073821Z","end":"2026-05-07T03:19:57.241699Z","steps":["trace[1905354402] 'process raft request' (duration: 167.75997ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:20:01.965748Z","caller":"traceutil/trace.go:171","msg":"trace[736777086] linearizableReadLoop","detail":"{readStateIndex:980; appliedIndex:979; }","duration":"189.94454ms","start":"2026-05-07T03:20:01.775787Z","end":"2026-05-07T03:20:01.965732Z","steps":["trace[736777086] 'read index received' (duration: 189.762536ms)","trace[736777086] 'applied index is now lower than readState.Index' (duration: 181.394µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:20:01.965814Z","caller":"traceutil/trace.go:171","msg":"trace[158624577] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"261.31215ms","start":"2026-05-07T03:20:01.704471Z","end":"2026-05-07T03:20:01.965783Z","steps":["trace[158624577] 'process raft request' (duration: 261.155537ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:20:01.966005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.108353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-07T03:20:01.966004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.082743ms","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-07T03:20:01.966059Z","caller":"traceutil/trace.go:171","msg":"trace[1612716603] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:941; }","duration":"189.179375ms","start":"2026-05-07T03:20:01.776869Z","end":"2026-05-07T03:20:01.966048Z","steps":["trace[1612716603] 'agreement among raft nodes before linearized reading' (duration: 188.990061ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:20:01.96606Z","caller":"traceutil/trace.go:171","msg":"trace[487213162] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:941; }","duration":"190.173785ms","start":"2026-05-07T03:20:01.775872Z","end":"2026-05-07T03:20:01.966046Z","steps":["trace[487213162] 'agreement among raft nodes before linearized reading' (duration: 189.982311ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:20:01.966012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.174514ms","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-07T03:20:01.966164Z","caller":"traceutil/trace.go:171","msg":"trace[2063137164] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:941; }","duration":"189.363529ms","start":"2026-05-07T03:20:01.776792Z","end":"2026-05-07T03:20:01.966156Z","steps":["trace[2063137164] 'agreement among raft nodes before linearized reading' (duration: 189.087933ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:20:01.966475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.681434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-07T03:20:01.966521Z","caller":"traceutil/trace.go:171","msg":"trace[951393135] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:941; }","duration":"190.753886ms","start":"2026-05-07T03:20:01.775756Z","end":"2026-05-07T03:20:01.96651Z","steps":["trace[951393135] 'agreement among raft nodes before linearized reading' (duration: 190.046312ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:20:04.222098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.484779ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:20:04.222232Z","caller":"traceutil/trace.go:171","msg":"trace[1458712428] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"213.318321ms","start":"2026-05-07T03:20:04.008892Z","end":"2026-05-07T03:20:04.222211Z","steps":["trace[1458712428] 'process raft request' (duration: 54.625239ms)","trace[1458712428] 'compare' (duration: 158.364856ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:20:04.384453Z","caller":"traceutil/trace.go:171","msg":"trace[1729496469] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"375.316865ms","start":"2026-05-07T03:20:04.009112Z","end":"2026-05-07T03:20:04.384429Z","steps":["trace[1729496469] 'process raft request' (duration: 319.737867ms)","trace[1729496469] 'compare' (duration: 55.341233ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:20:04.3845Z","caller":"traceutil/trace.go:171","msg":"trace[1109381354] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"375.348055ms","start":"2026-05-07T03:20:04.009119Z","end":"2026-05-07T03:20:04.384467Z","steps":["trace[1109381354] 'process raft request' (duration: 375.202273ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:20:04.38457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:20:04.009092Z","time spent":"375.416507ms","remote":"127.0.0.1:36442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:20:04.384572Z","caller":"traceutil/trace.go:171","msg":"trace[1619772915] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"375.418987ms","start":"2026-05-07T03:20:04.009138Z","end":"2026-05-07T03:20:04.384557Z","steps":["trace[1619772915] 'process raft request' (duration: 375.228094ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:20:04.38464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:20:04.009119Z","time spent":"375.487428ms","remote":"127.0.0.1:36442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:20:04.384868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:20:04.009096Z","time spent":"375.680382ms","remote":"127.0.0.1:36442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:20:04.385105Z","caller":"traceutil/trace.go:171","msg":"trace[1464711618] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"375.791544ms","start":"2026-05-07T03:20:04.009297Z","end":"2026-05-07T03:20:04.385088Z","steps":["trace[1464711618] 'process raft request' (duration: 375.104891ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:20:04.385291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:20:04.009288Z","time spent":"375.952086ms","remote":"127.0.0.1:36442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:20:11.814837Z","caller":"traceutil/trace.go:171","msg":"trace[1726240566] linearizableReadLoop","detail":"{readStateIndex:1029; appliedIndex:1028; }","duration":"448.053402ms","start":"2026-05-07T03:20:11.366755Z","end":"2026-05-07T03:20:11.814809Z","steps":["trace[1726240566] 'read index received' (duration: 447.828638ms)","trace[1726240566] 'applied index is now lower than readState.Index' (duration: 217.624µs)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:20:11.815282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.492999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:20:11.815363Z","caller":"traceutil/trace.go:171","msg":"trace[1810990162] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:987; }","duration":"448.605691ms","start":"2026-05-07T03:20:11.36674Z","end":"2026-05-07T03:20:11.815346Z","steps":["trace[1810990162] 'agreement among raft nodes before linearized reading' (duration: 448.460959ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:20:11.81556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:20:11.36666Z","time spent":"448.874587ms","remote":"127.0.0.1:33268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"info","ts":"2026-05-07T03:20:11.815719Z","caller":"traceutil/trace.go:171","msg":"trace[864863519] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"541.624759ms","start":"2026-05-07T03:20:11.274035Z","end":"2026-05-07T03:20:11.81566Z","steps":["trace[864863519] 'process raft request' (duration: 540.615111ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:20:11.815826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.394124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:20:11.815905Z","caller":"traceutil/trace.go:171","msg":"trace[719549160] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:987; }","duration":"294.502067ms","start":"2026-05-07T03:20:11.521386Z","end":"2026-05-07T03:20:11.815888Z","steps":["trace[719549160] 'agreement among raft nodes before linearized reading' (duration: 294.389385ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:20:11.816028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:20:11.273999Z","time spent":"541.812871ms","remote":"127.0.0.1:36442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:20:12.491865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.036201ms","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-07T03:20:12.492028Z","caller":"traceutil/trace.go:171","msg":"trace[1431744508] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:988; }","duration":"230.215535ms","start":"2026-05-07T03:20:12.261769Z","end":"2026-05-07T03:20:12.491984Z","steps":["trace[1431744508] 'range keys from in-memory index tree' (duration: 229.882759ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:20:34.984815Z","caller":"traceutil/trace.go:171","msg":"trace[1671109024] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"325.803032ms","start":"2026-05-07T03:20:34.658738Z","end":"2026-05-07T03:20:34.984541Z","steps":["trace[1671109024] 'process raft request' (duration: 325.609489ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:20:34.984959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:20:34.658719Z","time spent":"326.163557ms","remote":"127.0.0.1:36442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:20:35.034621Z","caller":"traceutil/trace.go:171","msg":"trace[884717534] linearizableReadLoop","detail":"{readStateIndex:1114; appliedIndex:1112; }","duration":"296.980631ms","start":"2026-05-07T03:20:34.737607Z","end":"2026-05-07T03:20:35.034587Z","steps":["trace[884717534] 'read index received' (duration: 246.717449ms)","trace[884717534] 'applied index is now lower than readState.Index' (duration: 50.261792ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:20:35.034765Z","caller":"traceutil/trace.go:171","msg":"trace[959064314] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"297.391557ms","start":"2026-05-07T03:20:34.737344Z","end":"2026-05-07T03:20:35.034735Z","steps":["trace[959064314] 'process raft request' (duration: 253.622496ms)","trace[959064314] 'compare' (duration: 43.473957ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:20:35.034875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.241165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-07T03:20:35.034913Z","caller":"traceutil/trace.go:171","msg":"trace[811272189] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1067; }","duration":"297.326486ms","start":"2026-05-07T03:20:34.737572Z","end":"2026-05-07T03:20:35.034899Z","steps":["trace[811272189] 'agreement among raft nodes before linearized reading' (duration: 297.172173ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:20:35.035039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.522808ms","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-07T03:20:35.035073Z","caller":"traceutil/trace.go:171","msg":"trace[284299440] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1067; }","duration":"295.583408ms","start":"2026-05-07T03:20:34.73948Z","end":"2026-05-07T03:20:35.035064Z","steps":["trace[284299440] 'agreement among raft nodes before linearized reading' (duration: 295.442506ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:20:35.035121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.223293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T03:20:35.035151Z","caller":"traceutil/trace.go:171","msg":"trace[1008975829] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1067; }","duration":"270.288854ms","start":"2026-05-07T03:20:34.764851Z","end":"2026-05-07T03:20:35.03514Z","steps":["trace[1008975829] 'agreement among raft nodes before linearized reading' (duration: 270.188723ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:20:35.035192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.082655ms","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-07T03:20:35.035217Z","caller":"traceutil/trace.go:171","msg":"trace[1848207405] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1067; }","duration":"294.119007ms","start":"2026-05-07T03:20:34.74109Z","end":"2026-05-07T03:20:35.035209Z","steps":["trace[1848207405] 'agreement among raft nodes before linearized reading' (duration: 294.049375ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:20:36.716922Z","caller":"traceutil/trace.go:171","msg":"trace[1219442834] linearizableReadLoop","detail":"{readStateIndex:1119; appliedIndex:1118; }","duration":"296.303375ms","start":"2026-05-07T03:20:36.420584Z","end":"2026-05-07T03:20:36.716888Z","steps":["trace[1219442834] 'read index received' (duration: 295.991361ms)","trace[1219442834] 'applied index is now lower than readState.Index' (duration: 310.404µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:20:36.717055Z","caller":"traceutil/trace.go:171","msg":"trace[799027034] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"332.334791ms","start":"2026-05-07T03:20:36.384679Z","end":"2026-05-07T03:20:36.717014Z","steps":["trace[799027034] 'process raft request' (duration: 331.978826ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:20:36.717234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:20:36.384661Z","time spent":"332.481964ms","remote":"127.0.0.1:36442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:20:36.717258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.59189ms","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-07T03:20:36.717419Z","caller":"traceutil/trace.go:171","msg":"trace[1681687522] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1072; }","duration":"296.846914ms","start":"2026-05-07T03:20:36.42054Z","end":"2026-05-07T03:20:36.717387Z","steps":["trace[1681687522] 'agreement among raft nodes before linearized reading' (duration: 296.520768ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:20:36.717361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.529663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:20:36.717612Z","caller":"traceutil/trace.go:171","msg":"trace[1292485389] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1072; }","duration":"194.978931ms","start":"2026-05-07T03:20:36.522568Z","end":"2026-05-07T03:20:36.717547Z","steps":["trace[1292485389] 'agreement among raft nodes before linearized reading' (duration: 194.540714ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:20:37.146639Z","caller":"traceutil/trace.go:171","msg":"trace[459344734] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"152.830749ms","start":"2026-05-07T03:20:36.993785Z","end":"2026-05-07T03:20:37.146615Z","steps":["trace[459344734] 'process raft request' (duration: 152.630456ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:20:37.152446Z","caller":"traceutil/trace.go:171","msg":"trace[1464509937] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"110.137822ms","start":"2026-05-07T03:20:37.042276Z","end":"2026-05-07T03:20:37.152414Z","steps":["trace[1464509937] 'process raft request' (duration: 108.247133ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:20:37.401028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.361982ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:20:37.40115Z","caller":"traceutil/trace.go:171","msg":"trace[1258635748] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"242.444016ms","start":"2026-05-07T03:20:37.158685Z","end":"2026-05-07T03:20:37.401129Z","steps":["trace[1258635748] 'process raft request' (duration: 103.064214ms)","trace[1258635748] 'compare' (duration: 139.057837ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:20:37.415647Z","caller":"traceutil/trace.go:171","msg":"trace[1595375553] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"256.237648ms","start":"2026-05-07T03:20:37.159379Z","end":"2026-05-07T03:20:37.415616Z","steps":["trace[1595375553] 'process raft request' (duration: 256.170287ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:20:37.415696Z","caller":"traceutil/trace.go:171","msg":"trace[911605805] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"256.660194ms","start":"2026-05-07T03:20:37.158992Z","end":"2026-05-07T03:20:37.415652Z","steps":["trace[911605805] 'process raft request' (duration: 256.318569ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:20:38.858982Z","caller":"traceutil/trace.go:171","msg":"trace[1619206148] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"132.547377ms","start":"2026-05-07T03:20:38.726405Z","end":"2026-05-07T03:20:38.858953Z","steps":["trace[1619206148] 'process raft request' (duration: 132.300093ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:21:29.767618Z","caller":"traceutil/trace.go:171","msg":"trace[1003327090] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"207.109293ms","start":"2026-05-07T03:21:29.560475Z","end":"2026-05-07T03:21:29.767584Z","steps":["trace[1003327090] 'process raft request' (duration: 112.033109ms)","trace[1003327090] 'compare' (duration: 94.75679ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:21:29.898314Z","caller":"traceutil/trace.go:171","msg":"trace[481937342] linearizableReadLoop","detail":"{readStateIndex:1318; appliedIndex:1317; }","duration":"118.505462ms","start":"2026-05-07T03:21:29.779778Z","end":"2026-05-07T03:21:29.898283Z","steps":["trace[481937342] 'read index received' (duration: 112.698218ms)","trace[481937342] 'applied index is now lower than readState.Index' (duration: 5.805984ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:21:29.898356Z","caller":"traceutil/trace.go:171","msg":"trace[1279046287] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"123.009617ms","start":"2026-05-07T03:21:29.775322Z","end":"2026-05-07T03:21:29.898332Z","steps":["trace[1279046287] 'process raft request' (duration: 117.227274ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:21:29.898848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.996898ms","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-07T03:21:29.898951Z","caller":"traceutil/trace.go:171","msg":"trace[683893701] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1259; }","duration":"119.16306ms","start":"2026-05-07T03:21:29.77977Z","end":"2026-05-07T03:21:29.898933Z","steps":["trace[683893701] 'agreement among raft nodes before linearized reading' (duration: 118.614273ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:21:31.650909Z","caller":"traceutil/trace.go:171","msg":"trace[304256264] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"124.811641ms","start":"2026-05-07T03:21:31.526069Z","end":"2026-05-07T03:21:31.650881Z","steps":["trace[304256264] 'process raft request' (duration: 124.585158ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:21:46.023742Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836960328104534493,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T03:21:46.361421Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.000736345s","expected-duration":"1s"} {"level":"info","ts":"2026-05-07T03:21:46.361763Z","caller":"traceutil/trace.go:171","msg":"trace[1243832000] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"1.001143151s","start":"2026-05-07T03:21:45.360598Z","end":"2026-05-07T03:21:46.361741Z","steps":["trace[1243832000] 'process raft request' (duration: 1.000999159s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:21:46.361921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:21:45.360572Z","time spent":"1.001270712s","remote":"127.0.0.1:36442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:21:46.444366Z","caller":"traceutil/trace.go:171","msg":"trace[1585292200] linearizableReadLoop","detail":"{readStateIndex:1398; appliedIndex:1397; }","duration":"920.918991ms","start":"2026-05-07T03:21:45.523421Z","end":"2026-05-07T03:21:46.44434Z","steps":["trace[1585292200] 'read index received' (duration: 838.364447ms)","trace[1585292200] 'applied index is now lower than readState.Index' (duration: 82.553414ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:21:46.444524Z","caller":"traceutil/trace.go:171","msg":"trace[308173434] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"751.83691ms","start":"2026-05-07T03:21:45.692675Z","end":"2026-05-07T03:21:46.444512Z","steps":["trace[308173434] 'process raft request' (duration: 734.048914ms)","trace[308173434] 'compare' (duration: 17.511913ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:21:46.444732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:21:45.692653Z","time spent":"751.904861ms","remote":"127.0.0.1:36442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:21:46.445002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"921.562319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T03:21:46.44502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.90845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-05-07T03:21:46.445098Z","caller":"traceutil/trace.go:171","msg":"trace[1599764103] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1335; }","duration":"921.713001ms","start":"2026-05-07T03:21:45.523364Z","end":"2026-05-07T03:21:46.445077Z","steps":["trace[1599764103] 'agreement among raft nodes before linearized reading' (duration: 921.560889ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:21:46.44514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:21:45.523335Z","time spent":"921.798731ms","remote":"127.0.0.1:36184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-07T03:21:46.445135Z","caller":"traceutil/trace.go:171","msg":"trace[1939454243] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1335; }","duration":"399.050552ms","start":"2026-05-07T03:21:46.046042Z","end":"2026-05-07T03:21:46.445093Z","steps":["trace[1939454243] 'agreement among raft nodes before linearized reading' (duration: 398.805609ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:21:46.445236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:21:46.046022Z","time spent":"399.196363ms","remote":"127.0.0.1:36442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-07T03:21:46.445021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.430502ms","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-07T03:21:46.445366Z","caller":"traceutil/trace.go:171","msg":"trace[843954816] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1335; }","duration":"470.811376ms","start":"2026-05-07T03:21:45.97454Z","end":"2026-05-07T03:21:46.445351Z","steps":["trace[843954816] 'agreement among raft nodes before linearized reading' (duration: 470.346261ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:21:46.445008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.145566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-07T03:21:46.44546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:21:45.97452Z","time spent":"470.924677ms","remote":"127.0.0.1:36442","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-07T03:21:46.445507Z","caller":"traceutil/trace.go:171","msg":"trace[606408371] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1335; }","duration":"606.694992ms","start":"2026-05-07T03:21:45.838795Z","end":"2026-05-07T03:21:46.44549Z","steps":["trace[606408371] 'agreement among raft nodes before linearized reading' (duration: 605.966013ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:21:46.445723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:21:45.838766Z","time spent":"606.937945ms","remote":"127.0.0.1:36442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-07T03:21:46.445871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.844467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-07T03:21:46.445953Z","caller":"traceutil/trace.go:171","msg":"trace[870539453] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1335; }","duration":"479.932848ms","start":"2026-05-07T03:21:45.965975Z","end":"2026-05-07T03:21:46.445908Z","steps":["trace[870539453] 'agreement among raft nodes before linearized reading' (duration: 479.089637ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:21:46.445989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:21:45.965956Z","time spent":"480.025779ms","remote":"127.0.0.1:36442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-07T03:21:48.07025Z","caller":"traceutil/trace.go:171","msg":"trace[1817685470] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"171.908914ms","start":"2026-05-07T03:21:47.898312Z","end":"2026-05-07T03:21:48.070221Z","steps":["trace[1817685470] 'process raft request' (duration: 171.729692ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:22:03.567776Z","caller":"traceutil/trace.go:171","msg":"trace[2005768702] linearizableReadLoop","detail":"{readStateIndex:1463; appliedIndex:1462; }","duration":"118.85783ms","start":"2026-05-07T03:22:03.448887Z","end":"2026-05-07T03:22:03.567745Z","steps":["trace[2005768702] 'read index received' (duration: 55.750557ms)","trace[2005768702] 'applied index is now lower than readState.Index' (duration: 63.106053ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:22:03.567918Z","caller":"traceutil/trace.go:171","msg":"trace[908855357] transaction","detail":"{read_only:false; response_revision:1396; number_of_response:1; }","duration":"149.695134ms","start":"2026-05-07T03:22:03.418187Z","end":"2026-05-07T03:22:03.567882Z","steps":["trace[908855357] 'process raft request' (duration: 86.446849ms)","trace[908855357] 'compare' (duration: 62.965672ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:22:03.568014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.072433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:22:03.568212Z","caller":"traceutil/trace.go:171","msg":"trace[517898434] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:1396; }","duration":"119.329925ms","start":"2026-05-07T03:22:03.448852Z","end":"2026-05-07T03:22:03.568182Z","steps":["trace[517898434] 'agreement among raft nodes before linearized reading' (duration: 119.054022ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:22:23.018046Z","caller":"traceutil/trace.go:171","msg":"trace[1078176683] linearizableReadLoop","detail":"{readStateIndex:1528; appliedIndex:1527; }","duration":"143.167529ms","start":"2026-05-07T03:22:22.87485Z","end":"2026-05-07T03:22:23.018018Z","steps":["trace[1078176683] 'read index received' (duration: 114.951056ms)","trace[1078176683] 'applied index is now lower than readState.Index' (duration: 28.215183ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:22:23.01838Z","caller":"traceutil/trace.go:171","msg":"trace[2109169281] transaction","detail":"{read_only:false; response_revision:1458; number_of_response:1; }","duration":"148.132786ms","start":"2026-05-07T03:22:22.870178Z","end":"2026-05-07T03:22:23.018311Z","steps":["trace[2109169281] 'process raft request' (duration: 119.681301ms)","trace[2109169281] 'compare' (duration: 27.99677ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:22:23.018398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.515682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-qpdtg\" ","response":"range_response_count:1 size:4905"} {"level":"info","ts":"2026-05-07T03:22:23.01868Z","caller":"traceutil/trace.go:171","msg":"trace[1117474421] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-qpdtg; range_end:; response_count:1; response_revision:1458; }","duration":"143.838966ms","start":"2026-05-07T03:22:22.874809Z","end":"2026-05-07T03:22:23.018648Z","steps":["trace[1117474421] 'agreement among raft nodes before linearized reading' (duration: 143.35622ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:22:23.233884Z","caller":"traceutil/trace.go:171","msg":"trace[1728281068] transaction","detail":"{read_only:false; response_revision:1459; number_of_response:1; }","duration":"210.182725ms","start":"2026-05-07T03:22:23.023671Z","end":"2026-05-07T03:22:23.233854Z","steps":["trace[1728281068] 'process raft request' (duration: 123.839477ms)","trace[1728281068] 'compare' (duration: 85.983714ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:22:23.233933Z","caller":"traceutil/trace.go:171","msg":"trace[923018454] linearizableReadLoop","detail":"{readStateIndex:1532; appliedIndex:1528; }","duration":"126.846351ms","start":"2026-05-07T03:22:23.107065Z","end":"2026-05-07T03:22:23.233911Z","steps":["trace[923018454] 'read index received' (duration: 40.462823ms)","trace[923018454] 'applied index is now lower than readState.Index' (duration: 86.382558ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:22:23.234121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.042683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5164"} {"level":"info","ts":"2026-05-07T03:22:23.234156Z","caller":"traceutil/trace.go:171","msg":"trace[1726562368] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:1462; }","duration":"127.135634ms","start":"2026-05-07T03:22:23.10701Z","end":"2026-05-07T03:22:23.234145Z","steps":["trace[1726562368] 'agreement among raft nodes before linearized reading' (duration: 126.984182ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:22:23.234179Z","caller":"traceutil/trace.go:171","msg":"trace[1404237274] transaction","detail":"{read_only:false; response_revision:1460; number_of_response:1; }","duration":"210.003122ms","start":"2026-05-07T03:22:23.024166Z","end":"2026-05-07T03:22:23.234169Z","steps":["trace[1404237274] 'process raft request' (duration: 209.585008ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:22:23.234337Z","caller":"traceutil/trace.go:171","msg":"trace[1058162415] transaction","detail":"{read_only:false; response_revision:1461; number_of_response:1; }","duration":"210.092783ms","start":"2026-05-07T03:22:23.02423Z","end":"2026-05-07T03:22:23.234323Z","steps":["trace[1058162415] 'process raft request' (duration: 209.607768ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:22:23.234436Z","caller":"traceutil/trace.go:171","msg":"trace[395764820] transaction","detail":"{read_only:false; response_revision:1462; number_of_response:1; }","duration":"209.682529ms","start":"2026-05-07T03:22:23.024745Z","end":"2026-05-07T03:22:23.234428Z","steps":["trace[395764820] 'process raft request' (duration: 209.127993ms)"],"step_count":1}