{"level":"warn","ts":"2026-07-09T15:35:43.504883Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-09T15:35:43.504972Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.238: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.19.213.238:2380","--initial-cluster=instance=https://199.19.213.238:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.238:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.238:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-09T15:35:43.505042Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-09T15:35:43.505051Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.238:2380"]} {"level":"info","ts":"2026-07-09T15:35:43.505095Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-09T15:35:43.505759Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.238:2379"]} {"level":"info","ts":"2026-07-09T15:35:43.505833Z","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.19.213.238:2380"],"listen-peer-urls":["https://199.19.213.238:2380"],"advertise-client-urls":["https://199.19.213.238:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.238:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.238:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-09T15:35:43.511325Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.272997ms"} {"level":"info","ts":"2026-07-09T15:35:43.519975Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"8cf6035374726417","cluster-id":"e837e6e114b087e2"} {"level":"info","ts":"2026-07-09T15:35:43.520037Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8cf6035374726417 switched to configuration voters=()"} {"level":"info","ts":"2026-07-09T15:35:43.520058Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8cf6035374726417 became follower at term 0"} {"level":"info","ts":"2026-07-09T15:35:43.520067Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8cf6035374726417 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-09T15:35:43.52008Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8cf6035374726417 became follower at term 1"} {"level":"info","ts":"2026-07-09T15:35:43.520115Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8cf6035374726417 switched to configuration voters=(10157309666551555095)"} {"level":"warn","ts":"2026-07-09T15:35:43.52576Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-09T15:35:43.527049Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-09T15:35:43.528917Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-09T15:35:43.531045Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"8cf6035374726417","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-09T15:35:43.531202Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-09T15:35:43.531285Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-09T15:35:43.531297Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-09T15:35:43.531972Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8cf6035374726417","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-09T15:35:43.532347Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8cf6035374726417 switched to configuration voters=(10157309666551555095)"} {"level":"info","ts":"2026-07-09T15:35:43.532461Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e837e6e114b087e2","local-member-id":"8cf6035374726417","added-peer-id":"8cf6035374726417","added-peer-peer-urls":["https://199.19.213.238:2380"]} {"level":"info","ts":"2026-07-09T15:35:43.536714Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-09T15:35:43.536976Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"8cf6035374726417","initial-advertise-peer-urls":["https://199.19.213.238:2380"],"listen-peer-urls":["https://199.19.213.238:2380"],"advertise-client-urls":["https://199.19.213.238:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.238:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-09T15:35:43.537009Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-09T15:35:43.536931Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.238:2380"} {"level":"info","ts":"2026-07-09T15:35:43.537122Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.238:2380"} {"level":"info","ts":"2026-07-09T15:35:43.620918Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8cf6035374726417 is starting a new election at term 1"} {"level":"info","ts":"2026-07-09T15:35:43.62098Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8cf6035374726417 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-09T15:35:43.621003Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8cf6035374726417 received MsgPreVoteResp from 8cf6035374726417 at term 1"} {"level":"info","ts":"2026-07-09T15:35:43.621022Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8cf6035374726417 became candidate at term 2"} {"level":"info","ts":"2026-07-09T15:35:43.621033Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8cf6035374726417 received MsgVoteResp from 8cf6035374726417 at term 2"} {"level":"info","ts":"2026-07-09T15:35:43.621049Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8cf6035374726417 became leader at term 2"} {"level":"info","ts":"2026-07-09T15:35:43.621069Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8cf6035374726417 elected leader 8cf6035374726417 at term 2"} {"level":"info","ts":"2026-07-09T15:35:43.622167Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"8cf6035374726417","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.238:2379]}","request-path":"/0/members/8cf6035374726417/attributes","cluster-id":"e837e6e114b087e2","publish-timeout":"7s"} {"level":"info","ts":"2026-07-09T15:35:43.622214Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-09T15:35:43.622594Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-09T15:35:43.622926Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T15:35:43.623076Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-09T15:35:43.623174Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-09T15:35:43.624911Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-09T15:35:43.626216Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.238:2379"} {"level":"info","ts":"2026-07-09T15:35:43.626326Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e837e6e114b087e2","local-member-id":"8cf6035374726417","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T15:35:43.6264Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T15:35:43.626425Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T15:35:46.6913Z","caller":"traceutil/trace.go:171","msg":"trace[1645761897] transaction","detail":"{read_only:false; response_revision:97; number_of_response:1; }","duration":"193.401524ms","start":"2026-07-09T15:35:46.49762Z","end":"2026-07-09T15:35:46.691021Z","steps":["trace[1645761897] 'process raft request' (duration: 107.037035ms)","trace[1645761897] 'compare' (duration: 86.200445ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:35:46.921069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.201776ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-07-09T15:35:46.921308Z","caller":"traceutil/trace.go:171","msg":"trace[683410308] transaction","detail":"{read_only:false; response_revision:101; number_of_response:1; }","duration":"147.191946ms","start":"2026-07-09T15:35:46.774076Z","end":"2026-07-09T15:35:46.921268Z","steps":["trace[683410308] 'process raft request' (duration: 18.148607ms)","trace[683410308] 'compare' (duration: 128.020101ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:35:47.299279Z","caller":"traceutil/trace.go:171","msg":"trace[2000411474] transaction","detail":"{read_only:false; response_revision:109; number_of_response:1; }","duration":"114.521064ms","start":"2026-07-09T15:35:47.184734Z","end":"2026-07-09T15:35:47.299255Z","steps":["trace[2000411474] 'process raft request' (duration: 114.476613ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:35:47.299379Z","caller":"traceutil/trace.go:171","msg":"trace[380047341] transaction","detail":"{read_only:false; response_revision:108; number_of_response:1; }","duration":"115.398408ms","start":"2026-07-09T15:35:47.183934Z","end":"2026-07-09T15:35:47.299332Z","steps":["trace[380047341] 'process raft request' (duration: 38.04329ms)","trace[380047341] 'compare' (duration: 77.06695ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:35:47.470546Z","caller":"traceutil/trace.go:171","msg":"trace[1095799723] transaction","detail":"{read_only:false; response_revision:113; number_of_response:1; }","duration":"104.225217ms","start":"2026-07-09T15:35:47.366294Z","end":"2026-07-09T15:35:47.47052Z","steps":["trace[1095799723] 'process raft request' (duration: 104.148205ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:35:47.470547Z","caller":"traceutil/trace.go:171","msg":"trace[807884720] transaction","detail":"{read_only:false; response_revision:112; number_of_response:1; }","duration":"106.766938ms","start":"2026-07-09T15:35:47.363755Z","end":"2026-07-09T15:35:47.470522Z","steps":["trace[807884720] 'process raft request' (duration: 42.087324ms)","trace[807884720] 'compare' (duration: 64.473208ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:35:48.285386Z","caller":"traceutil/trace.go:171","msg":"trace[1075339117] transaction","detail":"{read_only:false; response_revision:214; number_of_response:1; }","duration":"139.690766ms","start":"2026-07-09T15:35:48.145669Z","end":"2026-07-09T15:35:48.28536Z","steps":["trace[1075339117] 'process raft request' (duration: 85.517946ms)","trace[1075339117] 'compare' (duration: 54.001585ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:35:48.437834Z","caller":"traceutil/trace.go:171","msg":"trace[1310630811] transaction","detail":"{read_only:false; response_revision:217; number_of_response:1; }","duration":"104.255629ms","start":"2026-07-09T15:35:48.333549Z","end":"2026-07-09T15:35:48.437805Z","steps":["trace[1310630811] 'process raft request' (duration: 52.108134ms)","trace[1310630811] 'compare' (duration: 52.014251ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:35:48.859558Z","caller":"traceutil/trace.go:171","msg":"trace[183061938] transaction","detail":"{read_only:false; response_revision:227; number_of_response:1; }","duration":"166.479974ms","start":"2026-07-09T15:35:48.693052Z","end":"2026-07-09T15:35:48.859532Z","steps":["trace[183061938] 'process raft request' (duration: 166.31723ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:35:49.064534Z","caller":"traceutil/trace.go:171","msg":"trace[1197439660] transaction","detail":"{read_only:false; response_revision:229; number_of_response:1; }","duration":"116.953882ms","start":"2026-07-09T15:35:48.947541Z","end":"2026-07-09T15:35:49.064495Z","steps":["trace[1197439660] 'process raft request' (duration: 35.093889ms)","trace[1197439660] 'compare' (duration: 81.38475ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:35:52.532352Z","caller":"traceutil/trace.go:171","msg":"trace[1249506860] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"101.353497ms","start":"2026-07-09T15:35:52.430974Z","end":"2026-07-09T15:35:52.532328Z","steps":["trace[1249506860] 'process raft request' (duration: 101.304746ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:35:52.5329Z","caller":"traceutil/trace.go:171","msg":"trace[1386133645] transaction","detail":"{read_only:false; response_revision:323; number_of_response:1; }","duration":"147.344751ms","start":"2026-07-09T15:35:52.385542Z","end":"2026-07-09T15:35:52.532887Z","steps":["trace[1386133645] 'process raft request' (duration: 52.375061ms)","trace[1386133645] 'compare' (duration: 94.224289ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:36:08.317098Z","caller":"traceutil/trace.go:171","msg":"trace[1564364013] linearizableReadLoop","detail":"{readStateIndex:448; appliedIndex:447; }","duration":"321.476007ms","start":"2026-07-09T15:36:07.99559Z","end":"2026-07-09T15:36:08.317066Z","steps":["trace[1564364013] 'read index received' (duration: 321.262071ms)","trace[1564364013] 'applied index is now lower than readState.Index' (duration: 212.816µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:36:08.317182Z","caller":"traceutil/trace.go:171","msg":"trace[441589858] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"357.016248ms","start":"2026-07-09T15:36:07.960143Z","end":"2026-07-09T15:36:08.317159Z","steps":["trace[441589858] 'process raft request' (duration: 356.765881ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:36:08.317344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.727504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-09T15:36:08.317416Z","caller":"traceutil/trace.go:171","msg":"trace[1110208510] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:428; }","duration":"321.835147ms","start":"2026-07-09T15:36:07.995544Z","end":"2026-07-09T15:36:08.317379Z","steps":["trace[1110208510] 'agreement among raft nodes before linearized reading' (duration: 321.714634ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:36:08.317471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:36:07.995524Z","time spent":"321.934079ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-09T15:36:08.317584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.56409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-09T15:36:08.3178Z","caller":"traceutil/trace.go:171","msg":"trace[323634666] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:428; }","duration":"150.875138ms","start":"2026-07-09T15:36:08.166897Z","end":"2026-07-09T15:36:08.317772Z","steps":["trace[323634666] 'agreement among raft nodes before linearized reading' (duration: 150.534548ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:36:08.318311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:36:07.960121Z","time spent":"357.582064ms","remote":"127.0.0.1:52404","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-07-09T15:36:09.047303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.834454ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T15:36:09.047576Z","caller":"traceutil/trace.go:171","msg":"trace[1344300392] linearizableReadLoop","detail":"{readStateIndex:450; appliedIndex:448; }","duration":"570.407689ms","start":"2026-07-09T15:36:08.477155Z","end":"2026-07-09T15:36:09.047563Z","steps":["trace[1344300392] 'read index received' (duration: 333.110132ms)","trace[1344300392] 'applied index is now lower than readState.Index' (duration: 237.296707ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:36:09.047694Z","caller":"traceutil/trace.go:171","msg":"trace[1557897329] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"725.771292ms","start":"2026-07-09T15:36:08.321906Z","end":"2026-07-09T15:36:09.047677Z","steps":["trace[1557897329] 'process raft request' (duration: 725.599377ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:36:09.047692Z","caller":"traceutil/trace.go:171","msg":"trace[1766504264] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"725.922506ms","start":"2026-07-09T15:36:08.321691Z","end":"2026-07-09T15:36:09.047613Z","steps":["trace[1766504264] 'process raft request' (duration: 488.595348ms)","trace[1766504264] 'compare' (duration: 236.703181ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:36:09.047842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.694187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T15:36:09.047881Z","caller":"traceutil/trace.go:171","msg":"trace[1317277271] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:430; }","duration":"570.762929ms","start":"2026-07-09T15:36:08.477107Z","end":"2026-07-09T15:36:09.047869Z","steps":["trace[1317277271] 'agreement among raft nodes before linearized reading' (duration: 570.707087ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:36:09.047909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:36:08.477087Z","time spent":"570.81521ms","remote":"127.0.0.1:52114","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-07-09T15:36:09.047979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:36:08.321673Z","time spent":"726.166533ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T15:36:09.047771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:36:08.321885Z","time spent":"725.843024ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T15:36:14.501792Z","caller":"traceutil/trace.go:171","msg":"trace[670410052] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"156.427593ms","start":"2026-07-09T15:36:14.34534Z","end":"2026-07-09T15:36:14.501767Z","steps":["trace[670410052] 'process raft request' (duration: 156.156396ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:36:25.232539Z","caller":"traceutil/trace.go:171","msg":"trace[1737337290] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"111.290587ms","start":"2026-07-09T15:36:25.121206Z","end":"2026-07-09T15:36:25.232497Z","steps":["trace[1737337290] 'process raft request' (duration: 111.04028ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:36:25.235348Z","caller":"traceutil/trace.go:171","msg":"trace[1405948037] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"111.818252ms","start":"2026-07-09T15:36:25.123499Z","end":"2026-07-09T15:36:25.235318Z","steps":["trace[1405948037] 'process raft request' (duration: 111.617566ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:36:26.854601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.162692ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T15:36:26.854713Z","caller":"traceutil/trace.go:171","msg":"trace[1688394533] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"201.848035ms","start":"2026-07-09T15:36:26.652841Z","end":"2026-07-09T15:36:26.854689Z","steps":["trace[1688394533] 'process raft request' (duration: 44.509738ms)","trace[1688394533] 'compare' (duration: 157.042189ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:36:36.801672Z","caller":"traceutil/trace.go:171","msg":"trace[1070543029] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"177.90905ms","start":"2026-07-09T15:36:36.623739Z","end":"2026-07-09T15:36:36.801648Z","steps":["trace[1070543029] 'process raft request' (duration: 177.794587ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:36:37.477278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.125774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-09T15:36:37.477366Z","caller":"traceutil/trace.go:171","msg":"trace[458538513] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:493; }","duration":"156.268278ms","start":"2026-07-09T15:36:37.321078Z","end":"2026-07-09T15:36:37.477346Z","steps":["trace[458538513] 'range keys from in-memory index tree' (duration: 155.981241ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:36:37.477363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.325449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-09T15:36:37.477444Z","caller":"traceutil/trace.go:171","msg":"trace[1530927221] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:493; }","duration":"161.467434ms","start":"2026-07-09T15:36:37.315957Z","end":"2026-07-09T15:36:37.477424Z","steps":["trace[1530927221] 'range keys from in-memory index tree' (duration: 161.185586ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:36:48.254498Z","caller":"traceutil/trace.go:171","msg":"trace[432835591] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"235.276687ms","start":"2026-07-09T15:36:48.019198Z","end":"2026-07-09T15:36:48.254475Z","steps":["trace[432835591] 'process raft request' (duration: 235.113933ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:36:49.003726Z","caller":"traceutil/trace.go:171","msg":"trace[1821667154] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"115.819453ms","start":"2026-07-09T15:36:48.887881Z","end":"2026-07-09T15:36:49.0037Z","steps":["trace[1821667154] 'process raft request' (duration: 115.632187ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:36:55.176976Z","caller":"traceutil/trace.go:171","msg":"trace[385865298] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"140.829479ms","start":"2026-07-09T15:36:55.036119Z","end":"2026-07-09T15:36:55.176949Z","steps":["trace[385865298] 'process raft request' (duration: 140.49903ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:36:57.99168Z","caller":"traceutil/trace.go:171","msg":"trace[712194584] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"105.597119ms","start":"2026-07-09T15:36:57.88606Z","end":"2026-07-09T15:36:57.991657Z","steps":["trace[712194584] 'process raft request' (duration: 101.726221ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:37:10.258449Z","caller":"traceutil/trace.go:171","msg":"trace[743850441] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:600; }","duration":"214.773857ms","start":"2026-07-09T15:37:10.043641Z","end":"2026-07-09T15:37:10.258414Z","steps":["trace[743850441] 'read index received' (duration: 214.609832ms)","trace[743850441] 'applied index is now lower than readState.Index' (duration: 162.785µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:37:10.258535Z","caller":"traceutil/trace.go:171","msg":"trace[1657009409] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"232.205871ms","start":"2026-07-09T15:37:10.0263Z","end":"2026-07-09T15:37:10.258506Z","steps":["trace[1657009409] 'process raft request' (duration: 231.906183ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:37:10.258618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.960662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-09T15:37:10.258666Z","caller":"traceutil/trace.go:171","msg":"trace[635015378] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:568; }","duration":"215.076375ms","start":"2026-07-09T15:37:10.043578Z","end":"2026-07-09T15:37:10.258654Z","steps":["trace[635015378] 'agreement among raft nodes before linearized reading' (duration: 214.967532ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:37:14.771847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.231833ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-09T15:37:26.468974Z","caller":"traceutil/trace.go:171","msg":"trace[1795591035] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"109.820026ms","start":"2026-07-09T15:37:26.359124Z","end":"2026-07-09T15:37:26.468944Z","steps":["trace[1795591035] 'process raft request' (duration: 109.388064ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:37:26.590302Z","caller":"traceutil/trace.go:171","msg":"trace[764590246] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:640; }","duration":"111.920315ms","start":"2026-07-09T15:37:26.478358Z","end":"2026-07-09T15:37:26.590278Z","steps":["trace[764590246] 'read index received' (duration: 62.627163ms)","trace[764590246] 'applied index is now lower than readState.Index' (duration: 49.292042ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:37:26.590336Z","caller":"traceutil/trace.go:171","msg":"trace[39417793] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"117.409408ms","start":"2026-07-09T15:37:26.472901Z","end":"2026-07-09T15:37:26.59031Z","steps":["trace[39417793] 'process raft request' (duration: 68.138366ms)","trace[39417793] 'compare' (duration: 49.050626ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:37:26.59066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.280035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T15:37:26.590709Z","caller":"traceutil/trace.go:171","msg":"trace[1783803158] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:605; }","duration":"112.363517ms","start":"2026-07-09T15:37:26.47833Z","end":"2026-07-09T15:37:26.590694Z","steps":["trace[1783803158] 'agreement among raft nodes before linearized reading' (duration: 112.074469ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:37:26.894564Z","caller":"traceutil/trace.go:171","msg":"trace[830602473] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"300.184634ms","start":"2026-07-09T15:37:26.594355Z","end":"2026-07-09T15:37:26.89454Z","steps":["trace[830602473] 'process raft request' (duration: 288.19687ms)","trace[830602473] 'compare' (duration: 11.8706ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:37:26.894758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:37:26.594333Z","time spent":"300.358838ms","remote":"127.0.0.1:52404","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-07-09T15:38:08.995059Z","caller":"traceutil/trace.go:171","msg":"trace[1948471830] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"159.73758ms","start":"2026-07-09T15:38:08.835297Z","end":"2026-07-09T15:38:08.995035Z","steps":["trace[1948471830] 'process raft request' (duration: 159.523734ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:38:10.18857Z","caller":"traceutil/trace.go:171","msg":"trace[583865983] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"740.203719ms","start":"2026-07-09T15:38:09.448322Z","end":"2026-07-09T15:38:10.188525Z","steps":["trace[583865983] 'process raft request' (duration: 739.927191ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:38:10.18862Z","caller":"traceutil/trace.go:171","msg":"trace[1937520712] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"737.316129ms","start":"2026-07-09T15:38:09.451273Z","end":"2026-07-09T15:38:10.188589Z","steps":["trace[1937520712] 'process raft request' (duration: 737.143764ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:38:10.188719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:38:09.448304Z","time spent":"740.344023ms","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T15:38:10.188798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:38:09.451266Z","time spent":"737.451222ms","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T15:38:10.188925Z","caller":"traceutil/trace.go:171","msg":"trace[1289734648] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"717.358317ms","start":"2026-07-09T15:38:09.471539Z","end":"2026-07-09T15:38:10.188897Z","steps":["trace[1289734648] 'process raft request' (duration: 717.10034ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:38:10.188995Z","caller":"traceutil/trace.go:171","msg":"trace[352524662] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"732.342352ms","start":"2026-07-09T15:38:09.456643Z","end":"2026-07-09T15:38:10.188985Z","steps":["trace[352524662] 'process raft request' (duration: 731.823307ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:38:10.189054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:38:09.471521Z","time spent":"717.47543ms","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T15:38:10.189101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:38:09.456621Z","time spent":"732.415743ms","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T15:38:10.189203Z","caller":"traceutil/trace.go:171","msg":"trace[47890370] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"722.966223ms","start":"2026-07-09T15:38:09.466191Z","end":"2026-07-09T15:38:10.189158Z","steps":["trace[47890370] 'process raft request' (duration: 722.303204ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:38:10.189229Z","caller":"traceutil/trace.go:171","msg":"trace[560072926] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"722.754646ms","start":"2026-07-09T15:38:09.466465Z","end":"2026-07-09T15:38:10.189219Z","steps":["trace[560072926] 'process raft request' (duration: 722.067927ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:38:10.189277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:38:09.466459Z","time spent":"722.794237ms","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T15:38:10.189277Z","caller":"traceutil/trace.go:171","msg":"trace[268307535] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"713.743587ms","start":"2026-07-09T15:38:09.475515Z","end":"2026-07-09T15:38:10.189259Z","steps":["trace[268307535] 'process raft request' (duration: 713.165321ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:38:10.189307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:38:09.466172Z","time spent":"723.072455ms","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T15:38:10.188921Z","caller":"traceutil/trace.go:171","msg":"trace[1471135253] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"722.409357ms","start":"2026-07-09T15:38:09.466484Z","end":"2026-07-09T15:38:10.188893Z","steps":["trace[1471135253] 'process raft request' (duration: 722.084428ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:38:10.189353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:38:09.475503Z","time spent":"713.818069ms","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T15:38:10.189379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:38:09.466459Z","time spent":"722.9001ms","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T15:38:10.189437Z","caller":"traceutil/trace.go:171","msg":"trace[1082418385] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"722.918951ms","start":"2026-07-09T15:38:09.466509Z","end":"2026-07-09T15:38:10.189428Z","steps":["trace[1082418385] 'process raft request' (duration: 722.095018ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:38:10.18949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:38:09.466504Z","time spent":"722.963352ms","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T15:38:10.189622Z","caller":"traceutil/trace.go:171","msg":"trace[709300236] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"710.375714ms","start":"2026-07-09T15:38:09.479232Z","end":"2026-07-09T15:38:10.189607Z","steps":["trace[709300236] 'process raft request' (duration: 709.49531ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:38:10.189681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:38:09.47921Z","time spent":"710.444816ms","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T15:38:10.18998Z","caller":"traceutil/trace.go:171","msg":"trace[1455070242] linearizableReadLoop","detail":"{readStateIndex:822; appliedIndex:814; }","duration":"714.871398ms","start":"2026-07-09T15:38:09.475097Z","end":"2026-07-09T15:38:10.189969Z","steps":["trace[1455070242] 'read index received' (duration: 98.916497ms)","trace[1455070242] 'applied index is now lower than readState.Index' (duration: 615.954091ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:38:10.19Z","caller":"traceutil/trace.go:171","msg":"trace[1415381412] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"695.483662ms","start":"2026-07-09T15:38:09.494496Z","end":"2026-07-09T15:38:10.18998Z","steps":["trace[1415381412] 'process raft request' (duration: 695.06676ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:38:10.190144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"715.047084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T15:38:10.190121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:38:09.494479Z","time spent":"695.610276ms","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T15:38:10.190181Z","caller":"traceutil/trace.go:171","msg":"trace[1147186895] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:777; }","duration":"715.109155ms","start":"2026-07-09T15:38:09.475064Z","end":"2026-07-09T15:38:10.190173Z","steps":["trace[1147186895] 'agreement among raft nodes before linearized reading' (duration: 714.970601ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:38:10.190209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:38:09.475056Z","time spent":"715.147327ms","remote":"127.0.0.1:52114","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-07-09T15:38:10.198956Z","caller":"traceutil/trace.go:171","msg":"trace[1812479176] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"624.275312ms","start":"2026-07-09T15:38:09.574667Z","end":"2026-07-09T15:38:10.198942Z","steps":["trace[1812479176] 'process raft request' (duration: 623.965083ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:38:10.200355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:38:09.574648Z","time spent":"625.66664ms","remote":"127.0.0.1:52146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T15:38:10.199827Z","caller":"traceutil/trace.go:171","msg":"trace[1099025776] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"587.34752ms","start":"2026-07-09T15:38:09.612447Z","end":"2026-07-09T15:38:10.199794Z","steps":["trace[1099025776] 'process raft request' (duration: 586.339882ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:38:10.200539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:38:09.612429Z","time spent":"588.081241ms","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T15:38:10.199953Z","caller":"traceutil/trace.go:171","msg":"trace[1297594195] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"608.30234ms","start":"2026-07-09T15:38:09.591644Z","end":"2026-07-09T15:38:10.199947Z","steps":["trace[1297594195] 'process raft request' (duration: 607.046616ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:38:10.200657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:38:09.591613Z","time spent":"609.02283ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T15:38:10.200181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.440589ms","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-07-09T15:38:10.200763Z","caller":"traceutil/trace.go:171","msg":"trace[985250449] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:780; }","duration":"418.062067ms","start":"2026-07-09T15:38:09.782692Z","end":"2026-07-09T15:38:10.200754Z","steps":["trace[985250449] 'agreement among raft nodes before linearized reading' (duration: 417.46123ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:38:10.200789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:38:09.782673Z","time spent":"418.110157ms","remote":"127.0.0.1:52404","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-07-09T15:38:10.650399Z","caller":"traceutil/trace.go:171","msg":"trace[1471446269] transaction","detail":"{read_only:false; response_revision:821; number_of_response:1; }","duration":"102.740822ms","start":"2026-07-09T15:38:10.547636Z","end":"2026-07-09T15:38:10.650377Z","steps":["trace[1471446269] 'process raft request' (duration: 102.481354ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:38:12.187794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.338635ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T15:38:12.187862Z","caller":"traceutil/trace.go:171","msg":"trace[1203525474] linearizableReadLoop","detail":"{readStateIndex:898; appliedIndex:897; }","duration":"691.642886ms","start":"2026-07-09T15:38:11.496209Z","end":"2026-07-09T15:38:12.187852Z","steps":["trace[1203525474] 'read index received' (duration: 46.188198ms)","trace[1203525474] 'applied index is now lower than readState.Index' (duration: 645.454068ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:38:12.18812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.908953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T15:38:12.18814Z","caller":"traceutil/trace.go:171","msg":"trace[49338730] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:845; }","duration":"691.957745ms","start":"2026-07-09T15:38:11.496177Z","end":"2026-07-09T15:38:12.188135Z","steps":["trace[49338730] 'agreement among raft nodes before linearized reading' (duration: 691.923434ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:38:12.188157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:38:11.496155Z","time spent":"691.998456ms","remote":"127.0.0.1:52114","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-07-09T15:38:12.188169Z","caller":"traceutil/trace.go:171","msg":"trace[235404699] transaction","detail":"{read_only:false; response_revision:845; number_of_response:1; }","duration":"699.513673ms","start":"2026-07-09T15:38:11.488594Z","end":"2026-07-09T15:38:12.188108Z","steps":["trace[235404699] 'process raft request' (duration: 53.798598ms)","trace[235404699] 'compare' (duration: 645.237902ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:38:12.188375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:38:11.488575Z","time spent":"699.693549ms","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":208461,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T15:38:12.590054Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.047735058s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-09T15:38:12.762362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.982869ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T15:38:12.762509Z","caller":"traceutil/trace.go:171","msg":"trace[1341006180] linearizableReadLoop","detail":"{readStateIndex:899; appliedIndex:898; }","duration":"574.612298ms","start":"2026-07-09T15:38:12.187876Z","end":"2026-07-09T15:38:12.762488Z","steps":["trace[1341006180] 'read index received' (duration: 402.324051ms)","trace[1341006180] 'applied index is now lower than readState.Index' (duration: 172.286677ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:38:12.762693Z","caller":"traceutil/trace.go:171","msg":"trace[477613082] transaction","detail":"{read_only:false; response_revision:846; number_of_response:1; }","duration":"1.246221269s","start":"2026-07-09T15:38:11.516424Z","end":"2026-07-09T15:38:12.762646Z","steps":["trace[477613082] 'process raft request' (duration: 1.07385788s)","trace[477613082] 'compare' (duration: 171.819954ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:38:12.762714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.071341131s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T15:38:12.762803Z","caller":"traceutil/trace.go:171","msg":"trace[1919465189] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:846; }","duration":"1.071485125s","start":"2026-07-09T15:38:11.691296Z","end":"2026-07-09T15:38:12.762781Z","steps":["trace[1919465189] 'agreement among raft nodes before linearized reading' (duration: 1.07130614s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:38:12.762856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:38:11.691269Z","time spent":"1.071572328s","remote":"127.0.0.1:52266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-07-09T15:38:12.762838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.019822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T15:38:12.762883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:38:11.516405Z","time spent":"1.246363674s","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T15:38:12.76291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.006471437s","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-07-09T15:38:12.762925Z","caller":"traceutil/trace.go:171","msg":"trace[442559128] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:846; }","duration":"574.125554ms","start":"2026-07-09T15:38:12.188775Z","end":"2026-07-09T15:38:12.7629Z","steps":["trace[442559128] 'agreement among raft nodes before linearized reading' (duration: 573.994541ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:38:12.762974Z","caller":"traceutil/trace.go:171","msg":"trace[230261143] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:846; }","duration":"1.00658206s","start":"2026-07-09T15:38:11.756374Z","end":"2026-07-09T15:38:12.762956Z","steps":["trace[230261143] 'agreement among raft nodes before linearized reading' (duration: 1.006461856s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:38:12.762991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:38:12.188764Z","time spent":"574.213687ms","remote":"127.0.0.1:52122","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-07-09T15:38:12.763036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.86291ms","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-07-09T15:38:12.763069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:38:11.756351Z","time spent":"1.006702343s","remote":"127.0.0.1:52330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":2,"response size":31,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"info","ts":"2026-07-09T15:38:12.763119Z","caller":"traceutil/trace.go:171","msg":"trace[1126109384] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:846; }","duration":"525.999313ms","start":"2026-07-09T15:38:12.237099Z","end":"2026-07-09T15:38:12.763098Z","steps":["trace[1126109384] 'agreement among raft nodes before linearized reading' (duration: 525.759226ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:38:12.763208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.259789285s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backends.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:18056"} {"level":"warn","ts":"2026-07-09T15:38:12.76324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:38:12.237086Z","time spent":"526.109566ms","remote":"127.0.0.1:52404","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-07-09T15:38:12.763293Z","caller":"traceutil/trace.go:171","msg":"trace[75081966] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backends.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:846; }","duration":"1.259903529s","start":"2026-07-09T15:38:11.50337Z","end":"2026-07-09T15:38:12.763274Z","steps":["trace[75081966] 'agreement among raft nodes before linearized reading' (duration: 1.259606s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:38:12.763335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:38:11.503356Z","time spent":"1.25997049s","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":89,"response count":1,"response size":18080,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backends.gateway.envoyproxy.io\" "} {"level":"info","ts":"2026-07-09T15:38:12.952218Z","caller":"traceutil/trace.go:171","msg":"trace[2104386757] transaction","detail":"{read_only:false; response_revision:847; number_of_response:1; }","duration":"184.813493ms","start":"2026-07-09T15:38:12.767362Z","end":"2026-07-09T15:38:12.952176Z","steps":["trace[2104386757] 'process raft request' (duration: 130.602413ms)","trace[2104386757] 'compare' (duration: 54.013884ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:38:12.952396Z","caller":"traceutil/trace.go:171","msg":"trace[1193419472] linearizableReadLoop","detail":"{readStateIndex:901; appliedIndex:899; }","duration":"180.442082ms","start":"2026-07-09T15:38:12.771905Z","end":"2026-07-09T15:38:12.952347Z","steps":["trace[1193419472] 'read index received' (duration: 126.166551ms)","trace[1193419472] 'applied index is now lower than readState.Index' (duration: 54.274501ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:38:12.952522Z","caller":"traceutil/trace.go:171","msg":"trace[1462944288] transaction","detail":"{read_only:false; response_revision:849; number_of_response:1; }","duration":"128.820614ms","start":"2026-07-09T15:38:12.823689Z","end":"2026-07-09T15:38:12.952509Z","steps":["trace[1462944288] 'process raft request' (duration: 128.498545ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:38:12.95258Z","caller":"traceutil/trace.go:171","msg":"trace[1285770500] transaction","detail":"{read_only:false; response_revision:848; number_of_response:1; }","duration":"183.090526ms","start":"2026-07-09T15:38:12.769445Z","end":"2026-07-09T15:38:12.952536Z","steps":["trace[1285770500] 'process raft request' (duration: 182.658344ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:38:12.952838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.909435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T15:38:12.952901Z","caller":"traceutil/trace.go:171","msg":"trace[1318045531] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:849; }","duration":"180.983037ms","start":"2026-07-09T15:38:12.771899Z","end":"2026-07-09T15:38:12.952882Z","steps":["trace[1318045531] 'agreement among raft nodes before linearized reading' (duration: 180.872334ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:38:12.953092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.586469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtrafficpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:98713"} {"level":"info","ts":"2026-07-09T15:38:12.953151Z","caller":"traceutil/trace.go:171","msg":"trace[522556429] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtrafficpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:849; }","duration":"179.65891ms","start":"2026-07-09T15:38:12.773474Z","end":"2026-07-09T15:38:12.953133Z","steps":["trace[522556429] 'agreement among raft nodes before linearized reading' (duration: 179.465215ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:38:13.517317Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7212408457784371164,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T15:38:14.017678Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7212408457784371164,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T15:38:14.33204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.909807ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T15:38:14.332534Z","caller":"traceutil/trace.go:171","msg":"trace[912248968] linearizableReadLoop","detail":"{readStateIndex:903; appliedIndex:902; }","duration":"1.316092532s","start":"2026-07-09T15:38:13.016367Z","end":"2026-07-09T15:38:14.33246Z","steps":["trace[912248968] 'read index received' (duration: 1.178700671s)","trace[912248968] 'applied index is now lower than readState.Index' (duration: 137.389881ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:38:14.33263Z","caller":"traceutil/trace.go:171","msg":"trace[448169218] transaction","detail":"{read_only:false; response_revision:850; number_of_response:1; }","duration":"1.323564278s","start":"2026-07-09T15:38:13.009047Z","end":"2026-07-09T15:38:14.332611Z","steps":["trace[448169218] 'process raft request' (duration: 1.186007673s)","trace[448169218] 'compare' (duration: 136.565198ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:38:14.332718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:38:13.009037Z","time spent":"1.323639981s","remote":"127.0.0.1:52404","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-07-09T15:38:14.332905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.316503213s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoypatchpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:20866"} {"level":"info","ts":"2026-07-09T15:38:14.332995Z","caller":"traceutil/trace.go:171","msg":"trace[223724112] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/envoypatchpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:850; }","duration":"1.316605386s","start":"2026-07-09T15:38:13.016364Z","end":"2026-07-09T15:38:14.33297Z","steps":["trace[223724112] 'agreement among raft nodes before linearized reading' (duration: 1.31639939s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:38:14.33306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:38:13.016339Z","time spent":"1.316691009s","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":1,"response size":20890,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoypatchpolicies.gateway.envoyproxy.io\" "} {"level":"info","ts":"2026-07-09T15:38:14.334338Z","caller":"traceutil/trace.go:171","msg":"trace[559079438] transaction","detail":"{read_only:false; response_revision:851; number_of_response:1; }","duration":"186.54963ms","start":"2026-07-09T15:38:14.147775Z","end":"2026-07-09T15:38:14.334325Z","steps":["trace[559079438] 'process raft request' (duration: 186.456708ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:38:14.33499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.16677315s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-09T15:38:14.335058Z","caller":"traceutil/trace.go:171","msg":"trace[202203021] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:851; }","duration":"1.166883403s","start":"2026-07-09T15:38:13.168159Z","end":"2026-07-09T15:38:14.335042Z","steps":["trace[202203021] 'agreement among raft nodes before linearized reading' (duration: 1.166717779s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:38:14.335126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:38:13.168139Z","time spent":"1.166974286s","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-09T15:38:14.335364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"857.061322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T15:38:14.33542Z","caller":"traceutil/trace.go:171","msg":"trace[1606447708] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:851; }","duration":"857.152814ms","start":"2026-07-09T15:38:13.478253Z","end":"2026-07-09T15:38:14.335406Z","steps":["trace[1606447708] 'agreement among raft nodes before linearized reading' (duration: 857.075492ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:38:14.335457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:38:13.478234Z","time spent":"857.214325ms","remote":"127.0.0.1:52122","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-07-09T15:38:16.202944Z","caller":"traceutil/trace.go:171","msg":"trace[1233929690] transaction","detail":"{read_only:false; response_revision:858; number_of_response:1; }","duration":"213.877947ms","start":"2026-07-09T15:38:15.989044Z","end":"2026-07-09T15:38:16.202922Z","steps":["trace[1233929690] 'process raft request' (duration: 213.25013ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:38:17.094294Z","caller":"traceutil/trace.go:171","msg":"trace[96886707] linearizableReadLoop","detail":"{readStateIndex:917; appliedIndex:916; }","duration":"105.89436ms","start":"2026-07-09T15:38:16.988377Z","end":"2026-07-09T15:38:17.094272Z","steps":["trace[96886707] 'read index received' (duration: 105.663753ms)","trace[96886707] 'applied index is now lower than readState.Index' (duration: 229.497µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:38:17.09436Z","caller":"traceutil/trace.go:171","msg":"trace[1031008461] transaction","detail":"{read_only:false; response_revision:863; number_of_response:1; }","duration":"108.07219ms","start":"2026-07-09T15:38:16.986256Z","end":"2026-07-09T15:38:17.094328Z","steps":["trace[1031008461] 'process raft request' (duration: 107.873005ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:38:17.094505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.112396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-09T15:38:17.094955Z","caller":"traceutil/trace.go:171","msg":"trace[1565382310] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:863; }","duration":"106.233589ms","start":"2026-07-09T15:38:16.988312Z","end":"2026-07-09T15:38:17.094546Z","steps":["trace[1565382310] 'agreement among raft nodes before linearized reading' (duration: 106.056094ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:38:19.603136Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7212408457784371229,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T15:38:19.786541Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.008196474s","expected-duration":"1s"} {"level":"info","ts":"2026-07-09T15:38:19.787372Z","caller":"traceutil/trace.go:171","msg":"trace[774070742] linearizableReadLoop","detail":"{readStateIndex:931; appliedIndex:930; }","duration":"684.467807ms","start":"2026-07-09T15:38:19.102888Z","end":"2026-07-09T15:38:19.787356Z","steps":["trace[774070742] 'read index received' (duration: 683.771578ms)","trace[774070742] 'applied index is now lower than readState.Index' (duration: 695.529µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:38:19.787437Z","caller":"traceutil/trace.go:171","msg":"trace[513803008] transaction","detail":"{read_only:false; response_revision:877; number_of_response:1; }","duration":"1.010275092s","start":"2026-07-09T15:38:18.777156Z","end":"2026-07-09T15:38:19.787431Z","steps":["trace[513803008] 'process raft request' (duration: 1.009769928s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:38:19.787517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:38:18.777136Z","time spent":"1.010322363s","remote":"127.0.0.1:52222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036265,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T15:38:19.787606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.818852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T15:38:19.787659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.255518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-09T15:38:19.787751Z","caller":"traceutil/trace.go:171","msg":"trace[1134652979] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:877; }","duration":"677.373642ms","start":"2026-07-09T15:38:19.110355Z","end":"2026-07-09T15:38:19.787729Z","steps":["trace[1134652979] 'agreement among raft nodes before linearized reading' (duration: 677.127275ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:38:19.787804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:38:19.110349Z","time spent":"677.445133ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-09T15:38:19.787838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.94582ms","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-07-09T15:38:19.787875Z","caller":"traceutil/trace.go:171","msg":"trace[1480392664] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:877; }","duration":"685.006642ms","start":"2026-07-09T15:38:19.102859Z","end":"2026-07-09T15:38:19.787866Z","steps":["trace[1480392664] 'agreement among raft nodes before linearized reading' (duration: 684.865588ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:38:19.787884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.477316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.238\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-09T15:38:19.787915Z","caller":"traceutil/trace.go:171","msg":"trace[412752677] range","detail":"{range_begin:/registry/masterleases/199.19.213.238; range_end:; response_count:1; response_revision:877; }","duration":"415.546827ms","start":"2026-07-09T15:38:19.37236Z","end":"2026-07-09T15:38:19.787907Z","steps":["trace[412752677] 'agreement among raft nodes before linearized reading' (duration: 415.441695ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:38:19.787917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:38:19.102827Z","time spent":"685.083035ms","remote":"127.0.0.1:52404","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-07-09T15:38:19.787944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:38:19.372337Z","time spent":"415.602098ms","remote":"127.0.0.1:52146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.238\" "} {"level":"info","ts":"2026-07-09T15:38:19.787696Z","caller":"traceutil/trace.go:171","msg":"trace[1412670490] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:877; }","duration":"309.972346ms","start":"2026-07-09T15:38:19.477704Z","end":"2026-07-09T15:38:19.787676Z","steps":["trace[1412670490] 'agreement among raft nodes before linearized reading' (duration: 309.795381ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:38:19.788047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:38:19.477684Z","time spent":"310.355897ms","remote":"127.0.0.1:52122","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-07-09T15:38:19.99848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.367542ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T15:38:19.99866Z","caller":"traceutil/trace.go:171","msg":"trace[606770778] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"206.217276ms","start":"2026-07-09T15:38:19.792425Z","end":"2026-07-09T15:38:19.998643Z","steps":["trace[606770778] 'process raft request' (duration: 206.151864ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:38:19.998709Z","caller":"traceutil/trace.go:171","msg":"trace[805359147] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"206.325559ms","start":"2026-07-09T15:38:19.792346Z","end":"2026-07-09T15:38:19.998671Z","steps":["trace[805359147] 'process raft request' (duration: 64.68761ms)","trace[805359147] 'compare' (duration: 141.212437ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:38:20.048811Z","caller":"traceutil/trace.go:171","msg":"trace[719924238] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"208.724895ms","start":"2026-07-09T15:38:19.840063Z","end":"2026-07-09T15:38:20.048788Z","steps":["trace[719924238] 'process raft request' (duration: 208.677944ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:38:20.048908Z","caller":"traceutil/trace.go:171","msg":"trace[1968064923] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"234.808637ms","start":"2026-07-09T15:38:19.814072Z","end":"2026-07-09T15:38:20.048881Z","steps":["trace[1968064923] 'process raft request' (duration: 234.55077ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:38:20.98259Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7212408457784371245,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T15:38:21.482784Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7212408457784371245,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T15:38:21.935951Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.512424293s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-09T15:38:22.041743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.672230494s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-09T15:38:22.041826Z","caller":"traceutil/trace.go:171","msg":"trace[1382224083] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:881; }","duration":"1.672407699s","start":"2026-07-09T15:38:20.369398Z","end":"2026-07-09T15:38:22.041806Z","steps":["trace[1382224083] 'range keys from in-memory index tree' (duration: 1.671970607s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:38:22.041866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:38:20.369375Z","time spent":"1.672482001s","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-09T15:38:22.042135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.667429261s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-09T15:38:22.042235Z","caller":"traceutil/trace.go:171","msg":"trace[1904104628] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:881; }","duration":"1.667571805s","start":"2026-07-09T15:38:20.374637Z","end":"2026-07-09T15:38:22.042209Z","steps":["trace[1904104628] 'range keys from in-memory index tree' (duration: 1.667060981s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:38:22.042289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:38:20.374619Z","time spent":"1.667656168s","remote":"127.0.0.1:52404","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-07-09T15:38:22.042355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.890579ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T15:38:22.042829Z","caller":"traceutil/trace.go:171","msg":"trace[67138819] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"1.619379912s","start":"2026-07-09T15:38:20.423426Z","end":"2026-07-09T15:38:22.042806Z","steps":["trace[67138819] 'process raft request' (duration: 1.512945908s)","trace[67138819] 'compare' (duration: 105.288603ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:38:22.042964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:38:20.423403Z","time spent":"1.619482824s","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T15:38:22.043215Z","caller":"traceutil/trace.go:171","msg":"trace[1072404882] linearizableReadLoop","detail":"{readStateIndex:938; appliedIndex:936; }","duration":"1.561054408s","start":"2026-07-09T15:38:20.482143Z","end":"2026-07-09T15:38:22.043198Z","steps":["trace[1072404882] 'read index received' (duration: 1.454469509s)","trace[1072404882] 'applied index is now lower than readState.Index' (duration: 106.583929ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:38:22.043285Z","caller":"traceutil/trace.go:171","msg":"trace[2034698709] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"1.597953829s","start":"2026-07-09T15:38:20.445306Z","end":"2026-07-09T15:38:22.04326Z","steps":["trace[2034698709] 'process raft request' (duration: 1.597346072s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:38:22.043325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.561175651s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T15:38:22.043404Z","caller":"traceutil/trace.go:171","msg":"trace[425531049] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:883; }","duration":"1.561600933s","start":"2026-07-09T15:38:20.481787Z","end":"2026-07-09T15:38:22.043388Z","steps":["trace[425531049] 'agreement among raft nodes before linearized reading' (duration: 1.56149312s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:38:22.043405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:38:20.445271Z","time spent":"1.598065092s","remote":"127.0.0.1:52222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036293,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T15:38:22.043456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:38:20.481693Z","time spent":"1.561751357s","remote":"127.0.0.1:52114","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-07-09T15:38:24.052412Z","caller":"traceutil/trace.go:171","msg":"trace[1117084625] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"250.342426ms","start":"2026-07-09T15:38:23.802043Z","end":"2026-07-09T15:38:24.052386Z","steps":["trace[1117084625] 'process raft request' (duration: 250.173251ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:38:24.05585Z","caller":"traceutil/trace.go:171","msg":"trace[1645393833] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"203.773108ms","start":"2026-07-09T15:38:23.852055Z","end":"2026-07-09T15:38:24.055828Z","steps":["trace[1645393833] 'process raft request' (duration: 203.46394ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:38:25.768035Z","caller":"traceutil/trace.go:171","msg":"trace[1414817309] linearizableReadLoop","detail":"{readStateIndex:1005; appliedIndex:1004; }","duration":"118.397296ms","start":"2026-07-09T15:38:25.64962Z","end":"2026-07-09T15:38:25.768017Z","steps":["trace[1414817309] 'read index received' (duration: 118.263302ms)","trace[1414817309] 'applied index is now lower than readState.Index' (duration: 133.184µs)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:38:25.768205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.562451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-07-09T15:38:25.768247Z","caller":"traceutil/trace.go:171","msg":"trace[1592267670] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:949; }","duration":"118.629922ms","start":"2026-07-09T15:38:25.649604Z","end":"2026-07-09T15:38:25.768234Z","steps":["trace[1592267670] 'agreement among raft nodes before linearized reading' (duration: 118.502149ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:38:25.768288Z","caller":"traceutil/trace.go:171","msg":"trace[574159263] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"127.95133ms","start":"2026-07-09T15:38:25.640201Z","end":"2026-07-09T15:38:25.768152Z","steps":["trace[574159263] 'process raft request' (duration: 127.681642ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:38:25.780934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.423193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T15:38:25.780991Z","caller":"traceutil/trace.go:171","msg":"trace[347537645] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:950; }","duration":"111.500165ms","start":"2026-07-09T15:38:25.669479Z","end":"2026-07-09T15:38:25.780979Z","steps":["trace[347537645] 'agreement among raft nodes before linearized reading' (duration: 111.420323ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:38:25.781194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.298945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T15:38:25.781253Z","caller":"traceutil/trace.go:171","msg":"trace[154762441] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:950; }","duration":"130.401088ms","start":"2026-07-09T15:38:25.650838Z","end":"2026-07-09T15:38:25.781239Z","steps":["trace[154762441] 'agreement among raft nodes before linearized reading' (duration: 130.309896ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:38:25.78126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.03029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T15:38:25.781299Z","caller":"traceutil/trace.go:171","msg":"trace[783633998] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:950; }","duration":"112.094722ms","start":"2026-07-09T15:38:25.669194Z","end":"2026-07-09T15:38:25.781289Z","steps":["trace[783633998] 'agreement among raft nodes before linearized reading' (duration: 112.03741ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:38:25.781446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.928523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T15:38:25.781504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.329988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T15:38:25.781528Z","caller":"traceutil/trace.go:171","msg":"trace[1764042877] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:950; }","duration":"131.063747ms","start":"2026-07-09T15:38:25.650446Z","end":"2026-07-09T15:38:25.78151Z","steps":["trace[1764042877] 'agreement among raft nodes before linearized reading' (duration: 130.952814ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:38:25.781556Z","caller":"traceutil/trace.go:171","msg":"trace[1758294665] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:950; }","duration":"112.40705ms","start":"2026-07-09T15:38:25.669138Z","end":"2026-07-09T15:38:25.781545Z","steps":["trace[1758294665] 'agreement among raft nodes before linearized reading' (duration: 112.313597ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:38:25.781741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.365825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T15:38:25.781772Z","caller":"traceutil/trace.go:171","msg":"trace[218023494] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:950; }","duration":"131.409546ms","start":"2026-07-09T15:38:25.650356Z","end":"2026-07-09T15:38:25.781765Z","steps":["trace[218023494] 'agreement among raft nodes before linearized reading' (duration: 131.368935ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:38:39.675301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.047635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-09T15:38:39.675385Z","caller":"traceutil/trace.go:171","msg":"trace[593598443] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1053; }","duration":"124.176518ms","start":"2026-07-09T15:38:39.551191Z","end":"2026-07-09T15:38:39.675368Z","steps":["trace[593598443] 'range keys from in-memory index tree' (duration: 123.859709ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:38:39.675389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.32362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.238\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-07-09T15:38:39.675397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.169631ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T15:38:39.675548Z","caller":"traceutil/trace.go:171","msg":"trace[1613906812] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1053; }","duration":"628.316325ms","start":"2026-07-09T15:38:39.047207Z","end":"2026-07-09T15:38:39.675523Z","steps":["trace[1613906812] 'range keys from in-memory index tree' (duration: 628.14894ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:38:39.67548Z","caller":"traceutil/trace.go:171","msg":"trace[1541168787] range","detail":"{range_begin:/registry/masterleases/199.19.213.238; range_end:; response_count:1; response_revision:1053; }","duration":"301.460834ms","start":"2026-07-09T15:38:39.373998Z","end":"2026-07-09T15:38:39.675459Z","steps":["trace[1541168787] 'range keys from in-memory index tree' (duration: 301.143455ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:38:39.675666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:38:39.37398Z","time spent":"301.645859ms","remote":"127.0.0.1:52146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.238\" "} {"level":"info","ts":"2026-07-09T15:38:39.858829Z","caller":"traceutil/trace.go:171","msg":"trace[1283752784] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"179.459195ms","start":"2026-07-09T15:38:39.679351Z","end":"2026-07-09T15:38:39.85881Z","steps":["trace[1283752784] 'process raft request' (duration: 179.26743ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:38:44.10452Z","caller":"traceutil/trace.go:171","msg":"trace[1625026236] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"104.785675ms","start":"2026-07-09T15:38:43.9997Z","end":"2026-07-09T15:38:44.104485Z","steps":["trace[1625026236] 'process raft request' (duration: 94.373089ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:38:44.231514Z","caller":"traceutil/trace.go:171","msg":"trace[561316337] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"111.202512ms","start":"2026-07-09T15:38:44.120274Z","end":"2026-07-09T15:38:44.231476Z","steps":["trace[561316337] 'process raft request' (duration: 96.113418ms)","trace[561316337] 'compare' (duration: 14.880478ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:38:44.40975Z","caller":"traceutil/trace.go:171","msg":"trace[1662827180] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"102.952405ms","start":"2026-07-09T15:38:44.306775Z","end":"2026-07-09T15:38:44.409728Z","steps":["trace[1662827180] 'process raft request' (duration: 102.75081ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:38:44.446135Z","caller":"traceutil/trace.go:171","msg":"trace[157124248] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"139.18543ms","start":"2026-07-09T15:38:44.306926Z","end":"2026-07-09T15:38:44.446111Z","steps":["trace[157124248] 'process raft request' (duration: 139.068847ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:38:44.772754Z","caller":"traceutil/trace.go:171","msg":"trace[752604235] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"126.758799ms","start":"2026-07-09T15:38:44.645968Z","end":"2026-07-09T15:38:44.772726Z","steps":["trace[752604235] 'process raft request' (duration: 45.404476ms)","trace[752604235] 'compare' (duration: 80.927712ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:38:44.773281Z","caller":"traceutil/trace.go:171","msg":"trace[118601973] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"126.151282ms","start":"2026-07-09T15:38:44.647114Z","end":"2026-07-09T15:38:44.773265Z","steps":["trace[118601973] 'process raft request' (duration: 126.103961ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:38:44.773584Z","caller":"traceutil/trace.go:171","msg":"trace[429931798] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"126.680427ms","start":"2026-07-09T15:38:44.646887Z","end":"2026-07-09T15:38:44.773567Z","steps":["trace[429931798] 'process raft request' (duration: 126.168233ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:38:58.699117Z","caller":"traceutil/trace.go:171","msg":"trace[912910229] linearizableReadLoop","detail":"{readStateIndex:1279; appliedIndex:1278; }","duration":"350.700775ms","start":"2026-07-09T15:38:58.348392Z","end":"2026-07-09T15:38:58.699093Z","steps":["trace[912910229] 'read index received' (duration: 350.49494ms)","trace[912910229] 'applied index is now lower than readState.Index' (duration: 204.935µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:38:58.699271Z","caller":"traceutil/trace.go:171","msg":"trace[1939984005] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"463.004638ms","start":"2026-07-09T15:38:58.236244Z","end":"2026-07-09T15:38:58.699249Z","steps":["trace[1939984005] 'process raft request' (duration: 462.72095ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:38:58.699354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.941372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-09T15:38:58.699442Z","caller":"traceutil/trace.go:171","msg":"trace[84078244] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1215; }","duration":"351.079495ms","start":"2026-07-09T15:38:58.348342Z","end":"2026-07-09T15:38:58.699421Z","steps":["trace[84078244] 'agreement among raft nodes before linearized reading' (duration: 350.89636ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:38:58.699447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:38:58.236223Z","time spent":"463.139302ms","remote":"127.0.0.1:52404","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-07-09T15:38:58.699496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:38:58.34832Z","time spent":"351.163118ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-09T15:38:58.699541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.513306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-09T15:38:58.699581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.494265ms","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-07-09T15:38:58.699648Z","caller":"traceutil/trace.go:171","msg":"trace[1140469783] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1215; }","duration":"348.656399ms","start":"2026-07-09T15:38:58.350965Z","end":"2026-07-09T15:38:58.699621Z","steps":["trace[1140469783] 'agreement among raft nodes before linearized reading' (duration: 348.387381ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:38:58.699659Z","caller":"traceutil/trace.go:171","msg":"trace[2009111818] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1215; }","duration":"348.607158ms","start":"2026-07-09T15:38:58.351033Z","end":"2026-07-09T15:38:58.69964Z","steps":["trace[2009111818] 'agreement among raft nodes before linearized reading' (duration: 348.354371ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:38:58.699708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:38:58.351021Z","time spent":"348.67818ms","remote":"127.0.0.1:52404","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-07-09T15:38:58.69971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:38:58.350953Z","time spent":"348.743042ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-09T15:38:58.699541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.082282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-09T15:38:58.700089Z","caller":"traceutil/trace.go:171","msg":"trace[1981256180] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1215; }","duration":"294.672788ms","start":"2026-07-09T15:38:58.405397Z","end":"2026-07-09T15:38:58.700069Z","steps":["trace[1981256180] 'agreement among raft nodes before linearized reading' (duration: 293.99648ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:38:58.821174Z","caller":"traceutil/trace.go:171","msg":"trace[1944813760] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"116.671802ms","start":"2026-07-09T15:38:58.704469Z","end":"2026-07-09T15:38:58.821141Z","steps":["trace[1944813760] 'process raft request' (duration: 111.903671ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:38:58.82152Z","caller":"traceutil/trace.go:171","msg":"trace[1674547720] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"116.922739ms","start":"2026-07-09T15:38:58.704583Z","end":"2026-07-09T15:38:58.821505Z","steps":["trace[1674547720] 'process raft request' (duration: 116.504748ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:38:58.821576Z","caller":"traceutil/trace.go:171","msg":"trace[482835703] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"116.807846ms","start":"2026-07-09T15:38:58.704734Z","end":"2026-07-09T15:38:58.821542Z","steps":["trace[482835703] 'process raft request' (duration: 116.620391ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:38:58.82158Z","caller":"traceutil/trace.go:171","msg":"trace[1788414243] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"116.800986ms","start":"2026-07-09T15:38:58.704745Z","end":"2026-07-09T15:38:58.821546Z","steps":["trace[1788414243] 'process raft request' (duration: 116.735744ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:39:00.153503Z","caller":"traceutil/trace.go:171","msg":"trace[819091456] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"199.395003ms","start":"2026-07-09T15:38:59.95408Z","end":"2026-07-09T15:39:00.153475Z","steps":["trace[819091456] 'process raft request' (duration: 199.000872ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:39:04.979636Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7212408457784371983,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T15:39:06.479011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00018131s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-09T15:39:06.479097Z","caller":"traceutil/trace.go:171","msg":"trace[713480434] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000316873s","start":"2026-07-09T15:39:04.478763Z","end":"2026-07-09T15:39:06.47908Z","steps":["trace[713480434] 'agreement among raft nodes before linearized reading' (duration: 2.000217271s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:39:06.479166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:04.478741Z","time spent":"2.000394476s","remote":"127.0.0.1:52114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/09 15:39:06 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-09T15:39:07.163214Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.064583627s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-09T15:39:07.165246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.066700536s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-09T15:39:07.167541Z","caller":"traceutil/trace.go:171","msg":"trace[789727905] linearizableReadLoop","detail":"{readStateIndex:1301; appliedIndex:1299; }","duration":"2.688676966s","start":"2026-07-09T15:39:04.47883Z","end":"2026-07-09T15:39:07.167507Z","steps":["trace[789727905] 'read index received' (duration: 619.856063ms)","trace[789727905] 'applied index is now lower than readState.Index' (duration: 2.068819373s)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:39:07.167554Z","caller":"traceutil/trace.go:171","msg":"trace[163048057] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"2.443020694s","start":"2026-07-09T15:39:04.72451Z","end":"2026-07-09T15:39:07.167531Z","steps":["trace[163048057] 'process raft request' (duration: 2.440836084s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:39:07.167705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:04.724487Z","time spent":"2.443152088s","remote":"127.0.0.1:52404","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-07-09T15:39:07.170872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.542026071s","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-07-09T15:39:07.17092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"992.536112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-09T15:39:07.170904Z","caller":"traceutil/trace.go:171","msg":"trace[1643596119] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"1.728535184s","start":"2026-07-09T15:39:05.442339Z","end":"2026-07-09T15:39:07.170874Z","steps":["trace[1643596119] 'process raft request' (duration: 1.725161201s)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:39:07.170954Z","caller":"traceutil/trace.go:171","msg":"trace[561440689] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1236; }","duration":"992.603064ms","start":"2026-07-09T15:39:06.178343Z","end":"2026-07-09T15:39:07.170946Z","steps":["trace[561440689] 'agreement among raft nodes before linearized reading' (duration: 992.497541ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:39:07.170979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:06.178324Z","time spent":"992.648896ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-09T15:39:07.171056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.314465686s","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-07-09T15:39:07.171055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:05.442312Z","time spent":"1.728660587s","remote":"127.0.0.1:52404","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-07-09T15:39:07.171091Z","caller":"traceutil/trace.go:171","msg":"trace[514312949] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1236; }","duration":"2.314518898s","start":"2026-07-09T15:39:04.856561Z","end":"2026-07-09T15:39:07.17108Z","steps":["trace[514312949] 'agreement among raft nodes before linearized reading' (duration: 2.314382284s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:39:07.171126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:04.85655Z","time spent":"2.314568659s","remote":"127.0.0.1:52404","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-07-09T15:39:07.171176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.937491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T15:39:07.171201Z","caller":"traceutil/trace.go:171","msg":"trace[1033021820] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1236; }","duration":"691.988513ms","start":"2026-07-09T15:39:06.479205Z","end":"2026-07-09T15:39:07.171193Z","steps":["trace[1033021820] 'agreement among raft nodes before linearized reading' (duration: 691.934031ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:39:07.17122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:06.47919Z","time spent":"692.026634ms","remote":"127.0.0.1:52122","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-07-09T15:39:07.17128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.322833806s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-09T15:39:07.170923Z","caller":"traceutil/trace.go:171","msg":"trace[1461919037] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1236; }","duration":"2.542138605s","start":"2026-07-09T15:39:04.628772Z","end":"2026-07-09T15:39:07.17091Z","steps":["trace[1461919037] 'agreement among raft nodes before linearized reading' (duration: 2.542029582s)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:39:07.171307Z","caller":"traceutil/trace.go:171","msg":"trace[1719614270] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1236; }","duration":"2.322890768s","start":"2026-07-09T15:39:04.848407Z","end":"2026-07-09T15:39:07.171297Z","steps":["trace[1719614270] 'agreement among raft nodes before linearized reading' (duration: 2.322816916s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:39:07.171327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:04.628759Z","time spent":"2.542558796s","remote":"127.0.0.1:52470","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-07-09T15:39:07.171344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:04.848395Z","time spent":"2.322940529s","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-09T15:39:07.171361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.323435562s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-09T15:39:07.171413Z","caller":"traceutil/trace.go:171","msg":"trace[1293063695] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1236; }","duration":"2.323524324s","start":"2026-07-09T15:39:04.847873Z","end":"2026-07-09T15:39:07.171397Z","steps":["trace[1293063695] 'agreement among raft nodes before linearized reading' (duration: 2.32336664s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:39:07.171499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:04.847854Z","time spent":"2.323633908s","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-09T15:39:07.171549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.326121656s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-09T15:39:07.171607Z","caller":"traceutil/trace.go:171","msg":"trace[679927447] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1236; }","duration":"2.326220329s","start":"2026-07-09T15:39:04.84537Z","end":"2026-07-09T15:39:07.171591Z","steps":["trace[679927447] 'agreement among raft nodes before linearized reading' (duration: 2.326061324s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:39:07.171646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:04.845353Z","time spent":"2.32628521s","remote":"127.0.0.1:52404","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-07-09T15:39:09.401399Z","caller":"traceutil/trace.go:171","msg":"trace[1277382190] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"129.72336ms","start":"2026-07-09T15:39:09.271646Z","end":"2026-07-09T15:39:09.40137Z","steps":["trace[1277382190] 'process raft request' (duration: 41.601251ms)","trace[1277382190] 'compare' (duration: 87.947284ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:39:09.401854Z","caller":"traceutil/trace.go:171","msg":"trace[1621120867] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"130.112801ms","start":"2026-07-09T15:39:09.271712Z","end":"2026-07-09T15:39:09.401825Z","steps":["trace[1621120867] 'process raft request' (duration: 129.619398ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:39:09.402073Z","caller":"traceutil/trace.go:171","msg":"trace[726107358] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"130.264075ms","start":"2026-07-09T15:39:09.271796Z","end":"2026-07-09T15:39:09.40206Z","steps":["trace[726107358] 'process raft request' (duration: 129.857154ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:39:09.402182Z","caller":"traceutil/trace.go:171","msg":"trace[1217705186] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"130.238285ms","start":"2026-07-09T15:39:09.271896Z","end":"2026-07-09T15:39:09.402135Z","steps":["trace[1217705186] 'process raft request' (duration: 129.899446ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:39:09.402193Z","caller":"traceutil/trace.go:171","msg":"trace[1743277287] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"128.64219ms","start":"2026-07-09T15:39:09.27354Z","end":"2026-07-09T15:39:09.402183Z","steps":["trace[1743277287] 'process raft request' (duration: 128.300021ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:39:09.670945Z","caller":"traceutil/trace.go:171","msg":"trace[7981266] linearizableReadLoop","detail":"{readStateIndex:1317; appliedIndex:1316; }","duration":"182.1453ms","start":"2026-07-09T15:39:09.488757Z","end":"2026-07-09T15:39:09.670902Z","steps":["trace[7981266] 'read index received' (duration: 179.167758ms)","trace[7981266] 'applied index is now lower than readState.Index' (duration: 2.976252ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:39:09.670941Z","caller":"traceutil/trace.go:171","msg":"trace[319816628] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"182.317404ms","start":"2026-07-09T15:39:09.488589Z","end":"2026-07-09T15:39:09.670906Z","steps":["trace[319816628] 'process raft request' (duration: 179.411474ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:39:09.671105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.340525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T15:39:09.671131Z","caller":"traceutil/trace.go:171","msg":"trace[1016166341] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1249; }","duration":"182.411557ms","start":"2026-07-09T15:39:09.48871Z","end":"2026-07-09T15:39:09.671122Z","steps":["trace[1016166341] 'agreement among raft nodes before linearized reading' (duration: 182.328804ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:39:09.671342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.60331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T15:39:09.6714Z","caller":"traceutil/trace.go:171","msg":"trace[519629289] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1249; }","duration":"179.725493ms","start":"2026-07-09T15:39:09.491661Z","end":"2026-07-09T15:39:09.671386Z","steps":["trace[519629289] 'agreement among raft nodes before linearized reading' (duration: 179.594789ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:39:17.979095Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7212408457784372085,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T15:39:18.570664Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7212408457784372085,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T15:39:18.601882Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.379687398s","expected-duration":"1s"} {"level":"info","ts":"2026-07-09T15:39:18.602259Z","caller":"traceutil/trace.go:171","msg":"trace[156248014] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"1.380519421s","start":"2026-07-09T15:39:17.221715Z","end":"2026-07-09T15:39:18.602234Z","steps":["trace[156248014] 'process raft request' (duration: 1.380347636s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:39:18.602399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:17.221677Z","time spent":"1.380649974s","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T15:39:18.643836Z","caller":"traceutil/trace.go:171","msg":"trace[1380103244] linearizableReadLoop","detail":"{readStateIndex:1344; appliedIndex:1342; }","duration":"1.164770699s","start":"2026-07-09T15:39:17.479044Z","end":"2026-07-09T15:39:18.643815Z","steps":["trace[1380103244] 'read index received' (duration: 1.123063174s)","trace[1380103244] 'applied index is now lower than readState.Index' (duration: 41.706845ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:39:18.643924Z","caller":"traceutil/trace.go:171","msg":"trace[877160996] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"1.302825359s","start":"2026-07-09T15:39:17.341086Z","end":"2026-07-09T15:39:18.643912Z","steps":["trace[877160996] 'process raft request' (duration: 1.302630284s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:39:18.644005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:17.341058Z","time spent":"1.30288963s","remote":"127.0.0.1:52404","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-07-09T15:39:18.644112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.165082117s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T15:39:18.644132Z","caller":"traceutil/trace.go:171","msg":"trace[1119039313] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1274; }","duration":"1.16515498s","start":"2026-07-09T15:39:17.478971Z","end":"2026-07-09T15:39:18.644126Z","steps":["trace[1119039313] 'agreement among raft nodes before linearized reading' (duration: 1.165121389s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:39:18.644379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"934.702145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-09T15:39:18.644464Z","caller":"traceutil/trace.go:171","msg":"trace[223349081] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1274; }","duration":"934.848249ms","start":"2026-07-09T15:39:17.709597Z","end":"2026-07-09T15:39:18.644445Z","steps":["trace[223349081] 'agreement among raft nodes before linearized reading' (duration: 934.54279ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:39:18.644479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"861.498725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-09T15:39:18.644489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"928.528926ms","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-07-09T15:39:18.644519Z","caller":"traceutil/trace.go:171","msg":"trace[2106358329] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1274; }","duration":"861.555177ms","start":"2026-07-09T15:39:17.78295Z","end":"2026-07-09T15:39:18.644505Z","steps":["trace[2106358329] 'agreement among raft nodes before linearized reading' (duration: 861.489906ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:39:18.644527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:17.709576Z","time spent":"934.924401ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-09T15:39:18.644552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:17.782941Z","time spent":"861.603368ms","remote":"127.0.0.1:52482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":16,"response size":31,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"info","ts":"2026-07-09T15:39:18.64455Z","caller":"traceutil/trace.go:171","msg":"trace[1392690803] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1274; }","duration":"928.637238ms","start":"2026-07-09T15:39:17.715891Z","end":"2026-07-09T15:39:18.644529Z","steps":["trace[1392690803] 'agreement among raft nodes before linearized reading' (duration: 928.456713ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:39:18.644633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:17.715875Z","time spent":"928.746102ms","remote":"127.0.0.1:52404","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-07-09T15:39:18.644825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"929.070311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-09T15:39:18.644843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"928.402912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-09T15:39:18.644866Z","caller":"traceutil/trace.go:171","msg":"trace[61709494] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1274; }","duration":"928.455513ms","start":"2026-07-09T15:39:17.716404Z","end":"2026-07-09T15:39:18.644859Z","steps":["trace[61709494] 'agreement among raft nodes before linearized reading' (duration: 928.392491ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:39:18.64415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:17.478941Z","time spent":"1.165205071s","remote":"127.0.0.1:52114","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-07-09T15:39:18.644888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:17.716388Z","time spent":"928.495035ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-09T15:39:18.64487Z","caller":"traceutil/trace.go:171","msg":"trace[328465904] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1274; }","duration":"929.127652ms","start":"2026-07-09T15:39:17.715729Z","end":"2026-07-09T15:39:18.644856Z","steps":["trace[328465904] 'agreement among raft nodes before linearized reading' (duration: 929.023889ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:39:18.645026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:17.715721Z","time spent":"929.295116ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-09T15:39:18.644397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"928.244288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-09T15:39:18.645082Z","caller":"traceutil/trace.go:171","msg":"trace[170149457] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1274; }","duration":"928.950197ms","start":"2026-07-09T15:39:17.716123Z","end":"2026-07-09T15:39:18.645073Z","steps":["trace[170149457] 'agreement among raft nodes before linearized reading' (duration: 928.101284ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:39:18.6451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:17.716081Z","time spent":"929.013429ms","remote":"127.0.0.1:52404","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-07-09T15:39:19.154116Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7212408457784372090,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T15:39:19.608619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.233414ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T15:39:19.608715Z","caller":"traceutil/trace.go:171","msg":"trace[1128180857] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1274; }","duration":"561.346047ms","start":"2026-07-09T15:39:19.047353Z","end":"2026-07-09T15:39:19.608699Z","steps":["trace[1128180857] 'range keys from in-memory index tree' (duration: 561.215873ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:39:19.609248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.529451ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T15:39:19.609345Z","caller":"traceutil/trace.go:171","msg":"trace[639188170] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"961.19877ms","start":"2026-07-09T15:39:18.648125Z","end":"2026-07-09T15:39:19.609324Z","steps":["trace[639188170] 'process raft request' (duration: 626.468113ms)","trace[639188170] 'compare' (duration: 334.384727ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:39:19.609435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:18.648099Z","time spent":"961.285513ms","remote":"127.0.0.1:52404","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-07-09T15:39:20.494588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.595003ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T15:39:20.494889Z","caller":"traceutil/trace.go:171","msg":"trace[475815151] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"1.845308516s","start":"2026-07-09T15:39:18.649559Z","end":"2026-07-09T15:39:20.494867Z","steps":["trace[475815151] 'process raft request' (duration: 1.845219004s)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:39:20.494891Z","caller":"traceutil/trace.go:171","msg":"trace[1049284750] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"1.84653431s","start":"2026-07-09T15:39:18.648309Z","end":"2026-07-09T15:39:20.494843Z","steps":["trace[1049284750] 'process raft request' (duration: 1.440573798s)","trace[1049284750] 'compare' (duration: 405.472688ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:39:20.49491Z","caller":"traceutil/trace.go:171","msg":"trace[1864090528] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"1.845385179s","start":"2026-07-09T15:39:18.649498Z","end":"2026-07-09T15:39:20.494883Z","steps":["trace[1864090528] 'process raft request' (duration: 1.845197594s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:39:20.494979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:18.6489Z","time spent":"1.846032456s","remote":"127.0.0.1:52404","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-07-09T15:39:20.495018Z","caller":"traceutil/trace.go:171","msg":"trace[1001788441] linearizableReadLoop","detail":"{readStateIndex:1349; appliedIndex:1344; }","duration":"1.841109542s","start":"2026-07-09T15:39:18.653895Z","end":"2026-07-09T15:39:20.495004Z","steps":["trace[1001788441] 'read index received' (duration: 620.814559ms)","trace[1001788441] 'applied index is now lower than readState.Index' (duration: 1.220294223s)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:39:20.495049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:18.648295Z","time spent":"1.846670034s","remote":"127.0.0.1:52404","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-07-09T15:39:20.49748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.121019608s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.238\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-09T15:39:20.497517Z","caller":"traceutil/trace.go:171","msg":"trace[1814682706] range","detail":"{range_begin:/registry/masterleases/199.19.213.238; range_end:; response_count:1; response_revision:1279; }","duration":"1.12108773s","start":"2026-07-09T15:39:19.376418Z","end":"2026-07-09T15:39:20.497506Z","steps":["trace[1814682706] 'agreement among raft nodes before linearized reading' (duration: 1.12072426s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:39:20.497551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:19.376395Z","time spent":"1.121149532s","remote":"127.0.0.1:52146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.238\" "} {"level":"warn","ts":"2026-07-09T15:39:20.496456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:18.648868Z","time spent":"1.846107188s","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T15:39:20.498717Z","caller":"traceutil/trace.go:171","msg":"trace[37170219] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"1.848911035s","start":"2026-07-09T15:39:18.649786Z","end":"2026-07-09T15:39:20.498697Z","steps":["trace[37170219] 'process raft request' (duration: 1.845034509s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:39:20.498776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:18.649765Z","time spent":"1.848978977s","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T15:39:20.499552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.845642076s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T15:39:20.50089Z","caller":"traceutil/trace.go:171","msg":"trace[475697306] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1279; }","duration":"1.845801791s","start":"2026-07-09T15:39:18.653867Z","end":"2026-07-09T15:39:20.499669Z","steps":["trace[475697306] 'agreement among raft nodes before linearized reading' (duration: 1.845577185s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:39:20.500948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:18.653859Z","time spent":"1.847074516s","remote":"127.0.0.1:52114","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-07-09T15:39:20.869589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:20.499146Z","time spent":"370.439327ms","remote":"127.0.0.1:52146","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-09T15:39:20.869718Z","caller":"traceutil/trace.go:171","msg":"trace[1297356000] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"219.554506ms","start":"2026-07-09T15:39:20.650142Z","end":"2026-07-09T15:39:20.869697Z","steps":["trace[1297356000] 'process raft request' (duration: 219.408952ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:39:20.869727Z","caller":"traceutil/trace.go:171","msg":"trace[1576855215] linearizableReadLoop","detail":"{readStateIndex:1350; appliedIndex:1349; }","duration":"366.626752ms","start":"2026-07-09T15:39:20.503071Z","end":"2026-07-09T15:39:20.869698Z","steps":["trace[1576855215] 'read index received' (duration: 360.103973ms)","trace[1576855215] 'applied index is now lower than readState.Index' (duration: 6.519019ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:39:20.869842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.754936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T15:39:20.869866Z","caller":"traceutil/trace.go:171","msg":"trace[211476489] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1280; }","duration":"366.809768ms","start":"2026-07-09T15:39:20.503048Z","end":"2026-07-09T15:39:20.869857Z","steps":["trace[211476489] 'agreement among raft nodes before linearized reading' (duration: 366.741476ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:39:20.869888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:20.503039Z","time spent":"366.844888ms","remote":"127.0.0.1:52122","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-07-09T15:39:44.715522Z","caller":"traceutil/trace.go:171","msg":"trace[995151057] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"103.062424ms","start":"2026-07-09T15:39:44.612439Z","end":"2026-07-09T15:39:44.715502Z","steps":["trace[995151057] 'process raft request' (duration: 102.929581ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:39:45.048672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.313007ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T15:39:45.048913Z","caller":"traceutil/trace.go:171","msg":"trace[524439810] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"433.355067ms","start":"2026-07-09T15:39:44.615525Z","end":"2026-07-09T15:39:45.04888Z","steps":["trace[524439810] 'process raft request' (duration: 254.764542ms)","trace[524439810] 'compare' (duration: 177.943887ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:39:45.049068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:44.615515Z","time spent":"433.468109ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T15:39:45.368834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.060221ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-09T15:39:45.369139Z","caller":"traceutil/trace.go:171","msg":"trace[1342006409] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"413.608616ms","start":"2026-07-09T15:39:44.9555Z","end":"2026-07-09T15:39:45.369108Z","steps":["trace[1342006409] 'process raft request' (duration: 413.449811ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:39:45.36928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:44.955481Z","time spent":"413.715358ms","remote":"127.0.0.1:52404","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-07-09T15:39:45.369333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.080476ms","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-07-09T15:39:45.369369Z","caller":"traceutil/trace.go:171","msg":"trace[1822510011] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1358; }","duration":"750.142038ms","start":"2026-07-09T15:39:44.619218Z","end":"2026-07-09T15:39:45.36936Z","steps":["trace[1822510011] 'agreement among raft nodes before linearized reading' (duration: 749.961223ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:39:45.369392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:44.619208Z","time spent":"750.178679ms","remote":"127.0.0.1:52404","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-07-09T15:39:45.369136Z","caller":"traceutil/trace.go:171","msg":"trace[950795656] linearizableReadLoop","detail":"{readStateIndex:1432; appliedIndex:1430; }","duration":"749.870701ms","start":"2026-07-09T15:39:44.619234Z","end":"2026-07-09T15:39:45.369105Z","steps":["trace[950795656] 'read index received' (duration: 96.148065ms)","trace[950795656] 'applied index is now lower than readState.Index' (duration: 653.718975ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:39:45.369711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.167168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-09T15:39:45.369769Z","caller":"traceutil/trace.go:171","msg":"trace[1676610007] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1358; }","duration":"750.268791ms","start":"2026-07-09T15:39:44.619483Z","end":"2026-07-09T15:39:45.369752Z","steps":["trace[1676610007] 'agreement among raft nodes before linearized reading' (duration: 750.068436ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:39:45.369807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:44.619465Z","time spent":"750.333574ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-09T15:39:45.614933Z","caller":"traceutil/trace.go:171","msg":"trace[938824902] linearizableReadLoop","detail":"{readStateIndex:1436; appliedIndex:1435; }","duration":"136.49479ms","start":"2026-07-09T15:39:45.478397Z","end":"2026-07-09T15:39:45.614892Z","steps":["trace[938824902] 'read index received' (duration: 86.287724ms)","trace[938824902] 'applied index is now lower than readState.Index' (duration: 50.205976ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:39:45.615066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.659295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T15:39:45.615096Z","caller":"traceutil/trace.go:171","msg":"trace[1606488692] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1360; }","duration":"136.738468ms","start":"2026-07-09T15:39:45.478348Z","end":"2026-07-09T15:39:45.615087Z","steps":["trace[1606488692] 'agreement among raft nodes before linearized reading' (duration: 136.654015ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:39:45.615444Z","caller":"traceutil/trace.go:171","msg":"trace[1188937529] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"241.076656ms","start":"2026-07-09T15:39:45.374326Z","end":"2026-07-09T15:39:45.615402Z","steps":["trace[1188937529] 'process raft request' (duration: 190.467499ms)","trace[1188937529] 'compare' (duration: 49.99003ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:39:47.326677Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7212408457784372317,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T15:39:47.827808Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7212408457784372317,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T15:39:48.101383Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.377085948s","expected-duration":"1s"} {"level":"info","ts":"2026-07-09T15:39:48.101814Z","caller":"traceutil/trace.go:171","msg":"trace[948782868] transaction","detail":"{read_only:false; response_revision:1362; number_of_response:1; }","duration":"1.377607593s","start":"2026-07-09T15:39:46.724162Z","end":"2026-07-09T15:39:48.10177Z","steps":["trace[948782868] 'process raft request' (duration: 1.377411138s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:39:48.101995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:46.724136Z","time spent":"1.377748366s","remote":"127.0.0.1:52404","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-07-09T15:39:48.167531Z","caller":"traceutil/trace.go:171","msg":"trace[1088978739] linearizableReadLoop","detail":"{readStateIndex:1439; appliedIndex:1437; }","duration":"1.341167704s","start":"2026-07-09T15:39:46.826336Z","end":"2026-07-09T15:39:48.167504Z","steps":["trace[1088978739] 'read index received' (duration: 1.275201897s)","trace[1088978739] 'applied index is now lower than readState.Index' (duration: 65.961707ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:39:48.167672Z","caller":"traceutil/trace.go:171","msg":"trace[430296040] transaction","detail":"{read_only:false; response_revision:1363; number_of_response:1; }","duration":"793.355962ms","start":"2026-07-09T15:39:47.374279Z","end":"2026-07-09T15:39:48.167635Z","steps":["trace[430296040] 'process raft request' (duration: 793.066254ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:39:48.167827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.114425531s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-09T15:39:48.167898Z","caller":"traceutil/trace.go:171","msg":"trace[768426719] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1363; }","duration":"1.114538734s","start":"2026-07-09T15:39:47.053342Z","end":"2026-07-09T15:39:48.167881Z","steps":["trace[768426719] 'agreement among raft nodes before linearized reading' (duration: 1.114335828s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:39:48.16783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.341465132s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-09T15:39:48.167902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.321607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-09T15:39:48.167956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.185063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-09T15:39:48.168015Z","caller":"traceutil/trace.go:171","msg":"trace[1097408221] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1363; }","duration":"394.287126ms","start":"2026-07-09T15:39:47.773714Z","end":"2026-07-09T15:39:48.168001Z","steps":["trace[1097408221] 'agreement among raft nodes before linearized reading' (duration: 394.122251ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:39:48.167961Z","caller":"traceutil/trace.go:171","msg":"trace[2107538011] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1363; }","duration":"1.341657507s","start":"2026-07-09T15:39:46.826285Z","end":"2026-07-09T15:39:48.167943Z","steps":["trace[2107538011] 'agreement among raft nodes before linearized reading' (duration: 1.341415951s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:39:48.168052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:47.773697Z","time spent":"394.348427ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-09T15:39:48.168013Z","caller":"traceutil/trace.go:171","msg":"trace[1398220563] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1363; }","duration":"548.471871ms","start":"2026-07-09T15:39:47.619525Z","end":"2026-07-09T15:39:48.167996Z","steps":["trace[1398220563] 'agreement among raft nodes before linearized reading' (duration: 548.165132ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:39:48.168076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"722.976464ms","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-07-09T15:39:48.168091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"690.627147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T15:39:48.16813Z","caller":"traceutil/trace.go:171","msg":"trace[1430472770] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1363; }","duration":"723.060956ms","start":"2026-07-09T15:39:47.445056Z","end":"2026-07-09T15:39:48.168117Z","steps":["trace[1430472770] 'agreement among raft nodes before linearized reading' (duration: 722.927072ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:39:48.168076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:46.826265Z","time spent":"1.341789861s","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":31,"response size":31,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-07-09T15:39:48.168175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:47.619504Z","time spent":"548.653726ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-09T15:39:48.168192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:47.445038Z","time spent":"723.142978ms","remote":"127.0.0.1:52404","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-07-09T15:39:48.167956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:47.053321Z","time spent":"1.114620885s","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-09T15:39:48.168507Z","caller":"traceutil/trace.go:171","msg":"trace[255329005] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1363; }","duration":"690.792512ms","start":"2026-07-09T15:39:47.477339Z","end":"2026-07-09T15:39:48.168131Z","steps":["trace[255329005] 'agreement among raft nodes before linearized reading' (duration: 690.557045ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:39:48.168659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:47.374252Z","time spent":"793.520817ms","remote":"127.0.0.1:52404","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-07-09T15:39:48.168569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:47.47732Z","time spent":"691.230943ms","remote":"127.0.0.1:52122","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-07-09T15:39:48.735638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.579826ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T15:39:48.735773Z","caller":"traceutil/trace.go:171","msg":"trace[1907508946] transaction","detail":"{read_only:false; response_revision:1364; number_of_response:1; }","duration":"562.921337ms","start":"2026-07-09T15:39:48.172824Z","end":"2026-07-09T15:39:48.735745Z","steps":["trace[1907508946] 'process raft request' (duration: 408.074354ms)","trace[1907508946] 'compare' (duration: 154.411951ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:39:48.73624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:48.172797Z","time spent":"563.3843ms","remote":"127.0.0.1:52404","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-07-09T15:39:49.280755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.274553ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T15:39:49.280882Z","caller":"traceutil/trace.go:171","msg":"trace[122292291] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1364; }","duration":"233.424147ms","start":"2026-07-09T15:39:49.04744Z","end":"2026-07-09T15:39:49.280864Z","steps":["trace[122292291] 'range keys from in-memory index tree' (duration: 233.252563ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:39:49.281461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.629413ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T15:39:49.281897Z","caller":"traceutil/trace.go:171","msg":"trace[1238621856] linearizableReadLoop","detail":"{readStateIndex:1443; appliedIndex:1439; }","duration":"881.105257ms","start":"2026-07-09T15:39:48.400779Z","end":"2026-07-09T15:39:49.281885Z","steps":["trace[1238621856] 'read index received' (duration: 180.131197ms)","trace[1238621856] 'applied index is now lower than readState.Index' (duration: 700.97326ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:39:49.282029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"881.253791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T15:39:49.282013Z","caller":"traceutil/trace.go:171","msg":"trace[1701259848] transaction","detail":"{read_only:false; response_revision:1365; number_of_response:1; }","duration":"1.109147286s","start":"2026-07-09T15:39:48.172841Z","end":"2026-07-09T15:39:49.281988Z","steps":["trace[1701259848] 'process raft request' (duration: 797.911057ms)","trace[1701259848] 'compare' (duration: 310.313624ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:39:49.282096Z","caller":"traceutil/trace.go:171","msg":"trace[2068505370] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1367; }","duration":"881.362354ms","start":"2026-07-09T15:39:48.400724Z","end":"2026-07-09T15:39:49.282086Z","steps":["trace[2068505370] 'agreement among raft nodes before linearized reading' (duration: 881.272601ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:39:49.282026Z","caller":"traceutil/trace.go:171","msg":"trace[1708230010] transaction","detail":"{read_only:false; response_revision:1367; number_of_response:1; }","duration":"1.107187353s","start":"2026-07-09T15:39:48.174821Z","end":"2026-07-09T15:39:49.282008Z","steps":["trace[1708230010] 'process raft request' (duration: 1.107030328s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:39:49.282218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:48.400634Z","time spent":"881.540609ms","remote":"127.0.0.1:51920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-09T15:39:49.282253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:48.174812Z","time spent":"1.107384398s","remote":"127.0.0.1:52404","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-07-09T15:39:49.28222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:48.172827Z","time spent":"1.109294961s","remote":"127.0.0.1:52404","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-07-09T15:39:49.282452Z","caller":"traceutil/trace.go:171","msg":"trace[1883810466] transaction","detail":"{read_only:false; response_revision:1366; number_of_response:1; }","duration":"1.107403498s","start":"2026-07-09T15:39:48.174524Z","end":"2026-07-09T15:39:49.281927Z","steps":["trace[1883810466] 'process raft request' (duration: 1.107268915s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:39:49.28257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:48.174499Z","time spent":"1.107996135s","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T15:39:49.769247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.589988ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T15:39:49.76947Z","caller":"traceutil/trace.go:171","msg":"trace[989852509] transaction","detail":"{read_only:false; response_revision:1368; number_of_response:1; }","duration":"745.196853ms","start":"2026-07-09T15:39:49.024246Z","end":"2026-07-09T15:39:49.769443Z","steps":["trace[989852509] 'process raft request' (duration: 533.306696ms)","trace[989852509] 'compare' (duration: 211.477086ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:39:49.769603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:49.024223Z","time spent":"745.309525ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T15:39:49.769777Z","caller":"traceutil/trace.go:171","msg":"trace[1209302324] linearizableReadLoop","detail":"{readStateIndex:1444; appliedIndex:1443; }","duration":"487.382006ms","start":"2026-07-09T15:39:49.281966Z","end":"2026-07-09T15:39:49.769348Z","steps":["trace[1209302324] 'read index received' (duration: 275.491129ms)","trace[1209302324] 'applied index is now lower than readState.Index' (duration: 211.888967ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:39:49.77008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.291114713s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T15:39:49.77013Z","caller":"traceutil/trace.go:171","msg":"trace[501587265] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1368; }","duration":"1.291207496s","start":"2026-07-09T15:39:48.478907Z","end":"2026-07-09T15:39:49.770114Z","steps":["trace[501587265] 'agreement among raft nodes before linearized reading' (duration: 1.291125504s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:39:49.77018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:48.478878Z","time spent":"1.291284717s","remote":"127.0.0.1:52114","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-07-09T15:39:49.770239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.941171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.238\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-09T15:39:49.770279Z","caller":"traceutil/trace.go:171","msg":"trace[1404622237] range","detail":"{range_begin:/registry/masterleases/199.19.213.238; range_end:; response_count:1; response_revision:1368; }","duration":"392.020703ms","start":"2026-07-09T15:39:49.378247Z","end":"2026-07-09T15:39:49.770268Z","steps":["trace[1404622237] 'agreement among raft nodes before linearized reading' (duration: 391.89055ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:39:49.770307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:49.378224Z","time spent":"392.076245ms","remote":"127.0.0.1:52146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.238\" "} {"level":"warn","ts":"2026-07-09T15:39:49.770287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.936435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T15:39:49.770297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.340173728s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T15:39:49.77037Z","caller":"traceutil/trace.go:171","msg":"trace[725725822] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1368; }","duration":"279.076939ms","start":"2026-07-09T15:39:49.491271Z","end":"2026-07-09T15:39:49.770347Z","steps":["trace[725725822] 'agreement among raft nodes before linearized reading' (duration: 278.941135ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:39:49.770391Z","caller":"traceutil/trace.go:171","msg":"trace[1347649816] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1368; }","duration":"1.340328352s","start":"2026-07-09T15:39:48.430044Z","end":"2026-07-09T15:39:49.770372Z","steps":["trace[1347649816] 'agreement among raft nodes before linearized reading' (duration: 1.340046085s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:39:49.770443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:48.430029Z","time spent":"1.340398634s","remote":"127.0.0.1:52492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":68,"response size":31,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"info","ts":"2026-07-09T15:39:50.185941Z","caller":"traceutil/trace.go:171","msg":"trace[1885490754] transaction","detail":"{read_only:false; response_revision:1369; number_of_response:1; }","duration":"341.290883ms","start":"2026-07-09T15:39:49.844614Z","end":"2026-07-09T15:39:50.185905Z","steps":["trace[1885490754] 'process raft request' (duration: 265.038503ms)","trace[1885490754] 'compare' (duration: 76.107316ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:39:50.18608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:49.844591Z","time spent":"341.418007ms","remote":"127.0.0.1:52146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T15:39:50.186085Z","caller":"traceutil/trace.go:171","msg":"trace[405513609] linearizableReadLoop","detail":"{readStateIndex:1446; appliedIndex:1445; }","duration":"336.43114ms","start":"2026-07-09T15:39:49.849471Z","end":"2026-07-09T15:39:50.185902Z","steps":["trace[405513609] 'read index received' (duration: 260.16831ms)","trace[405513609] 'applied index is now lower than readState.Index' (duration: 76.26168ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:39:50.186434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.934804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T15:39:50.18655Z","caller":"traceutil/trace.go:171","msg":"trace[621621540] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1369; }","duration":"337.115449ms","start":"2026-07-09T15:39:49.849416Z","end":"2026-07-09T15:39:50.186531Z","steps":["trace[621621540] 'agreement among raft nodes before linearized reading' (duration: 336.866162ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:39:50.186747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:49.849387Z","time spent":"337.340925ms","remote":"127.0.0.1:52114","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-07-09T15:39:51.32165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.72836ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T15:39:51.32175Z","caller":"traceutil/trace.go:171","msg":"trace[1227357431] linearizableReadLoop","detail":"{readStateIndex:1447; appliedIndex:1446; }","duration":"1.135538699s","start":"2026-07-09T15:39:50.186195Z","end":"2026-07-09T15:39:51.321734Z","steps":["trace[1227357431] 'read index received' (duration: 410.561832ms)","trace[1227357431] 'applied index is now lower than readState.Index' (duration: 724.975407ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:39:51.321858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.446353157s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T15:39:51.321892Z","caller":"traceutil/trace.go:171","msg":"trace[1287385839] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1370; }","duration":"1.446422099s","start":"2026-07-09T15:39:49.875461Z","end":"2026-07-09T15:39:51.321883Z","steps":["trace[1287385839] 'agreement among raft nodes before linearized reading' (duration: 1.446357887s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:39:51.321925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:49.875441Z","time spent":"1.44647513s","remote":"127.0.0.1:51654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-07-09T15:39:51.321931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.21583356s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-09T15:39:51.32215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.133786751s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-07-09T15:39:51.322179Z","caller":"traceutil/trace.go:171","msg":"trace[14158658] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1370; }","duration":"1.133850003s","start":"2026-07-09T15:39:50.18832Z","end":"2026-07-09T15:39:51.32217Z","steps":["trace[14158658] 'agreement among raft nodes before linearized reading' (duration: 1.133786881s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:39:51.322204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:50.188287Z","time spent":"1.133911175s","remote":"127.0.0.1:52296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":446,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-07-09T15:39:51.322613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.125262527s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T15:39:51.322694Z","caller":"traceutil/trace.go:171","msg":"trace[355480801] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1370; }","duration":"1.125390101s","start":"2026-07-09T15:39:50.197286Z","end":"2026-07-09T15:39:51.322676Z","steps":["trace[355480801] 'agreement among raft nodes before linearized reading' (duration: 1.125243007s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:39:51.322742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:50.19727Z","time spent":"1.125462392s","remote":"127.0.0.1:52236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":5,"response size":31,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-07-09T15:39:51.322799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.283157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-09T15:39:51.322833Z","caller":"traceutil/trace.go:171","msg":"trace[360132752] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1370; }","duration":"582.319788ms","start":"2026-07-09T15:39:50.740503Z","end":"2026-07-09T15:39:51.322823Z","steps":["trace[360132752] 'agreement among raft nodes before linearized reading' (duration: 582.231665ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:39:51.322859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:50.740444Z","time spent":"582.409029ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-09T15:39:51.322921Z","caller":"traceutil/trace.go:171","msg":"trace[1841948109] transaction","detail":"{read_only:false; response_revision:1370; number_of_response:1; }","duration":"1.148480684s","start":"2026-07-09T15:39:50.174423Z","end":"2026-07-09T15:39:51.322904Z","steps":["trace[1841948109] 'process raft request' (duration: 422.415197ms)","trace[1841948109] 'compare' (duration: 724.558646ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:39:51.323058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:50.174398Z","time spent":"1.148604887s","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T15:39:51.323242Z","caller":"traceutil/trace.go:171","msg":"trace[178239364] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1370; }","duration":"1.215943943s","start":"2026-07-09T15:39:50.10604Z","end":"2026-07-09T15:39:51.321984Z","steps":["trace[178239364] 'agreement among raft nodes before linearized reading' (duration: 1.215751187s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:39:51.323702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:50.106022Z","time spent":"1.217663249s","remote":"127.0.0.1:52404","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-07-09T15:39:52.195875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.238935ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T15:39:52.196097Z","caller":"traceutil/trace.go:171","msg":"trace[1072606574] transaction","detail":"{read_only:false; response_revision:1371; number_of_response:1; }","duration":"868.395509ms","start":"2026-07-09T15:39:51.327585Z","end":"2026-07-09T15:39:52.195981Z","steps":["trace[1072606574] 'process raft request' (duration: 454.535987ms)","trace[1072606574] 'compare' (duration: 412.630958ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:39:52.196176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:51.327571Z","time spent":"868.569523ms","remote":"127.0.0.1:52404","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-07-09T15:39:52.535819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.315289ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T15:39:52.536081Z","caller":"traceutil/trace.go:171","msg":"trace[1726083673] transaction","detail":"{read_only:false; response_revision:1372; number_of_response:1; }","duration":"1.207733209s","start":"2026-07-09T15:39:51.328321Z","end":"2026-07-09T15:39:52.536054Z","steps":["trace[1726083673] 'process raft request' (duration: 1.034051969s)","trace[1726083673] 'compare' (duration: 173.140345ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:39:52.536251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:51.328303Z","time spent":"1.207831961s","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T15:39:52.536399Z","caller":"traceutil/trace.go:171","msg":"trace[2044132055] linearizableReadLoop","detail":"{readStateIndex:1452; appliedIndex:1447; }","duration":"1.167101454s","start":"2026-07-09T15:39:51.369276Z","end":"2026-07-09T15:39:52.536378Z","steps":["trace[2044132055] 'read index received' (duration: 412.806813ms)","trace[2044132055] 'applied index is now lower than readState.Index' (duration: 754.293321ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:39:52.53648Z","caller":"traceutil/trace.go:171","msg":"trace[1156178732] transaction","detail":"{read_only:false; response_revision:1373; number_of_response:1; }","duration":"1.208110089s","start":"2026-07-09T15:39:51.32836Z","end":"2026-07-09T15:39:52.536471Z","steps":["trace[1156178732] 'process raft request' (duration: 1.207551024s)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:39:52.536514Z","caller":"traceutil/trace.go:171","msg":"trace[1571206081] transaction","detail":"{read_only:false; response_revision:1374; number_of_response:1; }","duration":"1.207298096s","start":"2026-07-09T15:39:51.329204Z","end":"2026-07-09T15:39:52.536502Z","steps":["trace[1571206081] 'process raft request' (duration: 1.20706694s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:39:52.536527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:51.328343Z","time spent":"1.20815768s","remote":"127.0.0.1:52404","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-07-09T15:39:52.536728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.167457345s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T15:39:52.536775Z","caller":"traceutil/trace.go:171","msg":"trace[457635285] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1375; }","duration":"1.167530387s","start":"2026-07-09T15:39:51.369222Z","end":"2026-07-09T15:39:52.536752Z","steps":["trace[457635285] 'agreement among raft nodes before linearized reading' (duration: 1.167475915s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:39:52.536805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:51.369202Z","time spent":"1.167597018s","remote":"127.0.0.1:52114","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-07-09T15:39:52.536937Z","caller":"traceutil/trace.go:171","msg":"trace[850129797] transaction","detail":"{read_only:false; response_revision:1375; number_of_response:1; }","duration":"1.20746586s","start":"2026-07-09T15:39:51.329459Z","end":"2026-07-09T15:39:52.536924Z","steps":["trace[850129797] 'process raft request' (duration: 1.206873594s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:39:52.537156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.160234506s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T15:39:52.537213Z","caller":"traceutil/trace.go:171","msg":"trace[3247582] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1375; }","duration":"1.160317839s","start":"2026-07-09T15:39:51.376867Z","end":"2026-07-09T15:39:52.537185Z","steps":["trace[3247582] 'agreement among raft nodes before linearized reading' (duration: 1.160244807s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:39:52.537256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:51.376849Z","time spent":"1.160395381s","remote":"127.0.0.1:52396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":31,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-07-09T15:39:52.537404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.057739817s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T15:39:52.53742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"693.138245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T15:39:52.53745Z","caller":"traceutil/trace.go:171","msg":"trace[1377774510] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1375; }","duration":"1.05782856s","start":"2026-07-09T15:39:51.479608Z","end":"2026-07-09T15:39:52.537437Z","steps":["trace[1377774510] 'agreement among raft nodes before linearized reading' (duration: 1.057753778s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:39:52.537481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:51.478882Z","time spent":"1.058591812s","remote":"127.0.0.1:52122","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-07-09T15:39:52.537481Z","caller":"traceutil/trace.go:171","msg":"trace[774942582] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1375; }","duration":"693.234928ms","start":"2026-07-09T15:39:51.844229Z","end":"2026-07-09T15:39:52.537464Z","steps":["trace[774942582] 'agreement among raft nodes before linearized reading' (duration: 693.116035ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:39:52.53753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:51.844212Z","time spent":"693.3046ms","remote":"127.0.0.1:52698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":29,"response size":31,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-07-09T15:39:52.536591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:51.329197Z","time spent":"1.207346888s","remote":"127.0.0.1:52404","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-07-09T15:39:52.538027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:51.329424Z","time spent":"1.207534072s","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T15:39:53.49238Z","caller":"traceutil/trace.go:171","msg":"trace[1713848634] transaction","detail":"{read_only:false; response_revision:1376; number_of_response:1; }","duration":"163.383108ms","start":"2026-07-09T15:39:53.328973Z","end":"2026-07-09T15:39:53.492357Z","steps":["trace[1713848634] 'process raft request' (duration: 163.236303ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:39:54.555138Z","caller":"traceutil/trace.go:171","msg":"trace[2099723668] linearizableReadLoop","detail":"{readStateIndex:1454; appliedIndex:1453; }","duration":"354.568776ms","start":"2026-07-09T15:39:54.200547Z","end":"2026-07-09T15:39:54.555115Z","steps":["trace[2099723668] 'read index received' (duration: 314.718943ms)","trace[2099723668] 'applied index is now lower than readState.Index' (duration: 39.848753ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:39:54.555269Z","caller":"traceutil/trace.go:171","msg":"trace[1578219611] transaction","detail":"{read_only:false; response_revision:1377; number_of_response:1; }","duration":"834.772166ms","start":"2026-07-09T15:39:53.720485Z","end":"2026-07-09T15:39:54.555257Z","steps":["trace[1578219611] 'process raft request' (duration: 794.706368ms)","trace[1578219611] 'compare' (duration: 39.820302ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:39:54.555397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:53.720461Z","time spent":"834.834698ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T15:39:54.555735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.196713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-09T15:39:54.555787Z","caller":"traceutil/trace.go:171","msg":"trace[1174646791] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1377; }","duration":"355.288046ms","start":"2026-07-09T15:39:54.200488Z","end":"2026-07-09T15:39:54.555776Z","steps":["trace[1174646791] 'agreement among raft nodes before linearized reading' (duration: 355.129781ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:39:54.55582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:54.200445Z","time spent":"355.367988ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-09T15:39:55.037493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.846659ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-09T15:39:55.03775Z","caller":"traceutil/trace.go:171","msg":"trace[825708646] linearizableReadLoop","detail":"{readStateIndex:1455; appliedIndex:1454; }","duration":"482.497213ms","start":"2026-07-09T15:39:54.55523Z","end":"2026-07-09T15:39:55.037727Z","steps":["trace[825708646] 'read index received' (duration: 12.849272ms)","trace[825708646] 'applied index is now lower than readState.Index' (duration: 469.646651ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:39:55.037851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.736198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T15:39:55.037886Z","caller":"traceutil/trace.go:171","msg":"trace[760523024] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1377; }","duration":"560.8142ms","start":"2026-07-09T15:39:54.47706Z","end":"2026-07-09T15:39:55.037874Z","steps":["trace[760523024] 'agreement among raft nodes before linearized reading' (duration: 560.728057ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:39:55.037927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:54.477031Z","time spent":"560.887211ms","remote":"127.0.0.1:52114","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-07-09T15:39:55.039221Z","caller":"traceutil/trace.go:171","msg":"trace[362742852] transaction","detail":"{read_only:false; response_revision:1378; number_of_response:1; }","duration":"479.245294ms","start":"2026-07-09T15:39:54.559956Z","end":"2026-07-09T15:39:55.039201Z","steps":["trace[362742852] 'process raft request' (duration: 477.661711ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:39:55.03933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:54.559933Z","time spent":"479.338546ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T15:39:55.128937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.672759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-09T15:39:55.129023Z","caller":"traceutil/trace.go:171","msg":"trace[513686944] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1378; }","duration":"586.795301ms","start":"2026-07-09T15:39:54.542205Z","end":"2026-07-09T15:39:55.129001Z","steps":["trace[513686944] 'agreement among raft nodes before linearized reading' (duration: 496.27296ms)","trace[513686944] 'range keys from in-memory index tree' (duration: 90.245543ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:39:55.129071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:54.542197Z","time spent":"586.865123ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-09T15:39:55.129254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.141971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-09T15:39:55.129347Z","caller":"traceutil/trace.go:171","msg":"trace[409916232] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1378; }","duration":"587.280065ms","start":"2026-07-09T15:39:54.542041Z","end":"2026-07-09T15:39:55.129321Z","steps":["trace[409916232] 'agreement among raft nodes before linearized reading' (duration: 496.520598ms)","trace[409916232] 'range keys from in-memory index tree' (duration: 90.548482ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:39:55.12934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.687173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-09T15:39:55.129391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:54.542018Z","time spent":"587.362877ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-09T15:39:55.129428Z","caller":"traceutil/trace.go:171","msg":"trace[1619236580] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1378; }","duration":"588.809086ms","start":"2026-07-09T15:39:54.540593Z","end":"2026-07-09T15:39:55.129402Z","steps":["trace[1619236580] 'agreement among raft nodes before linearized reading' (duration: 497.980907ms)","trace[1619236580] 'range keys from in-memory index tree' (duration: 90.615154ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:39:55.129482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:54.540573Z","time spent":"588.896689ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-09T15:39:55.129553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.449669ms","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-07-09T15:39:55.129594Z","caller":"traceutil/trace.go:171","msg":"trace[1516144843] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1378; }","duration":"587.522531ms","start":"2026-07-09T15:39:54.542059Z","end":"2026-07-09T15:39:55.129582Z","steps":["trace[1516144843] 'agreement among raft nodes before linearized reading' (duration: 496.487906ms)","trace[1516144843] 'range keys from in-memory index tree' (duration: 90.939772ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:39:55.129629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:39:54.542042Z","time spent":"587.580643ms","remote":"127.0.0.1:52404","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-07-09T15:39:55.309592Z","caller":"traceutil/trace.go:171","msg":"trace[1136316890] transaction","detail":"{read_only:false; response_revision:1380; number_of_response:1; }","duration":"174.875723ms","start":"2026-07-09T15:39:55.134697Z","end":"2026-07-09T15:39:55.309573Z","steps":["trace[1136316890] 'process raft request' (duration: 174.740649ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:39:55.30979Z","caller":"traceutil/trace.go:171","msg":"trace[2123013381] linearizableReadLoop","detail":"{readStateIndex:1460; appliedIndex:1456; }","duration":"150.33868ms","start":"2026-07-09T15:39:55.15944Z","end":"2026-07-09T15:39:55.309778Z","steps":["trace[2123013381] 'read index received' (duration: 51.664706ms)","trace[2123013381] 'applied index is now lower than readState.Index' (duration: 98.673364ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:39:55.309832Z","caller":"traceutil/trace.go:171","msg":"trace[423865238] transaction","detail":"{read_only:false; response_revision:1382; number_of_response:1; }","duration":"175.059358ms","start":"2026-07-09T15:39:55.134758Z","end":"2026-07-09T15:39:55.309817Z","steps":["trace[423865238] 'process raft request' (duration: 174.959475ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:39:55.309895Z","caller":"traceutil/trace.go:171","msg":"trace[1739594357] transaction","detail":"{read_only:false; response_revision:1381; number_of_response:1; }","duration":"175.105719ms","start":"2026-07-09T15:39:55.134745Z","end":"2026-07-09T15:39:55.30985Z","steps":["trace[1739594357] 'process raft request' (duration: 174.801051ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:39:55.309907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.457953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T15:39:55.309942Z","caller":"traceutil/trace.go:171","msg":"trace[112004692] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1382; }","duration":"150.540056ms","start":"2026-07-09T15:39:55.159391Z","end":"2026-07-09T15:39:55.309931Z","steps":["trace[112004692] 'agreement among raft nodes before linearized reading' (duration: 150.463914ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:39:55.310004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.372443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T15:39:55.310078Z","caller":"traceutil/trace.go:171","msg":"trace[803785872] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1382; }","duration":"134.502216ms","start":"2026-07-09T15:39:55.175559Z","end":"2026-07-09T15:39:55.310062Z","steps":["trace[803785872] 'agreement among raft nodes before linearized reading' (duration: 134.396493ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:40:13.391229Z","caller":"traceutil/trace.go:171","msg":"trace[1110153703] transaction","detail":"{read_only:false; response_revision:1498; number_of_response:1; }","duration":"275.604823ms","start":"2026-07-09T15:40:13.115607Z","end":"2026-07-09T15:40:13.391212Z","steps":["trace[1110153703] 'process raft request' (duration: 275.266593ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:40:15.116832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.42005ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-09T15:40:15.116898Z","caller":"traceutil/trace.go:171","msg":"trace[638322987] linearizableReadLoop","detail":"{readStateIndex:1587; appliedIndex:1586; }","duration":"431.66201ms","start":"2026-07-09T15:40:14.685224Z","end":"2026-07-09T15:40:15.116886Z","steps":["trace[638322987] 'read index received' (duration: 33.701µs)","trace[638322987] 'applied index is now lower than readState.Index' (duration: 431.627359ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:40:15.11697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.737332ms","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-07-09T15:40:15.116988Z","caller":"traceutil/trace.go:171","msg":"trace[985101583] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1503; }","duration":"431.764923ms","start":"2026-07-09T15:40:14.685219Z","end":"2026-07-09T15:40:15.116984Z","steps":["trace[985101583] 'agreement among raft nodes before linearized reading' (duration: 431.708991ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:40:15.117009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:40:14.685175Z","time spent":"431.828884ms","remote":"127.0.0.1:52314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":13,"response size":31,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"info","ts":"2026-07-09T15:40:15.195463Z","caller":"traceutil/trace.go:171","msg":"trace[465856726] transaction","detail":"{read_only:false; response_revision:1504; number_of_response:1; }","duration":"106.739315ms","start":"2026-07-09T15:40:15.088698Z","end":"2026-07-09T15:40:15.195437Z","steps":["trace[465856726] 'process raft request' (duration: 106.532699ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:40:29.068494Z","caller":"traceutil/trace.go:171","msg":"trace[1337881273] transaction","detail":"{read_only:false; response_revision:1561; number_of_response:1; }","duration":"258.512134ms","start":"2026-07-09T15:40:28.809953Z","end":"2026-07-09T15:40:29.068466Z","steps":["trace[1337881273] 'process raft request' (duration: 258.330829ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:40:33.703845Z","caller":"traceutil/trace.go:171","msg":"trace[189432126] transaction","detail":"{read_only:false; response_revision:1587; number_of_response:1; }","duration":"121.600143ms","start":"2026-07-09T15:40:33.582196Z","end":"2026-07-09T15:40:33.703796Z","steps":["trace[189432126] 'process raft request' (duration: 121.214522ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:40:33.721935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.653418ms","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-07-09T15:40:33.722062Z","caller":"traceutil/trace.go:171","msg":"trace[1551537990] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1588; }","duration":"108.799172ms","start":"2026-07-09T15:40:33.613213Z","end":"2026-07-09T15:40:33.722012Z","steps":["trace[1551537990] 'agreement among raft nodes before linearized reading' (duration: 108.498174ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:40:33.824489Z","caller":"traceutil/trace.go:171","msg":"trace[1146982025] transaction","detail":"{read_only:false; response_revision:1589; number_of_response:1; }","duration":"115.961607ms","start":"2026-07-09T15:40:33.708248Z","end":"2026-07-09T15:40:33.82421Z","steps":["trace[1146982025] 'process raft request' (duration: 56.344804ms)","trace[1146982025] 'compare' (duration: 58.77853ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:40:34.587525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.758165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T15:40:34.587632Z","caller":"traceutil/trace.go:171","msg":"trace[1364189376] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1591; }","duration":"110.908789ms","start":"2026-07-09T15:40:34.476704Z","end":"2026-07-09T15:40:34.587612Z","steps":["trace[1364189376] 'range keys from in-memory index tree' (duration: 110.684034ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:40:35.237391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.036395ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-09T15:40:35.342853Z","caller":"traceutil/trace.go:171","msg":"trace[1572002909] transaction","detail":"{read_only:false; response_revision:1592; number_of_response:1; }","duration":"110.283682ms","start":"2026-07-09T15:40:35.232541Z","end":"2026-07-09T15:40:35.342825Z","steps":["trace[1572002909] 'process raft request' (duration: 110.099887ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:40:35.688637Z","caller":"traceutil/trace.go:171","msg":"trace[1981445701] linearizableReadLoop","detail":"{readStateIndex:1683; appliedIndex:1681; }","duration":"149.705193ms","start":"2026-07-09T15:40:35.538906Z","end":"2026-07-09T15:40:35.688611Z","steps":["trace[1981445701] 'read index received' (duration: 24.391138ms)","trace[1981445701] 'applied index is now lower than readState.Index' (duration: 125.313175ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:40:35.688731Z","caller":"traceutil/trace.go:171","msg":"trace[868862999] transaction","detail":"{read_only:false; response_revision:1595; number_of_response:1; }","duration":"169.323061ms","start":"2026-07-09T15:40:35.51936Z","end":"2026-07-09T15:40:35.688683Z","steps":["trace[868862999] 'process raft request' (duration: 130.029714ms)","trace[868862999] 'compare' (duration: 39.094261ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:40:35.688902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.717378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-07-09T15:40:35.688889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.96444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-09T15:40:35.688982Z","caller":"traceutil/trace.go:171","msg":"trace[1773125904] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1595; }","duration":"128.847061ms","start":"2026-07-09T15:40:35.560115Z","end":"2026-07-09T15:40:35.688962Z","steps":["trace[1773125904] 'agreement among raft nodes before linearized reading' (duration: 128.666366ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:40:35.689049Z","caller":"traceutil/trace.go:171","msg":"trace[1408765216] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1595; }","duration":"150.117205ms","start":"2026-07-09T15:40:35.538857Z","end":"2026-07-09T15:40:35.688975Z","steps":["trace[1408765216] 'agreement among raft nodes before linearized reading' (duration: 149.907079ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:40:35.89382Z","caller":"traceutil/trace.go:171","msg":"trace[2005948909] transaction","detail":"{read_only:false; response_revision:1596; number_of_response:1; }","duration":"200.442623ms","start":"2026-07-09T15:40:35.693318Z","end":"2026-07-09T15:40:35.893761Z","steps":["trace[2005948909] 'process raft request' (duration: 186.903622ms)","trace[2005948909] 'compare' (duration: 13.170011ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:40:36.079646Z","caller":"traceutil/trace.go:171","msg":"trace[1365761153] transaction","detail":"{read_only:false; response_revision:1597; number_of_response:1; }","duration":"384.789015ms","start":"2026-07-09T15:40:35.694823Z","end":"2026-07-09T15:40:36.079612Z","steps":["trace[1365761153] 'process raft request' (duration: 334.157957ms)","trace[1365761153] 'compare' (duration: 50.406442ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:40:36.079866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:40:35.694793Z","time spent":"384.94973ms","remote":"127.0.0.1:52404","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-07-09T15:40:36.080024Z","caller":"traceutil/trace.go:171","msg":"trace[802879887] linearizableReadLoop","detail":"{readStateIndex:1685; appliedIndex:1683; }","duration":"372.104227ms","start":"2026-07-09T15:40:35.707889Z","end":"2026-07-09T15:40:36.079993Z","steps":["trace[802879887] 'read index received' (duration: 172.346093ms)","trace[802879887] 'applied index is now lower than readState.Index' (duration: 199.755604ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:40:36.080117Z","caller":"traceutil/trace.go:171","msg":"trace[815936290] transaction","detail":"{read_only:false; response_revision:1598; number_of_response:1; }","duration":"352.452819ms","start":"2026-07-09T15:40:35.727626Z","end":"2026-07-09T15:40:36.080079Z","steps":["trace[815936290] 'process raft request' (duration: 351.928744ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:40:36.080285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.378146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-07-09T15:40:36.080301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:40:35.727604Z","time spent":"352.592493ms","remote":"127.0.0.1:52404","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-07-09T15:40:36.080333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.034915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-09T15:40:36.080354Z","caller":"traceutil/trace.go:171","msg":"trace[247178389] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1598; }","duration":"372.495639ms","start":"2026-07-09T15:40:35.707839Z","end":"2026-07-09T15:40:36.080335Z","steps":["trace[247178389] 'agreement among raft nodes before linearized reading' (duration: 372.280583ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:40:36.080346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.84109ms","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-07-09T15:40:36.080379Z","caller":"traceutil/trace.go:171","msg":"trace[2054100303] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1598; }","duration":"249.137008ms","start":"2026-07-09T15:40:35.831229Z","end":"2026-07-09T15:40:36.080366Z","steps":["trace[2054100303] 'agreement among raft nodes before linearized reading' (duration: 248.928952ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:40:36.080393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:40:35.70782Z","time spent":"372.5665ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-09T15:40:36.080407Z","caller":"traceutil/trace.go:171","msg":"trace[1987775636] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1598; }","duration":"248.921772ms","start":"2026-07-09T15:40:35.831466Z","end":"2026-07-09T15:40:36.080388Z","steps":["trace[1987775636] 'agreement among raft nodes before linearized reading' (duration: 248.718107ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:40:37.631745Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7212408457784372847,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-09T15:40:37.671158Z","caller":"traceutil/trace.go:171","msg":"trace[1200059530] linearizableReadLoop","detail":"{readStateIndex:1693; appliedIndex:1692; }","duration":"539.664779ms","start":"2026-07-09T15:40:37.131471Z","end":"2026-07-09T15:40:37.671136Z","steps":["trace[1200059530] 'read index received' (duration: 539.489864ms)","trace[1200059530] 'applied index is now lower than readState.Index' (duration: 174.025µs)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:40:37.671545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.098957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T15:40:37.671518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.984967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T15:40:37.671618Z","caller":"traceutil/trace.go:171","msg":"trace[1536514408] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1605; }","duration":"214.22908ms","start":"2026-07-09T15:40:37.457367Z","end":"2026-07-09T15:40:37.671596Z","steps":["trace[1536514408] 'agreement among raft nodes before linearized reading' (duration: 214.097697ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:40:37.671623Z","caller":"traceutil/trace.go:171","msg":"trace[1211654458] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1605; }","duration":"540.168113ms","start":"2026-07-09T15:40:37.131437Z","end":"2026-07-09T15:40:37.671605Z","steps":["trace[1211654458] 'agreement among raft nodes before linearized reading' (duration: 539.782202ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:40:37.671707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:40:37.131427Z","time spent":"540.239634ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-09T15:40:37.671664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.796361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T15:40:37.671755Z","caller":"traceutil/trace.go:171","msg":"trace[1444930403] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1605; }","duration":"193.899904ms","start":"2026-07-09T15:40:37.477845Z","end":"2026-07-09T15:40:37.671745Z","steps":["trace[1444930403] 'agreement among raft nodes before linearized reading' (duration: 193.77142ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:40:37.671924Z","caller":"traceutil/trace.go:171","msg":"trace[1276097471] transaction","detail":"{read_only:false; response_revision:1605; number_of_response:1; }","duration":"547.36054ms","start":"2026-07-09T15:40:37.124542Z","end":"2026-07-09T15:40:37.671903Z","steps":["trace[1276097471] 'process raft request' (duration: 546.465585ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:40:37.672043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:40:37.124522Z","time spent":"547.470922ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T15:40:37.672213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.880237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T15:40:37.672249Z","caller":"traceutil/trace.go:171","msg":"trace[1444600301] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1605; }","duration":"176.947279ms","start":"2026-07-09T15:40:37.495292Z","end":"2026-07-09T15:40:37.672239Z","steps":["trace[1444600301] 'agreement among raft nodes before linearized reading' (duration: 176.893038ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:40:37.817729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.784766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-09T15:40:37.817842Z","caller":"traceutil/trace.go:171","msg":"trace[1445017310] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1605; }","duration":"141.93246ms","start":"2026-07-09T15:40:37.675885Z","end":"2026-07-09T15:40:37.817818Z","steps":["trace[1445017310] 'range keys from in-memory index tree' (duration: 141.701203ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:40:37.820303Z","caller":"traceutil/trace.go:171","msg":"trace[621427088] transaction","detail":"{read_only:false; response_revision:1606; number_of_response:1; }","duration":"143.558085ms","start":"2026-07-09T15:40:37.676722Z","end":"2026-07-09T15:40:37.82028Z","steps":["trace[621427088] 'process raft request' (duration: 143.240856ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:40:37.822697Z","caller":"traceutil/trace.go:171","msg":"trace[510264058] transaction","detail":"{read_only:false; response_revision:1609; number_of_response:1; }","duration":"144.696255ms","start":"2026-07-09T15:40:37.677982Z","end":"2026-07-09T15:40:37.822679Z","steps":["trace[510264058] 'process raft request' (duration: 144.670815ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:40:37.822974Z","caller":"traceutil/trace.go:171","msg":"trace[524889867] transaction","detail":"{read_only:false; response_revision:1607; number_of_response:1; }","duration":"146.170186ms","start":"2026-07-09T15:40:37.676788Z","end":"2026-07-09T15:40:37.822959Z","steps":["trace[524889867] 'process raft request' (duration: 145.755715ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:40:37.823054Z","caller":"traceutil/trace.go:171","msg":"trace[1807731030] transaction","detail":"{read_only:false; response_revision:1608; number_of_response:1; }","duration":"146.122454ms","start":"2026-07-09T15:40:37.676926Z","end":"2026-07-09T15:40:37.823048Z","steps":["trace[1807731030] 'process raft request' (duration: 145.698563ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:40:42.255424Z","caller":"traceutil/trace.go:171","msg":"trace[1444977276] linearizableReadLoop","detail":"{readStateIndex:1738; appliedIndex:1737; }","duration":"156.729155ms","start":"2026-07-09T15:40:42.09867Z","end":"2026-07-09T15:40:42.255399Z","steps":["trace[1444977276] 'read index received' (duration: 156.587201ms)","trace[1444977276] 'applied index is now lower than readState.Index' (duration: 140.944µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:40:42.255481Z","caller":"traceutil/trace.go:171","msg":"trace[403277697] transaction","detail":"{read_only:false; response_revision:1649; number_of_response:1; }","duration":"160.89907ms","start":"2026-07-09T15:40:42.094561Z","end":"2026-07-09T15:40:42.25546Z","steps":["trace[403277697] 'process raft request' (duration: 160.693914ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:40:42.255747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.952081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-09T15:40:42.255731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.032753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-09T15:40:42.255782Z","caller":"traceutil/trace.go:171","msg":"trace[2047889943] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1649; }","duration":"103.036853ms","start":"2026-07-09T15:40:42.152736Z","end":"2026-07-09T15:40:42.255772Z","steps":["trace[2047889943] 'agreement among raft nodes before linearized reading' (duration: 102.90346ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:40:42.255808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.838024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-09T15:40:42.255813Z","caller":"traceutil/trace.go:171","msg":"trace[1499775671] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1649; }","duration":"157.169537ms","start":"2026-07-09T15:40:42.098626Z","end":"2026-07-09T15:40:42.255795Z","steps":["trace[1499775671] 'agreement among raft nodes before linearized reading' (duration: 156.9194ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:40:42.255844Z","caller":"traceutil/trace.go:171","msg":"trace[177143395] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1649; }","duration":"127.892645ms","start":"2026-07-09T15:40:42.127941Z","end":"2026-07-09T15:40:42.255834Z","steps":["trace[177143395] 'agreement among raft nodes before linearized reading' (duration: 127.774162ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:40:42.255669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.903163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:621"} {"level":"info","ts":"2026-07-09T15:40:42.255967Z","caller":"traceutil/trace.go:171","msg":"trace[384847786] range","detail":"{range_begin:/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1649; }","duration":"152.232592ms","start":"2026-07-09T15:40:42.103716Z","end":"2026-07-09T15:40:42.255949Z","steps":["trace[384847786] 'agreement among raft nodes before linearized reading' (duration: 151.822171ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:40:42.450959Z","caller":"traceutil/trace.go:171","msg":"trace[1102824262] transaction","detail":"{read_only:false; response_revision:1650; number_of_response:1; }","duration":"190.156985ms","start":"2026-07-09T15:40:42.260778Z","end":"2026-07-09T15:40:42.450935Z","steps":["trace[1102824262] 'process raft request' (duration: 156.948982ms)","trace[1102824262] 'compare' (duration: 32.869934ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:40:42.784918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.857478ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T15:40:42.785463Z","caller":"traceutil/trace.go:171","msg":"trace[1207910304] transaction","detail":"{read_only:false; response_revision:1651; number_of_response:1; }","duration":"524.519261ms","start":"2026-07-09T15:40:42.260911Z","end":"2026-07-09T15:40:42.78543Z","steps":["trace[1207910304] 'process raft request' (duration: 401.078906ms)","trace[1207910304] 'compare' (duration: 122.664453ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:40:42.785644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:40:42.260883Z","time spent":"524.684305ms","remote":"127.0.0.1:52404","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-07-09T15:40:42.785654Z","caller":"traceutil/trace.go:171","msg":"trace[650081340] linearizableReadLoop","detail":"{readStateIndex:1743; appliedIndex:1739; }","duration":"305.376668ms","start":"2026-07-09T15:40:42.480254Z","end":"2026-07-09T15:40:42.785631Z","steps":["trace[650081340] 'read index received' (duration: 181.633655ms)","trace[650081340] 'applied index is now lower than readState.Index' (duration: 123.741593ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:40:42.785691Z","caller":"traceutil/trace.go:171","msg":"trace[1843070829] transaction","detail":"{read_only:false; response_revision:1653; number_of_response:1; }","duration":"524.596323ms","start":"2026-07-09T15:40:42.261066Z","end":"2026-07-09T15:40:42.785663Z","steps":["trace[1843070829] 'process raft request' (duration: 524.321675ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:40:42.78576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.484141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T15:40:42.785811Z","caller":"traceutil/trace.go:171","msg":"trace[593628108] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1654; }","duration":"305.577534ms","start":"2026-07-09T15:40:42.480219Z","end":"2026-07-09T15:40:42.785797Z","steps":["trace[593628108] 'agreement among raft nodes before linearized reading' (duration: 305.488211ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:40:42.785764Z","caller":"traceutil/trace.go:171","msg":"trace[1721548862] transaction","detail":"{read_only:false; response_revision:1654; number_of_response:1; }","duration":"523.074331ms","start":"2026-07-09T15:40:42.262664Z","end":"2026-07-09T15:40:42.785739Z","steps":["trace[1721548862] 'process raft request' (duration: 522.772823ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:40:42.785862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:40:42.480203Z","time spent":"305.647886ms","remote":"127.0.0.1:52114","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-07-09T15:40:42.785531Z","caller":"traceutil/trace.go:171","msg":"trace[143143271] transaction","detail":"{read_only:false; response_revision:1652; number_of_response:1; }","duration":"524.516791ms","start":"2026-07-09T15:40:42.260992Z","end":"2026-07-09T15:40:42.785509Z","steps":["trace[143143271] 'process raft request' (duration: 524.278784ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:40:42.78611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:40:42.26097Z","time spent":"525.084696ms","remote":"127.0.0.1:52404","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-07-09T15:40:42.785838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:40:42.261043Z","time spent":"524.717486ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T15:40:42.786935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:40:42.262655Z","time spent":"523.292167ms","remote":"127.0.0.1:52230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":606,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T15:40:43.160507Z","caller":"traceutil/trace.go:171","msg":"trace[633085133] linearizableReadLoop","detail":"{readStateIndex:1744; appliedIndex:1743; }","duration":"326.987983ms","start":"2026-07-09T15:40:42.833492Z","end":"2026-07-09T15:40:43.16048Z","steps":["trace[633085133] 'read index received' (duration: 308.246887ms)","trace[633085133] 'applied index is now lower than readState.Index' (duration: 18.739966ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:40:43.1606Z","caller":"traceutil/trace.go:171","msg":"trace[14409467] transaction","detail":"{read_only:false; response_revision:1655; number_of_response:1; }","duration":"366.5453ms","start":"2026-07-09T15:40:42.794018Z","end":"2026-07-09T15:40:43.160563Z","steps":["trace[14409467] 'process raft request' (duration: 347.712772ms)","trace[14409467] 'compare' (duration: 18.633723ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:40:43.160745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:40:42.794Z","time spent":"366.671174ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":513,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T15:40:43.160836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.353442ms","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:528"} {"level":"info","ts":"2026-07-09T15:40:43.160885Z","caller":"traceutil/trace.go:171","msg":"trace[528727451] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1655; }","duration":"311.442205ms","start":"2026-07-09T15:40:42.849432Z","end":"2026-07-09T15:40:43.160875Z","steps":["trace[528727451] 'agreement among raft nodes before linearized reading' (duration: 311.27318ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:40:43.160884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.201266ms","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:510"} {"level":"info","ts":"2026-07-09T15:40:43.161023Z","caller":"traceutil/trace.go:171","msg":"trace[1528633780] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1655; }","duration":"308.36013ms","start":"2026-07-09T15:40:42.852628Z","end":"2026-07-09T15:40:43.160989Z","steps":["trace[1528633780] 'agreement among raft nodes before linearized reading' (duration: 308.081232ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:40:43.160931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:40:42.849413Z","time spent":"311.510277ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":552,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-09T15:40:43.161091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:40:42.852618Z","time spent":"308.458173ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":534,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-09T15:40:43.161156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.862069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:536"} {"level":"info","ts":"2026-07-09T15:40:43.161326Z","caller":"traceutil/trace.go:171","msg":"trace[1525704085] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1655; }","duration":"311.006503ms","start":"2026-07-09T15:40:42.850244Z","end":"2026-07-09T15:40:43.161251Z","steps":["trace[1525704085] 'agreement among raft nodes before linearized reading' (duration: 310.633533ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:40:43.161349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.768524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-09T15:40:43.161446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:40:42.850235Z","time spent":"311.139527ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":560,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-07-09T15:40:43.161474Z","caller":"traceutil/trace.go:171","msg":"trace[1721223013] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1655; }","duration":"328.001051ms","start":"2026-07-09T15:40:42.833459Z","end":"2026-07-09T15:40:43.16146Z","steps":["trace[1721223013] 'agreement among raft nodes before linearized reading' (duration: 327.163888ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:40:43.161524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:40:42.833446Z","time spent":"328.064702ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-09T15:45:24.046211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.796527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-leader-election-rolebinding\" ","response":"range_response_count:1 size:1146"} {"level":"info","ts":"2026-07-09T15:45:24.046273Z","caller":"traceutil/trace.go:171","msg":"trace[1582616122] range","detail":"{range_begin:/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-leader-election-rolebinding; range_end:; response_count:1; response_revision:3119; }","duration":"123.90783ms","start":"2026-07-09T15:45:23.922353Z","end":"2026-07-09T15:45:24.04626Z","steps":["trace[1582616122] 'range keys from in-memory index tree' (duration: 123.670503ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:45:24.295522Z","caller":"traceutil/trace.go:171","msg":"trace[247394857] transaction","detail":"{read_only:false; response_revision:3121; number_of_response:1; }","duration":"164.868391ms","start":"2026-07-09T15:45:24.130617Z","end":"2026-07-09T15:45:24.295486Z","steps":["trace[247394857] 'process raft request' (duration: 136.495868ms)","trace[247394857] 'compare' (duration: 28.203758ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:45:24.295628Z","caller":"traceutil/trace.go:171","msg":"trace[1078217997] linearizableReadLoop","detail":"{readStateIndex:3268; appliedIndex:3267; }","duration":"152.835949ms","start":"2026-07-09T15:45:24.142743Z","end":"2026-07-09T15:45:24.295579Z","steps":["trace[1078217997] 'read index received' (duration: 124.473596ms)","trace[1078217997] 'applied index is now lower than readState.Index' (duration: 28.357262ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:45:24.295681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.321682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/envoy-gateway-system/\" range_end:\"/registry/statefulsets/envoy-gateway-system0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T15:45:24.295534Z","caller":"traceutil/trace.go:171","msg":"trace[980026817] transaction","detail":"{read_only:false; response_revision:3122; number_of_response:1; }","duration":"150.185716ms","start":"2026-07-09T15:45:24.145321Z","end":"2026-07-09T15:45:24.295507Z","steps":["trace[980026817] 'process raft request' (duration: 150.133304ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:45:24.295746Z","caller":"traceutil/trace.go:171","msg":"trace[534473453] range","detail":"{range_begin:/registry/statefulsets/envoy-gateway-system/; range_end:/registry/statefulsets/envoy-gateway-system0; response_count:0; response_revision:3122; }","duration":"153.417295ms","start":"2026-07-09T15:45:24.142314Z","end":"2026-07-09T15:45:24.295731Z","steps":["trace[534473453] 'agreement among raft nodes before linearized reading' (duration: 153.297582ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:45:24.295963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.132955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-node-lease/\" range_end:\"/registry/configmaps/kube-node-lease0\" limit:500 ","response":"range_response_count:1 size:1754"} {"level":"warn","ts":"2026-07-09T15:45:24.296006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.787151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-leader-election-rolebinding\" ","response":"range_response_count:1 size:1146"} {"level":"info","ts":"2026-07-09T15:45:24.296056Z","caller":"traceutil/trace.go:171","msg":"trace[290157565] range","detail":"{range_begin:/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-leader-election-rolebinding; range_end:; response_count:1; response_revision:3122; }","duration":"163.863253ms","start":"2026-07-09T15:45:24.132175Z","end":"2026-07-09T15:45:24.296038Z","steps":["trace[290157565] 'agreement among raft nodes before linearized reading' (duration: 163.613436ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:45:24.296054Z","caller":"traceutil/trace.go:171","msg":"trace[1495999551] range","detail":"{range_begin:/registry/configmaps/kube-node-lease/; range_end:/registry/configmaps/kube-node-lease0; response_count:1; response_revision:3122; }","duration":"112.260369ms","start":"2026-07-09T15:45:24.18377Z","end":"2026-07-09T15:45:24.29603Z","steps":["trace[1495999551] 'agreement among raft nodes before linearized reading' (duration: 112.109525ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:45:24.296299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.64331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/\" range_end:\"/registry/events/default0\" limit:500 ","response":"range_response_count:23 size:15555"} {"level":"info","ts":"2026-07-09T15:45:24.296354Z","caller":"traceutil/trace.go:171","msg":"trace[689918307] range","detail":"{range_begin:/registry/events/default/; range_end:/registry/events/default0; response_count:23; response_revision:3122; }","duration":"166.712812ms","start":"2026-07-09T15:45:24.129628Z","end":"2026-07-09T15:45:24.296341Z","steps":["trace[689918307] 'agreement among raft nodes before linearized reading' (duration: 166.364782ms)"],"step_count":1}