{"level":"warn","ts":"2026-04-23T01:24:24.998887Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-23T01:24:24.99898Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.95: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.95:2380","--initial-cluster=instance=https://199.204.45.95:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.95:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.95:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-23T01:24:24.999073Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-23T01:24:24.999087Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.95:2380"]} {"level":"info","ts":"2026-04-23T01:24:24.999104Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-23T01:24:24.999878Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"]} {"level":"info","ts":"2026-04-23T01:24:25.000003Z","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.95:2380"],"listen-peer-urls":["https://199.204.45.95:2380"],"advertise-client-urls":["https://199.204.45.95:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.95:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-23T01:24:25.005359Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.101911ms"} {"level":"info","ts":"2026-04-23T01:24:25.012026Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"be773dfc027af3eb","cluster-id":"9ba5fbd7288fd5af"} {"level":"info","ts":"2026-04-23T01:24:25.012189Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=()"} {"level":"info","ts":"2026-04-23T01:24:25.012254Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became follower at term 0"} {"level":"info","ts":"2026-04-23T01:24:25.012272Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft be773dfc027af3eb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-23T01:24:25.012318Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became follower at term 1"} {"level":"info","ts":"2026-04-23T01:24:25.012402Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=(13724506542017541099)"} {"level":"warn","ts":"2026-04-23T01:24:25.023523Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-23T01:24:25.02513Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-23T01:24:25.026511Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-23T01:24:25.02948Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"be773dfc027af3eb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-23T01:24:25.030069Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"be773dfc027af3eb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-23T01:24:25.030172Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-23T01:24:25.030258Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-23T01:24:25.030273Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-23T01:24:25.031041Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=(13724506542017541099)"} {"level":"info","ts":"2026-04-23T01:24:25.031235Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9ba5fbd7288fd5af","local-member-id":"be773dfc027af3eb","added-peer-id":"be773dfc027af3eb","added-peer-peer-urls":["https://199.204.45.95:2380"]} {"level":"info","ts":"2026-04-23T01:24:25.034512Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-23T01:24:25.034828Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"be773dfc027af3eb","initial-advertise-peer-urls":["https://199.204.45.95:2380"],"listen-peer-urls":["https://199.204.45.95:2380"],"advertise-client-urls":["https://199.204.45.95:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-23T01:24:25.0349Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-23T01:24:25.035198Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.95:2380"} {"level":"info","ts":"2026-04-23T01:24:25.035315Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.95:2380"} {"level":"info","ts":"2026-04-23T01:24:25.414409Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb is starting a new election at term 1"} {"level":"info","ts":"2026-04-23T01:24:25.414466Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became pre-candidate at term 1"} {"level":"info","ts":"2026-04-23T01:24:25.41449Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb received MsgPreVoteResp from be773dfc027af3eb at term 1"} {"level":"info","ts":"2026-04-23T01:24:25.414503Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became candidate at term 2"} {"level":"info","ts":"2026-04-23T01:24:25.414509Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb received MsgVoteResp from be773dfc027af3eb at term 2"} {"level":"info","ts":"2026-04-23T01:24:25.414517Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became leader at term 2"} {"level":"info","ts":"2026-04-23T01:24:25.414528Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: be773dfc027af3eb elected leader be773dfc027af3eb at term 2"} {"level":"info","ts":"2026-04-23T01:24:25.416483Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T01:24:25.417486Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-23T01:24:25.417681Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-23T01:24:25.417472Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"be773dfc027af3eb","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.95:2379]}","request-path":"/0/members/be773dfc027af3eb/attributes","cluster-id":"9ba5fbd7288fd5af","publish-timeout":"7s"} {"level":"info","ts":"2026-04-23T01:24:25.418083Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9ba5fbd7288fd5af","local-member-id":"be773dfc027af3eb","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T01:24:25.418195Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T01:24:25.418238Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T01:24:25.418403Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-23T01:24:25.418717Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-23T01:24:25.420956Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.95:2379"} {"level":"info","ts":"2026-04-23T01:24:25.421646Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-23T01:24:38.992138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.155337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.95\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-23T01:24:38.992416Z","caller":"traceutil/trace.go:171","msg":"trace[1260830113] range","detail":"{range_begin:/registry/masterleases/199.204.45.95; range_end:; response_count:1; response_revision:365; }","duration":"226.496726ms","start":"2026-04-23T01:24:38.765888Z","end":"2026-04-23T01:24:38.992385Z","steps":["trace[1260830113] 'range keys from in-memory index tree' (duration: 226.036574ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:24:39.270457Z","caller":"traceutil/trace.go:171","msg":"trace[1593497990] linearizableReadLoop","detail":"{readStateIndex:382; appliedIndex:381; }","duration":"226.115746ms","start":"2026-04-23T01:24:39.044317Z","end":"2026-04-23T01:24:39.270432Z","steps":["trace[1593497990] 'read index received' (duration: 225.864868ms)","trace[1593497990] 'applied index is now lower than readState.Index' (duration: 249.997µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T01:24:39.270653Z","caller":"traceutil/trace.go:171","msg":"trace[1675019210] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"227.493393ms","start":"2026-04-23T01:24:39.043126Z","end":"2026-04-23T01:24:39.27062Z","steps":["trace[1675019210] 'process raft request' (duration: 227.128494ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:24:39.271198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.274128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T01:24:39.270747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.418014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-23T01:24:39.271242Z","caller":"traceutil/trace.go:171","msg":"trace[1385397559] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:367; }","duration":"131.316899ms","start":"2026-04-23T01:24:39.139911Z","end":"2026-04-23T01:24:39.271228Z","steps":["trace[1385397559] 'agreement among raft nodes before linearized reading' (duration: 131.257016ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:24:39.271277Z","caller":"traceutil/trace.go:171","msg":"trace[439536629] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:367; }","duration":"226.99167ms","start":"2026-04-23T01:24:39.044259Z","end":"2026-04-23T01:24:39.27125Z","steps":["trace[439536629] 'agreement among raft nodes before linearized reading' (duration: 226.297661ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:24:41.049512Z","caller":"traceutil/trace.go:171","msg":"trace[1363751600] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"181.264873ms","start":"2026-04-23T01:24:40.868223Z","end":"2026-04-23T01:24:41.049488Z","steps":["trace[1363751600] 'process raft request' (duration: 181.057518ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:24:57.524691Z","caller":"traceutil/trace.go:171","msg":"trace[1678328540] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"284.886204ms","start":"2026-04-23T01:24:57.23979Z","end":"2026-04-23T01:24:57.524676Z","steps":["trace[1678328540] 'process raft request' (duration: 284.790722ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:24:57.524845Z","caller":"traceutil/trace.go:171","msg":"trace[925016755] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"285.329117ms","start":"2026-04-23T01:24:57.239483Z","end":"2026-04-23T01:24:57.524812Z","steps":["trace[925016755] 'process raft request' (duration: 185.171011ms)","trace[925016755] 'compare' (duration: 99.760996ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T01:24:57.524675Z","caller":"traceutil/trace.go:171","msg":"trace[1160255962] linearizableReadLoop","detail":"{readStateIndex:465; appliedIndex:463; }","duration":"152.62976ms","start":"2026-04-23T01:24:57.372012Z","end":"2026-04-23T01:24:57.524641Z","steps":["trace[1160255962] 'read index received' (duration: 52.583557ms)","trace[1160255962] 'applied index is now lower than readState.Index' (duration: 100.044623ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T01:24:57.525041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.000939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-23T01:24:57.525108Z","caller":"traceutil/trace.go:171","msg":"trace[23576069] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:446; }","duration":"153.113103ms","start":"2026-04-23T01:24:57.371978Z","end":"2026-04-23T01:24:57.525091Z","steps":["trace[23576069] 'agreement among raft nodes before linearized reading' (duration: 152.726002ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:24:59.658163Z","caller":"traceutil/trace.go:171","msg":"trace[106715294] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"175.752544ms","start":"2026-04-23T01:24:59.482187Z","end":"2026-04-23T01:24:59.65794Z","steps":["trace[106715294] 'process raft request' (duration: 106.374743ms)","trace[106715294] 'compare' (duration: 69.209126ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T01:24:59.658414Z","caller":"traceutil/trace.go:171","msg":"trace[272719176] transaction","detail":"{read_only:false; number_of_response:1; response_revision:527; }","duration":"140.939591ms","start":"2026-04-23T01:24:59.517467Z","end":"2026-04-23T01:24:59.658406Z","steps":["trace[272719176] 'process raft request' (duration: 140.651502ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:24:59.658457Z","caller":"traceutil/trace.go:171","msg":"trace[29504446] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"116.289515ms","start":"2026-04-23T01:24:59.542136Z","end":"2026-04-23T01:24:59.658426Z","steps":["trace[29504446] 'process raft request' (duration: 116.134771ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:24:59.866316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.96425ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T01:24:59.866476Z","caller":"traceutil/trace.go:171","msg":"trace[505906116] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"117.88503ms","start":"2026-04-23T01:24:59.748573Z","end":"2026-04-23T01:24:59.866458Z","steps":["trace[505906116] 'process raft request' (duration: 10.174049ms)","trace[505906116] 'compare' (duration: 106.824367ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T01:24:59.867836Z","caller":"traceutil/trace.go:171","msg":"trace[371356379] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"101.392547ms","start":"2026-04-23T01:24:59.766412Z","end":"2026-04-23T01:24:59.867805Z","steps":["trace[371356379] 'process raft request' (duration: 100.955385ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:25:00.546223Z","caller":"traceutil/trace.go:171","msg":"trace[1185500612] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"183.576238ms","start":"2026-04-23T01:25:00.362626Z","end":"2026-04-23T01:25:00.546202Z","steps":["trace[1185500612] 'process raft request' (duration: 183.428974ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:25:00.555157Z","caller":"traceutil/trace.go:171","msg":"trace[1809867097] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"103.219478ms","start":"2026-04-23T01:25:00.451919Z","end":"2026-04-23T01:25:00.555139Z","steps":["trace[1809867097] 'process raft request' (duration: 102.650362ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:25:01.781411Z","caller":"traceutil/trace.go:171","msg":"trace[579288238] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"105.376777ms","start":"2026-04-23T01:25:01.676007Z","end":"2026-04-23T01:25:01.781384Z","steps":["trace[579288238] 'process raft request' (duration: 105.235864ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:25:01.781528Z","caller":"traceutil/trace.go:171","msg":"trace[741044736] linearizableReadLoop","detail":"{readStateIndex:607; appliedIndex:605; }","duration":"103.483835ms","start":"2026-04-23T01:25:01.678017Z","end":"2026-04-23T01:25:01.781501Z","steps":["trace[741044736] 'read index received' (duration: 96.221555ms)","trace[741044736] 'applied index is now lower than readState.Index' (duration: 7.260259ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T01:25:01.781804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.767193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-23T01:25:01.781846Z","caller":"traceutil/trace.go:171","msg":"trace[347153954] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:584; }","duration":"103.831484ms","start":"2026-04-23T01:25:01.678005Z","end":"2026-04-23T01:25:01.781836Z","steps":["trace[347153954] 'agreement among raft nodes before linearized reading' (duration: 103.629538ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:25:03.371321Z","caller":"traceutil/trace.go:171","msg":"trace[1458301867] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:618; }","duration":"283.099415ms","start":"2026-04-23T01:25:03.088198Z","end":"2026-04-23T01:25:03.371298Z","steps":["trace[1458301867] 'read index received' (duration: 282.018295ms)","trace[1458301867] 'applied index is now lower than readState.Index' (duration: 1.08015ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T01:25:03.371619Z","caller":"traceutil/trace.go:171","msg":"trace[202911253] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"458.902619ms","start":"2026-04-23T01:25:02.912705Z","end":"2026-04-23T01:25:03.371608Z","steps":["trace[202911253] 'process raft request' (duration: 458.431556ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:25:03.371715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:25:02.912689Z","time spent":"458.957491ms","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T01:25:03.371963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.759213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-04-23T01:25:03.372007Z","caller":"traceutil/trace.go:171","msg":"trace[1499187279] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:595; }","duration":"283.816243ms","start":"2026-04-23T01:25:03.088171Z","end":"2026-04-23T01:25:03.371987Z","steps":["trace[1499187279] 'agreement among raft nodes before linearized reading' (duration: 283.70211ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:25:03.372376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.097586ms","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-04-23T01:25:03.372435Z","caller":"traceutil/trace.go:171","msg":"trace[1723125408] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:595; }","duration":"275.187987ms","start":"2026-04-23T01:25:03.097232Z","end":"2026-04-23T01:25:03.37242Z","steps":["trace[1723125408] 'agreement among raft nodes before linearized reading' (duration: 275.065264ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:25:03.725487Z","caller":"traceutil/trace.go:171","msg":"trace[1086324699] linearizableReadLoop","detail":"{readStateIndex:620; appliedIndex:619; }","duration":"237.053794ms","start":"2026-04-23T01:25:03.488409Z","end":"2026-04-23T01:25:03.725463Z","steps":["trace[1086324699] 'read index received' (duration: 236.820936ms)","trace[1086324699] 'applied index is now lower than readState.Index' (duration: 232.078µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T01:25:03.725584Z","caller":"traceutil/trace.go:171","msg":"trace[500515793] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"348.879147ms","start":"2026-04-23T01:25:03.376698Z","end":"2026-04-23T01:25:03.725577Z","steps":["trace[500515793] 'process raft request' (duration: 348.627289ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:25:03.725674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:25:03.376677Z","time spent":"348.929147ms","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T01:25:03.725719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.651149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T01:25:03.725801Z","caller":"traceutil/trace.go:171","msg":"trace[1501665065] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway; range_end:; response_count:0; response_revision:596; }","duration":"226.76059ms","start":"2026-04-23T01:25:03.499018Z","end":"2026-04-23T01:25:03.725778Z","steps":["trace[1501665065] 'agreement among raft nodes before linearized reading' (duration: 226.644557ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:25:03.726225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.792863ms","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":"info","ts":"2026-04-23T01:25:03.72631Z","caller":"traceutil/trace.go:171","msg":"trace[462241621] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:596; }","duration":"237.941327ms","start":"2026-04-23T01:25:03.488349Z","end":"2026-04-23T01:25:03.72629Z","steps":["trace[462241621] 'agreement among raft nodes before linearized reading' (duration: 237.68972ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:25:10.327625Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576315384077826496,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T01:25:11.202199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.740559ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T01:25:11.202302Z","caller":"traceutil/trace.go:171","msg":"trace[451250716] linearizableReadLoop","detail":"{readStateIndex:679; appliedIndex:678; }","duration":"1.374970133s","start":"2026-04-23T01:25:09.827313Z","end":"2026-04-23T01:25:11.202284Z","steps":["trace[451250716] 'read index received' (duration: 944.023468ms)","trace[451250716] 'applied index is now lower than readState.Index' (duration: 430.945075ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T01:25:11.202398Z","caller":"traceutil/trace.go:171","msg":"trace[1791415067] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"1.430750189s","start":"2026-04-23T01:25:09.771608Z","end":"2026-04-23T01:25:11.202358Z","steps":["trace[1791415067] 'process raft request' (duration: 999.750243ms)","trace[1791415067] 'compare' (duration: 430.429221ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T01:25:11.202465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:25:09.771592Z","time spent":"1.430841181s","remote":"127.0.0.1:47738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T01:25:11.202728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.375433564s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-23T01:25:11.202774Z","caller":"traceutil/trace.go:171","msg":"trace[1849505149] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:653; }","duration":"1.375515346s","start":"2026-04-23T01:25:09.827247Z","end":"2026-04-23T01:25:11.202762Z","steps":["trace[1849505149] 'agreement among raft nodes before linearized reading' (duration: 1.375383543s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:25:11.202807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:25:09.827193Z","time spent":"1.375606989s","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-23T01:25:11.205408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.375194147s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-23T01:25:11.205471Z","caller":"traceutil/trace.go:171","msg":"trace[229219304] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:654; }","duration":"1.37528045s","start":"2026-04-23T01:25:09.830169Z","end":"2026-04-23T01:25:11.20545Z","steps":["trace[229219304] 'agreement among raft nodes before linearized reading' (duration: 1.375064464s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:25:11.205503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:25:09.830158Z","time spent":"1.375337451s","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-23T01:25:11.205626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.375048924s","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-04-23T01:25:11.205707Z","caller":"traceutil/trace.go:171","msg":"trace[1240825958] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:654; }","duration":"1.375229039s","start":"2026-04-23T01:25:09.830458Z","end":"2026-04-23T01:25:11.205687Z","steps":["trace[1240825958] 'agreement among raft nodes before linearized reading' (duration: 1.375008983s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:25:11.205749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:25:09.830436Z","time spent":"1.375300571s","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-23T01:25:11.487522Z","caller":"traceutil/trace.go:171","msg":"trace[1478296354] linearizableReadLoop","detail":"{readStateIndex:683; appliedIndex:680; }","duration":"277.43418ms","start":"2026-04-23T01:25:11.210058Z","end":"2026-04-23T01:25:11.487492Z","steps":["trace[1478296354] 'read index received' (duration: 36.523641ms)","trace[1478296354] 'applied index is now lower than readState.Index' (duration: 240.908779ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T01:25:11.487571Z","caller":"traceutil/trace.go:171","msg":"trace[1373015774] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"279.372252ms","start":"2026-04-23T01:25:11.208158Z","end":"2026-04-23T01:25:11.487531Z","steps":["trace[1373015774] 'process raft request' (duration: 279.089195ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:25:11.487695Z","caller":"traceutil/trace.go:171","msg":"trace[217984255] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"278.034106ms","start":"2026-04-23T01:25:11.209645Z","end":"2026-04-23T01:25:11.487679Z","steps":["trace[217984255] 'process raft request' (duration: 277.780208ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:25:11.48804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.960864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-66gnb\" ","response":"range_response_count:1 size:17145"} {"level":"info","ts":"2026-04-23T01:25:11.488085Z","caller":"traceutil/trace.go:171","msg":"trace[1927645984] range","detail":"{range_begin:/registry/pods/kube-system/cilium-66gnb; range_end:; response_count:1; response_revision:657; }","duration":"278.033175ms","start":"2026-04-23T01:25:11.210039Z","end":"2026-04-23T01:25:11.488072Z","steps":["trace[1927645984] 'agreement among raft nodes before linearized reading' (duration: 277.84302ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:25:11.648372Z","caller":"traceutil/trace.go:171","msg":"trace[1450638027] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"156.6566ms","start":"2026-04-23T01:25:11.49169Z","end":"2026-04-23T01:25:11.648347Z","steps":["trace[1450638027] 'process raft request' (duration: 138.567795ms)","trace[1450638027] 'compare' (duration: 17.977662ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T01:25:11.950959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.802651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-23T01:25:11.951053Z","caller":"traceutil/trace.go:171","msg":"trace[1099001161] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:658; }","duration":"188.956636ms","start":"2026-04-23T01:25:11.762072Z","end":"2026-04-23T01:25:11.951028Z","steps":["trace[1099001161] 'range keys from in-memory index tree' (duration: 188.681208ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:25:12.129414Z","caller":"traceutil/trace.go:171","msg":"trace[2049361542] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"173.497782ms","start":"2026-04-23T01:25:11.955888Z","end":"2026-04-23T01:25:12.129386Z","steps":["trace[2049361542] 'process raft request' (duration: 173.339958ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:25:13.992784Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576315384077826512,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T01:25:14.493221Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576315384077826512,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T01:25:14.993569Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576315384077826512,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T01:25:15.494164Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576315384077826512,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T01:25:15.795302Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.539306063s","expected-duration":"1s"} {"level":"info","ts":"2026-04-23T01:25:15.795689Z","caller":"traceutil/trace.go:171","msg":"trace[1924127807] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"2.539741164s","start":"2026-04-23T01:25:13.255911Z","end":"2026-04-23T01:25:15.795653Z","steps":["trace[1924127807] 'process raft request' (duration: 2.53958727s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:25:15.79586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:25:13.255897Z","time spent":"2.539879648s","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T01:25:16.140824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00028902s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-23T01:25:16.140934Z","caller":"traceutil/trace.go:171","msg":"trace[870090918] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000463454s","start":"2026-04-23T01:25:14.140446Z","end":"2026-04-23T01:25:16.140909Z","steps":["trace[870090918] 'agreement among raft nodes before linearized reading' (duration: 2.00032622s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:25:16.141043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:25:14.140429Z","time spent":"2.000599137s","remote":"127.0.0.1:47584","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-04-23T01:25:16.295994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.412009ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-23T01:25:16.296187Z","caller":"traceutil/trace.go:171","msg":"trace[560607429] linearizableReadLoop","detail":"{readStateIndex:687; appliedIndex:685; }","duration":"2.803842969s","start":"2026-04-23T01:25:13.492324Z","end":"2026-04-23T01:25:16.296167Z","steps":["trace[560607429] 'read index received' (duration: 2.303158405s)","trace[560607429] 'applied index is now lower than readState.Index' (duration: 500.682574ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T01:25:16.296495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.804115945s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-23T01:25:16.296544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.804200898s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-23T01:25:16.296606Z","caller":"traceutil/trace.go:171","msg":"trace[610724784] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:660; }","duration":"2.804319332s","start":"2026-04-23T01:25:13.492264Z","end":"2026-04-23T01:25:16.296583Z","steps":["trace[610724784] 'agreement among raft nodes before linearized reading' (duration: 2.803994013s)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:25:16.296637Z","caller":"traceutil/trace.go:171","msg":"trace[2039349706] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:660; }","duration":"2.804354953s","start":"2026-04-23T01:25:13.492257Z","end":"2026-04-23T01:25:16.296612Z","steps":["trace[2039349706] 'agreement among raft nodes before linearized reading' (duration: 2.804079735s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:25:16.296673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:25:13.492252Z","time spent":"2.804410474s","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-23T01:25:16.296613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.943394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T01:25:16.296683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:25:13.492242Z","time spent":"2.804432236s","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-23T01:25:16.29669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.644155714s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-23T01:25:16.296779Z","caller":"traceutil/trace.go:171","msg":"trace[116403192] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:660; }","duration":"155.122959ms","start":"2026-04-23T01:25:16.141592Z","end":"2026-04-23T01:25:16.296715Z","steps":["trace[116403192] 'agreement among raft nodes before linearized reading' (duration: 154.897053ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:25:16.296808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.163232912s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-23T01:25:16.296807Z","caller":"traceutil/trace.go:171","msg":"trace[1867956908] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:660; }","duration":"2.64434898s","start":"2026-04-23T01:25:13.652444Z","end":"2026-04-23T01:25:16.296793Z","steps":["trace[1867956908] 'agreement among raft nodes before linearized reading' (duration: 2.643936629s)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:25:16.296899Z","caller":"traceutil/trace.go:171","msg":"trace[1347311415] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:660; }","duration":"2.163368656s","start":"2026-04-23T01:25:14.133506Z","end":"2026-04-23T01:25:16.296875Z","steps":["trace[1347311415] 'agreement among raft nodes before linearized reading' (duration: 2.163091579s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:25:16.296981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:25:14.133489Z","time spent":"2.163477149s","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-23T01:25:16.297005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:25:13.652424Z","time spent":"2.644563676s","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-23T01:25:16.54169Z","caller":"traceutil/trace.go:171","msg":"trace[573592772] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"239.744297ms","start":"2026-04-23T01:25:16.301927Z","end":"2026-04-23T01:25:16.541671Z","steps":["trace[573592772] 'process raft request' (duration: 239.619653ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:25:16.563798Z","caller":"traceutil/trace.go:171","msg":"trace[1235869649] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"261.096362ms","start":"2026-04-23T01:25:16.30268Z","end":"2026-04-23T01:25:16.563777Z","steps":["trace[1235869649] 'process raft request' (duration: 260.879206ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:25:16.564147Z","caller":"traceutil/trace.go:171","msg":"trace[107471620] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"261.429731ms","start":"2026-04-23T01:25:16.30269Z","end":"2026-04-23T01:25:16.56412Z","steps":["trace[107471620] 'process raft request' (duration: 261.04326ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:25:16.564168Z","caller":"traceutil/trace.go:171","msg":"trace[1035179659] linearizableReadLoop","detail":"{readStateIndex:692; appliedIndex:687; }","duration":"257.202835ms","start":"2026-04-23T01:25:16.306939Z","end":"2026-04-23T01:25:16.564142Z","steps":["trace[1035179659] 'read index received' (duration: 234.671757ms)","trace[1035179659] 'applied index is now lower than readState.Index' (duration: 22.530078ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T01:25:16.564202Z","caller":"traceutil/trace.go:171","msg":"trace[1178689802] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"261.40443ms","start":"2026-04-23T01:25:16.302782Z","end":"2026-04-23T01:25:16.564187Z","steps":["trace[1178689802] 'process raft request' (duration: 261.254496ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:25:16.564378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.38547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T01:25:16.56428Z","caller":"traceutil/trace.go:171","msg":"trace[1395286550] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"261.639596ms","start":"2026-04-23T01:25:16.301979Z","end":"2026-04-23T01:25:16.563618Z","steps":["trace[1395286550] 'process raft request' (duration: 261.4347ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:25:16.564424Z","caller":"traceutil/trace.go:171","msg":"trace[2140202805] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:665; }","duration":"257.494853ms","start":"2026-04-23T01:25:16.306917Z","end":"2026-04-23T01:25:16.564412Z","steps":["trace[2140202805] 'agreement among raft nodes before linearized reading' (duration: 257.327007ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:25:16.911672Z","caller":"traceutil/trace.go:171","msg":"trace[1037928031] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"121.393075ms","start":"2026-04-23T01:25:16.790257Z","end":"2026-04-23T01:25:16.91165Z","steps":["trace[1037928031] 'process raft request' (duration: 121.24236ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:25:34.416534Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576315384077826754,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T01:25:34.913636Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.008491822s","expected-duration":"1s"} {"level":"info","ts":"2026-04-23T01:25:34.913957Z","caller":"traceutil/trace.go:171","msg":"trace[1397972173] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"1.008923994s","start":"2026-04-23T01:25:33.905009Z","end":"2026-04-23T01:25:34.913933Z","steps":["trace[1397972173] 'process raft request' (duration: 1.008790951s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:25:34.914099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:25:33.904994Z","time spent":"1.009031717s","remote":"127.0.0.1:47738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4027,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T01:25:35.426848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.430126ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-23T01:25:35.426961Z","caller":"traceutil/trace.go:171","msg":"trace[412201937] linearizableReadLoop","detail":"{readStateIndex:814; appliedIndex:812; }","duration":"1.511272814s","start":"2026-04-23T01:25:33.915671Z","end":"2026-04-23T01:25:35.426943Z","steps":["trace[412201937] 'read index received' (duration: 998.158669ms)","trace[412201937] 'applied index is now lower than readState.Index' (duration: 513.112495ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T01:25:35.42718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.28695629s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T01:25:35.427231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.511545251s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-23T01:25:35.427261Z","caller":"traceutil/trace.go:171","msg":"trace[471324467] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:781; }","duration":"1.287096573s","start":"2026-04-23T01:25:34.140146Z","end":"2026-04-23T01:25:35.427242Z","steps":["trace[471324467] 'agreement among raft nodes before linearized reading' (duration: 1.28696627s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:25:35.427305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:25:34.140122Z","time spent":"1.287174725s","remote":"127.0.0.1:47584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-23T01:25:35.427301Z","caller":"traceutil/trace.go:171","msg":"trace[1402425436] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:781; }","duration":"1.511665095s","start":"2026-04-23T01:25:33.91562Z","end":"2026-04-23T01:25:35.427285Z","steps":["trace[1402425436] 'agreement among raft nodes before linearized reading' (duration: 1.511439339s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:25:35.427354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:25:33.915594Z","time spent":"1.511748376s","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-23T01:25:35.427356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.494552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-23T01:25:35.427368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.01619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-23T01:25:35.427446Z","caller":"traceutil/trace.go:171","msg":"trace[2009787731] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:781; }","duration":"801.503333ms","start":"2026-04-23T01:25:34.625925Z","end":"2026-04-23T01:25:35.427428Z","steps":["trace[2009787731] 'agreement among raft nodes before linearized reading' (duration: 801.249906ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:25:35.427453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.280630196s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-23T01:25:35.427471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.79317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-23T01:25:35.427482Z","caller":"traceutil/trace.go:171","msg":"trace[1201219482] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:781; }","duration":"1.280662108s","start":"2026-04-23T01:25:34.146812Z","end":"2026-04-23T01:25:35.427474Z","steps":["trace[1201219482] 'agreement among raft nodes before linearized reading' (duration: 1.280613556s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:25:35.427533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:25:34.14678Z","time spent":"1.28074355s","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":2,"response size":31,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-04-23T01:25:35.427535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:25:34.625892Z","time spent":"801.633898ms","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-23T01:25:35.427402Z","caller":"traceutil/trace.go:171","msg":"trace[154519434] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:781; }","duration":"759.578865ms","start":"2026-04-23T01:25:34.66781Z","end":"2026-04-23T01:25:35.427389Z","steps":["trace[154519434] 'agreement among raft nodes before linearized reading' (duration: 759.4002ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:25:35.427663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:25:34.667788Z","time spent":"759.860593ms","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-23T01:25:35.427335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"770.31192ms","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-04-23T01:25:35.427741Z","caller":"traceutil/trace.go:171","msg":"trace[758898516] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:781; }","duration":"770.773621ms","start":"2026-04-23T01:25:34.656953Z","end":"2026-04-23T01:25:35.427727Z","steps":["trace[758898516] 'agreement among raft nodes before linearized reading' (duration: 770.187825ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:25:35.427783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:25:34.656934Z","time spent":"770.835423ms","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-23T01:25:35.428146Z","caller":"traceutil/trace.go:171","msg":"trace[1157146928] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:781; }","duration":"759.881603ms","start":"2026-04-23T01:25:34.667627Z","end":"2026-04-23T01:25:35.427509Z","steps":["trace[1157146928] 'agreement among raft nodes before linearized reading' (duration: 759.76178ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:25:35.428221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:25:34.667608Z","time spent":"760.595172ms","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-23T01:25:35.807824Z","caller":"traceutil/trace.go:171","msg":"trace[1808179037] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"375.237578ms","start":"2026-04-23T01:25:35.43256Z","end":"2026-04-23T01:25:35.807798Z","steps":["trace[1808179037] 'process raft request' (duration: 374.816507ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:25:35.808394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:25:35.432534Z","time spent":"375.722952ms","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T01:25:35.81996Z","caller":"traceutil/trace.go:171","msg":"trace[1529824259] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"386.131097ms","start":"2026-04-23T01:25:35.433806Z","end":"2026-04-23T01:25:35.819937Z","steps":["trace[1529824259] 'process raft request' (duration: 385.958542ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:25:35.820076Z","caller":"traceutil/trace.go:171","msg":"trace[854542910] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"385.935201ms","start":"2026-04-23T01:25:35.434129Z","end":"2026-04-23T01:25:35.820064Z","steps":["trace[854542910] 'process raft request' (duration: 385.771656ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:25:35.820328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:25:35.434117Z","time spent":"386.138607ms","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T01:25:35.820492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:25:35.433788Z","time spent":"386.346453ms","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T01:25:35.820791Z","caller":"traceutil/trace.go:171","msg":"trace[729259786] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"386.576399ms","start":"2026-04-23T01:25:35.434204Z","end":"2026-04-23T01:25:35.82078Z","steps":["trace[729259786] 'process raft request' (duration: 385.809488ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:25:35.820873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:25:35.434196Z","time spent":"386.63569ms","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T01:25:35.821031Z","caller":"traceutil/trace.go:171","msg":"trace[609516405] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"386.732733ms","start":"2026-04-23T01:25:35.434266Z","end":"2026-04-23T01:25:35.820999Z","steps":["trace[609516405] 'process raft request' (duration: 386.468746ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:25:35.82104Z","caller":"traceutil/trace.go:171","msg":"trace[1306373610] linearizableReadLoop","detail":"{readStateIndex:819; appliedIndex:814; }","duration":"384.4383ms","start":"2026-04-23T01:25:35.436531Z","end":"2026-04-23T01:25:35.82097Z","steps":["trace[1306373610] 'read index received' (duration: 370.981201ms)","trace[1306373610] 'applied index is now lower than readState.Index' (duration: 13.455719ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T01:25:35.821121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.583774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T01:25:35.821169Z","caller":"traceutil/trace.go:171","msg":"trace[863301115] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:786; }","duration":"384.641646ms","start":"2026-04-23T01:25:35.436509Z","end":"2026-04-23T01:25:35.821151Z","steps":["trace[863301115] 'agreement among raft nodes before linearized reading' (duration: 384.571024ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:25:35.8212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:25:35.436501Z","time spent":"384.691207ms","remote":"127.0.0.1:47584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T01:25:35.821251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.922913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-23T01:25:35.821148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:25:35.434259Z","time spent":"386.821495ms","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T01:25:35.821381Z","caller":"traceutil/trace.go:171","msg":"trace[1776505337] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:786; }","duration":"151.051667ms","start":"2026-04-23T01:25:35.670239Z","end":"2026-04-23T01:25:35.82129Z","steps":["trace[1776505337] 'agreement among raft nodes before linearized reading' (duration: 150.926903ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:25:36.292436Z","caller":"traceutil/trace.go:171","msg":"trace[1352743907] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"372.03016ms","start":"2026-04-23T01:25:35.920378Z","end":"2026-04-23T01:25:36.292408Z","steps":["trace[1352743907] 'process raft request' (duration: 306.032642ms)","trace[1352743907] 'compare' (duration: 65.73032ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T01:25:36.292576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:25:35.920359Z","time spent":"372.134124ms","remote":"127.0.0.1:47800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T01:25:39.819371Z","caller":"traceutil/trace.go:171","msg":"trace[158154645] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"119.229936ms","start":"2026-04-23T01:25:39.700122Z","end":"2026-04-23T01:25:39.819352Z","steps":["trace[158154645] 'process raft request' (duration: 80.28113ms)","trace[158154645] 'compare' (duration: 38.845683ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T01:25:47.247354Z","caller":"traceutil/trace.go:171","msg":"trace[1848236448] linearizableReadLoop","detail":"{readStateIndex:940; appliedIndex:939; }","duration":"108.529093ms","start":"2026-04-23T01:25:47.1388Z","end":"2026-04-23T01:25:47.247329Z","steps":["trace[1848236448] 'read index received' (duration: 108.276376ms)","trace[1848236448] 'applied index is now lower than readState.Index' (duration: 240.687µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T01:25:47.247416Z","caller":"traceutil/trace.go:171","msg":"trace[2101275234] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"115.939555ms","start":"2026-04-23T01:25:47.131435Z","end":"2026-04-23T01:25:47.247375Z","steps":["trace[2101275234] 'process raft request' (duration: 115.737829ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:25:47.247477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.661986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T01:25:47.247525Z","caller":"traceutil/trace.go:171","msg":"trace[1222885207] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:905; }","duration":"108.759288ms","start":"2026-04-23T01:25:47.138749Z","end":"2026-04-23T01:25:47.247508Z","steps":["trace[1222885207] 'agreement among raft nodes before linearized reading' (duration: 108.675567ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:25:48.492125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.019318ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T01:25:48.492239Z","caller":"traceutil/trace.go:171","msg":"trace[459306195] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"443.763164ms","start":"2026-04-23T01:25:48.048456Z","end":"2026-04-23T01:25:48.492219Z","steps":["trace[459306195] 'process raft request' (duration: 312.54383ms)","trace[459306195] 'compare' (duration: 130.781282ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T01:25:48.492553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:25:48.048439Z","time spent":"444.014142ms","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T01:25:48.571796Z","caller":"traceutil/trace.go:171","msg":"trace[428833554] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"523.267242ms","start":"2026-04-23T01:25:48.048511Z","end":"2026-04-23T01:25:48.571778Z","steps":["trace[428833554] 'process raft request' (duration: 523.050276ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:25:48.571977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.952834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T01:25:48.573695Z","caller":"traceutil/trace.go:171","msg":"trace[1315940835] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:911; }","duration":"432.723673ms","start":"2026-04-23T01:25:48.140958Z","end":"2026-04-23T01:25:48.573681Z","steps":["trace[1315940835] 'agreement among raft nodes before linearized reading' (duration: 430.968514ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:25:48.573754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:25:48.140936Z","time spent":"432.797125ms","remote":"127.0.0.1:47584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T01:25:48.574081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:25:48.048493Z","time spent":"525.523735ms","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T01:25:48.574332Z","caller":"traceutil/trace.go:171","msg":"trace[1613530309] linearizableReadLoop","detail":"{readStateIndex:946; appliedIndex:944; }","duration":"430.726398ms","start":"2026-04-23T01:25:48.141009Z","end":"2026-04-23T01:25:48.571735Z","steps":["trace[1613530309] 'read index received' (duration: 220.219412ms)","trace[1613530309] 'applied index is now lower than readState.Index' (duration: 210.505806ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T01:25:48.574588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.626978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-jmpcl\" ","response":"range_response_count:1 size:5676"} {"level":"info","ts":"2026-04-23T01:25:48.574692Z","caller":"traceutil/trace.go:171","msg":"trace[308952978] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-jmpcl; range_end:; response_count:1; response_revision:911; }","duration":"115.766731ms","start":"2026-04-23T01:25:48.458914Z","end":"2026-04-23T01:25:48.574681Z","steps":["trace[308952978] 'agreement among raft nodes before linearized reading' (duration: 115.601376ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:25:48.575047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.352673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T01:25:48.575122Z","caller":"traceutil/trace.go:171","msg":"trace[898515531] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:911; }","duration":"130.029963ms","start":"2026-04-23T01:25:48.445085Z","end":"2026-04-23T01:25:48.575115Z","steps":["trace[898515531] 'agreement among raft nodes before linearized reading' (duration: 129.376735ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:25:48.766527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.075867ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T01:25:48.766645Z","caller":"traceutil/trace.go:171","msg":"trace[746248491] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:911; }","duration":"121.20649ms","start":"2026-04-23T01:25:48.645419Z","end":"2026-04-23T01:25:48.766625Z","steps":["trace[746248491] 'range keys from in-memory index tree' (duration: 121.058106ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:25:48.77023Z","caller":"traceutil/trace.go:171","msg":"trace[1864312286] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"188.600918ms","start":"2026-04-23T01:25:48.581198Z","end":"2026-04-23T01:25:48.769799Z","steps":["trace[1864312286] 'process raft request' (duration: 188.445743ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:25:49.051489Z","caller":"traceutil/trace.go:171","msg":"trace[1976667522] linearizableReadLoop","detail":"{readStateIndex:954; appliedIndex:953; }","duration":"244.917608ms","start":"2026-04-23T01:25:48.806541Z","end":"2026-04-23T01:25:49.051458Z","steps":["trace[1976667522] 'read index received' (duration: 244.220929ms)","trace[1976667522] 'applied index is now lower than readState.Index' (duration: 695.539µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T01:25:49.051643Z","caller":"traceutil/trace.go:171","msg":"trace[1643316825] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"248.904738ms","start":"2026-04-23T01:25:48.802709Z","end":"2026-04-23T01:25:49.051614Z","steps":["trace[1643316825] 'process raft request' (duration: 248.040464ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:25:49.052048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.483193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-04-23T01:25:49.052115Z","caller":"traceutil/trace.go:171","msg":"trace[900678271] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:918; }","duration":"245.598527ms","start":"2026-04-23T01:25:48.806501Z","end":"2026-04-23T01:25:49.052099Z","steps":["trace[900678271] 'agreement among raft nodes before linearized reading' (duration: 245.470234ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:25:49.432976Z","caller":"traceutil/trace.go:171","msg":"trace[1607550406] transaction","detail":"{read_only:false; number_of_response:1; response_revision:920; }","duration":"153.667249ms","start":"2026-04-23T01:25:49.279287Z","end":"2026-04-23T01:25:49.432954Z","steps":["trace[1607550406] 'process raft request' (duration: 81.811462ms)","trace[1607550406] 'compare' (duration: 71.648752ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T01:25:49.657088Z","caller":"traceutil/trace.go:171","msg":"trace[142514261] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"211.78204ms","start":"2026-04-23T01:25:49.445282Z","end":"2026-04-23T01:25:49.657064Z","steps":["trace[142514261] 'process raft request' (duration: 203.659438ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:25:56.733312Z","caller":"traceutil/trace.go:171","msg":"trace[1669146459] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"110.411835ms","start":"2026-04-23T01:25:56.622869Z","end":"2026-04-23T01:25:56.733281Z","steps":["trace[1669146459] 'process raft request' (duration: 52.001825ms)","trace[1669146459] 'compare' (duration: 58.238745ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T01:25:57.663908Z","caller":"traceutil/trace.go:171","msg":"trace[404423662] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"355.371553ms","start":"2026-04-23T01:25:57.30851Z","end":"2026-04-23T01:25:57.663881Z","steps":["trace[404423662] 'process raft request' (duration: 355.106936ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:25:57.664245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:25:57.30848Z","time spent":"355.508237ms","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T01:25:58.759059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.831481ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T01:25:58.759229Z","caller":"traceutil/trace.go:171","msg":"trace[1594334313] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:953; }","duration":"113.019135ms","start":"2026-04-23T01:25:58.646189Z","end":"2026-04-23T01:25:58.759208Z","steps":["trace[1594334313] 'range keys from in-memory index tree' (duration: 112.811539ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:25:59.062528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.261986ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-04-23T01:25:59.117901Z","caller":"traceutil/trace.go:171","msg":"trace[1228027320] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"247.613994ms","start":"2026-04-23T01:25:58.870261Z","end":"2026-04-23T01:25:59.117875Z","steps":["trace[1228027320] 'process raft request' (duration: 247.426939ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:26:07.116888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.80629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-23T01:26:07.11697Z","caller":"traceutil/trace.go:171","msg":"trace[182775010] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:981; }","duration":"285.944833ms","start":"2026-04-23T01:26:06.831007Z","end":"2026-04-23T01:26:07.116951Z","steps":["trace[182775010] 'range keys from in-memory index tree' (duration: 285.632195ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:26:11.312486Z","caller":"traceutil/trace.go:171","msg":"trace[1512579638] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"120.303978ms","start":"2026-04-23T01:26:11.192159Z","end":"2026-04-23T01:26:11.312463Z","steps":["trace[1512579638] 'process raft request' (duration: 120.094143ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:26:12.314794Z","caller":"traceutil/trace.go:171","msg":"trace[289536071] linearizableReadLoop","detail":"{readStateIndex:1042; appliedIndex:1041; }","duration":"216.167744ms","start":"2026-04-23T01:26:12.098604Z","end":"2026-04-23T01:26:12.314771Z","steps":["trace[289536071] 'read index received' (duration: 215.99357ms)","trace[289536071] 'applied index is now lower than readState.Index' (duration: 173.104µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T01:26:12.314831Z","caller":"traceutil/trace.go:171","msg":"trace[1279193584] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"569.123293ms","start":"2026-04-23T01:26:11.745682Z","end":"2026-04-23T01:26:12.314805Z","steps":["trace[1279193584] 'process raft request' (duration: 568.944327ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:26:12.31497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:26:11.745657Z","time spent":"569.231836ms","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T01:26:12.315029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.411261ms","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-04-23T01:26:12.315073Z","caller":"traceutil/trace.go:171","msg":"trace[600200616] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1001; }","duration":"216.504423ms","start":"2026-04-23T01:26:12.098558Z","end":"2026-04-23T01:26:12.315063Z","steps":["trace[600200616] 'agreement among raft nodes before linearized reading' (duration: 216.35961ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:26:12.315135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.966299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-23T01:26:12.31519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.853314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T01:26:12.315219Z","caller":"traceutil/trace.go:171","msg":"trace[207141062] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1001; }","duration":"174.914118ms","start":"2026-04-23T01:26:12.140297Z","end":"2026-04-23T01:26:12.315211Z","steps":["trace[207141062] 'agreement among raft nodes before linearized reading' (duration: 174.874936ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:26:12.315215Z","caller":"traceutil/trace.go:171","msg":"trace[335282943] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1001; }","duration":"192.093072ms","start":"2026-04-23T01:26:12.123104Z","end":"2026-04-23T01:26:12.315197Z","steps":["trace[335282943] 'agreement among raft nodes before linearized reading' (duration: 191.836485ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:26:12.922282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.975861ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T01:26:12.922411Z","caller":"traceutil/trace.go:171","msg":"trace[494745779] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"602.263379ms","start":"2026-04-23T01:26:12.320102Z","end":"2026-04-23T01:26:12.922366Z","steps":["trace[494745779] 'process raft request' (duration: 171.126304ms)","trace[494745779] 'compare' (duration: 430.853978ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T01:26:12.922488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:26:12.320086Z","time spent":"602.359252ms","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T01:26:13.236106Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576315384077827225,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-23T01:26:13.359074Z","caller":"traceutil/trace.go:171","msg":"trace[572962396] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"1.038668607s","start":"2026-04-23T01:26:12.320383Z","end":"2026-04-23T01:26:13.359052Z","steps":["trace[572962396] 'process raft request' (duration: 1.038598874s)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:26:13.359109Z","caller":"traceutil/trace.go:171","msg":"trace[443210046] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"1.038817002s","start":"2026-04-23T01:26:12.320245Z","end":"2026-04-23T01:26:13.359062Z","steps":["trace[443210046] 'process raft request' (duration: 1.038513804s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:26:13.359206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:26:12.320327Z","time spent":"1.038809361s","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T01:26:13.359282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:26:12.320232Z","time spent":"1.038937025s","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T01:26:13.360437Z","caller":"traceutil/trace.go:171","msg":"trace[1427253541] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"667.406423ms","start":"2026-04-23T01:26:12.693018Z","end":"2026-04-23T01:26:13.360425Z","steps":["trace[1427253541] 'process raft request' (duration: 667.251238ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:26:13.360449Z","caller":"traceutil/trace.go:171","msg":"trace[936109964] linearizableReadLoop","detail":"{readStateIndex:1046; appliedIndex:1043; }","duration":"624.657676ms","start":"2026-04-23T01:26:12.735761Z","end":"2026-04-23T01:26:13.360419Z","steps":["trace[936109964] 'read index received' (duration: 623.190286ms)","trace[936109964] 'applied index is now lower than readState.Index' (duration: 1.46597ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T01:26:13.360647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.862013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-23T01:26:13.360695Z","caller":"traceutil/trace.go:171","msg":"trace[2059609217] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1005; }","duration":"624.966144ms","start":"2026-04-23T01:26:12.735719Z","end":"2026-04-23T01:26:13.360685Z","steps":["trace[2059609217] 'agreement among raft nodes before linearized reading' (duration: 624.79438ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:26:13.360688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.835878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-23T01:26:13.360717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:26:12.735704Z","time spent":"625.007166ms","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-23T01:26:13.36073Z","caller":"traceutil/trace.go:171","msg":"trace[1675833415] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1005; }","duration":"426.944962ms","start":"2026-04-23T01:26:12.933774Z","end":"2026-04-23T01:26:13.360718Z","steps":["trace[1675833415] 'agreement among raft nodes before linearized reading' (duration: 426.797908ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:26:13.360768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:26:12.933741Z","time spent":"427.017754ms","remote":"127.0.0.1:47886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":16,"response size":31,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-04-23T01:26:13.360858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:26:12.692991Z","time spent":"667.488976ms","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T01:26:17.237781Z","caller":"traceutil/trace.go:171","msg":"trace[264087954] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"294.374783ms","start":"2026-04-23T01:26:16.943386Z","end":"2026-04-23T01:26:17.237761Z","steps":["trace[264087954] 'process raft request' (duration: 294.25374ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:26:17.626082Z","caller":"traceutil/trace.go:171","msg":"trace[726775699] linearizableReadLoop","detail":"{readStateIndex:1059; appliedIndex:1057; }","duration":"164.940266ms","start":"2026-04-23T01:26:17.46111Z","end":"2026-04-23T01:26:17.62605Z","steps":["trace[726775699] 'read index received' (duration: 38.402929ms)","trace[726775699] 'applied index is now lower than readState.Index' (duration: 126.536497ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T01:26:17.626118Z","caller":"traceutil/trace.go:171","msg":"trace[392453258] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"165.477772ms","start":"2026-04-23T01:26:17.460618Z","end":"2026-04-23T01:26:17.626096Z","steps":["trace[392453258] 'process raft request' (duration: 164.940048ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:26:17.626765Z","caller":"traceutil/trace.go:171","msg":"trace[224672448] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"164.975057ms","start":"2026-04-23T01:26:17.461743Z","end":"2026-04-23T01:26:17.626718Z","steps":["trace[224672448] 'process raft request' (duration: 164.258267ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:26:17.627098Z","caller":"traceutil/trace.go:171","msg":"trace[1403919165] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"164.31433ms","start":"2026-04-23T01:26:17.462764Z","end":"2026-04-23T01:26:17.627079Z","steps":["trace[1403919165] 'process raft request' (duration: 163.262371ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:26:17.627327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.328998ms","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-04-23T01:26:17.627357Z","caller":"traceutil/trace.go:171","msg":"trace[416101565] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1019; }","duration":"166.528449ms","start":"2026-04-23T01:26:17.460819Z","end":"2026-04-23T01:26:17.627348Z","steps":["trace[416101565] 'agreement among raft nodes before linearized reading' (duration: 165.314257ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:26:35.763375Z","caller":"traceutil/trace.go:171","msg":"trace[721305836] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"166.002365ms","start":"2026-04-23T01:26:35.597331Z","end":"2026-04-23T01:26:35.763333Z","steps":["trace[721305836] 'process raft request' (duration: 165.727378ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:26:35.897673Z","caller":"traceutil/trace.go:171","msg":"trace[1329663048] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"129.441455ms","start":"2026-04-23T01:26:35.768203Z","end":"2026-04-23T01:26:35.897644Z","steps":["trace[1329663048] 'process raft request' (duration: 129.345193ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:26:35.897728Z","caller":"traceutil/trace.go:171","msg":"trace[404480684] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"129.496227ms","start":"2026-04-23T01:26:35.768197Z","end":"2026-04-23T01:26:35.897693Z","steps":["trace[404480684] 'process raft request' (duration: 129.164268ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:26:37.587423Z","caller":"traceutil/trace.go:171","msg":"trace[2887495] linearizableReadLoop","detail":"{readStateIndex:1127; appliedIndex:1126; }","duration":"133.475594ms","start":"2026-04-23T01:26:37.453924Z","end":"2026-04-23T01:26:37.5874Z","steps":["trace[2887495] 'read index received' (duration: 133.29958ms)","trace[2887495] 'applied index is now lower than readState.Index' (duration: 174.784µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T01:26:37.587584Z","caller":"traceutil/trace.go:171","msg":"trace[1776724389] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"223.80671ms","start":"2026-04-23T01:26:37.363703Z","end":"2026-04-23T01:26:37.58751Z","steps":["trace[1776724389] 'process raft request' (duration: 223.560034ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:26:37.587715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.609628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T01:26:37.588068Z","caller":"traceutil/trace.go:171","msg":"trace[51141193] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1081; }","duration":"134.185964ms","start":"2026-04-23T01:26:37.453869Z","end":"2026-04-23T01:26:37.588055Z","steps":["trace[51141193] 'agreement among raft nodes before linearized reading' (duration: 133.615408ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:26:38.338379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.746953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T01:26:38.338624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.304725ms","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-04-23T01:26:38.338637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.642815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-23T01:26:38.338673Z","caller":"traceutil/trace.go:171","msg":"trace[1045815719] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1081; }","duration":"436.361717ms","start":"2026-04-23T01:26:37.902294Z","end":"2026-04-23T01:26:38.338656Z","steps":["trace[1045815719] 'range keys from in-memory index tree' (duration: 436.158522ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:26:38.338693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.644131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-23T01:26:38.338714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:26:37.902286Z","time spent":"436.417529ms","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-23T01:26:38.338704Z","caller":"traceutil/trace.go:171","msg":"trace[1729629166] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1081; }","duration":"436.742787ms","start":"2026-04-23T01:26:37.901945Z","end":"2026-04-23T01:26:38.338688Z","steps":["trace[1729629166] 'range keys from in-memory index tree' (duration: 436.48001ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:26:38.338746Z","caller":"traceutil/trace.go:171","msg":"trace[1643974138] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1081; }","duration":"564.715763ms","start":"2026-04-23T01:26:37.774017Z","end":"2026-04-23T01:26:38.338733Z","steps":["trace[1643974138] 'range keys from in-memory index tree' (duration: 564.449185ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:26:38.338772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:26:37.901929Z","time spent":"436.826079ms","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-23T01:26:38.33879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:26:37.774006Z","time spent":"564.775394ms","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-23T01:26:38.33864Z","caller":"traceutil/trace.go:171","msg":"trace[859317748] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1081; }","duration":"572.088322ms","start":"2026-04-23T01:26:37.766537Z","end":"2026-04-23T01:26:38.338625Z","steps":["trace[859317748] 'count revisions from in-memory index tree' (duration: 571.6411ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:26:38.338936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:26:37.766518Z","time spent":"572.40623ms","remote":"127.0.0.1:47640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-04-23T01:26:38.338473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.967081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-23T01:26:38.33914Z","caller":"traceutil/trace.go:171","msg":"trace[331934176] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1081; }","duration":"571.63667ms","start":"2026-04-23T01:26:37.767458Z","end":"2026-04-23T01:26:38.339094Z","steps":["trace[331934176] 'range keys from in-memory index tree' (duration: 570.778007ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:26:38.338518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.989331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T01:26:38.339201Z","caller":"traceutil/trace.go:171","msg":"trace[813535256] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1081; }","duration":"195.705209ms","start":"2026-04-23T01:26:38.143483Z","end":"2026-04-23T01:26:38.339188Z","steps":["trace[813535256] 'range keys from in-memory index tree' (duration: 194.927219ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:26:38.339204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:26:37.76745Z","time spent":"571.734893ms","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-23T01:26:38.585303Z","caller":"traceutil/trace.go:171","msg":"trace[1049427802] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"127.700049ms","start":"2026-04-23T01:26:38.457569Z","end":"2026-04-23T01:26:38.585269Z","steps":["trace[1049427802] 'process raft request' (duration: 38.855792ms)","trace[1049427802] 'compare' (duration: 88.722263ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T01:26:40.488325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.12587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T01:26:40.488695Z","caller":"traceutil/trace.go:171","msg":"trace[1105487054] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1088; }","duration":"347.597283ms","start":"2026-04-23T01:26:40.141081Z","end":"2026-04-23T01:26:40.488678Z","steps":["trace[1105487054] 'range keys from in-memory index tree' (duration: 347.038349ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:26:40.488829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:26:40.141059Z","time spent":"347.756178ms","remote":"127.0.0.1:47584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-23T01:26:40.516521Z","caller":"traceutil/trace.go:171","msg":"trace[147095647] linearizableReadLoop","detail":"{readStateIndex:1136; appliedIndex:1135; }","duration":"143.692782ms","start":"2026-04-23T01:26:40.372811Z","end":"2026-04-23T01:26:40.516504Z","steps":["trace[147095647] 'read index received' (duration: 143.575949ms)","trace[147095647] 'applied index is now lower than readState.Index' (duration: 115.973µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T01:26:40.516641Z","caller":"traceutil/trace.go:171","msg":"trace[149235504] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"299.076409ms","start":"2026-04-23T01:26:40.217552Z","end":"2026-04-23T01:26:40.516629Z","steps":["trace[149235504] 'process raft request' (duration: 298.839133ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:26:40.517021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.197895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-23T01:26:40.517095Z","caller":"traceutil/trace.go:171","msg":"trace[2068523016] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1089; }","duration":"144.316658ms","start":"2026-04-23T01:26:40.372759Z","end":"2026-04-23T01:26:40.517076Z","steps":["trace[2068523016] 'agreement among raft nodes before linearized reading' (duration: 144.161735ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:26:40.671854Z","caller":"traceutil/trace.go:171","msg":"trace[1784002687] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"150.046853ms","start":"2026-04-23T01:26:40.521776Z","end":"2026-04-23T01:26:40.671823Z","steps":["trace[1784002687] 'process raft request' (duration: 118.593322ms)","trace[1784002687] 'compare' (duration: 31.332098ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T01:26:40.690447Z","caller":"traceutil/trace.go:171","msg":"trace[631411344] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"167.61145ms","start":"2026-04-23T01:26:40.522807Z","end":"2026-04-23T01:26:40.690418Z","steps":["trace[631411344] 'process raft request' (duration: 167.382493ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:26:40.690695Z","caller":"traceutil/trace.go:171","msg":"trace[1106944419] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"167.836245ms","start":"2026-04-23T01:26:40.522839Z","end":"2026-04-23T01:26:40.690675Z","steps":["trace[1106944419] 'process raft request' (duration: 167.491086ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:26:40.690975Z","caller":"traceutil/trace.go:171","msg":"trace[512952778] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"168.470043ms","start":"2026-04-23T01:26:40.522486Z","end":"2026-04-23T01:26:40.690956Z","steps":["trace[512952778] 'process raft request' (duration: 168.131853ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:26:43.196007Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576315384077827466,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-23T01:26:43.514225Z","caller":"traceutil/trace.go:171","msg":"trace[1691682686] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"834.652694ms","start":"2026-04-23T01:26:42.679543Z","end":"2026-04-23T01:26:43.514196Z","steps":["trace[1691682686] 'process raft request' (duration: 834.18403ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:26:43.514463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:26:42.679524Z","time spent":"834.789078ms","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T01:26:43.542532Z","caller":"traceutil/trace.go:171","msg":"trace[632621112] linearizableReadLoop","detail":"{readStateIndex:1144; appliedIndex:1142; }","duration":"847.131541ms","start":"2026-04-23T01:26:42.695381Z","end":"2026-04-23T01:26:43.542513Z","steps":["trace[632621112] 'read index received' (duration: 818.374892ms)","trace[632621112] 'applied index is now lower than readState.Index' (duration: 28.755718ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T01:26:43.54261Z","caller":"traceutil/trace.go:171","msg":"trace[1936457924] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"846.577016ms","start":"2026-04-23T01:26:42.696004Z","end":"2026-04-23T01:26:43.542581Z","steps":["trace[1936457924] 'process raft request' (duration: 846.391781ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:26:43.542722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"847.319446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-23T01:26:43.542748Z","caller":"traceutil/trace.go:171","msg":"trace[555226481] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1097; }","duration":"847.42446ms","start":"2026-04-23T01:26:42.695315Z","end":"2026-04-23T01:26:43.54274Z","steps":["trace[555226481] 'agreement among raft nodes before linearized reading' (duration: 847.303666ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:26:43.542773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:26:42.695288Z","time spent":"847.4791ms","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-23T01:26:43.542781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:26:42.695965Z","time spent":"846.72172ms","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T01:26:43.542858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"847.315046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-23T01:26:43.542913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.924028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T01:26:43.542929Z","caller":"traceutil/trace.go:171","msg":"trace[338312834] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1097; }","duration":"847.44825ms","start":"2026-04-23T01:26:42.695462Z","end":"2026-04-23T01:26:43.54291Z","steps":["trace[338312834] 'agreement among raft nodes before linearized reading' (duration: 847.254945ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:26:43.542972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"717.12019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T01:26:43.542985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:26:42.695434Z","time spent":"847.526842ms","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-23T01:26:43.543025Z","caller":"traceutil/trace.go:171","msg":"trace[1485296803] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1097; }","duration":"717.216402ms","start":"2026-04-23T01:26:42.82579Z","end":"2026-04-23T01:26:43.543007Z","steps":["trace[1485296803] 'agreement among raft nodes before linearized reading' (duration: 717.13813ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:26:43.543029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.687996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T01:26:43.543077Z","caller":"traceutil/trace.go:171","msg":"trace[1890935060] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1097; }","duration":"186.758237ms","start":"2026-04-23T01:26:43.356297Z","end":"2026-04-23T01:26:43.543055Z","steps":["trace[1890935060] 'agreement among raft nodes before linearized reading' (duration: 186.706035ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:26:43.543077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:26:42.825761Z","time spent":"717.297725ms","remote":"127.0.0.1:47676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-04-23T01:26:43.543106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.407631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T01:26:43.543107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"847.511131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-23T01:26:43.543139Z","caller":"traceutil/trace.go:171","msg":"trace[1107555427] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1097; }","duration":"500.483183ms","start":"2026-04-23T01:26:43.042645Z","end":"2026-04-23T01:26:43.543128Z","steps":["trace[1107555427] 'agreement among raft nodes before linearized reading' (duration: 500.428382ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:26:43.543147Z","caller":"traceutil/trace.go:171","msg":"trace[1460714180] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1097; }","duration":"847.592934ms","start":"2026-04-23T01:26:42.695548Z","end":"2026-04-23T01:26:43.543141Z","steps":["trace[1460714180] 'agreement among raft nodes before linearized reading' (duration: 847.512902ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:26:43.543168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:26:42.695519Z","time spent":"847.644775ms","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-23T01:26:43.543169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:26:43.042624Z","time spent":"500.537396ms","remote":"127.0.0.1:47682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-23T01:26:43.542978Z","caller":"traceutil/trace.go:171","msg":"trace[1454121218] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1097; }","duration":"264.045331ms","start":"2026-04-23T01:26:43.278907Z","end":"2026-04-23T01:26:43.542952Z","steps":["trace[1454121218] 'agreement among raft nodes before linearized reading' (duration: 263.812505ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:26:43.796389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.168548ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T01:26:43.796606Z","caller":"traceutil/trace.go:171","msg":"trace[634614706] linearizableReadLoop","detail":"{readStateIndex:1147; appliedIndex:1144; }","duration":"160.062575ms","start":"2026-04-23T01:26:43.636512Z","end":"2026-04-23T01:26:43.796575Z","steps":["trace[634614706] 'read index received' (duration: 139.218921ms)","trace[634614706] 'applied index is now lower than readState.Index' (duration: 20.842394ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T01:26:43.796753Z","caller":"traceutil/trace.go:171","msg":"trace[85475842] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"249.849026ms","start":"2026-04-23T01:26:43.546879Z","end":"2026-04-23T01:26:43.796728Z","steps":["trace[85475842] 'process raft request' (duration: 31.257616ms)","trace[85475842] 'compare' (duration: 218.071476ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T01:26:43.796797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.285101ms","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-04-23T01:26:43.796843Z","caller":"traceutil/trace.go:171","msg":"trace[476020633] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1100; }","duration":"160.373903ms","start":"2026-04-23T01:26:43.636453Z","end":"2026-04-23T01:26:43.796827Z","steps":["trace[476020633] 'agreement among raft nodes before linearized reading' (duration: 160.215309ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:26:43.79702Z","caller":"traceutil/trace.go:171","msg":"trace[1658284920] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"249.532298ms","start":"2026-04-23T01:26:43.547459Z","end":"2026-04-23T01:26:43.796991Z","steps":["trace[1658284920] 'process raft request' (duration: 249.078165ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:26:43.797075Z","caller":"traceutil/trace.go:171","msg":"trace[1700429064] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"249.63847ms","start":"2026-04-23T01:26:43.547195Z","end":"2026-04-23T01:26:43.796833Z","steps":["trace[1700429064] 'process raft request' (duration: 249.293561ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:26:43.84442Z","caller":"traceutil/trace.go:171","msg":"trace[505065582] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"122.854307ms","start":"2026-04-23T01:26:43.721542Z","end":"2026-04-23T01:26:43.844396Z","steps":["trace[505065582] 'process raft request' (duration: 122.729474ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:26:43.992603Z","caller":"traceutil/trace.go:171","msg":"trace[106613371] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"190.961231ms","start":"2026-04-23T01:26:43.801612Z","end":"2026-04-23T01:26:43.992573Z","steps":["trace[106613371] 'process raft request' (duration: 190.762906ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:26:53.899566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.629934ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T01:26:53.899742Z","caller":"traceutil/trace.go:171","msg":"trace[628682560] transaction","detail":"{read_only:false; response_revision:1182; number_of_response:1; }","duration":"275.47782ms","start":"2026-04-23T01:26:53.62422Z","end":"2026-04-23T01:26:53.899698Z","steps":["trace[628682560] 'process raft request' (duration: 136.61888ms)","trace[628682560] 'compare' (duration: 138.088059ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T01:26:57.754843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.633950308s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-23T01:26:57.754901Z","caller":"traceutil/trace.go:171","msg":"trace[1286117324] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1192; }","duration":"1.634039321s","start":"2026-04-23T01:26:56.120847Z","end":"2026-04-23T01:26:57.754886Z","steps":["trace[1286117324] 'range keys from in-memory index tree' (duration: 1.633813104s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:26:57.754929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:26:56.12083Z","time spent":"1.634093512s","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-23T01:26:57.755041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.287182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T01:26:57.755116Z","caller":"traceutil/trace.go:171","msg":"trace[790662657] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1192; }","duration":"615.398186ms","start":"2026-04-23T01:26:57.1397Z","end":"2026-04-23T01:26:57.755098Z","steps":["trace[790662657] 'range keys from in-memory index tree' (duration: 615.19107ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:26:57.755155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:26:57.139683Z","time spent":"615.463548ms","remote":"127.0.0.1:47584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-23T01:27:08.123506Z","caller":"traceutil/trace.go:171","msg":"trace[767565942] linearizableReadLoop","detail":"{readStateIndex:1284; appliedIndex:1282; }","duration":"120.230255ms","start":"2026-04-23T01:27:08.003238Z","end":"2026-04-23T01:27:08.123468Z","steps":["trace[767565942] 'read index received' (duration: 25.558712ms)","trace[767565942] 'applied index is now lower than readState.Index' (duration: 94.670613ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T01:27:08.123591Z","caller":"traceutil/trace.go:171","msg":"trace[1358825677] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"127.887254ms","start":"2026-04-23T01:27:07.995673Z","end":"2026-04-23T01:27:08.12356Z","steps":["trace[1358825677] 'process raft request' (duration: 85.816194ms)","trace[1358825677] 'compare' (duration: 41.883045ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T01:27:08.123677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.416941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-23T01:27:08.123708Z","caller":"traceutil/trace.go:171","msg":"trace[1624823391] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1230; }","duration":"120.493573ms","start":"2026-04-23T01:27:08.003205Z","end":"2026-04-23T01:27:08.123699Z","steps":["trace[1624823391] 'agreement among raft nodes before linearized reading' (duration: 120.3613ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:27:08.256418Z","caller":"traceutil/trace.go:171","msg":"trace[605753071] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"128.922001ms","start":"2026-04-23T01:27:08.127475Z","end":"2026-04-23T01:27:08.256397Z","steps":["trace[605753071] 'process raft request' (duration: 128.088819ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:27:12.53633Z","caller":"traceutil/trace.go:171","msg":"trace[1699573271] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"709.685255ms","start":"2026-04-23T01:27:11.826617Z","end":"2026-04-23T01:27:12.536302Z","steps":["trace[1699573271] 'process raft request' (duration: 709.550902ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:27:12.53654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:27:11.826598Z","time spent":"709.87161ms","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T01:27:13.118605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.906626ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T01:27:13.118901Z","caller":"traceutil/trace.go:171","msg":"trace[1807870724] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"1.291626706s","start":"2026-04-23T01:27:11.827251Z","end":"2026-04-23T01:27:13.118878Z","steps":["trace[1807870724] 'process raft request' (duration: 1.07236179s)","trace[1807870724] 'compare' (duration: 218.699541ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T01:27:13.118958Z","caller":"traceutil/trace.go:171","msg":"trace[455449806] linearizableReadLoop","detail":"{readStateIndex:1305; appliedIndex:1302; }","duration":"1.07824722s","start":"2026-04-23T01:27:12.040693Z","end":"2026-04-23T01:27:13.118941Z","steps":["trace[455449806] 'read index received' (duration: 495.472055ms)","trace[455449806] 'applied index is now lower than readState.Index' (duration: 582.773905ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T01:27:13.119005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:27:11.827235Z","time spent":"1.29171521s","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T01:27:13.119276Z","caller":"traceutil/trace.go:171","msg":"trace[249171977] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"1.130466077s","start":"2026-04-23T01:27:11.988794Z","end":"2026-04-23T01:27:13.11926Z","steps":["trace[249171977] 'process raft request' (duration: 1.129915281s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:27:13.119348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:27:11.98877Z","time spent":"1.130542769s","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T01:27:13.119531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.078833976s","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-04-23T01:27:13.119557Z","caller":"traceutil/trace.go:171","msg":"trace[1324683069] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1250; }","duration":"1.078899388s","start":"2026-04-23T01:27:12.04065Z","end":"2026-04-23T01:27:13.119549Z","steps":["trace[1324683069] 'agreement among raft nodes before linearized reading' (duration: 1.078801725s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:27:13.11953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"979.942714ms","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":"warn","ts":"2026-04-23T01:27:13.119583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:27:12.040631Z","time spent":"1.078945428s","remote":"127.0.0.1:47830","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-04-23T01:27:13.119597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.019095ms","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-04-23T01:27:13.119618Z","caller":"traceutil/trace.go:171","msg":"trace[758358564] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1250; }","duration":"980.107889ms","start":"2026-04-23T01:27:12.139488Z","end":"2026-04-23T01:27:13.119596Z","steps":["trace[758358564] 'agreement among raft nodes before linearized reading' (duration: 979.937444ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:27:13.119661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"798.929355ms","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-04-23T01:27:13.11967Z","caller":"traceutil/trace.go:171","msg":"trace[691306804] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1250; }","duration":"283.136358ms","start":"2026-04-23T01:27:12.836515Z","end":"2026-04-23T01:27:13.119651Z","steps":["trace[691306804] 'agreement among raft nodes before linearized reading' (duration: 282.931042ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:27:13.119688Z","caller":"traceutil/trace.go:171","msg":"trace[37205624] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1250; }","duration":"798.959125ms","start":"2026-04-23T01:27:12.320722Z","end":"2026-04-23T01:27:13.119681Z","steps":["trace[37205624] 'agreement among raft nodes before linearized reading' (duration: 798.841792ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:27:13.119682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:27:12.139467Z","time spent":"980.196141ms","remote":"127.0.0.1:47598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":31,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-23T01:27:13.119706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"978.900777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-23T01:27:13.119709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:27:12.320673Z","time spent":"799.033149ms","remote":"127.0.0.1:47830","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-04-23T01:27:13.119738Z","caller":"traceutil/trace.go:171","msg":"trace[410066283] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1250; }","duration":"978.956227ms","start":"2026-04-23T01:27:12.140773Z","end":"2026-04-23T01:27:13.11973Z","steps":["trace[410066283] 'agreement among raft nodes before linearized reading' (duration: 978.872315ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:27:13.119767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:27:12.140762Z","time spent":"978.996919ms","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-23T01:27:13.332878Z","caller":"traceutil/trace.go:171","msg":"trace[1128924469] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"209.675886ms","start":"2026-04-23T01:27:13.123184Z","end":"2026-04-23T01:27:13.332859Z","steps":["trace[1128924469] 'process raft request' (duration: 209.527502ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:27:13.346388Z","caller":"traceutil/trace.go:171","msg":"trace[151403149] linearizableReadLoop","detail":"{readStateIndex:1308; appliedIndex:1306; }","duration":"208.657438ms","start":"2026-04-23T01:27:13.137675Z","end":"2026-04-23T01:27:13.346332Z","steps":["trace[151403149] 'read index received' (duration: 195.174633ms)","trace[151403149] 'applied index is now lower than readState.Index' (duration: 13.482135ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T01:27:13.346419Z","caller":"traceutil/trace.go:171","msg":"trace[2032524729] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"222.880474ms","start":"2026-04-23T01:27:13.123511Z","end":"2026-04-23T01:27:13.346392Z","steps":["trace[2032524729] 'process raft request' (duration: 222.676588ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:27:13.346483Z","caller":"traceutil/trace.go:171","msg":"trace[130638379] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"222.031731ms","start":"2026-04-23T01:27:13.124443Z","end":"2026-04-23T01:27:13.346474Z","steps":["trace[130638379] 'process raft request' (duration: 221.861537ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:27:13.346652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.965517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T01:27:13.346696Z","caller":"traceutil/trace.go:171","msg":"trace[667180435] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1253; }","duration":"209.056229ms","start":"2026-04-23T01:27:13.137629Z","end":"2026-04-23T01:27:13.346685Z","steps":["trace[667180435] 'agreement among raft nodes before linearized reading' (duration: 208.971998ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:27:13.346903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.400354ms","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":"info","ts":"2026-04-23T01:27:13.346944Z","caller":"traceutil/trace.go:171","msg":"trace[1221780840] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1253; }","duration":"200.480057ms","start":"2026-04-23T01:27:13.146455Z","end":"2026-04-23T01:27:13.346936Z","steps":["trace[1221780840] 'agreement among raft nodes before linearized reading' (duration: 200.405814ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:27:13.555663Z","caller":"traceutil/trace.go:171","msg":"trace[1134628121] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"110.579358ms","start":"2026-04-23T01:27:13.445042Z","end":"2026-04-23T01:27:13.555621Z","steps":["trace[1134628121] 'process raft request' (duration: 67.222673ms)","trace[1134628121] 'compare' (duration: 43.201312ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T01:27:13.811225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.388402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T01:27:13.811303Z","caller":"traceutil/trace.go:171","msg":"trace[1762319866] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1254; }","duration":"114.523556ms","start":"2026-04-23T01:27:13.696762Z","end":"2026-04-23T01:27:13.811285Z","steps":["trace[1762319866] 'count revisions from in-memory index tree' (duration: 114.30336ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:27:15.291601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.151776355s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T01:27:15.291658Z","caller":"traceutil/trace.go:171","msg":"trace[1027516966] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1255; }","duration":"1.151913608s","start":"2026-04-23T01:27:14.139732Z","end":"2026-04-23T01:27:15.291645Z","steps":["trace[1027516966] 'range keys from in-memory index tree' (duration: 1.151687221s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:27:15.291693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:27:14.139712Z","time spent":"1.15197182s","remote":"127.0.0.1:47584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T01:27:15.933469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.34298965s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-23T01:27:15.933552Z","caller":"traceutil/trace.go:171","msg":"trace[381814717] linearizableReadLoop","detail":"{readStateIndex:1311; appliedIndex:1310; }","duration":"1.393282265s","start":"2026-04-23T01:27:14.540256Z","end":"2026-04-23T01:27:15.933538Z","steps":["trace[381814717] 'read index received' (duration: 50.223433ms)","trace[381814717] 'applied index is now lower than readState.Index' (duration: 1.343057551s)"],"step_count":2} {"level":"warn","ts":"2026-04-23T01:27:15.933792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.393533041s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-23T01:27:15.93384Z","caller":"traceutil/trace.go:171","msg":"trace[23878229] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1255; }","duration":"1.393633963s","start":"2026-04-23T01:27:14.540196Z","end":"2026-04-23T01:27:15.93383Z","steps":["trace[23878229] 'agreement among raft nodes before linearized reading' (duration: 1.393488569s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:27:15.933863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:27:14.540172Z","time spent":"1.393686274s","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-23T01:27:16.008704Z","caller":"traceutil/trace.go:171","msg":"trace[1376577134] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"884.26299ms","start":"2026-04-23T01:27:15.124414Z","end":"2026-04-23T01:27:16.008677Z","steps":["trace[1376577134] 'process raft request' (duration: 884.109476ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:27:16.00887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:27:15.124394Z","time spent":"884.391894ms","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T01:27:16.008926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"885.540134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-23T01:27:16.008953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.337002ms","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-04-23T01:27:16.008937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.030725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T01:27:16.008985Z","caller":"traceutil/trace.go:171","msg":"trace[819584571] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1256; }","duration":"885.636428ms","start":"2026-04-23T01:27:15.123338Z","end":"2026-04-23T01:27:16.008974Z","steps":["trace[819584571] 'agreement among raft nodes before linearized reading' (duration: 885.427422ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:27:16.008991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.695405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T01:27:16.008996Z","caller":"traceutil/trace.go:171","msg":"trace[1704589769] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1256; }","duration":"659.394392ms","start":"2026-04-23T01:27:15.349588Z","end":"2026-04-23T01:27:16.008982Z","steps":["trace[1704589769] 'agreement among raft nodes before linearized reading' (duration: 659.271249ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:27:16.009015Z","caller":"traceutil/trace.go:171","msg":"trace[1680303420] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1256; }","duration":"310.756307ms","start":"2026-04-23T01:27:15.698252Z","end":"2026-04-23T01:27:16.009008Z","steps":["trace[1680303420] 'agreement among raft nodes before linearized reading' (duration: 310.720186ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:27:16.009014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:27:15.123319Z","time spent":"885.687819ms","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-23T01:27:16.009031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:27:15.698231Z","time spent":"310.795698ms","remote":"127.0.0.1:47924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":31,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-23T01:27:16.009028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:27:15.349581Z","time spent":"659.441413ms","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-23T01:27:16.00902Z","caller":"traceutil/trace.go:171","msg":"trace[1297561381] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1256; }","duration":"481.167499ms","start":"2026-04-23T01:27:15.527833Z","end":"2026-04-23T01:27:16.009Z","steps":["trace[1297561381] 'agreement among raft nodes before linearized reading' (duration: 481.013935ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:27:16.00907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:27:15.527814Z","time spent":"481.245072ms","remote":"127.0.0.1:47960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-04-23T01:27:16.009064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.379434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-23T01:27:16.008935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"693.613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T01:27:16.009107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"715.581575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T01:27:16.009126Z","caller":"traceutil/trace.go:171","msg":"trace[890879025] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1256; }","duration":"715.616147ms","start":"2026-04-23T01:27:15.293503Z","end":"2026-04-23T01:27:16.009119Z","steps":["trace[890879025] 'agreement among raft nodes before linearized reading' (duration: 715.589466ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:27:16.009119Z","caller":"traceutil/trace.go:171","msg":"trace[87109203] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1256; }","duration":"672.460186ms","start":"2026-04-23T01:27:15.336643Z","end":"2026-04-23T01:27:16.009103Z","steps":["trace[87109203] 'agreement among raft nodes before linearized reading' (duration: 672.318153ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:27:16.009145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:27:15.293493Z","time spent":"715.647138ms","remote":"127.0.0.1:47578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-23T01:27:16.009149Z","caller":"traceutil/trace.go:171","msg":"trace[1821193408] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1256; }","duration":"693.852537ms","start":"2026-04-23T01:27:15.315259Z","end":"2026-04-23T01:27:16.009112Z","steps":["trace[1821193408] 'agreement among raft nodes before linearized reading' (duration: 693.6114ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:27:16.009167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:27:15.336627Z","time spent":"672.528098ms","remote":"127.0.0.1:47830","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-04-23T01:27:16.009192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:27:15.315244Z","time spent":"693.933209ms","remote":"127.0.0.1:47566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-04-23T01:27:16.009537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.05093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-23T01:27:16.009575Z","caller":"traceutil/trace.go:171","msg":"trace[1247310196] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1256; }","duration":"660.108551ms","start":"2026-04-23T01:27:15.349456Z","end":"2026-04-23T01:27:16.009565Z","steps":["trace[1247310196] 'agreement among raft nodes before linearized reading' (duration: 659.33445ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:27:16.009604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:27:15.349445Z","time spent":"660.152462ms","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-23T01:27:16.138791Z","caller":"traceutil/trace.go:171","msg":"trace[329707244] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"201.230247ms","start":"2026-04-23T01:27:15.937517Z","end":"2026-04-23T01:27:16.138748Z","steps":["trace[329707244] 'process raft request' (duration: 183.863326ms)","trace[329707244] 'compare' (duration: 17.155596ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T01:27:16.152817Z","caller":"traceutil/trace.go:171","msg":"trace[1998261057] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"138.155966ms","start":"2026-04-23T01:27:16.014641Z","end":"2026-04-23T01:27:16.152797Z","steps":["trace[1998261057] 'process raft request' (duration: 138.021472ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:27:16.153035Z","caller":"traceutil/trace.go:171","msg":"trace[476969382] linearizableReadLoop","detail":"{readStateIndex:1317; appliedIndex:1312; }","duration":"137.269853ms","start":"2026-04-23T01:27:16.015754Z","end":"2026-04-23T01:27:16.153024Z","steps":["trace[476969382] 'read index received' (duration: 105.768898ms)","trace[476969382] 'applied index is now lower than readState.Index' (duration: 31.500165ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T01:27:16.153089Z","caller":"traceutil/trace.go:171","msg":"trace[1110750861] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"138.350942ms","start":"2026-04-23T01:27:16.014728Z","end":"2026-04-23T01:27:16.153079Z","steps":["trace[1110750861] 'process raft request' (duration: 138.154076ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:27:16.153158Z","caller":"traceutil/trace.go:171","msg":"trace[164669116] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"137.989893ms","start":"2026-04-23T01:27:16.01515Z","end":"2026-04-23T01:27:16.15314Z","steps":["trace[164669116] 'process raft request' (duration: 137.815308ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:27:16.15303Z","caller":"traceutil/trace.go:171","msg":"trace[1065793672] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"138.32086ms","start":"2026-04-23T01:27:16.014685Z","end":"2026-04-23T01:27:16.153006Z","steps":["trace[1065793672] 'process raft request' (duration: 138.078824ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:27:16.153151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.387476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T01:27:16.153345Z","caller":"traceutil/trace.go:171","msg":"trace[257550011] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1261; }","duration":"137.615191ms","start":"2026-04-23T01:27:16.015721Z","end":"2026-04-23T01:27:16.153337Z","steps":["trace[257550011] 'agreement among raft nodes before linearized reading' (duration: 137.358275ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:27:18.294482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.860031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T01:27:18.29456Z","caller":"traceutil/trace.go:171","msg":"trace[1431723044] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1262; }","duration":"393.988515ms","start":"2026-04-23T01:27:17.90055Z","end":"2026-04-23T01:27:18.294539Z","steps":["trace[1431723044] 'count revisions from in-memory index tree' (duration: 393.735997ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:27:18.294612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:27:17.900529Z","time spent":"394.063226ms","remote":"127.0.0.1:47516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-04-23T01:27:18.294763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.229461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5373"} {"level":"info","ts":"2026-04-23T01:27:18.294818Z","caller":"traceutil/trace.go:171","msg":"trace[289843222] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1262; }","duration":"460.326863ms","start":"2026-04-23T01:27:17.834477Z","end":"2026-04-23T01:27:18.294804Z","steps":["trace[289843222] 'range keys from in-memory index tree' (duration: 460.087757ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:27:18.294847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:27:17.83446Z","time spent":"460.380904ms","remote":"127.0.0.1:47726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":5397,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-04-23T01:27:18.310666Z","caller":"traceutil/trace.go:171","msg":"trace[1559714880] linearizableReadLoop","detail":"{readStateIndex:1319; appliedIndex:1318; }","duration":"168.012066ms","start":"2026-04-23T01:27:18.142635Z","end":"2026-04-23T01:27:18.310647Z","steps":["trace[1559714880] 'read index received' (duration: 167.894473ms)","trace[1559714880] 'applied index is now lower than readState.Index' (duration: 116.493µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T01:27:18.310712Z","caller":"traceutil/trace.go:171","msg":"trace[867374005] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"295.72558ms","start":"2026-04-23T01:27:18.014953Z","end":"2026-04-23T01:27:18.310678Z","steps":["trace[867374005] 'process raft request' (duration: 295.566756ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:27:18.310852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.208282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-23T01:27:18.310901Z","caller":"traceutil/trace.go:171","msg":"trace[1120647661] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1263; }","duration":"168.291353ms","start":"2026-04-23T01:27:18.142596Z","end":"2026-04-23T01:27:18.310888Z","steps":["trace[1120647661] 'agreement among raft nodes before linearized reading' (duration: 168.138909ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:27:18.811753Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576315384077827854,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T01:27:18.827512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.045049ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T01:27:18.827615Z","caller":"traceutil/trace.go:171","msg":"trace[866396293] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1263; }","duration":"181.166962ms","start":"2026-04-23T01:27:18.64643Z","end":"2026-04-23T01:27:18.827597Z","steps":["trace[866396293] 'range keys from in-memory index tree' (duration: 181.025479ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:27:19.312968Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576315384077827854,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T01:27:19.646739Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.336213996s","expected-duration":"1s"} {"level":"info","ts":"2026-04-23T01:27:19.647463Z","caller":"traceutil/trace.go:171","msg":"trace[2099599509] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"1.344388168s","start":"2026-04-23T01:27:18.30305Z","end":"2026-04-23T01:27:19.647438Z","steps":["trace[2099599509] 'process raft request' (duration: 1.343911715s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:27:19.647656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:27:18.303031Z","time spent":"1.344502821s","remote":"127.0.0.1:47726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5499,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T01:27:20.018592Z","caller":"traceutil/trace.go:171","msg":"trace[53778970] linearizableReadLoop","detail":"{readStateIndex:1322; appliedIndex:1319; }","duration":"1.707849024s","start":"2026-04-23T01:27:18.310721Z","end":"2026-04-23T01:27:20.01857Z","steps":["trace[53778970] 'read index received' (duration: 1.336243737s)","trace[53778970] 'applied index is now lower than readState.Index' (duration: 371.604217ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T01:27:20.018616Z","caller":"traceutil/trace.go:171","msg":"trace[1674673147] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"1.704124912s","start":"2026-04-23T01:27:18.314461Z","end":"2026-04-23T01:27:20.018586Z","steps":["trace[1674673147] 'process raft request' (duration: 1.703898326s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:27:20.018755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:27:18.314437Z","time spent":"1.704243477s","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T01:27:20.018813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.861961994s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-23T01:27:20.018849Z","caller":"traceutil/trace.go:171","msg":"trace[1666370010] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1266; }","duration":"1.862035866s","start":"2026-04-23T01:27:18.156801Z","end":"2026-04-23T01:27:20.018837Z","steps":["trace[1666370010] 'agreement among raft nodes before linearized reading' (duration: 1.861880121s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:27:20.018887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:27:18.156791Z","time spent":"1.862088856s","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-23T01:27:20.018862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.858120619s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-23T01:27:20.018955Z","caller":"traceutil/trace.go:171","msg":"trace[1726873082] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1266; }","duration":"1.858256712s","start":"2026-04-23T01:27:18.16068Z","end":"2026-04-23T01:27:20.018936Z","steps":["trace[1726873082] 'agreement among raft nodes before linearized reading' (duration: 1.857982416s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:27:20.019003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:27:18.160662Z","time spent":"1.858328555s","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-23T01:27:20.019045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.242273388s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.95\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-23T01:27:20.01907Z","caller":"traceutil/trace.go:171","msg":"trace[1060853033] range","detail":"{range_begin:/registry/masterleases/199.204.45.95; range_end:; response_count:1; response_revision:1266; }","duration":"1.242333271s","start":"2026-04-23T01:27:18.776729Z","end":"2026-04-23T01:27:20.019062Z","steps":["trace[1060853033] 'agreement among raft nodes before linearized reading' (duration: 1.242257988s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:27:20.019041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.862779486s","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-04-23T01:27:20.019092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:27:18.776708Z","time spent":"1.242378811s","remote":"127.0.0.1:47600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.95\" "} {"level":"info","ts":"2026-04-23T01:27:20.019126Z","caller":"traceutil/trace.go:171","msg":"trace[1101842354] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1266; }","duration":"1.862901429s","start":"2026-04-23T01:27:18.156203Z","end":"2026-04-23T01:27:20.019104Z","steps":["trace[1101842354] 'agreement among raft nodes before linearized reading' (duration: 1.862673273s)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:27:20.019142Z","caller":"traceutil/trace.go:171","msg":"trace[1735312955] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"1.492493714s","start":"2026-04-23T01:27:18.526619Z","end":"2026-04-23T01:27:20.019112Z","steps":["trace[1735312955] 'process raft request' (duration: 1.491890588s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:27:20.019179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:27:18.156189Z","time spent":"1.862977452s","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-23T01:27:20.019225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:27:18.5266Z","time spent":"1.492571707s","remote":"127.0.0.1:47634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":757,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T01:27:20.019383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.862607151s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-04-23T01:27:20.019406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.917569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-04-23T01:27:20.019425Z","caller":"traceutil/trace.go:171","msg":"trace[1747135544] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1266; }","duration":"1.862658163s","start":"2026-04-23T01:27:18.156755Z","end":"2026-04-23T01:27:20.019413Z","steps":["trace[1747135544] 'agreement among raft nodes before linearized reading' (duration: 1.86253845s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:27:20.019464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:27:18.156751Z","time spent":"1.862704284s","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-23T01:27:20.019511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.229623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-23T01:27:20.01946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"880.657412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T01:27:20.019739Z","caller":"traceutil/trace.go:171","msg":"trace[737316513] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1266; }","duration":"880.964191ms","start":"2026-04-23T01:27:19.138761Z","end":"2026-04-23T01:27:20.019725Z","steps":["trace[737316513] 'agreement among raft nodes before linearized reading' (duration: 880.658632ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:27:20.019829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:27:19.138745Z","time spent":"881.070364ms","remote":"127.0.0.1:47584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-23T01:27:20.019629Z","caller":"traceutil/trace.go:171","msg":"trace[1479070184] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1266; }","duration":"470.378947ms","start":"2026-04-23T01:27:19.549236Z","end":"2026-04-23T01:27:20.019615Z","steps":["trace[1479070184] 'agreement among raft nodes before linearized reading' (duration: 470.247803ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:27:20.020006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:27:19.549206Z","time spent":"470.783046ms","remote":"127.0.0.1:48052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":5,"response size":31,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"info","ts":"2026-04-23T01:27:20.019434Z","caller":"traceutil/trace.go:171","msg":"trace[577952156] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:1266; }","duration":"367.975459ms","start":"2026-04-23T01:27:19.65145Z","end":"2026-04-23T01:27:20.019426Z","steps":["trace[577952156] 'agreement among raft nodes before linearized reading' (duration: 367.922308ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:27:20.020152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:27:19.651436Z","time spent":"368.690739ms","remote":"127.0.0.1:47946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":688,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-04-23T01:27:22.822228Z","caller":"traceutil/trace.go:171","msg":"trace[35195119] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"105.684236ms","start":"2026-04-23T01:27:22.716519Z","end":"2026-04-23T01:27:22.822203Z","steps":["trace[35195119] 'process raft request' (duration: 69.323531ms)","trace[35195119] 'compare' (duration: 36.213491ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T01:27:22.863137Z","caller":"traceutil/trace.go:171","msg":"trace[1035611346] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"133.382666ms","start":"2026-04-23T01:27:22.729726Z","end":"2026-04-23T01:27:22.863109Z","steps":["trace[1035611346] 'process raft request' (duration: 133.211231ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:27:22.863137Z","caller":"traceutil/trace.go:171","msg":"trace[953681064] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"132.924084ms","start":"2026-04-23T01:27:22.730196Z","end":"2026-04-23T01:27:22.86312Z","steps":["trace[953681064] 'process raft request' (duration: 132.854592ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:27:26.548456Z","caller":"traceutil/trace.go:171","msg":"trace[1725070603] linearizableReadLoop","detail":"{readStateIndex:1388; appliedIndex:1387; }","duration":"410.662427ms","start":"2026-04-23T01:27:26.137769Z","end":"2026-04-23T01:27:26.548431Z","steps":["trace[1725070603] 'read index received' (duration: 410.504533ms)","trace[1725070603] 'applied index is now lower than readState.Index' (duration: 156.844µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T01:27:26.548647Z","caller":"traceutil/trace.go:171","msg":"trace[103658997] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"501.990443ms","start":"2026-04-23T01:27:26.046629Z","end":"2026-04-23T01:27:26.548619Z","steps":["trace[103658997] 'process raft request' (duration: 501.656744ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:27:26.548747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.929883ms","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-04-23T01:27:26.549041Z","caller":"traceutil/trace.go:171","msg":"trace[2099207935] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1330; }","duration":"411.310635ms","start":"2026-04-23T01:27:26.137718Z","end":"2026-04-23T01:27:26.549029Z","steps":["trace[2099207935] 'agreement among raft nodes before linearized reading' (duration: 410.827182ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:27:26.549156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:27:26.137691Z","time spent":"411.378066ms","remote":"127.0.0.1:47830","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-04-23T01:27:26.549646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:27:26.046601Z","time spent":"502.322562ms","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T01:27:27.394484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.107048ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T01:27:27.39458Z","caller":"traceutil/trace.go:171","msg":"trace[2072323348] linearizableReadLoop","detail":"{readStateIndex:1389; appliedIndex:1388; }","duration":"845.728765ms","start":"2026-04-23T01:27:26.548836Z","end":"2026-04-23T01:27:27.394565Z","steps":["trace[2072323348] 'read index received' (duration: 236.37575ms)","trace[2072323348] 'applied index is now lower than readState.Index' (duration: 609.351505ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T01:27:27.394933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.255889517s","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-04-23T01:27:27.394972Z","caller":"traceutil/trace.go:171","msg":"trace[534566040] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1331; }","duration":"1.255970411s","start":"2026-04-23T01:27:26.138992Z","end":"2026-04-23T01:27:27.394962Z","steps":["trace[534566040] 'agreement among raft nodes before linearized reading' (duration: 1.255758155s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:27:27.395008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:27:26.138971Z","time spent":"1.256029212s","remote":"127.0.0.1:47830","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-04-23T01:27:27.39509Z","caller":"traceutil/trace.go:171","msg":"trace[2012295886] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"1.043363545s","start":"2026-04-23T01:27:26.351641Z","end":"2026-04-23T01:27:27.395005Z","steps":["trace[2012295886] 'process raft request' (duration: 433.578398ms)","trace[2012295886] 'compare' (duration: 608.921073ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T01:27:27.395257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.256228667s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-23T01:27:27.395295Z","caller":"traceutil/trace.go:171","msg":"trace[185104541] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1331; }","duration":"1.25628936s","start":"2026-04-23T01:27:26.138996Z","end":"2026-04-23T01:27:27.395286Z","steps":["trace[185104541] 'agreement among raft nodes before linearized reading' (duration: 1.256181177s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:27:27.395324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:27:26.138984Z","time spent":"1.25633433s","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-23T01:27:27.395406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:27:26.351617Z","time spent":"1.04359259s","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T01:27:27.395487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.256857284s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-23T01:27:27.395785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.151593539s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T01:27:27.394881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.202394297s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:471"} {"level":"info","ts":"2026-04-23T01:27:27.395886Z","caller":"traceutil/trace.go:171","msg":"trace[968689350] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1331; }","duration":"1.151719333s","start":"2026-04-23T01:27:26.244147Z","end":"2026-04-23T01:27:27.395866Z","steps":["trace[968689350] 'agreement among raft nodes before linearized reading' (duration: 1.151556948s)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:27:27.396027Z","caller":"traceutil/trace.go:171","msg":"trace[398378069] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1331; }","duration":"1.203635921s","start":"2026-04-23T01:27:26.19238Z","end":"2026-04-23T01:27:27.396016Z","steps":["trace[398378069] 'agreement among raft nodes before linearized reading' (duration: 1.202206812s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:27:27.396062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:27:26.192363Z","time spent":"1.203688402s","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":495,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-23T01:27:27.39609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:27:26.244135Z","time spent":"1.151932599s","remote":"127.0.0.1:47426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"info","ts":"2026-04-23T01:27:27.395814Z","caller":"traceutil/trace.go:171","msg":"trace[138784973] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1331; }","duration":"1.257175712s","start":"2026-04-23T01:27:26.138605Z","end":"2026-04-23T01:27:27.395781Z","steps":["trace[138784973] 'agreement among raft nodes before linearized reading' (duration: 1.256828753s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:27:27.39694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:27:26.138597Z","time spent":"1.258331244s","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-23T01:27:27.827235Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.041954706s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-23T01:27:28.202525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.865516ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T01:27:28.202865Z","caller":"traceutil/trace.go:171","msg":"trace[1611956736] transaction","detail":"{read_only:false; response_revision:1336; number_of_response:1; }","duration":"802.74637ms","start":"2026-04-23T01:27:27.400101Z","end":"2026-04-23T01:27:28.202847Z","steps":["trace[1611956736] 'process raft request' (duration: 802.696929ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:27:28.202945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:27:27.400084Z","time spent":"802.825842ms","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T01:27:28.203086Z","caller":"traceutil/trace.go:171","msg":"trace[605825841] linearizableReadLoop","detail":"{readStateIndex:1390; appliedIndex:1389; }","duration":"808.331171ms","start":"2026-04-23T01:27:27.394725Z","end":"2026-04-23T01:27:28.203056Z","steps":["trace[605825841] 'read index received' (duration: 432.744596ms)","trace[605825841] 'applied index is now lower than readState.Index' (duration: 375.585585ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T01:27:28.203193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.113663951s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"warn","ts":"2026-04-23T01:27:28.2032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.063392288s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T01:27:28.20325Z","caller":"traceutil/trace.go:171","msg":"trace[2110125112] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1336; }","duration":"1.063497171s","start":"2026-04-23T01:27:27.13974Z","end":"2026-04-23T01:27:28.203237Z","steps":["trace[2110125112] 'agreement among raft nodes before linearized reading' (duration: 1.063407128s)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:27:28.203241Z","caller":"traceutil/trace.go:171","msg":"trace[1772771168] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1336; }","duration":"1.113719624s","start":"2026-04-23T01:27:27.089503Z","end":"2026-04-23T01:27:28.203222Z","steps":["trace[1772771168] 'agreement among raft nodes before linearized reading' (duration: 1.113652791s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:27:28.203285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:27:27.139713Z","time spent":"1.063566053s","remote":"127.0.0.1:47584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T01:27:28.203305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:27:27.089495Z","time spent":"1.113795085s","remote":"127.0.0.1:47620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":142,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"info","ts":"2026-04-23T01:27:28.20339Z","caller":"traceutil/trace.go:171","msg":"trace[713508935] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"803.375507ms","start":"2026-04-23T01:27:27.39987Z","end":"2026-04-23T01:27:28.203245Z","steps":["trace[713508935] 'process raft request' (duration: 802.838532ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:27:28.203456Z","caller":"traceutil/trace.go:171","msg":"trace[832899630] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"803.417318ms","start":"2026-04-23T01:27:27.400027Z","end":"2026-04-23T01:27:28.203445Z","steps":["trace[832899630] 'process raft request' (duration: 802.731048ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:27:28.203514Z","caller":"traceutil/trace.go:171","msg":"trace[1463276611] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"803.733647ms","start":"2026-04-23T01:27:27.399766Z","end":"2026-04-23T01:27:28.203499Z","steps":["trace[1463276611] 'process raft request' (duration: 802.859353ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:27:28.203598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:27:27.39975Z","time spent":"803.808659ms","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T01:27:28.203529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:27:27.40002Z","time spent":"803.459888ms","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":456,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T01:27:28.203731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:27:27.399848Z","time spent":"803.606913ms","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T01:27:28.20396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.003707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-04-23T01:27:28.204056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.115244763s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:111201"} {"level":"info","ts":"2026-04-23T01:27:28.204103Z","caller":"traceutil/trace.go:171","msg":"trace[509465696] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1336; }","duration":"1.115334437s","start":"2026-04-23T01:27:27.088757Z","end":"2026-04-23T01:27:28.204092Z","steps":["trace[509465696] 'agreement among raft nodes before linearized reading' (duration: 1.114742781s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:27:28.204141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:27:27.088739Z","time spent":"1.115392598s","remote":"127.0.0.1:47616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":111225,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"info","ts":"2026-04-23T01:27:28.204164Z","caller":"traceutil/trace.go:171","msg":"trace[2002937385] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1336; }","duration":"452.228544ms","start":"2026-04-23T01:27:27.751837Z","end":"2026-04-23T01:27:28.204066Z","steps":["trace[2002937385] 'agreement among raft nodes before linearized reading' (duration: 451.70701ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:27:28.204259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.555858273s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T01:27:28.204304Z","caller":"traceutil/trace.go:171","msg":"trace[908925442] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1336; }","duration":"1.555961146s","start":"2026-04-23T01:27:26.648332Z","end":"2026-04-23T01:27:28.204293Z","steps":["trace[908925442] 'agreement among raft nodes before linearized reading' (duration: 1.555683998s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:27:28.204337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:27:26.648305Z","time spent":"1.556023827s","remote":"127.0.0.1:47744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":5,"response size":31,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-04-23T01:27:28.204417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:27:27.751819Z","time spent":"452.578974ms","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-04-23T01:27:28.203992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.610659119s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-dgfsc\" ","response":"range_response_count:1 size:12641"} {"level":"info","ts":"2026-04-23T01:27:28.205447Z","caller":"traceutil/trace.go:171","msg":"trace[1932097482] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-dgfsc; range_end:; response_count:1; response_revision:1336; }","duration":"1.612139389s","start":"2026-04-23T01:27:26.593286Z","end":"2026-04-23T01:27:28.205426Z","steps":["trace[1932097482] 'agreement among raft nodes before linearized reading' (duration: 1.610621568s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:27:28.205784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:27:26.593272Z","time spent":"1.612490809s","remote":"127.0.0.1:47738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":12665,"request content":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-dgfsc\" "} {"level":"info","ts":"2026-04-23T01:27:28.202753Z","caller":"traceutil/trace.go:171","msg":"trace[1947899631] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"1.650039167s","start":"2026-04-23T01:27:26.552681Z","end":"2026-04-23T01:27:28.20272Z","steps":["trace[1947899631] 'process raft request' (duration: 1.274884152s)","trace[1947899631] 'compare' (duration: 374.418754ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T01:27:28.206124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.89164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-04-23T01:27:28.2062Z","caller":"traceutil/trace.go:171","msg":"trace[675830787] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1336; }","duration":"339.045204ms","start":"2026-04-23T01:27:27.867128Z","end":"2026-04-23T01:27:28.206174Z","steps":["trace[675830787] 'agreement among raft nodes before linearized reading' (duration: 337.057401ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:27:28.206254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:27:27.867105Z","time spent":"339.138377ms","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-23T01:27:28.20623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:27:26.552659Z","time spent":"1.653509791s","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T01:27:38.366613Z","caller":"traceutil/trace.go:171","msg":"trace[849926969] linearizableReadLoop","detail":"{readStateIndex:1449; appliedIndex:1445; }","duration":"109.989202ms","start":"2026-04-23T01:27:38.256572Z","end":"2026-04-23T01:27:38.366562Z","steps":["trace[849926969] 'read index received' (duration: 10.067433ms)","trace[849926969] 'applied index is now lower than readState.Index' (duration: 99.920829ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T01:27:38.3667Z","caller":"traceutil/trace.go:171","msg":"trace[2039412980] transaction","detail":"{read_only:false; response_revision:1387; number_of_response:1; }","duration":"112.664215ms","start":"2026-04-23T01:27:38.254003Z","end":"2026-04-23T01:27:38.366667Z","steps":["trace[2039412980] 'process raft request' (duration: 112.148612ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:27:38.366785Z","caller":"traceutil/trace.go:171","msg":"trace[413811243] transaction","detail":"{read_only:false; response_revision:1388; number_of_response:1; }","duration":"110.63041ms","start":"2026-04-23T01:27:38.25613Z","end":"2026-04-23T01:27:38.366761Z","steps":["trace[413811243] 'process raft request' (duration: 110.334361ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:27:38.366874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.27949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-04-23T01:27:38.366918Z","caller":"traceutil/trace.go:171","msg":"trace[269126718] transaction","detail":"{read_only:false; response_revision:1389; number_of_response:1; }","duration":"110.656871ms","start":"2026-04-23T01:27:38.256251Z","end":"2026-04-23T01:27:38.366907Z","steps":["trace[269126718] 'process raft request' (duration: 110.2746ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:27:38.367531Z","caller":"traceutil/trace.go:171","msg":"trace[1759593290] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1389; }","duration":"110.354073ms","start":"2026-04-23T01:27:38.256548Z","end":"2026-04-23T01:27:38.366902Z","steps":["trace[1759593290] 'agreement among raft nodes before linearized reading' (duration: 110.164448ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:27:45.625513Z","caller":"traceutil/trace.go:171","msg":"trace[1214329535] transaction","detail":"{read_only:false; response_revision:1421; number_of_response:1; }","duration":"132.911854ms","start":"2026-04-23T01:27:45.492583Z","end":"2026-04-23T01:27:45.625495Z","steps":["trace[1214329535] 'process raft request' (duration: 132.794561ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:27:50.68524Z","caller":"traceutil/trace.go:171","msg":"trace[123435872] linearizableReadLoop","detail":"{readStateIndex:1507; appliedIndex:1506; }","duration":"103.671941ms","start":"2026-04-23T01:27:50.581547Z","end":"2026-04-23T01:27:50.685219Z","steps":["trace[123435872] 'read index received' (duration: 103.524157ms)","trace[123435872] 'applied index is now lower than readState.Index' (duration: 146.564µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T01:27:50.685367Z","caller":"traceutil/trace.go:171","msg":"trace[178314763] transaction","detail":"{read_only:false; response_revision:1444; number_of_response:1; }","duration":"134.123247ms","start":"2026-04-23T01:27:50.551202Z","end":"2026-04-23T01:27:50.685325Z","steps":["trace[178314763] 'process raft request' (duration: 133.887761ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:27:50.685428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.863166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-04-23T01:27:50.685475Z","caller":"traceutil/trace.go:171","msg":"trace[1895590650] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1444; }","duration":"103.95604ms","start":"2026-04-23T01:27:50.581509Z","end":"2026-04-23T01:27:50.685465Z","steps":["trace[1895590650] 'agreement among raft nodes before linearized reading' (duration: 103.794595ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:27:52.725847Z","caller":"traceutil/trace.go:171","msg":"trace[672782439] transaction","detail":"{read_only:false; response_revision:1452; number_of_response:1; }","duration":"102.534691ms","start":"2026-04-23T01:27:52.623282Z","end":"2026-04-23T01:27:52.725817Z","steps":["trace[672782439] 'process raft request' (duration: 71.758616ms)","trace[672782439] 'compare' (duration: 30.630621ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T01:28:07.108214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.716169ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T01:28:07.108557Z","caller":"traceutil/trace.go:171","msg":"trace[395002764] linearizableReadLoop","detail":"{readStateIndex:1585; appliedIndex:1584; }","duration":"171.82584ms","start":"2026-04-23T01:28:06.936713Z","end":"2026-04-23T01:28:07.108539Z","steps":["trace[395002764] 'read index received' (duration: 31.670839ms)","trace[395002764] 'applied index is now lower than readState.Index' (duration: 140.153401ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T01:28:07.108574Z","caller":"traceutil/trace.go:171","msg":"trace[714855208] transaction","detail":"{read_only:false; response_revision:1519; number_of_response:1; }","duration":"193.723554ms","start":"2026-04-23T01:28:06.91482Z","end":"2026-04-23T01:28:07.108544Z","steps":["trace[714855208] 'process raft request' (duration: 53.597264ms)","trace[714855208] 'compare' (duration: 139.619756ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T01:28:07.108724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.995014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-23T01:28:07.108776Z","caller":"traceutil/trace.go:171","msg":"trace[861337885] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1519; }","duration":"172.085307ms","start":"2026-04-23T01:28:06.93668Z","end":"2026-04-23T01:28:07.108766Z","steps":["trace[861337885] 'agreement among raft nodes before linearized reading' (duration: 171.917753ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:28:49.115962Z","caller":"traceutil/trace.go:171","msg":"trace[801319988] transaction","detail":"{read_only:false; response_revision:1710; number_of_response:1; }","duration":"100.521188ms","start":"2026-04-23T01:28:49.015401Z","end":"2026-04-23T01:28:49.115923Z","steps":["trace[801319988] 'process raft request' (duration: 68.507302ms)","trace[801319988] 'compare' (duration: 31.827211ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T01:28:51.426759Z","caller":"traceutil/trace.go:171","msg":"trace[196238901] transaction","detail":"{read_only:false; response_revision:1721; number_of_response:1; }","duration":"121.507327ms","start":"2026-04-23T01:28:51.30522Z","end":"2026-04-23T01:28:51.426728Z","steps":["trace[196238901] 'process raft request' (duration: 78.626856ms)","trace[196238901] 'compare' (duration: 42.699125ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T01:29:07.76639Z","caller":"traceutil/trace.go:171","msg":"trace[1295354939] linearizableReadLoop","detail":"{readStateIndex:1873; appliedIndex:1872; }","duration":"107.059605ms","start":"2026-04-23T01:29:07.659284Z","end":"2026-04-23T01:29:07.766343Z","steps":["trace[1295354939] 'read index received' (duration: 68.907614ms)","trace[1295354939] 'applied index is now lower than readState.Index' (duration: 38.150831ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T01:29:07.766501Z","caller":"traceutil/trace.go:171","msg":"trace[1846433092] transaction","detail":"{read_only:false; response_revision:1795; number_of_response:1; }","duration":"148.508366ms","start":"2026-04-23T01:29:07.617942Z","end":"2026-04-23T01:29:07.76645Z","steps":["trace[1846433092] 'process raft request' (duration: 110.253662ms)","trace[1846433092] 'compare' (duration: 38.027638ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T01:29:07.766706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.400574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-23T01:29:07.766748Z","caller":"traceutil/trace.go:171","msg":"trace[539744055] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1795; }","duration":"107.504988ms","start":"2026-04-23T01:29:07.659231Z","end":"2026-04-23T01:29:07.766736Z","steps":["trace[539744055] 'agreement among raft nodes before linearized reading' (duration: 107.25822ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:29:31.286484Z","caller":"traceutil/trace.go:171","msg":"trace[814368985] transaction","detail":"{read_only:false; response_revision:1902; number_of_response:1; }","duration":"114.431027ms","start":"2026-04-23T01:29:31.172018Z","end":"2026-04-23T01:29:31.286449Z","steps":["trace[814368985] 'process raft request' (duration: 114.120448ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:29:31.46125Z","caller":"traceutil/trace.go:171","msg":"trace[1221095080] transaction","detail":"{read_only:false; response_revision:1904; number_of_response:1; }","duration":"100.105663ms","start":"2026-04-23T01:29:31.361115Z","end":"2026-04-23T01:29:31.461221Z","steps":["trace[1221095080] 'process raft request' (duration: 18.776254ms)","trace[1221095080] 'compare' (duration: 81.231057ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T01:29:37.881006Z","caller":"traceutil/trace.go:171","msg":"trace[1608079364] transaction","detail":"{read_only:false; response_revision:1932; number_of_response:1; }","duration":"125.966467ms","start":"2026-04-23T01:29:37.755012Z","end":"2026-04-23T01:29:37.880979Z","steps":["trace[1608079364] 'process raft request' (duration: 125.817582ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:29:55.650096Z","caller":"traceutil/trace.go:171","msg":"trace[1246622164] transaction","detail":"{read_only:false; response_revision:2013; number_of_response:1; }","duration":"214.53821ms","start":"2026-04-23T01:29:55.43551Z","end":"2026-04-23T01:29:55.650049Z","steps":["trace[1246622164] 'process raft request' (duration: 214.345506ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:29:55.756268Z","caller":"traceutil/trace.go:171","msg":"trace[1347247280] transaction","detail":"{read_only:false; response_revision:2014; number_of_response:1; }","duration":"101.794818ms","start":"2026-04-23T01:29:55.654441Z","end":"2026-04-23T01:29:55.756236Z","steps":["trace[1347247280] 'process raft request' (duration: 45.710026ms)","trace[1347247280] 'compare' (duration: 55.98283ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T01:29:56.089991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.931445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-23T01:29:56.090111Z","caller":"traceutil/trace.go:171","msg":"trace[391826523] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2014; }","duration":"126.10412ms","start":"2026-04-23T01:29:55.96398Z","end":"2026-04-23T01:29:56.090084Z","steps":["trace[391826523] 'range keys from in-memory index tree' (duration: 125.605906ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:29:56.090119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.864307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-23T01:29:56.090532Z","caller":"traceutil/trace.go:171","msg":"trace[1931982564] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2014; }","duration":"106.166376ms","start":"2026-04-23T01:29:55.984059Z","end":"2026-04-23T01:29:56.090225Z","steps":["trace[1931982564] 'range keys from in-memory index tree' (duration: 105.719254ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:29:56.090678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.080406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:6 size:363682"} {"level":"info","ts":"2026-04-23T01:29:56.090733Z","caller":"traceutil/trace.go:171","msg":"trace[225194168] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:2014; }","duration":"174.175487ms","start":"2026-04-23T01:29:55.916542Z","end":"2026-04-23T01:29:56.090718Z","steps":["trace[225194168] 'range keys from in-memory index tree' (duration: 173.030417ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:29:56.272717Z","caller":"traceutil/trace.go:171","msg":"trace[698551586] transaction","detail":"{read_only:false; response_revision:2015; number_of_response:1; }","duration":"178.340521ms","start":"2026-04-23T01:29:56.094353Z","end":"2026-04-23T01:29:56.272694Z","steps":["trace[698551586] 'process raft request' (duration: 178.187206ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:29:56.59735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.744936ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T01:29:56.597761Z","caller":"traceutil/trace.go:171","msg":"trace[1734542054] transaction","detail":"{read_only:false; response_revision:2019; number_of_response:1; }","duration":"250.916215ms","start":"2026-04-23T01:29:56.346823Z","end":"2026-04-23T01:29:56.59774Z","steps":["trace[1734542054] 'process raft request' (duration: 250.868084ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:29:56.597942Z","caller":"traceutil/trace.go:171","msg":"trace[1504030782] transaction","detail":"{read_only:false; response_revision:2017; number_of_response:1; }","duration":"501.329407ms","start":"2026-04-23T01:29:56.096594Z","end":"2026-04-23T01:29:56.597923Z","steps":["trace[1504030782] 'process raft request' (duration: 500.874816ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:29:56.59796Z","caller":"traceutil/trace.go:171","msg":"trace[586341503] linearizableReadLoop","detail":"{readStateIndex:2106; appliedIndex:2102; }","duration":"489.406278ms","start":"2026-04-23T01:29:56.108529Z","end":"2026-04-23T01:29:56.597935Z","steps":["trace[586341503] 'read index received' (duration: 164.037197ms)","trace[586341503] 'applied index is now lower than readState.Index' (duration: 325.366731ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T01:29:56.597994Z","caller":"traceutil/trace.go:171","msg":"trace[1098820509] transaction","detail":"{read_only:false; response_revision:2016; number_of_response:1; }","duration":"501.403979ms","start":"2026-04-23T01:29:56.096557Z","end":"2026-04-23T01:29:56.597961Z","steps":["trace[1098820509] 'process raft request' (duration: 338.950105ms)","trace[1098820509] 'compare' (duration: 161.462918ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T01:29:56.598064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:29:56.096574Z","time spent":"501.42551ms","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T01:29:56.597957Z","caller":"traceutil/trace.go:171","msg":"trace[1552022639] transaction","detail":"{read_only:false; response_revision:2018; number_of_response:1; }","duration":"501.244815ms","start":"2026-04-23T01:29:56.096693Z","end":"2026-04-23T01:29:56.597938Z","steps":["trace[1552022639] 'process raft request' (duration: 500.946157ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:29:56.598225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.686416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T01:29:56.59825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.694785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T01:29:56.598281Z","caller":"traceutil/trace.go:171","msg":"trace[1523552920] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:2019; }","duration":"489.761937ms","start":"2026-04-23T01:29:56.108501Z","end":"2026-04-23T01:29:56.598263Z","steps":["trace[1523552920] 'agreement among raft nodes before linearized reading' (duration: 489.684405ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:29:56.59834Z","caller":"traceutil/trace.go:171","msg":"trace[1596490246] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2019; }","duration":"458.827467ms","start":"2026-04-23T01:29:56.139489Z","end":"2026-04-23T01:29:56.598317Z","steps":["trace[1596490246] 'agreement among raft nodes before linearized reading' (duration: 458.621913ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:29:56.59834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:29:56.108491Z","time spent":"489.83553ms","remote":"127.0.0.1:47458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"warn","ts":"2026-04-23T01:29:56.598419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:29:56.139472Z","time spent":"458.93827ms","remote":"127.0.0.1:47584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T01:29:56.598469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:29:56.096642Z","time spent":"501.564633ms","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T01:29:56.598512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.612845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:6 size:363682"} {"level":"info","ts":"2026-04-23T01:29:56.598562Z","caller":"traceutil/trace.go:171","msg":"trace[509749812] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:2019; }","duration":"188.657646ms","start":"2026-04-23T01:29:56.409889Z","end":"2026-04-23T01:29:56.598547Z","steps":["trace[509749812] 'agreement among raft nodes before linearized reading' (duration: 188.290056ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:29:56.598139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:29:56.096532Z","time spent":"501.527803ms","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T01:29:56.598632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.109704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-04-23T01:29:56.598914Z","caller":"traceutil/trace.go:171","msg":"trace[1952293503] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2019; }","duration":"231.360242ms","start":"2026-04-23T01:29:56.367408Z","end":"2026-04-23T01:29:56.598768Z","steps":["trace[1952293503] 'agreement among raft nodes before linearized reading' (duration: 230.854268ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:30:06.025273Z","caller":"traceutil/trace.go:171","msg":"trace[903834060] transaction","detail":"{read_only:false; response_revision:2060; number_of_response:1; }","duration":"184.449713ms","start":"2026-04-23T01:30:05.840714Z","end":"2026-04-23T01:30:06.025164Z","steps":["trace[903834060] 'process raft request' (duration: 184.176516ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:30:40.611834Z","caller":"traceutil/trace.go:171","msg":"trace[122492801] transaction","detail":"{read_only:false; response_revision:2242; number_of_response:1; }","duration":"303.206336ms","start":"2026-04-23T01:30:40.308526Z","end":"2026-04-23T01:30:40.611732Z","steps":["trace[122492801] 'process raft request' (duration: 303.00229ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T01:30:40.611991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T01:30:40.308507Z","time spent":"303.414702ms","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T01:30:46.87682Z","caller":"traceutil/trace.go:171","msg":"trace[543819913] transaction","detail":"{read_only:false; response_revision:2274; number_of_response:1; }","duration":"199.360153ms","start":"2026-04-23T01:30:46.677434Z","end":"2026-04-23T01:30:46.876794Z","steps":["trace[543819913] 'process raft request' (duration: 199.20418ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:30:46.889039Z","caller":"traceutil/trace.go:171","msg":"trace[721054773] transaction","detail":"{read_only:false; response_revision:2275; number_of_response:1; }","duration":"208.311033ms","start":"2026-04-23T01:30:46.680718Z","end":"2026-04-23T01:30:46.889029Z","steps":["trace[721054773] 'process raft request' (duration: 208.068556ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T01:30:47.043641Z","caller":"traceutil/trace.go:171","msg":"trace[431827443] linearizableReadLoop","detail":"{readStateIndex:2378; appliedIndex:2377; }","duration":"101.631754ms","start":"2026-04-23T01:30:46.941987Z","end":"2026-04-23T01:30:47.043619Z","steps":["trace[431827443] 'read index received' (duration: 62.202417ms)","trace[431827443] 'applied index is now lower than readState.Index' (duration: 39.428427ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T01:30:47.043719Z","caller":"traceutil/trace.go:171","msg":"trace[645662504] transaction","detail":"{read_only:false; response_revision:2279; number_of_response:1; }","duration":"101.856231ms","start":"2026-04-23T01:30:46.941811Z","end":"2026-04-23T01:30:47.043667Z","steps":["trace[645662504] 'process raft request' (duration: 62.410183ms)","trace[645662504] 'compare' (duration: 39.300054ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T01:30:47.043927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.913631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-23T01:30:47.044139Z","caller":"traceutil/trace.go:171","msg":"trace[2113454713] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2279; }","duration":"102.170538ms","start":"2026-04-23T01:30:46.941947Z","end":"2026-04-23T01:30:47.044117Z","steps":["trace[2113454713] 'agreement among raft nodes before linearized reading' (duration: 101.770567ms)"],"step_count":1}