{"level":"warn","ts":"2026-07-08T23:36:13.613169Z","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-08T23:36:13.613345Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.78:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.78:2380","--initial-cluster=instance=https://199.204.45.78:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.78:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.78: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-08T23:36:13.613594Z","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-08T23:36:13.613633Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.78:2380"]} {"level":"info","ts":"2026-07-08T23:36:13.613861Z","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-08T23:36:13.615206Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.78:2379"]} {"level":"info","ts":"2026-07-08T23:36:13.615515Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.78:2380"],"listen-peer-urls":["https://199.204.45.78:2380"],"advertise-client-urls":["https://199.204.45.78:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.78:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.78: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-08T23:36:13.620179Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.164957ms"} {"level":"info","ts":"2026-07-08T23:36:13.62636Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6eb8766b1ec86e92","cluster-id":"9bbfe9e5d45d3718"} {"level":"info","ts":"2026-07-08T23:36:13.626455Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 switched to configuration voters=()"} {"level":"info","ts":"2026-07-08T23:36:13.626538Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became follower at term 0"} {"level":"info","ts":"2026-07-08T23:36:13.626599Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6eb8766b1ec86e92 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-08T23:36:13.626647Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became follower at term 1"} {"level":"info","ts":"2026-07-08T23:36:13.626746Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 switched to configuration voters=(7978256942336863890)"} {"level":"warn","ts":"2026-07-08T23:36:13.638481Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-08T23:36:13.640337Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-08T23:36:13.641961Z","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-08T23:36:13.645241Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6eb8766b1ec86e92","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-08T23:36:13.645492Z","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-08T23:36:13.64564Z","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-08T23:36:13.645656Z","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-08T23:36:13.645992Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6eb8766b1ec86e92","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-08T23:36:13.648038Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 switched to configuration voters=(7978256942336863890)"} {"level":"info","ts":"2026-07-08T23:36:13.648316Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9bbfe9e5d45d3718","local-member-id":"6eb8766b1ec86e92","added-peer-id":"6eb8766b1ec86e92","added-peer-peer-urls":["https://199.204.45.78:2380"]} {"level":"info","ts":"2026-07-08T23:36:13.651237Z","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-08T23:36:13.651361Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.78:2380"} {"level":"info","ts":"2026-07-08T23:36:13.651457Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.78:2380"} {"level":"info","ts":"2026-07-08T23:36:13.651576Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6eb8766b1ec86e92","initial-advertise-peer-urls":["https://199.204.45.78:2380"],"listen-peer-urls":["https://199.204.45.78:2380"],"advertise-client-urls":["https://199.204.45.78:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.78:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-08T23:36:13.651641Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-08T23:36:14.527315Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 is starting a new election at term 1"} {"level":"info","ts":"2026-07-08T23:36:14.527402Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-08T23:36:14.527441Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 received MsgPreVoteResp from 6eb8766b1ec86e92 at term 1"} {"level":"info","ts":"2026-07-08T23:36:14.527463Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became candidate at term 2"} {"level":"info","ts":"2026-07-08T23:36:14.527481Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 received MsgVoteResp from 6eb8766b1ec86e92 at term 2"} {"level":"info","ts":"2026-07-08T23:36:14.527611Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became leader at term 2"} {"level":"info","ts":"2026-07-08T23:36:14.527627Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6eb8766b1ec86e92 elected leader 6eb8766b1ec86e92 at term 2"} {"level":"info","ts":"2026-07-08T23:36:14.528695Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T23:36:14.529597Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6eb8766b1ec86e92","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.78:2379]}","request-path":"/0/members/6eb8766b1ec86e92/attributes","cluster-id":"9bbfe9e5d45d3718","publish-timeout":"7s"} {"level":"info","ts":"2026-07-08T23:36:14.529604Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-08T23:36:14.529703Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-08T23:36:14.529878Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-08T23:36:14.529979Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-08T23:36:14.531912Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-08T23:36:14.532072Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9bbfe9e5d45d3718","local-member-id":"6eb8766b1ec86e92","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T23:36:14.53216Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T23:36:14.53221Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T23:36:14.536668Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.78:2379"} {"level":"warn","ts":"2026-07-08T23:36:16.968481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.406844ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-07-08T23:36:16.968599Z","caller":"traceutil/trace.go:171","msg":"trace[1081928580] linearizableReadLoop","detail":"{readStateIndex:74; appliedIndex:73; }","duration":"270.981669ms","start":"2026-07-08T23:36:16.6976Z","end":"2026-07-08T23:36:16.968582Z","steps":["trace[1081928580] 'read index received' (duration: 84.882µs)","trace[1081928580] 'applied index is now lower than readState.Index' (duration: 270.895417ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:36:16.968705Z","caller":"traceutil/trace.go:171","msg":"trace[1994904286] transaction","detail":"{read_only:false; response_revision:70; number_of_response:1; }","duration":"896.637728ms","start":"2026-07-08T23:36:16.071978Z","end":"2026-07-08T23:36:16.968615Z","steps":["trace[1994904286] 'process raft request' (duration: 330.596568ms)","trace[1994904286] 'compare' (duration: 565.228139ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T23:36:16.968869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.266887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-08T23:36:16.96891Z","caller":"traceutil/trace.go:171","msg":"trace[2101671827] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:70; }","duration":"271.35572ms","start":"2026-07-08T23:36:16.697543Z","end":"2026-07-08T23:36:16.968899Z","steps":["trace[2101671827] 'agreement among raft nodes before linearized reading' (duration: 271.243777ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:36:16.968947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.456026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-08T23:36:16.969012Z","caller":"traceutil/trace.go:171","msg":"trace[1166100794] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:70; }","duration":"270.562109ms","start":"2026-07-08T23:36:16.698434Z","end":"2026-07-08T23:36:16.968996Z","steps":["trace[1166100794] 'agreement among raft nodes before linearized reading' (duration: 270.442566ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:36:16.969231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.561841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-08T23:36:16.969271Z","caller":"traceutil/trace.go:171","msg":"trace[1503077361] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:70; }","duration":"165.642983ms","start":"2026-07-08T23:36:16.803619Z","end":"2026-07-08T23:36:16.969262Z","steps":["trace[1503077361] 'agreement among raft nodes before linearized reading' (duration: 165.573921ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:36:16.970022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:36:16.071951Z","time spent":"896.871754ms","remote":"127.0.0.1:43474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":651,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-08T23:36:17.473326Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967605805561166548,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-08T23:36:17.886565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.443111ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-07-08T23:36:17.886664Z","caller":"traceutil/trace.go:171","msg":"trace[433374808] linearizableReadLoop","detail":"{readStateIndex:75; appliedIndex:74; }","duration":"913.875773ms","start":"2026-07-08T23:36:16.972772Z","end":"2026-07-08T23:36:17.886648Z","steps":["trace[433374808] 'read index received' (duration: 592.233327ms)","trace[433374808] 'applied index is now lower than readState.Index' (duration: 321.641456ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:36:17.886888Z","caller":"traceutil/trace.go:171","msg":"trace[1958950838] transaction","detail":"{read_only:false; response_revision:71; number_of_response:1; }","duration":"914.624783ms","start":"2026-07-08T23:36:16.972251Z","end":"2026-07-08T23:36:17.886876Z","steps":["trace[1958950838] 'process raft request' (duration: 592.807582ms)","trace[1958950838] 'compare' (duration: 321.320228ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T23:36:17.886946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:36:16.972236Z","time spent":"914.688244ms","remote":"127.0.0.1:43428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-08T23:36:17.88717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"914.392947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-08T23:36:17.8872Z","caller":"traceutil/trace.go:171","msg":"trace[421737459] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:71; }","duration":"914.465099ms","start":"2026-07-08T23:36:16.972725Z","end":"2026-07-08T23:36:17.88719Z","steps":["trace[421737459] 'agreement among raft nodes before linearized reading' (duration: 914.364616ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:36:17.887229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:36:16.972704Z","time spent":"914.51765ms","remote":"127.0.0.1:43426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":27,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" "} {"level":"info","ts":"2026-07-08T23:36:18.132728Z","caller":"traceutil/trace.go:171","msg":"trace[1899824939] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:75; }","duration":"239.221731ms","start":"2026-07-08T23:36:17.893487Z","end":"2026-07-08T23:36:18.132709Z","steps":["trace[1899824939] 'read index received' (duration: 239.145949ms)","trace[1899824939] 'applied index is now lower than readState.Index' (duration: 74.612µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:36:18.132822Z","caller":"traceutil/trace.go:171","msg":"trace[1872962884] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"239.742124ms","start":"2026-07-08T23:36:17.893073Z","end":"2026-07-08T23:36:18.132815Z","steps":["trace[1872962884] 'process raft request' (duration: 239.535319ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:36:18.133006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.498788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-08T23:36:18.133142Z","caller":"traceutil/trace.go:171","msg":"trace[1721624991] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-view; range_end:; response_count:0; response_revision:72; }","duration":"239.662642ms","start":"2026-07-08T23:36:17.893458Z","end":"2026-07-08T23:36:18.13312Z","steps":["trace[1721624991] 'agreement among raft nodes before linearized reading' (duration: 239.456997ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:36:18.133629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.306082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-08T23:36:18.133788Z","caller":"traceutil/trace.go:171","msg":"trace[1412478188] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:72; }","duration":"130.524027ms","start":"2026-07-08T23:36:18.00325Z","end":"2026-07-08T23:36:18.133774Z","steps":["trace[1412478188] 'agreement among raft nodes before linearized reading' (duration: 130.301301ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:36:50.91948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.098699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T23:36:50.919589Z","caller":"traceutil/trace.go:171","msg":"trace[1397974285] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:506; }","duration":"586.256162ms","start":"2026-07-08T23:36:50.333311Z","end":"2026-07-08T23:36:50.919567Z","steps":["trace[1397974285] 'range keys from in-memory index tree' (duration: 586.027447ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:36:50.919643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:36:50.333297Z","time spent":"586.332815ms","remote":"127.0.0.1:43184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"info","ts":"2026-07-08T23:36:51.169871Z","caller":"traceutil/trace.go:171","msg":"trace[384002776] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"113.59783ms","start":"2026-07-08T23:36:51.056243Z","end":"2026-07-08T23:36:51.169841Z","steps":["trace[384002776] 'process raft request' (duration: 112.8098ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:36:51.254483Z","caller":"traceutil/trace.go:171","msg":"trace[128821246] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"158.076177ms","start":"2026-07-08T23:36:51.096388Z","end":"2026-07-08T23:36:51.254464Z","steps":["trace[128821246] 'process raft request' (duration: 157.413ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:36:51.254606Z","caller":"traceutil/trace.go:171","msg":"trace[1421740998] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"158.123948ms","start":"2026-07-08T23:36:51.096475Z","end":"2026-07-08T23:36:51.254598Z","steps":["trace[1421740998] 'process raft request' (duration: 157.42131ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:36:51.254683Z","caller":"traceutil/trace.go:171","msg":"trace[757377229] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"155.733597ms","start":"2026-07-08T23:36:51.098944Z","end":"2026-07-08T23:36:51.254678Z","steps":["trace[757377229] 'process raft request' (duration: 154.980558ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:36:51.254764Z","caller":"traceutil/trace.go:171","msg":"trace[1586405709] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"147.576027ms","start":"2026-07-08T23:36:51.107182Z","end":"2026-07-08T23:36:51.254758Z","steps":["trace[1586405709] 'process raft request' (duration: 146.768566ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:36:51.254884Z","caller":"traceutil/trace.go:171","msg":"trace[91513550] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"147.669239ms","start":"2026-07-08T23:36:51.10721Z","end":"2026-07-08T23:36:51.254879Z","steps":["trace[91513550] 'process raft request' (duration: 146.769775ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:36:51.254933Z","caller":"traceutil/trace.go:171","msg":"trace[1538626442] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"145.191315ms","start":"2026-07-08T23:36:51.109736Z","end":"2026-07-08T23:36:51.254927Z","steps":["trace[1538626442] 'process raft request' (duration: 144.269911ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:36:51.25498Z","caller":"traceutil/trace.go:171","msg":"trace[874811924] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"145.204335ms","start":"2026-07-08T23:36:51.109771Z","end":"2026-07-08T23:36:51.254975Z","steps":["trace[874811924] 'process raft request' (duration: 144.258061ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:36:51.255023Z","caller":"traceutil/trace.go:171","msg":"trace[1159783113] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"144.432355ms","start":"2026-07-08T23:36:51.110586Z","end":"2026-07-08T23:36:51.255018Z","steps":["trace[1159783113] 'process raft request' (duration: 143.46801ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:36:51.255072Z","caller":"traceutil/trace.go:171","msg":"trace[1547348241] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"138.262486ms","start":"2026-07-08T23:36:51.116805Z","end":"2026-07-08T23:36:51.255067Z","steps":["trace[1547348241] 'process raft request' (duration: 137.276851ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:36:51.255116Z","caller":"traceutil/trace.go:171","msg":"trace[1528730159] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"135.179967ms","start":"2026-07-08T23:36:51.119932Z","end":"2026-07-08T23:36:51.255112Z","steps":["trace[1528730159] 'process raft request' (duration: 134.183671ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:36:51.25516Z","caller":"traceutil/trace.go:171","msg":"trace[551804975] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"120.411016ms","start":"2026-07-08T23:36:51.134744Z","end":"2026-07-08T23:36:51.255155Z","steps":["trace[551804975] 'process raft request' (duration: 119.40496ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:36:51.416119Z","caller":"traceutil/trace.go:171","msg":"trace[1226719497] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"150.262966ms","start":"2026-07-08T23:36:51.265823Z","end":"2026-07-08T23:36:51.416086Z","steps":["trace[1226719497] 'process raft request' (duration: 81.267946ms)","trace[1226719497] 'compare' (duration: 68.691272ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:36:51.416439Z","caller":"traceutil/trace.go:171","msg":"trace[2043298353] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"144.145439ms","start":"2026-07-08T23:36:51.272268Z","end":"2026-07-08T23:36:51.416414Z","steps":["trace[2043298353] 'process raft request' (duration: 143.908672ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:36:51.416657Z","caller":"traceutil/trace.go:171","msg":"trace[602842017] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"150.544863ms","start":"2026-07-08T23:36:51.2661Z","end":"2026-07-08T23:36:51.416645Z","steps":["trace[602842017] 'process raft request' (duration: 149.902067ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:36:51.41681Z","caller":"traceutil/trace.go:171","msg":"trace[450071613] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"143.495951ms","start":"2026-07-08T23:36:51.273294Z","end":"2026-07-08T23:36:51.41679Z","steps":["trace[450071613] 'process raft request' (duration: 142.934357ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:36:51.417032Z","caller":"traceutil/trace.go:171","msg":"trace[350575940] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"141.49061ms","start":"2026-07-08T23:36:51.27553Z","end":"2026-07-08T23:36:51.41702Z","steps":["trace[350575940] 'process raft request' (duration: 140.947166ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:36:51.417158Z","caller":"traceutil/trace.go:171","msg":"trace[2012242531] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"141.411638ms","start":"2026-07-08T23:36:51.275679Z","end":"2026-07-08T23:36:51.417091Z","steps":["trace[2012242531] 'process raft request' (duration: 141.002367ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:36:51.416646Z","caller":"traceutil/trace.go:171","msg":"trace[2080177809] transaction","detail":"{read_only:false; number_of_response:1; response_revision:521; }","duration":"147.581187ms","start":"2026-07-08T23:36:51.269044Z","end":"2026-07-08T23:36:51.416625Z","steps":["trace[2080177809] 'process raft request' (duration: 147.090234ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:36:51.417369Z","caller":"traceutil/trace.go:171","msg":"trace[662971317] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"140.116955ms","start":"2026-07-08T23:36:51.277229Z","end":"2026-07-08T23:36:51.417346Z","steps":["trace[662971317] 'process raft request' (duration: 139.622082ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:36:51.423507Z","caller":"traceutil/trace.go:171","msg":"trace[1864163550] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"135.758022ms","start":"2026-07-08T23:36:51.287723Z","end":"2026-07-08T23:36:51.423481Z","steps":["trace[1864163550] 'process raft request' (duration: 131.526383ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:36:51.424094Z","caller":"traceutil/trace.go:171","msg":"trace[614939510] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"146.800097ms","start":"2026-07-08T23:36:51.277276Z","end":"2026-07-08T23:36:51.424077Z","steps":["trace[614939510] 'process raft request' (duration: 139.965181ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:36:51.424195Z","caller":"traceutil/trace.go:171","msg":"trace[1786293836] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"139.253852ms","start":"2026-07-08T23:36:51.279106Z","end":"2026-07-08T23:36:51.41836Z","steps":["trace[1786293836] 'process raft request' (duration: 138.567594ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:36:51.956231Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967605805561167499,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-08T23:36:52.233275Z","caller":"traceutil/trace.go:171","msg":"trace[1239378402] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"801.541656ms","start":"2026-07-08T23:36:51.4316Z","end":"2026-07-08T23:36:52.233142Z","steps":["trace[1239378402] 'process raft request' (duration: 795.612903ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:36:52.233754Z","caller":"traceutil/trace.go:171","msg":"trace[72263646] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"802.113641ms","start":"2026-07-08T23:36:51.431606Z","end":"2026-07-08T23:36:52.233719Z","steps":["trace[72263646] 'process raft request' (duration: 801.489835ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:36:52.233864Z","caller":"traceutil/trace.go:171","msg":"trace[953738065] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"802.159762ms","start":"2026-07-08T23:36:51.431654Z","end":"2026-07-08T23:36:52.233813Z","steps":["trace[953738065] 'process raft request' (duration: 801.955317ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:36:52.234055Z","caller":"traceutil/trace.go:171","msg":"trace[1769309544] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"792.640006ms","start":"2026-07-08T23:36:51.441399Z","end":"2026-07-08T23:36:52.234038Z","steps":["trace[1769309544] 'process raft request' (duration: 792.442941ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:36:52.234087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:36:51.431585Z","time spent":"802.241834ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T23:36:52.234132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:36:51.441377Z","time spent":"792.705938ms","remote":"127.0.0.1:43136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T23:36:52.234205Z","caller":"traceutil/trace.go:171","msg":"trace[1164579387] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"788.096459ms","start":"2026-07-08T23:36:51.446056Z","end":"2026-07-08T23:36:52.234153Z","steps":["trace[1164579387] 'process raft request' (duration: 787.871543ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:36:52.234342Z","caller":"traceutil/trace.go:171","msg":"trace[694078599] linearizableReadLoop","detail":"{readStateIndex:559; appliedIndex:550; }","duration":"778.358687ms","start":"2026-07-08T23:36:51.45596Z","end":"2026-07-08T23:36:52.234319Z","steps":["trace[694078599] 'read index received' (duration: 771.318896ms)","trace[694078599] 'applied index is now lower than readState.Index' (duration: 7.038751ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:36:52.234426Z","caller":"traceutil/trace.go:171","msg":"trace[674575798] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"758.701261ms","start":"2026-07-08T23:36:51.475709Z","end":"2026-07-08T23:36:52.23441Z","steps":["trace[674575798] 'process raft request' (duration: 758.383382ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:36:52.234462Z","caller":"traceutil/trace.go:171","msg":"trace[276543875] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"731.864879ms","start":"2026-07-08T23:36:51.502586Z","end":"2026-07-08T23:36:52.23445Z","steps":["trace[276543875] 'process raft request' (duration: 731.603902ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:36:52.234489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:36:51.475689Z","time spent":"758.773742ms","remote":"127.0.0.1:43136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-08T23:36:52.234436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:36:51.446042Z","time spent":"788.301784ms","remote":"127.0.0.1:43136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T23:36:52.234582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.156092ms","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-08T23:36:52.234629Z","caller":"traceutil/trace.go:171","msg":"trace[409509165] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:537; }","duration":"752.218913ms","start":"2026-07-08T23:36:51.482398Z","end":"2026-07-08T23:36:52.234616Z","steps":["trace[409509165] 'agreement among raft nodes before linearized reading' (duration: 752.068259ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:36:52.234663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:36:51.482391Z","time spent":"752.264354ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-08T23:36:52.234707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"778.744257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha1.gateway.networking.x-k8s.io\" ","response":"range_response_count:1 size:1019"} {"level":"info","ts":"2026-07-08T23:36:52.234748Z","caller":"traceutil/trace.go:171","msg":"trace[406022219] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1alpha1.gateway.networking.x-k8s.io; range_end:; response_count:1; response_revision:537; }","duration":"778.810559ms","start":"2026-07-08T23:36:51.455927Z","end":"2026-07-08T23:36:52.234737Z","steps":["trace[406022219] 'agreement among raft nodes before linearized reading' (duration: 778.692986ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:36:52.234579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:36:51.502563Z","time spent":"731.93359ms","remote":"127.0.0.1:43136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T23:36:52.234782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:36:51.455912Z","time spent":"778.862721ms","remote":"127.0.0.1:43610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1042,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha1.gateway.networking.x-k8s.io\" "} {"level":"warn","ts":"2026-07-08T23:36:52.234796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.045202ms","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-08T23:36:52.233888Z","caller":"traceutil/trace.go:171","msg":"trace[1734424951] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"802.155832ms","start":"2026-07-08T23:36:51.43171Z","end":"2026-07-08T23:36:52.233866Z","steps":["trace[1734424951] 'process raft request' (duration: 801.989497ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:36:52.234831Z","caller":"traceutil/trace.go:171","msg":"trace[756825092] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:537; }","duration":"618.115264ms","start":"2026-07-08T23:36:51.616705Z","end":"2026-07-08T23:36:52.234821Z","steps":["trace[756825092] 'agreement among raft nodes before linearized reading' (duration: 617.995161ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:36:52.23487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:36:51.616686Z","time spent":"618.177346ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-08T23:36:52.234893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:36:51.431703Z","time spent":"803.154857ms","remote":"127.0.0.1:43136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T23:36:52.233938Z","caller":"traceutil/trace.go:171","msg":"trace[466911000] transaction","detail":"{read_only:false; number_of_response:1; response_revision:533; }","duration":"798.310482ms","start":"2026-07-08T23:36:51.435616Z","end":"2026-07-08T23:36:52.233927Z","steps":["trace[466911000] 'process raft request' (duration: 798.181029ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:36:52.235341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:36:51.435606Z","time spent":"799.696958ms","remote":"127.0.0.1:43136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7375,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T23:36:52.235677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:36:51.431638Z","time spent":"802.348007ms","remote":"127.0.0.1:43136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9125,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T23:36:52.235909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:36:51.431589Z","time spent":"801.765001ms","remote":"127.0.0.1:43136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7326,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T23:36:58.501508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.936223ms","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-08T23:36:58.501607Z","caller":"traceutil/trace.go:171","msg":"trace[2077472195] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:614; }","duration":"234.062296ms","start":"2026-07-08T23:36:58.267525Z","end":"2026-07-08T23:36:58.501587Z","steps":["trace[2077472195] 'range keys from in-memory index tree' (duration: 233.81583ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:36:58.50153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.488898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-07-08T23:36:58.501623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.147836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-08T23:36:58.501701Z","caller":"traceutil/trace.go:171","msg":"trace[699427873] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:614; }","duration":"247.701963ms","start":"2026-07-08T23:36:58.253983Z","end":"2026-07-08T23:36:58.501685Z","steps":["trace[699427873] 'range keys from in-memory index tree' (duration: 247.371805ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:36:58.501719Z","caller":"traceutil/trace.go:171","msg":"trace[604754220] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:614; }","duration":"231.271999ms","start":"2026-07-08T23:36:58.270426Z","end":"2026-07-08T23:36:58.501698Z","steps":["trace[604754220] 'range keys from in-memory index tree' (duration: 231.064614ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:36:58.582539Z","caller":"traceutil/trace.go:171","msg":"trace[1282707755] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:637; }","duration":"231.122895ms","start":"2026-07-08T23:36:58.351362Z","end":"2026-07-08T23:36:58.582485Z","steps":["trace[1282707755] 'read index received' (duration: 222.615251ms)","trace[1282707755] 'applied index is now lower than readState.Index' (duration: 8.506744ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:36:58.58253Z","caller":"traceutil/trace.go:171","msg":"trace[89566075] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"244.25665ms","start":"2026-07-08T23:36:58.338251Z","end":"2026-07-08T23:36:58.582507Z","steps":["trace[89566075] 'process raft request' (duration: 243.046611ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:36:58.582676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.298489ms","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-08T23:36:58.582712Z","caller":"traceutil/trace.go:171","msg":"trace[1290096754] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:615; }","duration":"231.366921ms","start":"2026-07-08T23:36:58.351335Z","end":"2026-07-08T23:36:58.582702Z","steps":["trace[1290096754] 'agreement among raft nodes before linearized reading' (duration: 231.263799ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:36:58.960641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.655876ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T23:36:58.960812Z","caller":"traceutil/trace.go:171","msg":"trace[1293696827] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"456.073183ms","start":"2026-07-08T23:36:58.504705Z","end":"2026-07-08T23:36:58.960778Z","steps":["trace[1293696827] 'process raft request' (duration: 297.216632ms)","trace[1293696827] 'compare' (duration: 158.38642ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:36:58.960915Z","caller":"traceutil/trace.go:171","msg":"trace[1101697935] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"329.229369ms","start":"2026-07-08T23:36:58.631672Z","end":"2026-07-08T23:36:58.960901Z","steps":["trace[1101697935] 'process raft request' (duration: 329.179048ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:36:58.960961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:36:58.504689Z","time spent":"456.197126ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T23:36:58.960979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:36:58.631646Z","time spent":"329.305551ms","remote":"127.0.0.1:43318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T23:36:58.960948Z","caller":"traceutil/trace.go:171","msg":"trace[6648982] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:638; }","duration":"378.300806ms","start":"2026-07-08T23:36:58.582597Z","end":"2026-07-08T23:36:58.960898Z","steps":["trace[6648982] 'read index received' (duration: 219.337272ms)","trace[6648982] 'applied index is now lower than readState.Index' (duration: 158.956734ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:36:58.961069Z","caller":"traceutil/trace.go:171","msg":"trace[1800109234] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"454.906025ms","start":"2026-07-08T23:36:58.506076Z","end":"2026-07-08T23:36:58.960982Z","steps":["trace[1800109234] 'process raft request' (duration: 454.67869ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:36:58.96113Z","caller":"traceutil/trace.go:171","msg":"trace[1137056903] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"375.10114ms","start":"2026-07-08T23:36:58.586021Z","end":"2026-07-08T23:36:58.961123Z","steps":["trace[1137056903] 'process raft request' (duration: 374.761041ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:36:58.961176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:36:58.586011Z","time spent":"375.141281ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T23:36:58.961278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:36:58.506049Z","time spent":"455.094679ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T23:36:58.96118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.846684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-mt7kq\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-07-08T23:36:58.961521Z","caller":"traceutil/trace.go:171","msg":"trace[1897361598] range","detail":"{range_begin:/registry/pods/kube-system/cilium-mt7kq; range_end:; response_count:1; response_revision:620; }","duration":"475.222433ms","start":"2026-07-08T23:36:58.486282Z","end":"2026-07-08T23:36:58.961505Z","steps":["trace[1897361598] 'agreement among raft nodes before linearized reading' (duration: 474.780192ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:36:58.961181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.838489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.78\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-08T23:36:58.96165Z","caller":"traceutil/trace.go:171","msg":"trace[2126262068] range","detail":"{range_begin:/registry/masterleases/199.204.45.78; range_end:; response_count:1; response_revision:620; }","duration":"156.370572ms","start":"2026-07-08T23:36:58.805265Z","end":"2026-07-08T23:36:58.961636Z","steps":["trace[2126262068] 'agreement among raft nodes before linearized reading' (duration: 155.818699ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:36:58.960893Z","caller":"traceutil/trace.go:171","msg":"trace[1139811103] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"455.042438ms","start":"2026-07-08T23:36:58.505824Z","end":"2026-07-08T23:36:58.960866Z","steps":["trace[1139811103] 'process raft request' (duration: 454.894615ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:36:58.96161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:36:58.486262Z","time spent":"475.333225ms","remote":"127.0.0.1:43290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-mt7kq\" "} {"level":"warn","ts":"2026-07-08T23:36:58.961768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:36:58.505818Z","time spent":"455.902279ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T23:36:59.478383Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967605805561167723,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-08T23:36:59.979461Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967605805561167723,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-08T23:37:00.232699Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.257876271s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-08T23:37:00.303649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.022793ms","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-08T23:37:00.303758Z","caller":"traceutil/trace.go:171","msg":"trace[1577325289] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:620; }","duration":"266.139606ms","start":"2026-07-08T23:37:00.037599Z","end":"2026-07-08T23:37:00.303739Z","steps":["trace[1577325289] 'range keys from in-memory index tree' (duration: 266.003962ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:37:00.303872Z","caller":"traceutil/trace.go:171","msg":"trace[1299784085] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"1.329308344s","start":"2026-07-08T23:36:58.974503Z","end":"2026-07-08T23:37:00.303811Z","steps":["trace[1299784085] 'process raft request' (duration: 1.258637488s)","trace[1299784085] 'compare' (duration: 70.388979ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T23:37:00.304011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:36:58.974491Z","time spent":"1.329443458s","remote":"127.0.0.1:43290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T23:37:00.345017Z","caller":"traceutil/trace.go:171","msg":"trace[62933212] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:644; }","duration":"1.367624123s","start":"2026-07-08T23:36:58.977376Z","end":"2026-07-08T23:37:00.345Z","steps":["trace[62933212] 'read index received' (duration: 1.255688118s)","trace[62933212] 'applied index is now lower than readState.Index' (duration: 111.934985ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:37:00.345098Z","caller":"traceutil/trace.go:171","msg":"trace[403101712] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"1.369479608s","start":"2026-07-08T23:36:58.975557Z","end":"2026-07-08T23:37:00.345036Z","steps":["trace[403101712] 'process raft request' (duration: 1.369347775s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:37:00.345185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.367791887s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-07-08T23:37:00.345226Z","caller":"traceutil/trace.go:171","msg":"trace[1184810595] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:622; }","duration":"1.367856509s","start":"2026-07-08T23:36:58.977357Z","end":"2026-07-08T23:37:00.345213Z","steps":["trace[1184810595] 'agreement among raft nodes before linearized reading' (duration: 1.367737586s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:37:00.345229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.172357749s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-08T23:37:00.345253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:36:58.975542Z","time spent":"1.369629431s","remote":"127.0.0.1:43112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T23:37:00.345311Z","caller":"traceutil/trace.go:171","msg":"trace[495201836] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:622; }","duration":"1.172492551s","start":"2026-07-08T23:36:59.172798Z","end":"2026-07-08T23:37:00.345291Z","steps":["trace[495201836] 'agreement among raft nodes before linearized reading' (duration: 1.172348918s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:37:00.345266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:36:58.977351Z","time spent":"1.36790651s","remote":"127.0.0.1:43296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-07-08T23:37:00.345359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:36:59.172777Z","time spent":"1.172574504s","remote":"127.0.0.1:43080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-08T23:37:01.909956Z","caller":"traceutil/trace.go:171","msg":"trace[1558498273] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"282.19655ms","start":"2026-07-08T23:37:01.627732Z","end":"2026-07-08T23:37:01.909928Z","steps":["trace[1558498273] 'process raft request' (duration: 281.297889ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:37:16.086118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.352649666s","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-08T23:37:16.086242Z","caller":"traceutil/trace.go:171","msg":"trace[895992203] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:780; }","duration":"1.352829619s","start":"2026-07-08T23:37:14.733389Z","end":"2026-07-08T23:37:16.086219Z","steps":["trace[895992203] 'count revisions from in-memory index tree' (duration: 1.352564823s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:37:16.086249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"912.791361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-08T23:37:16.086529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:37:14.733371Z","time spent":"1.352922822s","remote":"127.0.0.1:43244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-07-08T23:37:16.086597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.056241743s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-07-08T23:37:16.086603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.049518072s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-08T23:37:16.086654Z","caller":"traceutil/trace.go:171","msg":"trace[2099199282] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:780; }","duration":"1.056329565s","start":"2026-07-08T23:37:15.030305Z","end":"2026-07-08T23:37:16.086634Z","steps":["trace[2099199282] 'range keys from in-memory index tree' (duration: 1.056001097s)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:37:16.086699Z","caller":"traceutil/trace.go:171","msg":"trace[1173221752] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:780; }","duration":"1.049675786s","start":"2026-07-08T23:37:15.037Z","end":"2026-07-08T23:37:16.086676Z","steps":["trace[1173221752] 'range keys from in-memory index tree' (duration: 1.049303767s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:37:16.08671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:37:15.030284Z","time spent":"1.056416888s","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-08T23:37:16.086751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:37:15.036976Z","time spent":"1.049765228s","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-08T23:37:16.08695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.049986623s","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-08T23:37:16.086982Z","caller":"traceutil/trace.go:171","msg":"trace[667708771] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:780; }","duration":"1.050042574s","start":"2026-07-08T23:37:15.036928Z","end":"2026-07-08T23:37:16.08697Z","steps":["trace[667708771] 'range keys from in-memory index tree' (duration: 1.04986595s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:37:16.087008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:37:15.036909Z","time spent":"1.050092186s","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-08T23:37:16.087344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.048430306s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-08T23:37:16.087379Z","caller":"traceutil/trace.go:171","msg":"trace[212825496] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:780; }","duration":"1.048482037s","start":"2026-07-08T23:37:15.038886Z","end":"2026-07-08T23:37:16.087368Z","steps":["trace[212825496] 'range keys from in-memory index tree' (duration: 1.048308563s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:37:16.087414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:37:15.038875Z","time spent":"1.048532028s","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-08T23:37:16.087575Z","caller":"traceutil/trace.go:171","msg":"trace[794786034] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:780; }","duration":"912.969656ms","start":"2026-07-08T23:37:15.173396Z","end":"2026-07-08T23:37:16.086366Z","steps":["trace[794786034] 'range keys from in-memory index tree' (duration: 912.688349ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:37:16.08762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:37:15.173329Z","time spent":"914.281426ms","remote":"127.0.0.1:43080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-08T23:37:16.089928Z","caller":"traceutil/trace.go:171","msg":"trace[270673826] linearizableReadLoop","detail":"{readStateIndex:809; appliedIndex:808; }","duration":"111.078155ms","start":"2026-07-08T23:37:15.978827Z","end":"2026-07-08T23:37:16.089905Z","steps":["trace[270673826] 'read index received' (duration: 110.223204ms)","trace[270673826] 'applied index is now lower than readState.Index' (duration: 853.871µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:37:16.090008Z","caller":"traceutil/trace.go:171","msg":"trace[725652835] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"484.858674ms","start":"2026-07-08T23:37:15.605124Z","end":"2026-07-08T23:37:16.089983Z","steps":["trace[725652835] 'process raft request' (duration: 483.89951ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:37:16.090122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.28336ms","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-08T23:37:16.090124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:37:15.605099Z","time spent":"484.954366ms","remote":"127.0.0.1:43318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T23:37:16.090152Z","caller":"traceutil/trace.go:171","msg":"trace[1451549999] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:781; }","duration":"111.366412ms","start":"2026-07-08T23:37:15.978777Z","end":"2026-07-08T23:37:16.090143Z","steps":["trace[1451549999] 'agreement among raft nodes before linearized reading' (duration: 111.235059ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:37:50.401138Z","caller":"traceutil/trace.go:171","msg":"trace[670673358] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"145.645034ms","start":"2026-07-08T23:37:50.255467Z","end":"2026-07-08T23:37:50.401112Z","steps":["trace[670673358] 'process raft request' (duration: 145.428829ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:37:50.401196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.712172ms","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-08T23:37:50.401271Z","caller":"traceutil/trace.go:171","msg":"trace[1516978745] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1000; }","duration":"144.830545ms","start":"2026-07-08T23:37:50.256421Z","end":"2026-07-08T23:37:50.401251Z","steps":["trace[1516978745] 'agreement among raft nodes before linearized reading' (duration: 144.630331ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:37:50.401266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.639296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-08T23:37:50.401347Z","caller":"traceutil/trace.go:171","msg":"trace[1070858058] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1000; }","duration":"143.751759ms","start":"2026-07-08T23:37:50.25758Z","end":"2026-07-08T23:37:50.401332Z","steps":["trace[1070858058] 'agreement among raft nodes before linearized reading' (duration: 143.541704ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:37:51.649331Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.248451445s","expected-duration":"1s"} {"level":"info","ts":"2026-07-08T23:37:51.649707Z","caller":"traceutil/trace.go:171","msg":"trace[2014311736] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"1.295500224s","start":"2026-07-08T23:37:50.354183Z","end":"2026-07-08T23:37:51.649683Z","steps":["trace[2014311736] 'process raft request' (duration: 1.29534314s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:37:51.649853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:37:50.354163Z","time spent":"1.295616457s","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T23:37:52.03418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.709147ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T23:37:52.034381Z","caller":"traceutil/trace.go:171","msg":"trace[1639186964] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"1.629661841s","start":"2026-07-08T23:37:50.404693Z","end":"2026-07-08T23:37:52.034354Z","steps":["trace[1639186964] 'process raft request' (duration: 1.315537064s)","trace[1639186964] 'compare' (duration: 313.530503ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T23:37:52.034512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:37:50.404671Z","time spent":"1.629764414s","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T23:37:52.034513Z","caller":"traceutil/trace.go:171","msg":"trace[1948106835] linearizableReadLoop","detail":"{readStateIndex:1041; appliedIndex:1037; }","duration":"855.846975ms","start":"2026-07-08T23:37:51.17865Z","end":"2026-07-08T23:37:52.034497Z","steps":["trace[1948106835] 'read index received' (duration: 470.925969ms)","trace[1948106835] 'applied index is now lower than readState.Index' (duration: 384.919976ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:37:52.034605Z","caller":"traceutil/trace.go:171","msg":"trace[1083011843] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"1.182250236s","start":"2026-07-08T23:37:50.852344Z","end":"2026-07-08T23:37:52.034594Z","steps":["trace[1083011843] 'process raft request' (duration: 1.182025941s)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:37:52.034623Z","caller":"traceutil/trace.go:171","msg":"trace[211853958] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"1.629329473s","start":"2026-07-08T23:37:50.405266Z","end":"2026-07-08T23:37:52.034596Z","steps":["trace[211853958] 'process raft request' (duration: 1.629011405s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:37:52.034672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:37:50.852317Z","time spent":"1.182314088s","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T23:37:52.034749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:37:50.405253Z","time spent":"1.629428916s","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T23:37:52.034804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.039877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-08T23:37:52.034817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"856.158862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T23:37:52.034837Z","caller":"traceutil/trace.go:171","msg":"trace[1144664175] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1004; }","duration":"570.106208ms","start":"2026-07-08T23:37:51.464719Z","end":"2026-07-08T23:37:52.034825Z","steps":["trace[1144664175] 'agreement among raft nodes before linearized reading' (duration: 570.027277ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:37:52.034854Z","caller":"traceutil/trace.go:171","msg":"trace[1200176833] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1004; }","duration":"856.231804ms","start":"2026-07-08T23:37:51.178611Z","end":"2026-07-08T23:37:52.034843Z","steps":["trace[1200176833] 'agreement among raft nodes before linearized reading' (duration: 856.157292ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:37:52.034861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:37:51.464704Z","time spent":"570.151699ms","remote":"127.0.0.1:43296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":43,"response size":30,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-07-08T23:37:52.034878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:37:51.178594Z","time spent":"856.278955ms","remote":"127.0.0.1:43080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-08T23:37:52.034897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.353756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-08T23:37:52.034931Z","caller":"traceutil/trace.go:171","msg":"trace[551062890] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1004; }","duration":"435.425258ms","start":"2026-07-08T23:37:51.599495Z","end":"2026-07-08T23:37:52.034921Z","steps":["trace[551062890] 'agreement among raft nodes before linearized reading' (duration: 435.369046ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:37:52.034962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:37:51.599475Z","time spent":"435.479229ms","remote":"127.0.0.1:43280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"info","ts":"2026-07-08T23:38:14.4261Z","caller":"traceutil/trace.go:171","msg":"trace[1327918687] linearizableReadLoop","detail":"{readStateIndex:1116; appliedIndex:1115; }","duration":"307.506616ms","start":"2026-07-08T23:38:14.118569Z","end":"2026-07-08T23:38:14.426076Z","steps":["trace[1327918687] 'read index received' (duration: 307.353483ms)","trace[1327918687] 'applied index is now lower than readState.Index' (duration: 152.023µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:38:14.426215Z","caller":"traceutil/trace.go:171","msg":"trace[1420468407] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"325.083706ms","start":"2026-07-08T23:38:14.101102Z","end":"2026-07-08T23:38:14.426185Z","steps":["trace[1420468407] 'process raft request' (duration: 324.83809ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:38:14.426369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:38:14.101066Z","time spent":"325.213379ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T23:38:14.426392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.809893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-08T23:38:14.426466Z","caller":"traceutil/trace.go:171","msg":"trace[1928664366] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1075; }","duration":"307.924286ms","start":"2026-07-08T23:38:14.118523Z","end":"2026-07-08T23:38:14.426447Z","steps":["trace[1928664366] 'agreement among raft nodes before linearized reading' (duration: 307.6734ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:38:14.42653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:38:14.118499Z","time spent":"308.006788ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-08T23:38:14.92677Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967605805561168700,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-08T23:38:15.145732Z","caller":"traceutil/trace.go:171","msg":"trace[1474848098] linearizableReadLoop","detail":"{readStateIndex:1118; appliedIndex:1116; }","duration":"719.542391ms","start":"2026-07-08T23:38:14.426167Z","end":"2026-07-08T23:38:15.14571Z","steps":["trace[1474848098] 'read index received' (duration: 645.803897ms)","trace[1474848098] 'applied index is now lower than readState.Index' (duration: 73.737604ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T23:38:15.146107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"973.63603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T23:38:15.1462Z","caller":"traceutil/trace.go:171","msg":"trace[771523274] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1076; }","duration":"973.779404ms","start":"2026-07-08T23:38:14.172403Z","end":"2026-07-08T23:38:15.146182Z","steps":["trace[771523274] 'agreement among raft nodes before linearized reading' (duration: 973.567749ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:38:15.146249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:38:14.172387Z","time spent":"973.854525ms","remote":"127.0.0.1:43080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-08T23:38:15.146346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.843664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T23:38:15.146416Z","caller":"traceutil/trace.go:171","msg":"trace[966129387] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1076; }","duration":"644.936137ms","start":"2026-07-08T23:38:14.501464Z","end":"2026-07-08T23:38:15.1464Z","steps":["trace[966129387] 'agreement among raft nodes before linearized reading' (duration: 644.837135ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:38:15.146472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:38:14.501446Z","time spent":"645.003908ms","remote":"127.0.0.1:50016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-07-08T23:38:15.145807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"766.31279ms","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-08T23:38:15.146561Z","caller":"traceutil/trace.go:171","msg":"trace[736455476] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1076; }","duration":"767.117289ms","start":"2026-07-08T23:38:14.37943Z","end":"2026-07-08T23:38:15.146547Z","steps":["trace[736455476] 'agreement among raft nodes before linearized reading' (duration: 766.281829ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:38:15.146535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.338544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-08T23:38:15.146362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"692.983027ms","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-08T23:38:15.146652Z","caller":"traceutil/trace.go:171","msg":"trace[302450045] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1076; }","duration":"693.318005ms","start":"2026-07-08T23:38:14.453326Z","end":"2026-07-08T23:38:15.146644Z","steps":["trace[302450045] 'agreement among raft nodes before linearized reading' (duration: 692.932436ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:38:15.146687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:38:14.453304Z","time spent":"693.372756ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-08T23:38:15.146698Z","caller":"traceutil/trace.go:171","msg":"trace[1084397886] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1076; }","duration":"657.487708ms","start":"2026-07-08T23:38:14.489149Z","end":"2026-07-08T23:38:15.146637Z","steps":["trace[1084397886] 'agreement among raft nodes before linearized reading' (duration: 657.236202ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:38:15.146013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"866.268071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-08T23:38:15.146836Z","caller":"traceutil/trace.go:171","msg":"trace[337791185] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1076; }","duration":"867.128752ms","start":"2026-07-08T23:38:14.279698Z","end":"2026-07-08T23:38:15.146827Z","steps":["trace[337791185] 'agreement among raft nodes before linearized reading' (duration: 866.280862ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:38:15.146794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:38:14.489131Z","time spent":"657.650081ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-08T23:38:15.146973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:38:14.279668Z","time spent":"867.290156ms","remote":"127.0.0.1:43198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":14,"response size":30,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-07-08T23:38:15.146611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:38:14.379406Z","time spent":"767.188042ms","remote":"127.0.0.1:43208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":5,"response size":30,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-07-08T23:38:15.146064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.019080807s","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-08T23:38:15.146045Z","caller":"traceutil/trace.go:171","msg":"trace[463702393] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"715.582547ms","start":"2026-07-08T23:38:14.430389Z","end":"2026-07-08T23:38:15.145972Z","steps":["trace[463702393] 'process raft request' (duration: 715.193497ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:38:15.147139Z","caller":"traceutil/trace.go:171","msg":"trace[1867488407] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1076; }","duration":"1.020200593s","start":"2026-07-08T23:38:14.126927Z","end":"2026-07-08T23:38:15.147127Z","steps":["trace[1867488407] 'agreement among raft nodes before linearized reading' (duration: 1.019034245s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:38:15.147188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:38:14.12688Z","time spent":"1.020296777s","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-08T23:38:15.145915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"816.557463ms","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":"info","ts":"2026-07-08T23:38:15.14738Z","caller":"traceutil/trace.go:171","msg":"trace[1669969487] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1076; }","duration":"818.071079ms","start":"2026-07-08T23:38:14.329297Z","end":"2026-07-08T23:38:15.147368Z","steps":["trace[1669969487] 'agreement among raft nodes before linearized reading' (duration: 816.544623ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:38:15.147406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:38:14.430367Z","time spent":"716.775586ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T23:38:15.147456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:38:14.329244Z","time spent":"818.195962ms","remote":"127.0.0.1:43418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":16,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"info","ts":"2026-07-08T23:38:16.173861Z","caller":"traceutil/trace.go:171","msg":"trace[237720179] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"340.311261ms","start":"2026-07-08T23:38:15.833525Z","end":"2026-07-08T23:38:16.173836Z","steps":["trace[237720179] 'process raft request' (duration: 339.803188ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:38:16.17402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:38:15.833489Z","time spent":"340.438864ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T23:38:17.414198Z","caller":"traceutil/trace.go:171","msg":"trace[1557019870] linearizableReadLoop","detail":"{readStateIndex:1123; appliedIndex:1122; }","duration":"262.544771ms","start":"2026-07-08T23:38:17.15163Z","end":"2026-07-08T23:38:17.414174Z","steps":["trace[1557019870] 'read index received' (duration: 262.320276ms)","trace[1557019870] 'applied index is now lower than readState.Index' (duration: 223.575µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:38:17.414271Z","caller":"traceutil/trace.go:171","msg":"trace[56356601] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"982.676309ms","start":"2026-07-08T23:38:16.431567Z","end":"2026-07-08T23:38:17.414243Z","steps":["trace[56356601] 'process raft request' (duration: 982.470484ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:38:17.414411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:38:16.431541Z","time spent":"982.792962ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T23:38:17.414489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.591827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-08T23:38:17.414498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.116281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-08T23:38:17.414501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.050628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-08T23:38:17.414419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.775156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-08T23:38:17.414536Z","caller":"traceutil/trace.go:171","msg":"trace[1767533474] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1081; }","duration":"225.658878ms","start":"2026-07-08T23:38:17.188864Z","end":"2026-07-08T23:38:17.414523Z","steps":["trace[1767533474] 'agreement among raft nodes before linearized reading' (duration: 225.539445ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:38:17.414543Z","caller":"traceutil/trace.go:171","msg":"trace[1475770661] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1081; }","duration":"226.111709ms","start":"2026-07-08T23:38:17.188422Z","end":"2026-07-08T23:38:17.414534Z","steps":["trace[1475770661] 'agreement among raft nodes before linearized reading' (duration: 226.025566ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:38:17.414542Z","caller":"traceutil/trace.go:171","msg":"trace[209616558] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1081; }","duration":"247.184613ms","start":"2026-07-08T23:38:17.167345Z","end":"2026-07-08T23:38:17.41453Z","steps":["trace[209616558] 'agreement among raft nodes before linearized reading' (duration: 247.04921ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:38:17.414612Z","caller":"traceutil/trace.go:171","msg":"trace[1866828292] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1081; }","duration":"262.979761ms","start":"2026-07-08T23:38:17.15159Z","end":"2026-07-08T23:38:17.41457Z","steps":["trace[1866828292] 'agreement among raft nodes before linearized reading' (duration: 262.702295ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:38:17.639913Z","caller":"traceutil/trace.go:171","msg":"trace[1622221716] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"221.303234ms","start":"2026-07-08T23:38:17.418577Z","end":"2026-07-08T23:38:17.63988Z","steps":["trace[1622221716] 'process raft request' (duration: 155.340906ms)","trace[1622221716] 'compare' (duration: 65.735963ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:38:17.663123Z","caller":"traceutil/trace.go:171","msg":"trace[1987646974] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"243.721591ms","start":"2026-07-08T23:38:17.419378Z","end":"2026-07-08T23:38:17.663099Z","steps":["trace[1987646974] 'process raft request' (duration: 243.68195ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:38:17.663283Z","caller":"traceutil/trace.go:171","msg":"trace[942467346] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"244.375386ms","start":"2026-07-08T23:38:17.418875Z","end":"2026-07-08T23:38:17.66325Z","steps":["trace[942467346] 'process raft request' (duration: 244.037608ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:38:17.663338Z","caller":"traceutil/trace.go:171","msg":"trace[1177164098] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"244.089969ms","start":"2026-07-08T23:38:17.419211Z","end":"2026-07-08T23:38:17.663301Z","steps":["trace[1177164098] 'process raft request' (duration: 243.810363ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:38:18.880872Z","caller":"traceutil/trace.go:171","msg":"trace[600015816] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"301.558464ms","start":"2026-07-08T23:38:18.57928Z","end":"2026-07-08T23:38:18.880838Z","steps":["trace[600015816] 'process raft request' (duration: 301.332839ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:38:18.881044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:38:18.579244Z","time spent":"301.708748ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T23:38:19.054597Z","caller":"traceutil/trace.go:171","msg":"trace[1400001695] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"156.802691ms","start":"2026-07-08T23:38:18.897767Z","end":"2026-07-08T23:38:19.05457Z","steps":["trace[1400001695] 'process raft request' (duration: 79.871401ms)","trace[1400001695] 'compare' (duration: 76.600992ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T23:38:19.975052Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967605805561168733,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-08T23:38:20.228698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.554593ms","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-08T23:38:20.228838Z","caller":"traceutil/trace.go:171","msg":"trace[858512915] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1088; }","duration":"191.716077ms","start":"2026-07-08T23:38:20.0371Z","end":"2026-07-08T23:38:20.228816Z","steps":["trace[858512915] 'range keys from in-memory index tree' (duration: 191.532791ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:38:20.2295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.541225ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T23:38:20.229579Z","caller":"traceutil/trace.go:171","msg":"trace[838444451] linearizableReadLoop","detail":"{readStateIndex:1132; appliedIndex:1131; }","duration":"755.37122ms","start":"2026-07-08T23:38:19.474193Z","end":"2026-07-08T23:38:20.229564Z","steps":["trace[838444451] 'read index received' (duration: 608.641189ms)","trace[838444451] 'applied index is now lower than readState.Index' (duration: 146.728941ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:38:20.229663Z","caller":"traceutil/trace.go:171","msg":"trace[590438925] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"810.665563ms","start":"2026-07-08T23:38:19.418987Z","end":"2026-07-08T23:38:20.229653Z","steps":["trace[590438925] 'process raft request' (duration: 663.903252ms)","trace[590438925] 'compare' (duration: 146.110265ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T23:38:20.229728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:38:19.418959Z","time spent":"810.738874ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T23:38:20.229953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"755.724508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-08T23:38:20.230602Z","caller":"traceutil/trace.go:171","msg":"trace[270383267] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1089; }","duration":"756.393234ms","start":"2026-07-08T23:38:19.474186Z","end":"2026-07-08T23:38:20.230579Z","steps":["trace[270383267] 'agreement among raft nodes before linearized reading' (duration: 755.688277ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:38:20.230263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.605669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-08T23:38:20.230982Z","caller":"traceutil/trace.go:171","msg":"trace[733229997] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1089; }","duration":"563.355466ms","start":"2026-07-08T23:38:19.667605Z","end":"2026-07-08T23:38:20.23096Z","steps":["trace[733229997] 'agreement among raft nodes before linearized reading' (duration: 562.554187ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:38:20.231035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:38:19.667587Z","time spent":"563.432599ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-08T23:38:20.230355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.707571ms","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-08T23:38:20.231165Z","caller":"traceutil/trace.go:171","msg":"trace[1065504138] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1089; }","duration":"563.536291ms","start":"2026-07-08T23:38:19.667613Z","end":"2026-07-08T23:38:20.23115Z","steps":["trace[1065504138] 'agreement among raft nodes before linearized reading' (duration: 562.624339ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:38:20.231232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:38:19.667602Z","time spent":"563.613512ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-08T23:38:20.230027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.563148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-07-08T23:38:20.230939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:38:19.474145Z","time spent":"756.757713ms","remote":"127.0.0.1:43584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":30,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-07-08T23:38:20.231321Z","caller":"traceutil/trace.go:171","msg":"trace[1729324167] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1089; }","duration":"586.909339ms","start":"2026-07-08T23:38:19.6444Z","end":"2026-07-08T23:38:20.231309Z","steps":["trace[1729324167] 'agreement among raft nodes before linearized reading' (duration: 585.440794ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:38:20.231692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:38:19.64438Z","time spent":"587.297759ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-08T23:38:20.230428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.446159ms","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-08T23:38:20.231871Z","caller":"traceutil/trace.go:171","msg":"trace[1503131509] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1089; }","duration":"564.883873ms","start":"2026-07-08T23:38:19.666946Z","end":"2026-07-08T23:38:20.23183Z","steps":["trace[1503131509] 'agreement among raft nodes before linearized reading' (duration: 563.391737ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:38:20.231903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:38:19.666934Z","time spent":"564.961164ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-08T23:38:20.384412Z","caller":"traceutil/trace.go:171","msg":"trace[1587915395] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"147.897268ms","start":"2026-07-08T23:38:20.236493Z","end":"2026-07-08T23:38:20.38439Z","steps":["trace[1587915395] 'process raft request' (duration: 147.730024ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:38:20.384653Z","caller":"traceutil/trace.go:171","msg":"trace[1504054285] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"148.093923ms","start":"2026-07-08T23:38:20.236538Z","end":"2026-07-08T23:38:20.384632Z","steps":["trace[1504054285] 'process raft request' (duration: 147.97169ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:38:20.384688Z","caller":"traceutil/trace.go:171","msg":"trace[1083597546] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"148.186135ms","start":"2026-07-08T23:38:20.236487Z","end":"2026-07-08T23:38:20.384673Z","steps":["trace[1083597546] 'process raft request' (duration: 147.866167ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:38:20.384725Z","caller":"traceutil/trace.go:171","msg":"trace[521785278] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"147.53324ms","start":"2026-07-08T23:38:20.237167Z","end":"2026-07-08T23:38:20.3847Z","steps":["trace[521785278] 'process raft request' (duration: 147.420637ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:38:48.628403Z","caller":"traceutil/trace.go:171","msg":"trace[1075641418] linearizableReadLoop","detail":"{readStateIndex:1281; appliedIndex:1280; }","duration":"226.459278ms","start":"2026-07-08T23:38:48.401921Z","end":"2026-07-08T23:38:48.62838Z","steps":["trace[1075641418] 'read index received' (duration: 226.322074ms)","trace[1075641418] 'applied index is now lower than readState.Index' (duration: 136.214µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:38:48.628511Z","caller":"traceutil/trace.go:171","msg":"trace[1890564647] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"306.544094ms","start":"2026-07-08T23:38:48.321932Z","end":"2026-07-08T23:38:48.628476Z","steps":["trace[1890564647] 'process raft request' (duration: 306.229896ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:38:48.628636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:38:48.321912Z","time spent":"306.647946ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T23:38:48.628661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.722073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-08T23:38:48.628701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.191951ms","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-08T23:38:48.62874Z","caller":"traceutil/trace.go:171","msg":"trace[401812031] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1231; }","duration":"226.847307ms","start":"2026-07-08T23:38:48.401874Z","end":"2026-07-08T23:38:48.628722Z","steps":["trace[401812031] 'agreement among raft nodes before linearized reading' (duration: 226.623511ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:38:48.628754Z","caller":"traceutil/trace.go:171","msg":"trace[1339629552] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1231; }","duration":"129.274763ms","start":"2026-07-08T23:38:48.499454Z","end":"2026-07-08T23:38:48.628729Z","steps":["trace[1339629552] 'agreement among raft nodes before linearized reading' (duration: 129.104669ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:38:48.628819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.612638ms","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-08T23:38:48.628892Z","caller":"traceutil/trace.go:171","msg":"trace[452844844] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1231; }","duration":"126.714401ms","start":"2026-07-08T23:38:48.50216Z","end":"2026-07-08T23:38:48.628875Z","steps":["trace[452844844] 'agreement among raft nodes before linearized reading' (duration: 126.518547ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:38:48.628904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.130401ms","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-08T23:38:48.628918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.09673ms","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-08T23:38:48.628939Z","caller":"traceutil/trace.go:171","msg":"trace[1796231740] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1231; }","duration":"127.202703ms","start":"2026-07-08T23:38:48.501727Z","end":"2026-07-08T23:38:48.62893Z","steps":["trace[1796231740] 'agreement among raft nodes before linearized reading' (duration: 127.119821ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:38:48.628951Z","caller":"traceutil/trace.go:171","msg":"trace[589382582] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1231; }","duration":"127.128631ms","start":"2026-07-08T23:38:48.501814Z","end":"2026-07-08T23:38:48.628942Z","steps":["trace[589382582] 'agreement among raft nodes before linearized reading' (duration: 127.017318ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:38:48.629058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.403273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T23:38:48.629086Z","caller":"traceutil/trace.go:171","msg":"trace[2026957976] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1231; }","duration":"108.453174ms","start":"2026-07-08T23:38:48.520624Z","end":"2026-07-08T23:38:48.629077Z","steps":["trace[2026957976] 'agreement among raft nodes before linearized reading' (duration: 108.408763ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:38:49.722765Z","caller":"traceutil/trace.go:171","msg":"trace[159807957] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"157.250711ms","start":"2026-07-08T23:38:49.565499Z","end":"2026-07-08T23:38:49.72275Z","steps":["trace[159807957] 'process raft request' (duration: 157.159679ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:38:50.226862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.475352ms","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-08T23:38:50.226994Z","caller":"traceutil/trace.go:171","msg":"trace[804036897] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1238; }","duration":"189.617816ms","start":"2026-07-08T23:38:50.03736Z","end":"2026-07-08T23:38:50.226978Z","steps":["trace[804036897] 'range keys from in-memory index tree' (duration: 189.459052ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:38:51.152328Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967605805561169088,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-08T23:38:51.347517Z","caller":"traceutil/trace.go:171","msg":"trace[1656019637] linearizableReadLoop","detail":"{readStateIndex:1290; appliedIndex:1289; }","duration":"696.074142ms","start":"2026-07-08T23:38:50.651414Z","end":"2026-07-08T23:38:51.347488Z","steps":["trace[1656019637] 'read index received' (duration: 695.779635ms)","trace[1656019637] 'applied index is now lower than readState.Index' (duration: 293.177µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:38:51.347577Z","caller":"traceutil/trace.go:171","msg":"trace[1449162504] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"712.887464ms","start":"2026-07-08T23:38:50.634664Z","end":"2026-07-08T23:38:51.347552Z","steps":["trace[1449162504] 'process raft request' (duration: 712.638028ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:38:51.347714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:38:50.634641Z","time spent":"713.004547ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T23:38:51.347767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.621848ms","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-08T23:38:51.347768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.326818ms","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-08T23:38:51.34782Z","caller":"traceutil/trace.go:171","msg":"trace[1161078955] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1239; }","duration":"689.71281ms","start":"2026-07-08T23:38:50.658092Z","end":"2026-07-08T23:38:51.347805Z","steps":["trace[1161078955] 'agreement among raft nodes before linearized reading' (duration: 689.558586ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:38:51.347827Z","caller":"traceutil/trace.go:171","msg":"trace[1550446203] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1239; }","duration":"696.455781ms","start":"2026-07-08T23:38:50.651358Z","end":"2026-07-08T23:38:51.347814Z","steps":["trace[1550446203] 'agreement among raft nodes before linearized reading' (duration: 696.262826ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:38:51.347828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.123078ms","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-08T23:38:51.347857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.942175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-08T23:38:51.347907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:38:50.651341Z","time spent":"696.550933ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-08T23:38:51.347926Z","caller":"traceutil/trace.go:171","msg":"trace[858908255] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1239; }","duration":"687.2271ms","start":"2026-07-08T23:38:50.66067Z","end":"2026-07-08T23:38:51.347897Z","steps":["trace[858908255] 'agreement among raft nodes before linearized reading' (duration: 687.003094ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:38:51.347942Z","caller":"traceutil/trace.go:171","msg":"trace[56832121] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1239; }","duration":"170.103039ms","start":"2026-07-08T23:38:51.177818Z","end":"2026-07-08T23:38:51.347921Z","steps":["trace[56832121] 'agreement among raft nodes before linearized reading' (duration: 169.899534ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:38:51.347982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:38:50.660661Z","time spent":"687.306912ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-08T23:38:51.347981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.64764ms","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-08T23:38:51.347907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:38:50.658083Z","time spent":"689.809192ms","remote":"127.0.0.1:43340","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-08T23:38:51.348046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"688.778777ms","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-08T23:38:51.348069Z","caller":"traceutil/trace.go:171","msg":"trace[911122948] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1239; }","duration":"687.774383ms","start":"2026-07-08T23:38:50.660274Z","end":"2026-07-08T23:38:51.348048Z","steps":["trace[911122948] 'agreement among raft nodes before linearized reading' (duration: 687.485906ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:38:51.34809Z","caller":"traceutil/trace.go:171","msg":"trace[186576838] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1239; }","duration":"688.820618ms","start":"2026-07-08T23:38:50.659255Z","end":"2026-07-08T23:38:51.348076Z","steps":["trace[186576838] 'agreement among raft nodes before linearized reading' (duration: 688.680835ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:38:51.348116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:38:50.660264Z","time spent":"687.843285ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-08T23:38:51.348129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:38:50.659221Z","time spent":"688.89767ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-08T23:38:51.769924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.969918ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T23:38:51.770089Z","caller":"traceutil/trace.go:171","msg":"trace[1225634247] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"416.824251ms","start":"2026-07-08T23:38:51.353252Z","end":"2026-07-08T23:38:51.770076Z","steps":["trace[1225634247] 'process raft request' (duration: 416.7926ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:38:51.770137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:38:51.353247Z","time spent":"416.870693ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T23:38:51.770364Z","caller":"traceutil/trace.go:171","msg":"trace[1064484340] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"417.549829ms","start":"2026-07-08T23:38:51.352804Z","end":"2026-07-08T23:38:51.770354Z","steps":["trace[1064484340] 'process raft request' (duration: 282.075718ms)","trace[1064484340] 'compare' (duration: 134.781784ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T23:38:51.770406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:38:51.352794Z","time spent":"417.59251ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T23:38:51.770489Z","caller":"traceutil/trace.go:171","msg":"trace[893915141] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"417.545139ms","start":"2026-07-08T23:38:51.352938Z","end":"2026-07-08T23:38:51.770483Z","steps":["trace[893915141] 'process raft request' (duration: 417.055557ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:38:51.770523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:38:51.352924Z","time spent":"417.582589ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T23:38:51.770596Z","caller":"traceutil/trace.go:171","msg":"trace[102401218] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"417.397245ms","start":"2026-07-08T23:38:51.353193Z","end":"2026-07-08T23:38:51.77059Z","steps":["trace[102401218] 'process raft request' (duration: 416.833182ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:38:51.770631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:38:51.353172Z","time spent":"417.440706ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T23:38:54.777489Z","caller":"traceutil/trace.go:171","msg":"trace[636436074] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"182.006143ms","start":"2026-07-08T23:38:54.595446Z","end":"2026-07-08T23:38:54.777452Z","steps":["trace[636436074] 'process raft request' (duration: 146.32108ms)","trace[636436074] 'compare' (duration: 35.448548ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T23:38:55.443365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.265856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T23:38:55.443454Z","caller":"traceutil/trace.go:171","msg":"trace[520392764] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1271; }","duration":"270.427429ms","start":"2026-07-08T23:38:55.173008Z","end":"2026-07-08T23:38:55.443435Z","steps":["trace[520392764] 'range keys from in-memory index tree' (duration: 270.156883ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:38:55.880236Z","caller":"traceutil/trace.go:171","msg":"trace[1573891902] linearizableReadLoop","detail":"{readStateIndex:1324; appliedIndex:1323; }","duration":"475.743591ms","start":"2026-07-08T23:38:55.404462Z","end":"2026-07-08T23:38:55.880206Z","steps":["trace[1573891902] 'read index received' (duration: 475.523166ms)","trace[1573891902] 'applied index is now lower than readState.Index' (duration: 219.105µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:38:55.88036Z","caller":"traceutil/trace.go:171","msg":"trace[1721130807] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"518.708508ms","start":"2026-07-08T23:38:55.361606Z","end":"2026-07-08T23:38:55.880314Z","steps":["trace[1721130807] 'process raft request' (duration: 518.403651ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:38:55.880616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:38:55.361569Z","time spent":"518.913433ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T23:38:55.880702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.951829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T23:38:55.880791Z","caller":"traceutil/trace.go:171","msg":"trace[1245079198] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1272; }","duration":"436.082222ms","start":"2026-07-08T23:38:55.444688Z","end":"2026-07-08T23:38:55.880771Z","steps":["trace[1245079198] 'agreement among raft nodes before linearized reading' (duration: 435.944759ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:38:55.880843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:38:55.444673Z","time spent":"436.158434ms","remote":"127.0.0.1:43082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-08T23:38:55.880737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.247753ms","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-08T23:38:55.881226Z","caller":"traceutil/trace.go:171","msg":"trace[74116984] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1272; }","duration":"476.793006ms","start":"2026-07-08T23:38:55.40442Z","end":"2026-07-08T23:38:55.881213Z","steps":["trace[74116984] 'agreement among raft nodes before linearized reading' (duration: 476.12037ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:38:55.881262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:38:55.404402Z","time spent":"476.849657ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-08T23:38:56.185594Z","caller":"traceutil/trace.go:171","msg":"trace[2127707751] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"299.637879ms","start":"2026-07-08T23:38:55.885924Z","end":"2026-07-08T23:38:56.185562Z","steps":["trace[2127707751] 'process raft request' (duration: 226.666023ms)","trace[2127707751] 'compare' (duration: 72.73515ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:38:56.214148Z","caller":"traceutil/trace.go:171","msg":"trace[171545511] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"319.885662ms","start":"2026-07-08T23:38:55.894245Z","end":"2026-07-08T23:38:56.214131Z","steps":["trace[171545511] 'process raft request' (duration: 319.853751ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:38:56.214355Z","caller":"traceutil/trace.go:171","msg":"trace[546342221] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"327.038534ms","start":"2026-07-08T23:38:55.88728Z","end":"2026-07-08T23:38:56.214319Z","steps":["trace[546342221] 'process raft request' (duration: 326.718276ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:38:56.214541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:38:55.894235Z","time spent":"320.162339ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T23:38:56.214276Z","caller":"traceutil/trace.go:171","msg":"trace[1698730555] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"327.150416ms","start":"2026-07-08T23:38:55.887114Z","end":"2026-07-08T23:38:56.214265Z","steps":["trace[1698730555] 'process raft request' (duration: 326.776077ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:38:56.214726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:38:55.88727Z","time spent":"327.213288ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T23:38:56.214877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:38:55.887087Z","time spent":"327.71509ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T23:38:56.214342Z","caller":"traceutil/trace.go:171","msg":"trace[2009020728] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"326.909381ms","start":"2026-07-08T23:38:55.887426Z","end":"2026-07-08T23:38:56.214336Z","steps":["trace[2009020728] 'process raft request' (duration: 326.603174ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:38:56.215264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:38:55.887418Z","time spent":"327.585477ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T23:38:57.253323Z","caller":"traceutil/trace.go:171","msg":"trace[179896559] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"171.102443ms","start":"2026-07-08T23:38:57.08218Z","end":"2026-07-08T23:38:57.253282Z","steps":["trace[179896559] 'process raft request' (duration: 170.876468ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:38:58.357579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.026466935s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T23:38:58.357672Z","caller":"traceutil/trace.go:171","msg":"trace[1908200564] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1279; }","duration":"1.026599738s","start":"2026-07-08T23:38:57.331054Z","end":"2026-07-08T23:38:58.357654Z","steps":["trace[1908200564] 'range keys from in-memory index tree' (duration: 1.026391072s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:38:58.357721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:38:57.331032Z","time spent":"1.026679789s","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-07-08T23:38:58.531274Z","caller":"traceutil/trace.go:171","msg":"trace[1705699324] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"642.183553ms","start":"2026-07-08T23:38:57.889073Z","end":"2026-07-08T23:38:58.531257Z","steps":["trace[1705699324] 'process raft request' (duration: 642.06849ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:38:58.531372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:38:57.889047Z","time spent":"642.270954ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T23:38:58.716684Z","caller":"traceutil/trace.go:171","msg":"trace[18672880] linearizableReadLoop","detail":"{readStateIndex:1333; appliedIndex:1331; }","duration":"526.938665ms","start":"2026-07-08T23:38:58.189718Z","end":"2026-07-08T23:38:58.716656Z","steps":["trace[18672880] 'read index received' (duration: 341.466278ms)","trace[18672880] 'applied index is now lower than readState.Index' (duration: 185.471307ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:38:58.716838Z","caller":"traceutil/trace.go:171","msg":"trace[334448375] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"590.297801ms","start":"2026-07-08T23:38:58.126527Z","end":"2026-07-08T23:38:58.716825Z","steps":["trace[334448375] 'process raft request' (duration: 582.882554ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:38:58.716954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:38:58.126495Z","time spent":"590.391074ms","remote":"127.0.0.1:43164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-08T23:38:58.717184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.441118ms","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-08T23:38:58.717276Z","caller":"traceutil/trace.go:171","msg":"trace[985295054] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1282; }","duration":"527.586171ms","start":"2026-07-08T23:38:58.189669Z","end":"2026-07-08T23:38:58.717255Z","steps":["trace[985295054] 'agreement among raft nodes before linearized reading' (duration: 527.274254ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:38:58.717344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:38:58.189643Z","time spent":"527.692573ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-08T23:38:58.717426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.915026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-08T23:38:58.717485Z","caller":"traceutil/trace.go:171","msg":"trace[1287411354] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1282; }","duration":"257.033948ms","start":"2026-07-08T23:38:58.460429Z","end":"2026-07-08T23:38:58.717463Z","steps":["trace[1287411354] 'agreement among raft nodes before linearized reading' (duration: 256.938676ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:38:58.717553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.739287ms","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-08T23:38:58.717613Z","caller":"traceutil/trace.go:171","msg":"trace[1983195072] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1282; }","duration":"497.83846ms","start":"2026-07-08T23:38:58.219759Z","end":"2026-07-08T23:38:58.717598Z","steps":["trace[1983195072] 'agreement among raft nodes before linearized reading' (duration: 497.630565ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:38:58.717649Z","caller":"traceutil/trace.go:171","msg":"trace[278645669] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"355.799532ms","start":"2026-07-08T23:38:58.361835Z","end":"2026-07-08T23:38:58.717635Z","steps":["trace[278645669] 'process raft request' (duration: 354.730826ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:38:58.717662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:38:58.219735Z","time spent":"497.916561ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-08T23:38:58.717724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:38:58.361814Z","time spent":"355.864253ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-08T23:38:58.717318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.563491ms","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-08T23:38:58.717834Z","caller":"traceutil/trace.go:171","msg":"trace[2109696794] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1282; }","duration":"500.118844ms","start":"2026-07-08T23:38:58.217696Z","end":"2026-07-08T23:38:58.717815Z","steps":["trace[2109696794] 'agreement among raft nodes before linearized reading' (duration: 499.436448ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:38:58.717971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:38:58.217686Z","time spent":"500.265858ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-08T23:38:58.717789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.423134ms","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-08T23:38:58.718115Z","caller":"traceutil/trace.go:171","msg":"trace[1848429745] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1282; }","duration":"498.766922ms","start":"2026-07-08T23:38:58.219333Z","end":"2026-07-08T23:38:58.7181Z","steps":["trace[1848429745] 'agreement among raft nodes before linearized reading' (duration: 498.371102ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:38:58.718145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.584313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-08T23:38:58.718168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:38:58.219323Z","time spent":"498.831323ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-08T23:38:58.718208Z","caller":"traceutil/trace.go:171","msg":"trace[1777826817] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1282; }","duration":"497.664645ms","start":"2026-07-08T23:38:58.220528Z","end":"2026-07-08T23:38:58.718192Z","steps":["trace[1777826817] 'agreement among raft nodes before linearized reading' (duration: 497.415909ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:38:58.718255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:38:58.220519Z","time spent":"497.726427ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-08T23:38:59.942108Z","caller":"traceutil/trace.go:171","msg":"trace[921476955] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"128.152396ms","start":"2026-07-08T23:38:59.813926Z","end":"2026-07-08T23:38:59.942078Z","steps":["trace[921476955] 'process raft request' (duration: 127.986692ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:39:00.219616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.101597ms","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-08T23:39:00.219748Z","caller":"traceutil/trace.go:171","msg":"trace[1309057406] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1292; }","duration":"182.2571ms","start":"2026-07-08T23:39:00.037472Z","end":"2026-07-08T23:39:00.219729Z","steps":["trace[1309057406] 'range keys from in-memory index tree' (duration: 182.058246ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:39:00.736423Z","caller":"traceutil/trace.go:171","msg":"trace[847670893] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"201.242814ms","start":"2026-07-08T23:39:00.535156Z","end":"2026-07-08T23:39:00.736399Z","steps":["trace[847670893] 'process raft request' (duration: 201.09122ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:39:00.981065Z","caller":"traceutil/trace.go:171","msg":"trace[662252504] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"211.926819ms","start":"2026-07-08T23:39:00.769109Z","end":"2026-07-08T23:39:00.981035Z","steps":["trace[662252504] 'process raft request' (duration: 159.525296ms)","trace[662252504] 'compare' (duration: 52.180778ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:39:00.981369Z","caller":"traceutil/trace.go:171","msg":"trace[1650242554] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"211.450349ms","start":"2026-07-08T23:39:00.769894Z","end":"2026-07-08T23:39:00.981344Z","steps":["trace[1650242554] 'process raft request' (duration: 211.07157ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:39:00.981573Z","caller":"traceutil/trace.go:171","msg":"trace[1112063392] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"211.420058ms","start":"2026-07-08T23:39:00.770142Z","end":"2026-07-08T23:39:00.981563Z","steps":["trace[1112063392] 'process raft request' (duration: 211.10264ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:39:00.981707Z","caller":"traceutil/trace.go:171","msg":"trace[1592339050] linearizableReadLoop","detail":"{readStateIndex:1350; appliedIndex:1346; }","duration":"210.552717ms","start":"2026-07-08T23:39:00.771138Z","end":"2026-07-08T23:39:00.981691Z","steps":["trace[1592339050] 'read index received' (duration: 157.490258ms)","trace[1592339050] 'applied index is now lower than readState.Index' (duration: 53.061609ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:39:00.981773Z","caller":"traceutil/trace.go:171","msg":"trace[1621376190] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"211.208792ms","start":"2026-07-08T23:39:00.770536Z","end":"2026-07-08T23:39:00.981744Z","steps":["trace[1621376190] 'process raft request' (duration: 210.991267ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:39:00.981848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.69164ms","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-08T23:39:00.981877Z","caller":"traceutil/trace.go:171","msg":"trace[365884521] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1297; }","duration":"210.751751ms","start":"2026-07-08T23:39:00.771116Z","end":"2026-07-08T23:39:00.981868Z","steps":["trace[365884521] 'agreement among raft nodes before linearized reading' (duration: 210.628178ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:39:05.767424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.909737ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-08T23:39:05.767776Z","caller":"traceutil/trace.go:171","msg":"trace[589515102] linearizableReadLoop","detail":"{readStateIndex:1378; appliedIndex:1376; }","duration":"772.021484ms","start":"2026-07-08T23:39:04.995736Z","end":"2026-07-08T23:39:05.767758Z","steps":["trace[589515102] 'read index received' (duration: 327.846291ms)","trace[589515102] 'applied index is now lower than readState.Index' (duration: 444.174303ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:39:05.767793Z","caller":"traceutil/trace.go:171","msg":"trace[829031048] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"999.553095ms","start":"2026-07-08T23:39:04.768199Z","end":"2026-07-08T23:39:05.767752Z","steps":["trace[829031048] 'process raft request' (duration: 555.227288ms)","trace[829031048] 'compare' (duration: 443.521728ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T23:39:05.767975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:39:04.768187Z","time spent":"999.71536ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-08T23:39:05.768146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"771.548182ms","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-08T23:39:05.76819Z","caller":"traceutil/trace.go:171","msg":"trace[718572675] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1325; }","duration":"771.598564ms","start":"2026-07-08T23:39:04.99658Z","end":"2026-07-08T23:39:05.768179Z","steps":["trace[718572675] 'agreement among raft nodes before linearized reading' (duration: 771.46751ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:39:05.768189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.565174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-08T23:39:05.768223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:39:04.996517Z","time spent":"771.697666ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-08T23:39:05.768221Z","caller":"traceutil/trace.go:171","msg":"trace[1272586118] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"965.346965ms","start":"2026-07-08T23:39:04.802835Z","end":"2026-07-08T23:39:05.768182Z","steps":["trace[1272586118] 'process raft request' (duration: 964.715929ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:39:05.768267Z","caller":"traceutil/trace.go:171","msg":"trace[2211467] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1325; }","duration":"594.699827ms","start":"2026-07-08T23:39:05.173544Z","end":"2026-07-08T23:39:05.768244Z","steps":["trace[2211467] 'agreement among raft nodes before linearized reading' (duration: 594.514323ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:39:05.768257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"770.905667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-08T23:39:05.768303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:39:04.802819Z","time spent":"965.440826ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T23:39:05.768324Z","caller":"traceutil/trace.go:171","msg":"trace[213292271] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1325; }","duration":"770.99764ms","start":"2026-07-08T23:39:04.997308Z","end":"2026-07-08T23:39:05.768305Z","steps":["trace[213292271] 'agreement among raft nodes before linearized reading' (duration: 770.779904ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:39:05.768373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:39:04.997294Z","time spent":"771.065721ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-08T23:39:05.768371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"765.674931ms","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-08T23:39:05.76843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"771.592063ms","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-08T23:39:05.768444Z","caller":"traceutil/trace.go:171","msg":"trace[1703649346] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1325; }","duration":"765.779244ms","start":"2026-07-08T23:39:05.002648Z","end":"2026-07-08T23:39:05.768427Z","steps":["trace[1703649346] 'agreement among raft nodes before linearized reading' (duration: 765.562389ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:39:05.768456Z","caller":"traceutil/trace.go:171","msg":"trace[1391772632] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"674.398661ms","start":"2026-07-08T23:39:05.094046Z","end":"2026-07-08T23:39:05.768445Z","steps":["trace[1391772632] 'process raft request' (duration: 673.667373ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:39:05.76846Z","caller":"traceutil/trace.go:171","msg":"trace[405050788] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1325; }","duration":"771.662185ms","start":"2026-07-08T23:39:04.996789Z","end":"2026-07-08T23:39:05.768451Z","steps":["trace[405050788] 'agreement among raft nodes before linearized reading' (duration: 771.579133ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:39:05.768488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:39:04.996768Z","time spent":"771.714897ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-08T23:39:05.768487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:39:05.002638Z","time spent":"765.840506ms","remote":"127.0.0.1:43340","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-08T23:39:05.768508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:39:05.094024Z","time spent":"674.456022ms","remote":"127.0.0.1:43164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":782,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-08T23:39:05.768306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:39:05.173525Z","time spent":"594.772868ms","remote":"127.0.0.1:43080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-08T23:39:05.768146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"772.400383ms","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-08T23:39:05.768918Z","caller":"traceutil/trace.go:171","msg":"trace[270913674] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1325; }","duration":"773.142231ms","start":"2026-07-08T23:39:04.995695Z","end":"2026-07-08T23:39:05.768837Z","steps":["trace[270913674] 'agreement among raft nodes before linearized reading' (duration: 772.280661ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:39:05.769019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:39:04.995673Z","time spent":"773.317985ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-08T23:39:06.511033Z","caller":"traceutil/trace.go:171","msg":"trace[183000069] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"737.506035ms","start":"2026-07-08T23:39:05.773498Z","end":"2026-07-08T23:39:06.511004Z","steps":["trace[183000069] 'process raft request' (duration: 717.361662ms)","trace[183000069] 'compare' (duration: 19.747654ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T23:39:06.511197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:39:05.773486Z","time spent":"737.627628ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T23:39:06.511822Z","caller":"traceutil/trace.go:171","msg":"trace[1668930727] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"737.499544ms","start":"2026-07-08T23:39:05.774302Z","end":"2026-07-08T23:39:06.511801Z","steps":["trace[1668930727] 'process raft request' (duration: 737.268559ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:39:06.512029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:39:05.774279Z","time spent":"737.669519ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T23:39:06.512092Z","caller":"traceutil/trace.go:171","msg":"trace[1784450277] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"737.289ms","start":"2026-07-08T23:39:05.774786Z","end":"2026-07-08T23:39:06.512075Z","steps":["trace[1784450277] 'process raft request' (duration: 737.151697ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:39:06.512129Z","caller":"traceutil/trace.go:171","msg":"trace[1749839988] linearizableReadLoop","detail":"{readStateIndex:1387; appliedIndex:1379; }","duration":"487.411011ms","start":"2026-07-08T23:39:06.0247Z","end":"2026-07-08T23:39:06.512111Z","steps":["trace[1749839988] 'read index received' (duration: 466.164441ms)","trace[1749839988] 'applied index is now lower than readState.Index' (duration: 21.2456ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T23:39:06.512182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:39:05.774775Z","time spent":"737.366542ms","remote":"127.0.0.1:43164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":911,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T23:39:06.512222Z","caller":"traceutil/trace.go:171","msg":"trace[1893080482] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"737.370841ms","start":"2026-07-08T23:39:05.774844Z","end":"2026-07-08T23:39:06.512215Z","steps":["trace[1893080482] 'process raft request' (duration: 737.157686ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:39:06.512288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:39:05.774834Z","time spent":"737.406643ms","remote":"127.0.0.1:43164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":861,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T23:39:06.512351Z","caller":"traceutil/trace.go:171","msg":"trace[1818654709] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"737.953865ms","start":"2026-07-08T23:39:05.774386Z","end":"2026-07-08T23:39:06.51234Z","steps":["trace[1818654709] 'process raft request' (duration: 737.379492ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:39:06.512408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:39:05.774378Z","time spent":"737.996847ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T23:39:06.512437Z","caller":"traceutil/trace.go:171","msg":"trace[1745431307] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"736.969673ms","start":"2026-07-08T23:39:05.77546Z","end":"2026-07-08T23:39:06.51243Z","steps":["trace[1745431307] 'process raft request' (duration: 736.569043ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:39:06.512482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:39:05.775454Z","time spent":"736.999243ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T23:39:06.512554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.364883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-08T23:39:06.512554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.856431ms","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-08T23:39:06.512588Z","caller":"traceutil/trace.go:171","msg":"trace[1600694480] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1333; }","duration":"318.434184ms","start":"2026-07-08T23:39:06.194147Z","end":"2026-07-08T23:39:06.512581Z","steps":["trace[1600694480] 'agreement among raft nodes before linearized reading' (duration: 318.387743ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:39:06.512612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:39:06.194129Z","time spent":"318.479606ms","remote":"127.0.0.1:43080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-08T23:39:06.512617Z","caller":"traceutil/trace.go:171","msg":"trace[1992012004] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"737.098595ms","start":"2026-07-08T23:39:05.77551Z","end":"2026-07-08T23:39:06.512609Z","steps":["trace[1992012004] 'process raft request' (duration: 736.548772ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:39:06.512666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:39:05.775497Z","time spent":"737.142927ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T23:39:06.512586Z","caller":"traceutil/trace.go:171","msg":"trace[1923332801] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1333; }","duration":"487.923553ms","start":"2026-07-08T23:39:06.024653Z","end":"2026-07-08T23:39:06.512577Z","steps":["trace[1923332801] 'agreement among raft nodes before linearized reading' (duration: 487.867792ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:39:06.512737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:39:06.024604Z","time spent":"488.124568ms","remote":"127.0.0.1:42950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-07-08T23:39:06.512767Z","caller":"traceutil/trace.go:171","msg":"trace[1094524255] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"735.747892ms","start":"2026-07-08T23:39:05.777008Z","end":"2026-07-08T23:39:06.512756Z","steps":["trace[1094524255] 'process raft request' (duration: 735.075576ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:39:06.512839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:39:05.776989Z","time spent":"735.814005ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T23:39:20.803011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.720362ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T23:39:20.803131Z","caller":"traceutil/trace.go:171","msg":"trace[402709047] linearizableReadLoop","detail":"{readStateIndex:1466; appliedIndex:1465; }","duration":"101.420745ms","start":"2026-07-08T23:39:20.701688Z","end":"2026-07-08T23:39:20.803109Z","steps":["trace[402709047] 'read index received' (duration: 40.031µs)","trace[402709047] 'applied index is now lower than readState.Index' (duration: 101.379574ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:39:20.803218Z","caller":"traceutil/trace.go:171","msg":"trace[1532277067] transaction","detail":"{read_only:false; response_revision:1409; number_of_response:1; }","duration":"176.318933ms","start":"2026-07-08T23:39:20.626855Z","end":"2026-07-08T23:39:20.803174Z","steps":["trace[1532277067] 'process raft request' (duration: 49.344185ms)","trace[1532277067] 'compare' (duration: 126.601949ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T23:39:20.803291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.606029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-07-08T23:39:20.803321Z","caller":"traceutil/trace.go:171","msg":"trace[999676513] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1409; }","duration":"101.673171ms","start":"2026-07-08T23:39:20.701638Z","end":"2026-07-08T23:39:20.803311Z","steps":["trace[999676513] 'agreement among raft nodes before linearized reading' (duration: 101.531158ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:39:22.951603Z","caller":"traceutil/trace.go:171","msg":"trace[355008836] transaction","detail":"{read_only:false; response_revision:1419; number_of_response:1; }","duration":"109.118149ms","start":"2026-07-08T23:39:22.842454Z","end":"2026-07-08T23:39:22.951573Z","steps":["trace[355008836] 'process raft request' (duration: 108.929285ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:39:25.024177Z","caller":"traceutil/trace.go:171","msg":"trace[1652712422] transaction","detail":"{read_only:false; response_revision:1429; number_of_response:1; }","duration":"204.0925ms","start":"2026-07-08T23:39:24.820048Z","end":"2026-07-08T23:39:25.02414Z","steps":["trace[1652712422] 'process raft request' (duration: 203.775342ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:39:25.425659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.177273ms","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":"info","ts":"2026-07-08T23:39:25.425935Z","caller":"traceutil/trace.go:171","msg":"trace[1425549023] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1430; }","duration":"147.386968ms","start":"2026-07-08T23:39:25.278388Z","end":"2026-07-08T23:39:25.425775Z","steps":["trace[1425549023] 'count revisions from in-memory index tree' (duration: 147.02706ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:39:26.670193Z","caller":"traceutil/trace.go:171","msg":"trace[524883050] transaction","detail":"{read_only:false; response_revision:1432; number_of_response:1; }","duration":"116.441436ms","start":"2026-07-08T23:39:26.553715Z","end":"2026-07-08T23:39:26.670156Z","steps":["trace[524883050] 'process raft request' (duration: 116.232431ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:39:26.795726Z","caller":"traceutil/trace.go:171","msg":"trace[372106743] transaction","detail":"{read_only:false; response_revision:1435; number_of_response:1; }","duration":"120.019612ms","start":"2026-07-08T23:39:26.675688Z","end":"2026-07-08T23:39:26.795707Z","steps":["trace[372106743] 'process raft request' (duration: 119.94483ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:39:26.795792Z","caller":"traceutil/trace.go:171","msg":"trace[944067231] transaction","detail":"{read_only:false; response_revision:1436; number_of_response:1; }","duration":"119.495649ms","start":"2026-07-08T23:39:26.676284Z","end":"2026-07-08T23:39:26.79578Z","steps":["trace[944067231] 'process raft request' (duration: 119.387776ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:39:26.795726Z","caller":"traceutil/trace.go:171","msg":"trace[1684533756] transaction","detail":"{read_only:false; response_revision:1434; number_of_response:1; }","duration":"120.799931ms","start":"2026-07-08T23:39:26.674902Z","end":"2026-07-08T23:39:26.795702Z","steps":["trace[1684533756] 'process raft request' (duration: 120.652617ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:39:27.15355Z","caller":"traceutil/trace.go:171","msg":"trace[520914852] transaction","detail":"{read_only:false; response_revision:1438; number_of_response:1; }","duration":"105.074042ms","start":"2026-07-08T23:39:27.048422Z","end":"2026-07-08T23:39:27.153496Z","steps":["trace[520914852] 'process raft request' (duration: 87.086ms)","trace[520914852] 'compare' (duration: 17.862069ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:39:51.164131Z","caller":"traceutil/trace.go:171","msg":"trace[381454414] linearizableReadLoop","detail":"{readStateIndex:1606; appliedIndex:1605; }","duration":"258.071745ms","start":"2026-07-08T23:39:50.90603Z","end":"2026-07-08T23:39:51.164102Z","steps":["trace[381454414] 'read index received' (duration: 256.922338ms)","trace[381454414] 'applied index is now lower than readState.Index' (duration: 1.146757ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:39:51.164199Z","caller":"traceutil/trace.go:171","msg":"trace[2111347336] transaction","detail":"{read_only:false; response_revision:1543; number_of_response:1; }","duration":"273.735142ms","start":"2026-07-08T23:39:50.890424Z","end":"2026-07-08T23:39:51.164159Z","steps":["trace[2111347336] 'process raft request' (duration: 272.439181ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:39:51.164428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.359182ms","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-08T23:39:51.164492Z","caller":"traceutil/trace.go:171","msg":"trace[1502012443] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1543; }","duration":"258.478355ms","start":"2026-07-08T23:39:50.905996Z","end":"2026-07-08T23:39:51.164474Z","steps":["trace[1502012443] 'agreement among raft nodes before linearized reading' (duration: 258.227859ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:39:51.23575Z","caller":"traceutil/trace.go:171","msg":"trace[598614691] transaction","detail":"{read_only:false; response_revision:1544; number_of_response:1; }","duration":"208.28696ms","start":"2026-07-08T23:39:51.027437Z","end":"2026-07-08T23:39:51.235724Z","steps":["trace[598614691] 'process raft request' (duration: 186.332453ms)","trace[598614691] 'compare' (duration: 21.707841ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T23:39:51.235931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.611549ms","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-08T23:39:51.236002Z","caller":"traceutil/trace.go:171","msg":"trace[1441359246] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1545; }","duration":"293.738402ms","start":"2026-07-08T23:39:50.942247Z","end":"2026-07-08T23:39:51.235986Z","steps":["trace[1441359246] 'agreement among raft nodes before linearized reading' (duration: 293.522187ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:39:51.236052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.735852ms","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-08T23:39:51.236166Z","caller":"traceutil/trace.go:171","msg":"trace[1396419748] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1545; }","duration":"248.919286ms","start":"2026-07-08T23:39:50.987218Z","end":"2026-07-08T23:39:51.236138Z","steps":["trace[1396419748] 'agreement among raft nodes before linearized reading' (duration: 248.525056ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:40:09.474Z","caller":"traceutil/trace.go:171","msg":"trace[1219335440] transaction","detail":"{read_only:false; response_revision:1628; number_of_response:1; }","duration":"145.623921ms","start":"2026-07-08T23:40:09.328355Z","end":"2026-07-08T23:40:09.473979Z","steps":["trace[1219335440] 'process raft request' (duration: 145.493398ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:40:09.484141Z","caller":"traceutil/trace.go:171","msg":"trace[1701442355] transaction","detail":"{read_only:false; response_revision:1629; number_of_response:1; }","duration":"153.518138ms","start":"2026-07-08T23:40:09.330594Z","end":"2026-07-08T23:40:09.484112Z","steps":["trace[1701442355] 'process raft request' (duration: 153.300213ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:40:10.31647Z","caller":"traceutil/trace.go:171","msg":"trace[1016735319] linearizableReadLoop","detail":"{readStateIndex:1701; appliedIndex:1700; }","duration":"199.977746ms","start":"2026-07-08T23:40:10.116466Z","end":"2026-07-08T23:40:10.316444Z","steps":["trace[1016735319] 'read index received' (duration: 199.7307ms)","trace[1016735319] 'applied index is now lower than readState.Index' (duration: 246.016µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:40:10.316509Z","caller":"traceutil/trace.go:171","msg":"trace[942533852] transaction","detail":"{read_only:false; response_revision:1634; number_of_response:1; }","duration":"292.656345ms","start":"2026-07-08T23:40:10.023827Z","end":"2026-07-08T23:40:10.316483Z","steps":["trace[942533852] 'process raft request' (duration: 292.45516ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:40:10.316607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.118279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T23:40:10.316674Z","caller":"traceutil/trace.go:171","msg":"trace[1741233081] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1634; }","duration":"200.210561ms","start":"2026-07-08T23:40:10.11645Z","end":"2026-07-08T23:40:10.31666Z","steps":["trace[1741233081] 'agreement among raft nodes before linearized reading' (duration: 200.068518ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:40:10.316791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.737889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T23:40:10.316866Z","caller":"traceutil/trace.go:171","msg":"trace[1060511318] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1634; }","duration":"144.861472ms","start":"2026-07-08T23:40:10.171986Z","end":"2026-07-08T23:40:10.316847Z","steps":["trace[1060511318] 'agreement among raft nodes before linearized reading' (duration: 144.624177ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:40:12.922959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.814065ms","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-08T23:40:12.923084Z","caller":"traceutil/trace.go:171","msg":"trace[1711015676] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1644; }","duration":"221.99961ms","start":"2026-07-08T23:40:12.701058Z","end":"2026-07-08T23:40:12.923057Z","steps":["trace[1711015676] 'count revisions from in-memory index tree' (duration: 221.719443ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:40:13.640017Z","caller":"traceutil/trace.go:171","msg":"trace[226692935] linearizableReadLoop","detail":"{readStateIndex:1712; appliedIndex:1711; }","duration":"110.841242ms","start":"2026-07-08T23:40:13.529156Z","end":"2026-07-08T23:40:13.639997Z","steps":["trace[226692935] 'read index received' (duration: 110.617986ms)","trace[226692935] 'applied index is now lower than readState.Index' (duration: 222.396µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:40:13.640093Z","caller":"traceutil/trace.go:171","msg":"trace[169801891] transaction","detail":"{read_only:false; response_revision:1645; number_of_response:1; }","duration":"142.915716ms","start":"2026-07-08T23:40:13.497143Z","end":"2026-07-08T23:40:13.640058Z","steps":["trace[169801891] 'process raft request' (duration: 142.724252ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:40:13.640316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.128338ms","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-08T23:40:13.640433Z","caller":"traceutil/trace.go:171","msg":"trace[745224018] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1645; }","duration":"111.276972ms","start":"2026-07-08T23:40:13.529121Z","end":"2026-07-08T23:40:13.640398Z","steps":["trace[745224018] 'agreement among raft nodes before linearized reading' (duration: 110.974275ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:40:13.942022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.62298ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T23:40:13.942135Z","caller":"traceutil/trace.go:171","msg":"trace[1249206078] linearizableReadLoop","detail":"{readStateIndex:1718; appliedIndex:1717; }","duration":"129.359332ms","start":"2026-07-08T23:40:13.81276Z","end":"2026-07-08T23:40:13.942119Z","steps":["trace[1249206078] 'read index received' (duration: 75.761µs)","trace[1249206078] 'applied index is now lower than readState.Index' (duration: 129.282231ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:40:13.942187Z","caller":"traceutil/trace.go:171","msg":"trace[1323279273] transaction","detail":"{read_only:false; response_revision:1651; number_of_response:1; }","duration":"231.568208ms","start":"2026-07-08T23:40:13.710597Z","end":"2026-07-08T23:40:13.942165Z","steps":["trace[1323279273] 'process raft request' (duration: 72.711623ms)","trace[1323279273] 'compare' (duration: 158.500247ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T23:40:13.94222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.464645ms","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-08T23:40:13.942251Z","caller":"traceutil/trace.go:171","msg":"trace[46881922] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1651; }","duration":"129.537407ms","start":"2026-07-08T23:40:13.812704Z","end":"2026-07-08T23:40:13.942241Z","steps":["trace[46881922] 'agreement among raft nodes before linearized reading' (duration: 129.478616ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:40:14.267076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.313479ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-08T23:40:14.267181Z","caller":"traceutil/trace.go:171","msg":"trace[192130006] linearizableReadLoop","detail":"{readStateIndex:1719; appliedIndex:1718; }","duration":"325.000106ms","start":"2026-07-08T23:40:13.942163Z","end":"2026-07-08T23:40:14.267163Z","steps":["trace[192130006] 'read index received' (duration: 39.648196ms)","trace[192130006] 'applied index is now lower than readState.Index' (duration: 285.35068ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T23:40:14.267337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.205651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-08T23:40:14.26739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.868725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-07-08T23:40:14.267389Z","caller":"traceutil/trace.go:171","msg":"trace[1131588872] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1651; }","duration":"393.327564ms","start":"2026-07-08T23:40:13.874046Z","end":"2026-07-08T23:40:14.267373Z","steps":["trace[1131588872] 'agreement among raft nodes before linearized reading' (duration: 393.191551ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:40:14.267418Z","caller":"traceutil/trace.go:171","msg":"trace[1622359364] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1651; }","duration":"275.939867ms","start":"2026-07-08T23:40:13.991469Z","end":"2026-07-08T23:40:14.267409Z","steps":["trace[1622359364] 'agreement among raft nodes before linearized reading' (duration: 275.797003ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:40:14.267426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:40:13.874024Z","time spent":"393.391155ms","remote":"127.0.0.1:50172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-07-08T23:40:17.602916Z","caller":"traceutil/trace.go:171","msg":"trace[103466646] transaction","detail":"{read_only:false; response_revision:1663; number_of_response:1; }","duration":"218.114698ms","start":"2026-07-08T23:40:17.384781Z","end":"2026-07-08T23:40:17.602896Z","steps":["trace[103466646] 'process raft request' (duration: 217.945644ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:40:26.024621Z","caller":"traceutil/trace.go:171","msg":"trace[2097525938] transaction","detail":"{read_only:false; response_revision:1703; number_of_response:1; }","duration":"132.205651ms","start":"2026-07-08T23:40:25.892374Z","end":"2026-07-08T23:40:26.02458Z","steps":["trace[2097525938] 'process raft request' (duration: 111.545358ms)","trace[2097525938] 'compare' (duration: 20.526169ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:40:26.024732Z","caller":"traceutil/trace.go:171","msg":"trace[128284802] linearizableReadLoop","detail":"{readStateIndex:1774; appliedIndex:1771; }","duration":"112.963992ms","start":"2026-07-08T23:40:25.911739Z","end":"2026-07-08T23:40:26.024703Z","steps":["trace[128284802] 'read index received' (duration: 52.838269ms)","trace[128284802] 'applied index is now lower than readState.Index' (duration: 60.123192ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:40:26.024975Z","caller":"traceutil/trace.go:171","msg":"trace[283491449] transaction","detail":"{read_only:false; response_revision:1704; number_of_response:1; }","duration":"132.485028ms","start":"2026-07-08T23:40:25.892472Z","end":"2026-07-08T23:40:26.024957Z","steps":["trace[283491449] 'process raft request' (duration: 132.072968ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:40:26.025019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.254008ms","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-08T23:40:26.025072Z","caller":"traceutil/trace.go:171","msg":"trace[682939373] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1704; }","duration":"113.341091ms","start":"2026-07-08T23:40:25.911714Z","end":"2026-07-08T23:40:26.025055Z","steps":["trace[682939373] 'agreement among raft nodes before linearized reading' (duration: 113.159047ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:40:26.025105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.276866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-07-08T23:40:26.02522Z","caller":"traceutil/trace.go:171","msg":"trace[390876249] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1704; }","duration":"112.454379ms","start":"2026-07-08T23:40:25.912741Z","end":"2026-07-08T23:40:26.025195Z","steps":["trace[390876249] 'agreement among raft nodes before linearized reading' (duration: 112.160262ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:40:46.28741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.201488ms","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-08T23:40:46.287509Z","caller":"traceutil/trace.go:171","msg":"trace[1466621248] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1794; }","duration":"113.355342ms","start":"2026-07-08T23:40:46.174132Z","end":"2026-07-08T23:40:46.287487Z","steps":["trace[1466621248] 'range keys from in-memory index tree' (duration: 112.998504ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:40:46.287516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.216402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-08T23:40:46.287425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.341987ms","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-08T23:40:46.287587Z","caller":"traceutil/trace.go:171","msg":"trace[40091739] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1794; }","duration":"104.534272ms","start":"2026-07-08T23:40:46.183036Z","end":"2026-07-08T23:40:46.28757Z","steps":["trace[40091739] 'range keys from in-memory index tree' (duration: 104.150532ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:40:46.288079Z","caller":"traceutil/trace.go:171","msg":"trace[1765857147] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1794; }","duration":"115.033201ms","start":"2026-07-08T23:40:46.173031Z","end":"2026-07-08T23:40:46.288064Z","steps":["trace[1765857147] 'range keys from in-memory index tree' (duration: 114.12856ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:40:46.724382Z","caller":"traceutil/trace.go:171","msg":"trace[1862968432] transaction","detail":"{read_only:false; response_revision:1796; number_of_response:1; }","duration":"432.363214ms","start":"2026-07-08T23:40:46.291993Z","end":"2026-07-08T23:40:46.724357Z","steps":["trace[1862968432] 'process raft request' (duration: 359.454057ms)","trace[1862968432] 'compare' (duration: 72.722432ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:40:46.724618Z","caller":"traceutil/trace.go:171","msg":"trace[1159774692] transaction","detail":"{read_only:false; response_revision:1797; number_of_response:1; }","duration":"432.573618ms","start":"2026-07-08T23:40:46.29202Z","end":"2026-07-08T23:40:46.724594Z","steps":["trace[1159774692] 'process raft request' (duration: 432.278121ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:40:46.724754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:40:46.291999Z","time spent":"432.687952ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T23:40:46.725058Z","caller":"traceutil/trace.go:171","msg":"trace[429960295] transaction","detail":"{read_only:false; response_revision:1798; number_of_response:1; }","duration":"432.813144ms","start":"2026-07-08T23:40:46.292234Z","end":"2026-07-08T23:40:46.725047Z","steps":["trace[429960295] 'process raft request' (duration: 432.288562ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:40:46.725113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:40:46.292223Z","time spent":"432.859576ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":516,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T23:40:46.725696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:40:46.291973Z","time spent":"432.480366ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T23:40:46.78332Z","caller":"traceutil/trace.go:171","msg":"trace[1430294646] transaction","detail":"{read_only:false; response_revision:1799; number_of_response:1; }","duration":"204.305819ms","start":"2026-07-08T23:40:46.578989Z","end":"2026-07-08T23:40:46.783294Z","steps":["trace[1430294646] 'process raft request' (duration: 203.452258ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:41:38.81677Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967605805561170953,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-08T23:41:39.273391Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.12341659s","expected-duration":"1s"} {"level":"info","ts":"2026-07-08T23:41:39.273881Z","caller":"traceutil/trace.go:171","msg":"trace[496263641] linearizableReadLoop","detail":"{readStateIndex:2117; appliedIndex:2116; }","duration":"957.759185ms","start":"2026-07-08T23:41:38.316104Z","end":"2026-07-08T23:41:39.273863Z","steps":["trace[496263641] 'read index received' (duration: 957.399137ms)","trace[496263641] 'applied index is now lower than readState.Index' (duration: 359.118µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:41:39.274135Z","caller":"traceutil/trace.go:171","msg":"trace[1778961494] transaction","detail":"{read_only:false; response_revision:2033; number_of_response:1; }","duration":"1.124295052s","start":"2026-07-08T23:41:38.149825Z","end":"2026-07-08T23:41:39.27412Z","steps":["trace[1778961494] 'process raft request' (duration: 1.123736868s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:41:39.274251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:41:38.149787Z","time spent":"1.124374454s","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T23:41:39.274431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"958.33681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-08T23:41:39.274451Z","caller":"traceutil/trace.go:171","msg":"trace[728107565] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2033; }","duration":"958.401391ms","start":"2026-07-08T23:41:38.316043Z","end":"2026-07-08T23:41:39.274445Z","steps":["trace[728107565] 'agreement among raft nodes before linearized reading' (duration: 958.324629ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:41:39.274466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:41:38.31602Z","time spent":"958.442372ms","remote":"127.0.0.1:43340","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-08T23:41:39.27458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.602607ms","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-08T23:41:39.274594Z","caller":"traceutil/trace.go:171","msg":"trace[850054182] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2033; }","duration":"641.648249ms","start":"2026-07-08T23:41:38.632941Z","end":"2026-07-08T23:41:39.274589Z","steps":["trace[850054182] 'agreement among raft nodes before linearized reading' (duration: 641.587227ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:41:39.274607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:41:38.632924Z","time spent":"641.679619ms","remote":"127.0.0.1:43340","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-08T23:41:39.274711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.942317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T23:41:39.274726Z","caller":"traceutil/trace.go:171","msg":"trace[404993832] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2033; }","duration":"101.986659ms","start":"2026-07-08T23:41:39.172735Z","end":"2026-07-08T23:41:39.274722Z","steps":["trace[404993832] 'agreement among raft nodes before linearized reading' (duration: 101.958318ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:41:39.274819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.047314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-07-08T23:41:39.274831Z","caller":"traceutil/trace.go:171","msg":"trace[219378104] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2033; }","duration":"305.070764ms","start":"2026-07-08T23:41:38.969756Z","end":"2026-07-08T23:41:39.274827Z","steps":["trace[219378104] 'agreement among raft nodes before linearized reading' (duration: 305.034613ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:41:39.274843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:41:38.969751Z","time spent":"305.088745ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-07-08T23:41:39.275105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.516732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-08T23:41:39.275117Z","caller":"traceutil/trace.go:171","msg":"trace[1805330289] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2033; }","duration":"312.558203ms","start":"2026-07-08T23:41:38.962555Z","end":"2026-07-08T23:41:39.275113Z","steps":["trace[1805330289] 'agreement among raft nodes before linearized reading' (duration: 312.334168ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:41:39.275129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:41:38.96254Z","time spent":"312.586173ms","remote":"127.0.0.1:43340","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-08T23:41:39.275221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.983173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-08T23:41:39.275237Z","caller":"traceutil/trace.go:171","msg":"trace[2122877524] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2033; }","duration":"313.036124ms","start":"2026-07-08T23:41:38.962196Z","end":"2026-07-08T23:41:39.275232Z","steps":["trace[2122877524] 'agreement among raft nodes before linearized reading' (duration: 312.985223ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:41:39.275255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:41:38.962175Z","time spent":"313.073415ms","remote":"127.0.0.1:43340","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-08T23:41:39.275371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.598109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.78\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-08T23:41:39.275393Z","caller":"traceutil/trace.go:171","msg":"trace[328529699] range","detail":"{range_begin:/registry/masterleases/199.204.45.78; range_end:; response_count:1; response_revision:2033; }","duration":"455.676931ms","start":"2026-07-08T23:41:38.819709Z","end":"2026-07-08T23:41:39.275386Z","steps":["trace[328529699] 'agreement among raft nodes before linearized reading' (duration: 455.612589ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:41:39.275409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:41:38.819691Z","time spent":"455.713661ms","remote":"127.0.0.1:43112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.78\" "} {"level":"info","ts":"2026-07-08T23:42:49.649664Z","caller":"traceutil/trace.go:171","msg":"trace[1210635743] linearizableReadLoop","detail":"{readStateIndex:2479; appliedIndex:2478; }","duration":"100.96839ms","start":"2026-07-08T23:42:49.54868Z","end":"2026-07-08T23:42:49.649649Z","steps":["trace[1210635743] 'read index received' (duration: 100.608152ms)","trace[1210635743] 'applied index is now lower than readState.Index' (duration: 359.378µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:42:49.649852Z","caller":"traceutil/trace.go:171","msg":"trace[1574295400] transaction","detail":"{read_only:false; response_revision:2379; number_of_response:1; }","duration":"106.576524ms","start":"2026-07-08T23:42:49.543246Z","end":"2026-07-08T23:42:49.649823Z","steps":["trace[1574295400] 'process raft request' (duration: 106.108873ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:42:49.649867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.174906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-07-08T23:42:49.649993Z","caller":"traceutil/trace.go:171","msg":"trace[1321716813] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2379; }","duration":"101.334519ms","start":"2026-07-08T23:42:49.548643Z","end":"2026-07-08T23:42:49.649977Z","steps":["trace[1321716813] 'agreement among raft nodes before linearized reading' (duration: 101.100083ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:42:57.909936Z","caller":"traceutil/trace.go:171","msg":"trace[1032978003] transaction","detail":"{read_only:false; response_revision:2424; number_of_response:1; }","duration":"144.52568ms","start":"2026-07-08T23:42:57.765386Z","end":"2026-07-08T23:42:57.909911Z","steps":["trace[1032978003] 'process raft request' (duration: 144.464909ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:42:57.910006Z","caller":"traceutil/trace.go:171","msg":"trace[1305269105] transaction","detail":"{read_only:false; response_revision:2422; number_of_response:1; }","duration":"144.725605ms","start":"2026-07-08T23:42:57.765256Z","end":"2026-07-08T23:42:57.909981Z","steps":["trace[1305269105] 'process raft request' (duration: 69.321075ms)","trace[1305269105] 'compare' (duration: 74.915888ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:42:57.910175Z","caller":"traceutil/trace.go:171","msg":"trace[320348342] transaction","detail":"{read_only:false; response_revision:2423; number_of_response:1; }","duration":"144.862978ms","start":"2026-07-08T23:42:57.765298Z","end":"2026-07-08T23:42:57.910161Z","steps":["trace[320348342] 'process raft request' (duration: 144.467979ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:42:57.910386Z","caller":"traceutil/trace.go:171","msg":"trace[878238157] transaction","detail":"{read_only:false; response_revision:2425; number_of_response:1; }","duration":"144.907479ms","start":"2026-07-08T23:42:57.765463Z","end":"2026-07-08T23:42:57.91037Z","steps":["trace[878238157] 'process raft request' (duration: 144.419328ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:42:57.91048Z","caller":"traceutil/trace.go:171","msg":"trace[417695924] transaction","detail":"{read_only:false; response_revision:2426; number_of_response:1; }","duration":"143.139057ms","start":"2026-07-08T23:42:57.767323Z","end":"2026-07-08T23:42:57.910463Z","steps":["trace[417695924] 'process raft request' (duration: 142.991384ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:42:58.111987Z","caller":"traceutil/trace.go:171","msg":"trace[1780393961] transaction","detail":"{read_only:false; response_revision:2427; number_of_response:1; }","duration":"146.984949ms","start":"2026-07-08T23:42:57.964964Z","end":"2026-07-08T23:42:58.111949Z","steps":["trace[1780393961] 'process raft request' (duration: 111.524962ms)","trace[1780393961] 'compare' (duration: 35.202531ms)"],"step_count":2}