{"level":"warn","ts":"2026-08-08T00:43:58.911664Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-08T00:43:58.911741Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.38: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.38:2380","--initial-cluster=instance=https://199.204.45.38:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.38:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.38: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-08-08T00:43:58.911812Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-08T00:43:58.911828Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.38:2380"]} {"level":"info","ts":"2026-08-08T00:43:58.911857Z","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-08-08T00:43:58.912817Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"]} {"level":"info","ts":"2026-08-08T00:43:58.912888Z","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.38:2380"],"listen-peer-urls":["https://199.204.45.38:2380"],"advertise-client-urls":["https://199.204.45.38:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.38: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-08-08T00:43:58.934494Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"21.395632ms"} {"level":"info","ts":"2026-08-08T00:43:58.949402Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"331455d6226d3423","cluster-id":"25c7803fb83a1455"} {"level":"info","ts":"2026-08-08T00:43:58.949478Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=()"} {"level":"info","ts":"2026-08-08T00:43:58.949625Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became follower at term 0"} {"level":"info","ts":"2026-08-08T00:43:58.949733Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 331455d6226d3423 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-08T00:43:58.949837Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became follower at term 1"} {"level":"info","ts":"2026-08-08T00:43:58.949968Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=(3680661173657482275)"} {"level":"warn","ts":"2026-08-08T00:43:58.95762Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-08T00:43:58.959118Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-08T00:43:58.960149Z","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-08-08T00:43:58.962352Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"331455d6226d3423","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-08T00:43:58.962979Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"331455d6226d3423","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-08T00:43:58.963122Z","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-08-08T00:43:58.963545Z","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-08-08T00:43:58.964185Z","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-08-08T00:43:58.964587Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=(3680661173657482275)"} {"level":"info","ts":"2026-08-08T00:43:58.965174Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"25c7803fb83a1455","local-member-id":"331455d6226d3423","added-peer-id":"331455d6226d3423","added-peer-peer-urls":["https://199.204.45.38:2380"]} {"level":"info","ts":"2026-08-08T00:43:58.968314Z","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-08-08T00:43:58.968424Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.38:2380"} {"level":"info","ts":"2026-08-08T00:43:58.968448Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.38:2380"} {"level":"info","ts":"2026-08-08T00:43:58.968633Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"331455d6226d3423","initial-advertise-peer-urls":["https://199.204.45.38:2380"],"listen-peer-urls":["https://199.204.45.38:2380"],"advertise-client-urls":["https://199.204.45.38:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-08T00:43:58.968682Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-08T00:43:59.151209Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 is starting a new election at term 1"} {"level":"info","ts":"2026-08-08T00:43:59.151265Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-08T00:43:59.151294Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 received MsgPreVoteResp from 331455d6226d3423 at term 1"} {"level":"info","ts":"2026-08-08T00:43:59.151314Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became candidate at term 2"} {"level":"info","ts":"2026-08-08T00:43:59.151331Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 received MsgVoteResp from 331455d6226d3423 at term 2"} {"level":"info","ts":"2026-08-08T00:43:59.151347Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became leader at term 2"} {"level":"info","ts":"2026-08-08T00:43:59.15136Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 331455d6226d3423 elected leader 331455d6226d3423 at term 2"} {"level":"info","ts":"2026-08-08T00:43:59.15536Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"331455d6226d3423","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.38:2379]}","request-path":"/0/members/331455d6226d3423/attributes","cluster-id":"25c7803fb83a1455","publish-timeout":"7s"} {"level":"info","ts":"2026-08-08T00:43:59.155465Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-08T00:43:59.155793Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-08T00:43:59.156161Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-08T00:43:59.156785Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-08T00:43:59.156825Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-08T00:43:59.157528Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.38:2379"} {"level":"info","ts":"2026-08-08T00:43:59.157598Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"25c7803fb83a1455","local-member-id":"331455d6226d3423","cluster-version":"3.5"} {"level":"info","ts":"2026-08-08T00:43:59.157657Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-08T00:43:59.157676Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-08T00:43:59.165241Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-08T00:44:00.79284Z","caller":"traceutil/trace.go:171","msg":"trace[727915488] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"181.870178ms","start":"2026-08-08T00:44:00.610943Z","end":"2026-08-08T00:44:00.792813Z","steps":["trace[727915488] 'process raft request' (duration: 181.659571ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T00:44:01.240921Z","caller":"traceutil/trace.go:171","msg":"trace[18558835] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"629.068303ms","start":"2026-08-08T00:44:00.611814Z","end":"2026-08-08T00:44:01.240882Z","steps":["trace[18558835] 'process raft request' (duration: 628.775064ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T00:44:01.240967Z","caller":"traceutil/trace.go:171","msg":"trace[1136390610] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"552.204839ms","start":"2026-08-08T00:44:00.688722Z","end":"2026-08-08T00:44:01.240927Z","steps":["trace[1136390610] 'process raft request' (duration: 552.041353ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T00:44:01.241015Z","caller":"traceutil/trace.go:171","msg":"trace[412188327] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"628.497425ms","start":"2026-08-08T00:44:00.612493Z","end":"2026-08-08T00:44:01.24099Z","steps":["trace[412188327] 'read index received' (duration: 180.249336ms)","trace[412188327] 'applied index is now lower than readState.Index' (duration: 448.246949ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T00:44:01.241231Z","caller":"traceutil/trace.go:171","msg":"trace[143878983] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"553.205801ms","start":"2026-08-08T00:44:00.688007Z","end":"2026-08-08T00:44:01.241213Z","steps":["trace[143878983] 'process raft request' (duration: 552.716865ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T00:44:01.241299Z","caller":"traceutil/trace.go:171","msg":"trace[1724239059] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"545.492742ms","start":"2026-08-08T00:44:00.695776Z","end":"2026-08-08T00:44:01.241269Z","steps":["trace[1724239059] 'process raft request' (duration: 545.12779ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T00:44:01.241447Z","caller":"traceutil/trace.go:171","msg":"trace[1899032322] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"550.805594ms","start":"2026-08-08T00:44:00.69063Z","end":"2026-08-08T00:44:01.241436Z","steps":["trace[1899032322] 'process raft request' (duration: 550.242386ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T00:44:01.241422Z","caller":"traceutil/trace.go:171","msg":"trace[1150604107] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"552.418556ms","start":"2026-08-08T00:44:00.688948Z","end":"2026-08-08T00:44:01.241367Z","steps":["trace[1150604107] 'process raft request' (duration: 551.900259ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T00:44:01.241475Z","caller":"traceutil/trace.go:171","msg":"trace[379292313] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"552.546099ms","start":"2026-08-08T00:44:00.688905Z","end":"2026-08-08T00:44:01.241451Z","steps":["trace[379292313] 'process raft request' (duration: 551.920139ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:44:01.241525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:44:00.690555Z","time spent":"550.928288ms","remote":"127.0.0.1:59450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-08T00:44:01.24159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:44:00.688893Z","time spent":"552.621973ms","remote":"127.0.0.1:59450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-08T00:44:01.241606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:44:00.611801Z","time spent":"629.222749ms","remote":"127.0.0.1:58996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-08T00:44:01.241612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:44:00.688906Z","time spent":"552.607311ms","remote":"127.0.0.1:59450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-08T00:44:01.241672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:44:00.688705Z","time spent":"552.381295ms","remote":"127.0.0.1:59450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-08T00:44:01.241824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:44:00.695748Z","time spent":"545.622186ms","remote":"127.0.0.1:59178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-08T00:44:01.241823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.29165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3170"} {"level":"warn","ts":"2026-08-08T00:44:01.241885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.852964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-08T00:44:01.241918Z","caller":"traceutil/trace.go:171","msg":"trace[417584198] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"498.879145ms","start":"2026-08-08T00:44:00.743026Z","end":"2026-08-08T00:44:01.241905Z","steps":["trace[417584198] 'agreement among raft nodes before linearized reading' (duration: 498.843184ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:44:01.241956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:44:00.742978Z","time spent":"498.972009ms","remote":"127.0.0.1:59040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-08-08T00:44:01.241995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:44:00.687988Z","time spent":"553.280714ms","remote":"127.0.0.1:59362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-08T00:44:01.242058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.473025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-08T00:44:01.242092Z","caller":"traceutil/trace.go:171","msg":"trace[1137353795] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"555.547277ms","start":"2026-08-08T00:44:00.686537Z","end":"2026-08-08T00:44:01.242085Z","steps":["trace[1137353795] 'agreement among raft nodes before linearized reading' (duration: 555.496575ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T00:44:01.242001Z","caller":"traceutil/trace.go:171","msg":"trace[299578799] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"538.763525ms","start":"2026-08-08T00:44:00.703229Z","end":"2026-08-08T00:44:01.241992Z","steps":["trace[299578799] 'process raft request' (duration: 537.727141ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:44:01.242119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:44:00.686506Z","time spent":"555.607079ms","remote":"127.0.0.1:59040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-08-08T00:44:01.242171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.877038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.38\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-08T00:44:01.242233Z","caller":"traceutil/trace.go:171","msg":"trace[1999729599] range","detail":"{range_begin:/registry/masterleases/199.204.45.38; range_end:; response_count:0; response_revision:11; }","duration":"555.983491ms","start":"2026-08-08T00:44:00.68624Z","end":"2026-08-08T00:44:01.242224Z","steps":["trace[1999729599] 'agreement among raft nodes before linearized reading' (duration: 555.909589ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:44:01.242261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:44:00.686226Z","time spent":"556.028882ms","remote":"127.0.0.1:58978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":26,"request content":"key:\"/registry/masterleases/199.204.45.38\" "} {"level":"warn","ts":"2026-08-08T00:44:01.242279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.988186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-08T00:44:01.241885Z","caller":"traceutil/trace.go:171","msg":"trace[904697179] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:11; }","duration":"629.394864ms","start":"2026-08-08T00:44:00.612477Z","end":"2026-08-08T00:44:01.241872Z","steps":["trace[904697179] 'agreement among raft nodes before linearized reading' (duration: 629.227089ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T00:44:01.242313Z","caller":"traceutil/trace.go:171","msg":"trace[261478243] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"554.063549ms","start":"2026-08-08T00:44:00.688238Z","end":"2026-08-08T00:44:01.242302Z","steps":["trace[261478243] 'agreement among raft nodes before linearized reading' (duration: 554.018047ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:44:01.242164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:44:00.703218Z","time spent":"538.91811ms","remote":"127.0.0.1:59098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3158,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-08T00:44:01.242347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:44:00.688197Z","time spent":"554.141151ms","remote":"127.0.0.1:59006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":26,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-08-08T00:44:01.24235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:44:00.61247Z","time spent":"629.868389ms","remote":"127.0.0.1:59098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3192,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-08-08T00:44:01.241835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.146074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-08T00:44:01.242553Z","caller":"traceutil/trace.go:171","msg":"trace[329125498] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"581.876088ms","start":"2026-08-08T00:44:00.660662Z","end":"2026-08-08T00:44:01.242538Z","steps":["trace[329125498] 'agreement among raft nodes before linearized reading' (duration: 581.058191ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:44:01.242609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:44:00.660646Z","time spent":"581.94467ms","remote":"127.0.0.1:59040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-08-08T00:44:01.242714Z","caller":"traceutil/trace.go:171","msg":"trace[1586408913] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"553.880813ms","start":"2026-08-08T00:44:00.688818Z","end":"2026-08-08T00:44:01.242698Z","steps":["trace[1586408913] 'process raft request' (duration: 551.980571ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:44:01.242844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:44:00.688793Z","time spent":"554.013587ms","remote":"127.0.0.1:59450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-08T00:44:01.506436Z","caller":"traceutil/trace.go:171","msg":"trace[401601942] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"259.713325ms","start":"2026-08-08T00:44:01.246701Z","end":"2026-08-08T00:44:01.506414Z","steps":["trace[401601942] 'process raft request' (duration: 251.754867ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T00:44:01.5066Z","caller":"traceutil/trace.go:171","msg":"trace[792678927] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"259.383854ms","start":"2026-08-08T00:44:01.247205Z","end":"2026-08-08T00:44:01.506589Z","steps":["trace[792678927] 'process raft request' (duration: 259.177318ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T00:44:01.506843Z","caller":"traceutil/trace.go:171","msg":"trace[1410835208] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"259.695874ms","start":"2026-08-08T00:44:01.247137Z","end":"2026-08-08T00:44:01.506833Z","steps":["trace[1410835208] 'process raft request' (duration: 259.202628ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T00:44:01.506853Z","caller":"traceutil/trace.go:171","msg":"trace[1770119274] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"259.470757ms","start":"2026-08-08T00:44:01.24735Z","end":"2026-08-08T00:44:01.50682Z","steps":["trace[1770119274] 'process raft request' (duration: 259.094465ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T00:44:01.50707Z","caller":"traceutil/trace.go:171","msg":"trace[488442864] linearizableReadLoop","detail":"{readStateIndex:24; appliedIndex:14; }","duration":"201.542785ms","start":"2026-08-08T00:44:01.305488Z","end":"2026-08-08T00:44:01.507031Z","steps":["trace[488442864] 'read index received' (duration: 192.980008ms)","trace[488442864] 'applied index is now lower than readState.Index' (duration: 8.561477ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T00:44:01.50729Z","caller":"traceutil/trace.go:171","msg":"trace[358856920] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"258.965891ms","start":"2026-08-08T00:44:01.248311Z","end":"2026-08-08T00:44:01.507277Z","steps":["trace[358856920] 'process raft request' (duration: 258.180175ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T00:44:01.507317Z","caller":"traceutil/trace.go:171","msg":"trace[99406154] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"258.881837ms","start":"2026-08-08T00:44:01.248421Z","end":"2026-08-08T00:44:01.507303Z","steps":["trace[99406154] 'process raft request' (duration: 258.107582ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T00:44:01.507586Z","caller":"traceutil/trace.go:171","msg":"trace[513588137] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"255.113986ms","start":"2026-08-08T00:44:01.25246Z","end":"2026-08-08T00:44:01.507574Z","steps":["trace[513588137] 'process raft request' (duration: 254.395623ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T00:44:01.507608Z","caller":"traceutil/trace.go:171","msg":"trace[2090891078] transaction","detail":"{read_only:false; number_of_response:0; response_revision:18; }","duration":"259.056004ms","start":"2026-08-08T00:44:01.248531Z","end":"2026-08-08T00:44:01.507587Z","steps":["trace[2090891078] 'process raft request' (duration: 258.245307ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T00:44:01.507794Z","caller":"traceutil/trace.go:171","msg":"trace[1852150887] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"258.468725ms","start":"2026-08-08T00:44:01.249313Z","end":"2026-08-08T00:44:01.507781Z","steps":["trace[1852150887] 'process raft request' (duration: 257.490743ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:44:01.507859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.367441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-08-08T00:44:01.507902Z","caller":"traceutil/trace.go:171","msg":"trace[884047633] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:20; }","duration":"202.445913ms","start":"2026-08-08T00:44:01.305444Z","end":"2026-08-08T00:44:01.50789Z","steps":["trace[884047633] 'agreement among raft nodes before linearized reading' (duration: 202.365251ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T00:44:01.507107Z","caller":"traceutil/trace.go:171","msg":"trace[1166260640] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"259.86574ms","start":"2026-08-08T00:44:01.247232Z","end":"2026-08-08T00:44:01.507098Z","steps":["trace[1166260640] 'process raft request' (duration: 259.178858ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T00:44:34.868222Z","caller":"traceutil/trace.go:171","msg":"trace[1398253370] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"201.176562ms","start":"2026-08-08T00:44:34.667022Z","end":"2026-08-08T00:44:34.868199Z","steps":["trace[1398253370] 'process raft request' (duration: 201.042828ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T00:44:35.330434Z","caller":"traceutil/trace.go:171","msg":"trace[537505230] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"553.718817ms","start":"2026-08-08T00:44:34.776664Z","end":"2026-08-08T00:44:35.330383Z","steps":["trace[537505230] 'process raft request' (duration: 553.536131ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T00:44:35.33065Z","caller":"traceutil/trace.go:171","msg":"trace[268789011] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"541.315217ms","start":"2026-08-08T00:44:34.789301Z","end":"2026-08-08T00:44:35.330616Z","steps":["trace[268789011] 'process raft request' (duration: 541.046248ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T00:44:35.330773Z","caller":"traceutil/trace.go:171","msg":"trace[1438520874] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"541.485743ms","start":"2026-08-08T00:44:34.789247Z","end":"2026-08-08T00:44:35.330733Z","steps":["trace[1438520874] 'process raft request' (duration: 541.063389ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T00:44:35.330831Z","caller":"traceutil/trace.go:171","msg":"trace[1022035822] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"518.775058ms","start":"2026-08-08T00:44:34.812012Z","end":"2026-08-08T00:44:35.330788Z","steps":["trace[1022035822] 'process raft request' (duration: 518.538191ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T00:44:35.330859Z","caller":"traceutil/trace.go:171","msg":"trace[901205817] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"523.997117ms","start":"2026-08-08T00:44:34.806852Z","end":"2026-08-08T00:44:35.330849Z","steps":["trace[901205817] 'process raft request' (duration: 523.536082ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:44:35.330903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:44:34.812006Z","time spent":"518.861991ms","remote":"127.0.0.1:58986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-08T00:44:35.330986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:44:34.806841Z","time spent":"524.064069ms","remote":"127.0.0.1:58986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-08T00:44:35.330992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:44:34.789229Z","time spent":"541.676779ms","remote":"127.0.0.1:58986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-08T00:44:35.331057Z","caller":"traceutil/trace.go:171","msg":"trace[805218333] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"511.290397ms","start":"2026-08-08T00:44:34.819756Z","end":"2026-08-08T00:44:35.331046Z","steps":["trace[805218333] 'process raft request' (duration: 510.872763ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T00:44:35.330798Z","caller":"traceutil/trace.go:171","msg":"trace[816586780] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"523.825621ms","start":"2026-08-08T00:44:34.806931Z","end":"2026-08-08T00:44:35.330757Z","steps":["trace[816586780] 'process raft request' (duration: 523.578073ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T00:44:35.3311Z","caller":"traceutil/trace.go:171","msg":"trace[652751495] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"484.76848ms","start":"2026-08-08T00:44:34.846307Z","end":"2026-08-08T00:44:35.331076Z","steps":["trace[652751495] 'process raft request' (duration: 484.715628ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:44:35.331126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:44:34.819725Z","time spent":"511.37616ms","remote":"127.0.0.1:58986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-08T00:44:35.331147Z","caller":"traceutil/trace.go:171","msg":"trace[1696824062] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"514.928804ms","start":"2026-08-08T00:44:34.816203Z","end":"2026-08-08T00:44:35.331132Z","steps":["trace[1696824062] 'process raft request' (duration: 514.387247ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:44:35.331157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:44:34.806927Z","time spent":"524.205364ms","remote":"127.0.0.1:58986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-08T00:44:35.331138Z","caller":"traceutil/trace.go:171","msg":"trace[1926389951] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"502.74024ms","start":"2026-08-08T00:44:34.828357Z","end":"2026-08-08T00:44:35.331097Z","steps":["trace[1926389951] 'process raft request' (duration: 502.403769ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:44:35.331211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:44:34.816196Z","time spent":"514.987126ms","remote":"127.0.0.1:58986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-08T00:44:35.331221Z","caller":"traceutil/trace.go:171","msg":"trace[1054438796] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"501.682136ms","start":"2026-08-08T00:44:34.829529Z","end":"2026-08-08T00:44:35.331211Z","steps":["trace[1054438796] 'process raft request' (duration: 501.415097ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:44:35.331221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:44:34.846293Z","time spent":"484.867282ms","remote":"127.0.0.1:58986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-08T00:44:35.331268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:44:34.829511Z","time spent":"501.737497ms","remote":"127.0.0.1:58986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-08T00:44:35.331308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:44:34.828347Z","time spent":"502.883065ms","remote":"127.0.0.1:58986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-08T00:44:35.331366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:44:34.789296Z","time spent":"541.40821ms","remote":"127.0.0.1:58986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-08T00:44:35.331389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:44:34.77664Z","time spent":"553.874703ms","remote":"127.0.0.1:58986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-08T00:44:35.33634Z","caller":"traceutil/trace.go:171","msg":"trace[1602305059] linearizableReadLoop","detail":"{readStateIndex:561; appliedIndex:549; }","duration":"355.116098ms","start":"2026-08-08T00:44:34.981213Z","end":"2026-08-08T00:44:35.33633Z","steps":["trace[1602305059] 'read index received' (duration: 349.033862ms)","trace[1602305059] 'applied index is now lower than readState.Index' (duration: 6.081556ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T00:44:35.336388Z","caller":"traceutil/trace.go:171","msg":"trace[1453290477] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"399.449771ms","start":"2026-08-08T00:44:34.936905Z","end":"2026-08-08T00:44:35.336355Z","steps":["trace[1453290477] 'process raft request' (duration: 399.299386ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:44:35.336431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.210281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-08T00:44:35.336457Z","caller":"traceutil/trace.go:171","msg":"trace[2091472760] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:539; }","duration":"355.266973ms","start":"2026-08-08T00:44:34.981181Z","end":"2026-08-08T00:44:35.336448Z","steps":["trace[2091472760] 'agreement among raft nodes before linearized reading' (duration: 355.208011ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:44:35.336481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:44:34.98117Z","time spent":"355.306994ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-08T00:44:35.336471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:44:34.936892Z","time spent":"399.543514ms","remote":"127.0.0.1:58986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-08T00:44:41.626785Z","caller":"traceutil/trace.go:171","msg":"trace[181277741] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:655; }","duration":"429.586025ms","start":"2026-08-08T00:44:41.197173Z","end":"2026-08-08T00:44:41.626759Z","steps":["trace[181277741] 'read index received' (duration: 428.515711ms)","trace[181277741] 'applied index is now lower than readState.Index' (duration: 1.069004ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T00:44:41.626853Z","caller":"traceutil/trace.go:171","msg":"trace[1638241162] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"626.051495ms","start":"2026-08-08T00:44:41.000765Z","end":"2026-08-08T00:44:41.626817Z","steps":["trace[1638241162] 'process raft request' (duration: 625.151886ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:44:41.627021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.823993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8vt7f\" ","response":"range_response_count:1 size:16961"} {"level":"warn","ts":"2026-08-08T00:44:41.627041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:44:41.000743Z","time spent":"626.19823ms","remote":"127.0.0.1:59020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-08T00:44:41.627065Z","caller":"traceutil/trace.go:171","msg":"trace[2102731360] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8vt7f; range_end:; response_count:1; response_revision:632; }","duration":"429.929977ms","start":"2026-08-08T00:44:41.197122Z","end":"2026-08-08T00:44:41.627052Z","steps":["trace[2102731360] 'agreement among raft nodes before linearized reading' (duration: 429.773912ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:44:41.627107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:44:41.197101Z","time spent":"429.996928ms","remote":"127.0.0.1:59102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16983,"request content":"key:\"/registry/pods/kube-system/cilium-8vt7f\" "} {"level":"warn","ts":"2026-08-08T00:44:41.627264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.306749ms","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-08-08T00:44:41.627349Z","caller":"traceutil/trace.go:171","msg":"trace[251441586] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:632; }","duration":"254.424963ms","start":"2026-08-08T00:44:41.372897Z","end":"2026-08-08T00:44:41.627322Z","steps":["trace[251441586] 'agreement among raft nodes before linearized reading' (duration: 254.178315ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:44:41.779792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.939286ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-08T00:44:41.779918Z","caller":"traceutil/trace.go:171","msg":"trace[686170305] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"143.979763ms","start":"2026-08-08T00:44:41.635911Z","end":"2026-08-08T00:44:41.779891Z","steps":["trace[686170305] 'process raft request' (duration: 19.723737ms)","trace[686170305] 'compare' (duration: 123.794541ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T00:44:41.86945Z","caller":"traceutil/trace.go:171","msg":"trace[218120208] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"225.760856ms","start":"2026-08-08T00:44:41.64367Z","end":"2026-08-08T00:44:41.869431Z","steps":["trace[218120208] 'process raft request' (duration: 225.671603ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T00:44:41.869496Z","caller":"traceutil/trace.go:171","msg":"trace[1100498620] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"232.650078ms","start":"2026-08-08T00:44:41.636822Z","end":"2026-08-08T00:44:41.869472Z","steps":["trace[1100498620] 'process raft request' (duration: 232.413611ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T00:44:42.157868Z","caller":"traceutil/trace.go:171","msg":"trace[939672994] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"472.898114ms","start":"2026-08-08T00:44:41.684944Z","end":"2026-08-08T00:44:42.157842Z","steps":["trace[939672994] 'process raft request' (duration: 456.06871ms)","trace[939672994] 'compare' (duration: 16.587977ms)"],"step_count":2} {"level":"warn","ts":"2026-08-08T00:44:42.158003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:44:41.684911Z","time spent":"473.025738ms","remote":"127.0.0.1:59426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-08T00:44:43.074488Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757022293539187566,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-08T00:44:43.398386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:44:42.45335Z","time spent":"945.030656ms","remote":"127.0.0.1:58978","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-08-08T00:44:44.105918Z","caller":"traceutil/trace.go:171","msg":"trace[200160234] linearizableReadLoop","detail":"{readStateIndex:663; appliedIndex:662; }","duration":"1.532331639s","start":"2026-08-08T00:44:42.573564Z","end":"2026-08-08T00:44:44.105896Z","steps":["trace[200160234] 'read index received' (duration: 825.164421ms)","trace[200160234] 'applied index is now lower than readState.Index' (duration: 707.166508ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T00:44:44.106039Z","caller":"traceutil/trace.go:171","msg":"trace[1236677775] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"1.468761904s","start":"2026-08-08T00:44:42.637214Z","end":"2026-08-08T00:44:44.105976Z","steps":["trace[1236677775] 'process raft request' (duration: 1.468224427s)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:44:44.106088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.532496805s","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-08-08T00:44:44.106147Z","caller":"traceutil/trace.go:171","msg":"trace[96175787] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:638; }","duration":"1.532614228s","start":"2026-08-08T00:44:42.573522Z","end":"2026-08-08T00:44:44.106136Z","steps":["trace[96175787] 'agreement among raft nodes before linearized reading' (duration: 1.532463384s)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:44:44.106184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:44:42.573501Z","time spent":"1.532665539s","remote":"127.0.0.1:59182","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-08-08T00:44:44.106214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:44:42.637189Z","time spent":"1.46893216s","remote":"127.0.0.1:59020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-08T00:44:44.115587Z","caller":"traceutil/trace.go:171","msg":"trace[106182430] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"716.19583ms","start":"2026-08-08T00:44:43.39937Z","end":"2026-08-08T00:44:44.115566Z","steps":["trace[106182430] 'process raft request' (duration: 716.073126ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:44:44.115732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:44:43.399351Z","time spent":"716.303532ms","remote":"127.0.0.1:58978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-08T00:44:44.11635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.541399363s","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-08-08T00:44:44.116387Z","caller":"traceutil/trace.go:171","msg":"trace[564060450] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:639; }","duration":"1.541502946s","start":"2026-08-08T00:44:42.574875Z","end":"2026-08-08T00:44:44.116378Z","steps":["trace[564060450] 'agreement among raft nodes before linearized reading' (duration: 1.54133882s)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:44:44.116425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:44:42.574866Z","time spent":"1.541553027s","remote":"127.0.0.1:59182","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-08-08T00:44:44.117039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.428068ms","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-08-08T00:44:44.117109Z","caller":"traceutil/trace.go:171","msg":"trace[1857584331] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:639; }","duration":"243.542352ms","start":"2026-08-08T00:44:43.873548Z","end":"2026-08-08T00:44:44.11709Z","steps":["trace[1857584331] 'agreement among raft nodes before linearized reading' (duration: 242.982324ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:44:44.11729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.589023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-08T00:44:44.117315Z","caller":"traceutil/trace.go:171","msg":"trace[1713788579] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:639; }","duration":"333.655195ms","start":"2026-08-08T00:44:43.783651Z","end":"2026-08-08T00:44:44.117306Z","steps":["trace[1713788579] 'agreement among raft nodes before linearized reading' (duration: 333.565492ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:44:44.117359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:44:43.783618Z","time spent":"333.731947ms","remote":"127.0.0.1:59182","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-08-08T00:44:44.117644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.136210596s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-08T00:44:44.117769Z","caller":"traceutil/trace.go:171","msg":"trace[2003969345] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:639; }","duration":"1.136363361s","start":"2026-08-08T00:44:42.981399Z","end":"2026-08-08T00:44:44.117763Z","steps":["trace[2003969345] 'agreement among raft nodes before linearized reading' (duration: 1.136228156s)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:44:44.117786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:44:42.981383Z","time spent":"1.136396831s","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-08T00:44:46.205031Z","caller":"traceutil/trace.go:171","msg":"trace[1792456910] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"738.213541ms","start":"2026-08-08T00:44:45.46679Z","end":"2026-08-08T00:44:46.205004Z","steps":["trace[1792456910] 'process raft request' (duration: 738.035775ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:44:46.2052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:44:45.46674Z","time spent":"738.387857ms","remote":"127.0.0.1:58986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-08T00:44:46.284988Z","caller":"traceutil/trace.go:171","msg":"trace[881336303] linearizableReadLoop","detail":"{readStateIndex:704; appliedIndex:701; }","duration":"302.682643ms","start":"2026-08-08T00:44:45.982283Z","end":"2026-08-08T00:44:46.284966Z","steps":["trace[881336303] 'read index received' (duration: 222.580574ms)","trace[881336303] 'applied index is now lower than readState.Index' (duration: 80.101059ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T00:44:46.285038Z","caller":"traceutil/trace.go:171","msg":"trace[41905667] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"618.190632ms","start":"2026-08-08T00:44:45.666808Z","end":"2026-08-08T00:44:46.284999Z","steps":["trace[41905667] 'process raft request' (duration: 605.672427ms)","trace[41905667] 'compare' (duration: 12.302718ms)"],"step_count":2} {"level":"warn","ts":"2026-08-08T00:44:46.285162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.86392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-08T00:44:46.285191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:44:45.666783Z","time spent":"618.331626ms","remote":"127.0.0.1:58986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-08T00:44:46.285308Z","caller":"traceutil/trace.go:171","msg":"trace[1562210263] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"419.048355ms","start":"2026-08-08T00:44:45.866027Z","end":"2026-08-08T00:44:46.285076Z","steps":["trace[1562210263] 'process raft request' (duration: 418.89325ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T00:44:46.285384Z","caller":"traceutil/trace.go:171","msg":"trace[616196172] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:679; }","duration":"302.943522ms","start":"2026-08-08T00:44:45.982249Z","end":"2026-08-08T00:44:46.285192Z","steps":["trace[616196172] 'agreement among raft nodes before linearized reading' (duration: 302.826108ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:44:46.285477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:44:45.982234Z","time spent":"303.218631ms","remote":"127.0.0.1:58954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-08T00:44:46.285658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:44:45.865998Z","time spent":"419.577312ms","remote":"127.0.0.1:58986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-08T00:44:46.316505Z","caller":"traceutil/trace.go:171","msg":"trace[1004950220] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"106.481541ms","start":"2026-08-08T00:44:46.209983Z","end":"2026-08-08T00:44:46.316465Z","steps":["trace[1004950220] 'process raft request' (duration: 106.092309ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:44:46.316599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.123099ms","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-08-08T00:44:46.316674Z","caller":"traceutil/trace.go:171","msg":"trace[58411025] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:680; }","duration":"183.244623ms","start":"2026-08-08T00:44:46.133411Z","end":"2026-08-08T00:44:46.316656Z","steps":["trace[58411025] 'agreement among raft nodes before linearized reading' (duration: 183.008345ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:44:46.316757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.578201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-08T00:44:46.316778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.528285ms","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-08-08T00:44:46.316817Z","caller":"traceutil/trace.go:171","msg":"trace[1701940784] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:680; }","duration":"251.679274ms","start":"2026-08-08T00:44:46.065121Z","end":"2026-08-08T00:44:46.316801Z","steps":["trace[1701940784] 'agreement among raft nodes before linearized reading' (duration: 251.600672ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:44:46.316896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.627782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-08T00:44:46.316824Z","caller":"traceutil/trace.go:171","msg":"trace[471117935] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:680; }","duration":"193.609688ms","start":"2026-08-08T00:44:46.123204Z","end":"2026-08-08T00:44:46.316813Z","steps":["trace[471117935] 'agreement among raft nodes before linearized reading' (duration: 193.457453ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T00:44:46.31693Z","caller":"traceutil/trace.go:171","msg":"trace[999341557] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:680; }","duration":"186.697404ms","start":"2026-08-08T00:44:46.130224Z","end":"2026-08-08T00:44:46.316922Z","steps":["trace[999341557] 'agreement among raft nodes before linearized reading' (duration: 186.618592ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:44:46.316893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.439469ms","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-08-08T00:44:46.317081Z","caller":"traceutil/trace.go:171","msg":"trace[403333084] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:680; }","duration":"183.731549ms","start":"2026-08-08T00:44:46.133307Z","end":"2026-08-08T00:44:46.317039Z","steps":["trace[403333084] 'agreement among raft nodes before linearized reading' (duration: 183.110749ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:44:58.316636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.046904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-08T00:44:58.316938Z","caller":"traceutil/trace.go:171","msg":"trace[1924270660] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:793; }","duration":"335.407346ms","start":"2026-08-08T00:44:57.981507Z","end":"2026-08-08T00:44:58.316915Z","steps":["trace[1924270660] 'range keys from in-memory index tree' (duration: 334.952451ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:44:58.316992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:44:57.981488Z","time spent":"335.489968ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-08T00:45:12.116171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.0208ms","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-08-08T00:45:12.116269Z","caller":"traceutil/trace.go:171","msg":"trace[2128431520] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:931; }","duration":"178.171365ms","start":"2026-08-08T00:45:11.938077Z","end":"2026-08-08T00:45:12.116248Z","steps":["trace[2128431520] 'count revisions from in-memory index tree' (duration: 177.936187ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T00:45:12.736345Z","caller":"traceutil/trace.go:171","msg":"trace[745816545] linearizableReadLoop","detail":"{readStateIndex:964; appliedIndex:963; }","duration":"270.644828ms","start":"2026-08-08T00:45:12.465682Z","end":"2026-08-08T00:45:12.736327Z","steps":["trace[745816545] 'read index received' (duration: 270.522254ms)","trace[745816545] 'applied index is now lower than readState.Index' (duration: 121.584µs)"],"step_count":2} {"level":"warn","ts":"2026-08-08T00:45:12.736616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.915116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-08T00:45:12.736643Z","caller":"traceutil/trace.go:171","msg":"trace[2121713984] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:931; }","duration":"270.970728ms","start":"2026-08-08T00:45:12.465664Z","end":"2026-08-08T00:45:12.736635Z","steps":["trace[2121713984] 'agreement among raft nodes before linearized reading' (duration: 270.906796ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T00:45:12.881591Z","caller":"traceutil/trace.go:171","msg":"trace[2144050601] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"144.008073ms","start":"2026-08-08T00:45:12.737566Z","end":"2026-08-08T00:45:12.881575Z","steps":["trace[2144050601] 'process raft request' (duration: 143.967322ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T00:45:12.881628Z","caller":"traceutil/trace.go:171","msg":"trace[1920023634] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"314.268644ms","start":"2026-08-08T00:45:12.567332Z","end":"2026-08-08T00:45:12.881601Z","steps":["trace[1920023634] 'process raft request' (duration: 310.326517ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:45:12.881768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:45:12.567315Z","time spent":"314.379767ms","remote":"127.0.0.1:59182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-08T00:45:12.881848Z","caller":"traceutil/trace.go:171","msg":"trace[1919357821] linearizableReadLoop","detail":"{readStateIndex:965; appliedIndex:964; }","duration":"145.432459ms","start":"2026-08-08T00:45:12.736396Z","end":"2026-08-08T00:45:12.881828Z","steps":["trace[1919357821] 'read index received' (duration: 141.250854ms)","trace[1919357821] 'applied index is now lower than readState.Index' (duration: 4.180195ms)"],"step_count":2} {"level":"warn","ts":"2026-08-08T00:45:12.882017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.078889ms","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-08-08T00:45:12.882046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.88715ms","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-08-08T00:45:12.882053Z","caller":"traceutil/trace.go:171","msg":"trace[1506852197] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:933; }","duration":"368.135381ms","start":"2026-08-08T00:45:12.513908Z","end":"2026-08-08T00:45:12.882043Z","steps":["trace[1506852197] 'agreement among raft nodes before linearized reading' (duration: 368.018317ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T00:45:12.882079Z","caller":"traceutil/trace.go:171","msg":"trace[1035888000] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:933; }","duration":"370.948251ms","start":"2026-08-08T00:45:12.51112Z","end":"2026-08-08T00:45:12.882069Z","steps":["trace[1035888000] 'agreement among raft nodes before linearized reading' (duration: 370.812817ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:45:12.882082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:45:12.513901Z","time spent":"368.174342ms","remote":"127.0.0.1:59182","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-08-08T00:45:12.882102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.321541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-08T00:45:12.882136Z","caller":"traceutil/trace.go:171","msg":"trace[1746259434] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:933; }","duration":"374.377052ms","start":"2026-08-08T00:45:12.507751Z","end":"2026-08-08T00:45:12.882128Z","steps":["trace[1746259434] 'agreement among raft nodes before linearized reading' (duration: 374.290499ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:45:12.882157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:45:12.507736Z","time spent":"374.415364ms","remote":"127.0.0.1:59182","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-08-08T00:45:12.882158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.683356ms","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-08-08T00:45:12.882235Z","caller":"traceutil/trace.go:171","msg":"trace[434990526] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:933; }","duration":"367.78605ms","start":"2026-08-08T00:45:12.514431Z","end":"2026-08-08T00:45:12.882217Z","steps":["trace[434990526] 'agreement among raft nodes before linearized reading' (duration: 367.548692ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:45:12.882109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:45:12.511109Z","time spent":"370.993453ms","remote":"127.0.0.1:59182","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-08-08T00:45:12.882291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:45:12.514424Z","time spent":"367.857802ms","remote":"127.0.0.1:59182","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-08-08T00:45:25.993124Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757022293539188252,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-08T00:45:26.095706Z","caller":"traceutil/trace.go:171","msg":"trace[1360088845] linearizableReadLoop","detail":"{readStateIndex:1023; appliedIndex:1022; }","duration":"602.876881ms","start":"2026-08-08T00:45:25.492804Z","end":"2026-08-08T00:45:26.095681Z","steps":["trace[1360088845] 'read index received' (duration: 602.673584ms)","trace[1360088845] 'applied index is now lower than readState.Index' (duration: 197.827µs)"],"step_count":2} {"level":"info","ts":"2026-08-08T00:45:26.095827Z","caller":"traceutil/trace.go:171","msg":"trace[1486272193] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"612.760929ms","start":"2026-08-08T00:45:25.483056Z","end":"2026-08-08T00:45:26.095817Z","steps":["trace[1486272193] 'process raft request' (duration: 612.421848ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:45:26.095955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:45:25.483031Z","time spent":"612.845442ms","remote":"127.0.0.1:59182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-08T00:45:26.096132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.773886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-08T00:45:26.096198Z","caller":"traceutil/trace.go:171","msg":"trace[2106832844] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:987; }","duration":"263.881349ms","start":"2026-08-08T00:45:25.832295Z","end":"2026-08-08T00:45:26.096177Z","steps":["trace[2106832844] 'agreement among raft nodes before linearized reading' (duration: 263.774715ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:45:26.096198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.392097ms","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-08-08T00:45:26.096249Z","caller":"traceutil/trace.go:171","msg":"trace[1338199509] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:987; }","duration":"603.48384ms","start":"2026-08-08T00:45:25.492752Z","end":"2026-08-08T00:45:26.096236Z","steps":["trace[1338199509] 'agreement among raft nodes before linearized reading' (duration: 603.321025ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:45:26.096287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:45:25.492732Z","time spent":"603.545322ms","remote":"127.0.0.1:59182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-08T00:45:26.669081Z","caller":"traceutil/trace.go:171","msg":"trace[406031470] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"568.343787ms","start":"2026-08-08T00:45:26.100707Z","end":"2026-08-08T00:45:26.669051Z","steps":["trace[406031470] 'process raft request' (duration: 524.466432ms)","trace[406031470] 'compare' (duration: 43.654708ms)"],"step_count":2} {"level":"warn","ts":"2026-08-08T00:45:26.66923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:45:26.100686Z","time spent":"568.465331ms","remote":"127.0.0.1:59182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-08T00:45:26.677813Z","caller":"traceutil/trace.go:171","msg":"trace[1522191877] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"357.486298ms","start":"2026-08-08T00:45:26.320277Z","end":"2026-08-08T00:45:26.677764Z","steps":["trace[1522191877] 'process raft request' (duration: 357.366504ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:45:26.678005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:45:26.320259Z","time spent":"357.668753ms","remote":"127.0.0.1:59182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-08T00:45:28.239225Z","caller":"traceutil/trace.go:171","msg":"trace[40916645] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"137.97712ms","start":"2026-08-08T00:45:28.101221Z","end":"2026-08-08T00:45:28.239198Z","steps":["trace[40916645] 'process raft request' (duration: 137.833605ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T00:45:29.187347Z","caller":"traceutil/trace.go:171","msg":"trace[276298747] linearizableReadLoop","detail":"{readStateIndex:1036; appliedIndex:1035; }","duration":"109.560263ms","start":"2026-08-08T00:45:29.077764Z","end":"2026-08-08T00:45:29.187324Z","steps":["trace[276298747] 'read index received' (duration: 22.581388ms)","trace[276298747] 'applied index is now lower than readState.Index' (duration: 86.978025ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T00:45:29.187439Z","caller":"traceutil/trace.go:171","msg":"trace[58516988] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"199.048018ms","start":"2026-08-08T00:45:28.988379Z","end":"2026-08-08T00:45:29.187427Z","steps":["trace[58516988] 'process raft request' (duration: 111.995841ms)","trace[58516988] 'compare' (duration: 86.842111ms)"],"step_count":2} {"level":"warn","ts":"2026-08-08T00:45:29.187561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.770819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-08T00:45:29.187652Z","caller":"traceutil/trace.go:171","msg":"trace[1840403759] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:999; }","duration":"109.921685ms","start":"2026-08-08T00:45:29.077705Z","end":"2026-08-08T00:45:29.187626Z","steps":["trace[1840403759] 'agreement among raft nodes before linearized reading' (duration: 109.740629ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T00:45:33.115339Z","caller":"traceutil/trace.go:171","msg":"trace[49493390] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"115.444453ms","start":"2026-08-08T00:45:32.999865Z","end":"2026-08-08T00:45:33.115309Z","steps":["trace[49493390] 'process raft request' (duration: 74.567525ms)","trace[49493390] 'compare' (duration: 40.746134ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T00:45:33.134416Z","caller":"traceutil/trace.go:171","msg":"trace[54914112] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"133.611509ms","start":"2026-08-08T00:45:33.000788Z","end":"2026-08-08T00:45:33.134399Z","steps":["trace[54914112] 'process raft request' (duration: 133.521026ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T00:45:40.951469Z","caller":"traceutil/trace.go:171","msg":"trace[1809658665] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"208.576985ms","start":"2026-08-08T00:45:40.742855Z","end":"2026-08-08T00:45:40.951432Z","steps":["trace[1809658665] 'process raft request' (duration: 208.375339ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T00:45:43.282146Z","caller":"traceutil/trace.go:171","msg":"trace[1744836820] linearizableReadLoop","detail":"{readStateIndex:1087; appliedIndex:1086; }","duration":"115.505405ms","start":"2026-08-08T00:45:43.166618Z","end":"2026-08-08T00:45:43.282123Z","steps":["trace[1744836820] 'read index received' (duration: 115.317068ms)","trace[1744836820] 'applied index is now lower than readState.Index' (duration: 187.277µs)"],"step_count":2} {"level":"info","ts":"2026-08-08T00:45:43.282194Z","caller":"traceutil/trace.go:171","msg":"trace[898005298] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"127.136509ms","start":"2026-08-08T00:45:43.155004Z","end":"2026-08-08T00:45:43.282141Z","steps":["trace[898005298] 'process raft request' (duration: 126.984364ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:45:43.282393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.758152ms","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-08-08T00:45:43.282475Z","caller":"traceutil/trace.go:171","msg":"trace[1254275978] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1047; }","duration":"115.868066ms","start":"2026-08-08T00:45:43.166585Z","end":"2026-08-08T00:45:43.282453Z","steps":["trace[1254275978] 'agreement among raft nodes before linearized reading' (duration: 115.651199ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T00:45:43.705114Z","caller":"traceutil/trace.go:171","msg":"trace[1210467822] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"416.954276ms","start":"2026-08-08T00:45:43.288139Z","end":"2026-08-08T00:45:43.705093Z","steps":["trace[1210467822] 'process raft request' (duration: 416.879844ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:45:43.70527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:45:43.288057Z","time spent":"417.113681ms","remote":"127.0.0.1:59182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-08T00:45:43.705538Z","caller":"traceutil/trace.go:171","msg":"trace[1738745387] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"418.883617ms","start":"2026-08-08T00:45:43.286622Z","end":"2026-08-08T00:45:43.705505Z","steps":["trace[1738745387] 'process raft request' (duration: 383.019421ms)","trace[1738745387] 'compare' (duration: 35.246416ms)"],"step_count":2} {"level":"warn","ts":"2026-08-08T00:45:43.705887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:45:43.286603Z","time spent":"419.015542ms","remote":"127.0.0.1:59182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-08T00:45:44.083835Z","caller":"traceutil/trace.go:171","msg":"trace[136057250] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"258.663311ms","start":"2026-08-08T00:45:43.825154Z","end":"2026-08-08T00:45:44.083817Z","steps":["trace[136057250] 'process raft request' (duration: 258.446234ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:45:44.08406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.267038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-08T00:45:44.08414Z","caller":"traceutil/trace.go:171","msg":"trace[904304906] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1050; }","duration":"102.418173ms","start":"2026-08-08T00:45:43.981701Z","end":"2026-08-08T00:45:44.084119Z","steps":["trace[904304906] 'agreement among raft nodes before linearized reading' (duration: 102.259098ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T00:45:44.084598Z","caller":"traceutil/trace.go:171","msg":"trace[276123177] linearizableReadLoop","detail":"{readStateIndex:1090; appliedIndex:1089; }","duration":"101.983669ms","start":"2026-08-08T00:45:43.981757Z","end":"2026-08-08T00:45:44.083741Z","steps":["trace[276123177] 'read index received' (duration: 101.72102ms)","trace[276123177] 'applied index is now lower than readState.Index' (duration: 261.669µs)"],"step_count":2} {"level":"warn","ts":"2026-08-08T00:45:44.750721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.24252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-08T00:45:44.750806Z","caller":"traceutil/trace.go:171","msg":"trace[532344058] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:1050; }","duration":"562.342783ms","start":"2026-08-08T00:45:44.188442Z","end":"2026-08-08T00:45:44.750785Z","steps":["trace[532344058] 'count revisions from in-memory index tree' (duration: 562.168918ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:45:44.750852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:45:44.188424Z","time spent":"562.416316ms","remote":"127.0.0.1:44006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-08-08T00:45:44.750896Z","caller":"traceutil/trace.go:171","msg":"trace[625003249] linearizableReadLoop","detail":"{readStateIndex:1091; appliedIndex:1090; }","duration":"247.126838ms","start":"2026-08-08T00:45:44.503746Z","end":"2026-08-08T00:45:44.750873Z","steps":["trace[625003249] 'read index received' (duration: 229.899383ms)","trace[625003249] 'applied index is now lower than readState.Index' (duration: 17.226395ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T00:45:44.75106Z","caller":"traceutil/trace.go:171","msg":"trace[82756516] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"435.630207ms","start":"2026-08-08T00:45:44.315395Z","end":"2026-08-08T00:45:44.751025Z","steps":["trace[82756516] 'process raft request' (duration: 418.299288ms)","trace[82756516] 'compare' (duration: 16.766881ms)"],"step_count":2} {"level":"warn","ts":"2026-08-08T00:45:44.751208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.405918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-08T00:45:44.751306Z","caller":"traceutil/trace.go:171","msg":"trace[628258102] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1051; }","duration":"247.553522ms","start":"2026-08-08T00:45:44.503693Z","end":"2026-08-08T00:45:44.751247Z","steps":["trace[628258102] 'agreement among raft nodes before linearized reading' (duration: 247.256973ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:45:44.751416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:45:44.315365Z","time spent":"435.856935ms","remote":"127.0.0.1:59182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-08T00:45:49.228873Z","caller":"traceutil/trace.go:171","msg":"trace[863763218] linearizableReadLoop","detail":"{readStateIndex:1106; appliedIndex:1105; }","duration":"121.87349ms","start":"2026-08-08T00:45:49.106977Z","end":"2026-08-08T00:45:49.228851Z","steps":["trace[863763218] 'read index received' (duration: 121.639552ms)","trace[863763218] 'applied index is now lower than readState.Index' (duration: 233.068µs)"],"step_count":2} {"level":"info","ts":"2026-08-08T00:45:49.228952Z","caller":"traceutil/trace.go:171","msg":"trace[2066116873] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"135.962305ms","start":"2026-08-08T00:45:49.09295Z","end":"2026-08-08T00:45:49.228912Z","steps":["trace[2066116873] 'process raft request' (duration: 135.788919ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:45:49.229054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.063477ms","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-08-08T00:45:49.229092Z","caller":"traceutil/trace.go:171","msg":"trace[1447970878] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1065; }","duration":"122.134268ms","start":"2026-08-08T00:45:49.106948Z","end":"2026-08-08T00:45:49.229082Z","steps":["trace[1447970878] 'agreement among raft nodes before linearized reading' (duration: 121.990334ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:46:01.935778Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757022293539188664,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-08T00:46:02.434919Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.081398541s","expected-duration":"1s"} {"level":"info","ts":"2026-08-08T00:46:02.444005Z","caller":"traceutil/trace.go:171","msg":"trace[69772839] linearizableReadLoop","detail":"{readStateIndex:1200; appliedIndex:1199; }","duration":"1.008508731s","start":"2026-08-08T00:46:01.435434Z","end":"2026-08-08T00:46:02.443942Z","steps":["trace[69772839] 'read index received' (duration: 999.611974ms)","trace[69772839] 'applied index is now lower than readState.Index' (duration: 8.895627ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T00:46:02.444001Z","caller":"traceutil/trace.go:171","msg":"trace[824482871] transaction","detail":"{read_only:false; response_revision:1156; number_of_response:1; }","duration":"1.090495004s","start":"2026-08-08T00:46:01.353475Z","end":"2026-08-08T00:46:02.44397Z","steps":["trace[824482871] 'process raft request' (duration: 1.081644789s)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:46:02.444166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.008715208s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-08T00:46:02.444172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:46:01.353467Z","time spent":"1.090628919s","remote":"127.0.0.1:59182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-08T00:46:02.444217Z","caller":"traceutil/trace.go:171","msg":"trace[1921500084] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1156; }","duration":"1.00879322s","start":"2026-08-08T00:46:01.435398Z","end":"2026-08-08T00:46:02.444191Z","steps":["trace[1921500084] 'agreement among raft nodes before linearized reading' (duration: 1.008712878s)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:46:02.444221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.004797811s","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-08-08T00:46:02.444253Z","caller":"traceutil/trace.go:171","msg":"trace[1505421224] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1156; }","duration":"1.004870043s","start":"2026-08-08T00:46:01.439375Z","end":"2026-08-08T00:46:02.444245Z","steps":["trace[1505421224] 'agreement among raft nodes before linearized reading' (duration: 1.00476311s)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:46:02.444257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:46:01.435381Z","time spent":"1.008865072s","remote":"127.0.0.1:42186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-08-08T00:46:02.444275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:46:01.439353Z","time spent":"1.004916675s","remote":"127.0.0.1:59182","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-08-08T00:46:02.444357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.429052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-08T00:46:02.444377Z","caller":"traceutil/trace.go:171","msg":"trace[703887405] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1156; }","duration":"462.471533ms","start":"2026-08-08T00:46:01.981899Z","end":"2026-08-08T00:46:02.44437Z","steps":["trace[703887405] 'agreement among raft nodes before linearized reading' (duration: 462.443592ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:46:02.444392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:46:01.981881Z","time spent":"462.507385ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-08T00:46:02.444457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.41757ms","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-08-08T00:46:02.444514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.332621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-08T00:46:02.444518Z","caller":"traceutil/trace.go:171","msg":"trace[1222423869] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1156; }","duration":"622.519604ms","start":"2026-08-08T00:46:01.821984Z","end":"2026-08-08T00:46:02.444504Z","steps":["trace[1222423869] 'agreement among raft nodes before linearized reading' (duration: 622.36773ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T00:46:02.444547Z","caller":"traceutil/trace.go:171","msg":"trace[1534116885] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1156; }","duration":"623.401283ms","start":"2026-08-08T00:46:01.821139Z","end":"2026-08-08T00:46:02.44454Z","steps":["trace[1534116885] 'agreement among raft nodes before linearized reading' (duration: 623.33264ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:46:02.444555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:46:01.821959Z","time spent":"622.587636ms","remote":"127.0.0.1:59182","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-08-08T00:46:02.444564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:46:01.821119Z","time spent":"623.441024ms","remote":"127.0.0.1:59182","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-08-08T00:46:02.444604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.256553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-08T00:46:02.444643Z","caller":"traceutil/trace.go:171","msg":"trace[2072068443] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1156; }","duration":"621.317715ms","start":"2026-08-08T00:46:01.823312Z","end":"2026-08-08T00:46:02.44463Z","steps":["trace[2072068443] 'agreement among raft nodes before linearized reading' (duration: 621.260794ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:46:02.444679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:46:01.823298Z","time spent":"621.371657ms","remote":"127.0.0.1:44020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-08T00:46:17.386579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.422735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5330"} {"level":"info","ts":"2026-08-08T00:46:17.387329Z","caller":"traceutil/trace.go:171","msg":"trace[1511947423] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1225; }","duration":"144.215311ms","start":"2026-08-08T00:46:17.24308Z","end":"2026-08-08T00:46:17.387296Z","steps":["trace[1511947423] 'range keys from in-memory index tree' (duration: 142.819196ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:46:18.666654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.762422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-08T00:46:18.666734Z","caller":"traceutil/trace.go:171","msg":"trace[58825077] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1232; }","duration":"119.880176ms","start":"2026-08-08T00:46:18.546833Z","end":"2026-08-08T00:46:18.666713Z","steps":["trace[58825077] 'agreement among raft nodes before linearized reading' (duration: 62.898099ms)","trace[58825077] 'range keys from in-memory index tree' (duration: 56.799632ms)"],"step_count":2} {"level":"warn","ts":"2026-08-08T00:46:19.667639Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757022293539188838,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-08T00:46:19.714695Z","caller":"traceutil/trace.go:171","msg":"trace[1521885749] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"681.739673ms","start":"2026-08-08T00:46:19.032937Z","end":"2026-08-08T00:46:19.714677Z","steps":["trace[1521885749] 'process raft request' (duration: 681.619859ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:46:19.714815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:46:19.032913Z","time spent":"681.837597ms","remote":"127.0.0.1:59182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-08T00:46:19.865492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.814077ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-08T00:46:19.865604Z","caller":"traceutil/trace.go:171","msg":"trace[1025570496] linearizableReadLoop","detail":"{readStateIndex:1285; appliedIndex:1283; }","duration":"698.319118ms","start":"2026-08-08T00:46:19.167267Z","end":"2026-08-08T00:46:19.865586Z","steps":["trace[1025570496] 'read index received' (duration: 547.368381ms)","trace[1025570496] 'applied index is now lower than readState.Index' (duration: 150.949327ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T00:46:19.865641Z","caller":"traceutil/trace.go:171","msg":"trace[529757331] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"782.40994ms","start":"2026-08-08T00:46:19.083196Z","end":"2026-08-08T00:46:19.865606Z","steps":["trace[529757331] 'process raft request' (duration: 665.426378ms)","trace[529757331] 'compare' (duration: 116.703933ms)"],"step_count":2} {"level":"warn","ts":"2026-08-08T00:46:19.865789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.372237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-08T00:46:19.865802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:46:19.083157Z","time spent":"782.575465ms","remote":"127.0.0.1:59002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-08T00:46:19.865822Z","caller":"traceutil/trace.go:171","msg":"trace[1083565380] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1236; }","duration":"608.43948ms","start":"2026-08-08T00:46:19.257372Z","end":"2026-08-08T00:46:19.865812Z","steps":["trace[1083565380] 'agreement among raft nodes before linearized reading' (duration: 608.350897ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:46:19.86582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.521265ms","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":"warn","ts":"2026-08-08T00:46:19.865861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:46:19.257357Z","time spent":"608.494172ms","remote":"127.0.0.1:44092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"info","ts":"2026-08-08T00:46:19.865896Z","caller":"traceutil/trace.go:171","msg":"trace[1357921590] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1236; }","duration":"698.643029ms","start":"2026-08-08T00:46:19.167235Z","end":"2026-08-08T00:46:19.865878Z","steps":["trace[1357921590] 'agreement among raft nodes before linearized reading' (duration: 698.493814ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:46:19.865947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:46:19.167221Z","time spent":"698.715341ms","remote":"127.0.0.1:59182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-08-08T00:46:19.865969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.360358ms","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-08-08T00:46:19.866029Z","caller":"traceutil/trace.go:171","msg":"trace[1972523892] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1236; }","duration":"466.486122ms","start":"2026-08-08T00:46:19.399526Z","end":"2026-08-08T00:46:19.866013Z","steps":["trace[1972523892] 'agreement among raft nodes before linearized reading' (duration: 466.292106ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:46:19.866081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:46:19.399499Z","time spent":"466.569425ms","remote":"127.0.0.1:59182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-08T00:46:20.021654Z","caller":"traceutil/trace.go:171","msg":"trace[2027078801] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"100.253713ms","start":"2026-08-08T00:46:19.921373Z","end":"2026-08-08T00:46:20.021627Z","steps":["trace[2027078801] 'process raft request' (duration: 72.214589ms)","trace[2027078801] 'compare' (duration: 27.706613ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T00:46:20.045266Z","caller":"traceutil/trace.go:171","msg":"trace[488844142] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"101.635048ms","start":"2026-08-08T00:46:19.943571Z","end":"2026-08-08T00:46:20.045206Z","steps":["trace[488844142] 'process raft request' (duration: 101.490773ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T00:46:20.804322Z","caller":"traceutil/trace.go:171","msg":"trace[1773039821] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"127.706028ms","start":"2026-08-08T00:46:20.676586Z","end":"2026-08-08T00:46:20.804292Z","steps":["trace[1773039821] 'process raft request' (duration: 107.402004ms)","trace[1773039821] 'compare' (duration: 20.18629ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T00:46:20.841999Z","caller":"traceutil/trace.go:171","msg":"trace[1037002619] linearizableReadLoop","detail":"{readStateIndex:1294; appliedIndex:1292; }","duration":"107.598799ms","start":"2026-08-08T00:46:20.734371Z","end":"2026-08-08T00:46:20.84197Z","steps":["trace[1037002619] 'read index received' (duration: 49.571108ms)","trace[1037002619] 'applied index is now lower than readState.Index' (duration: 58.026271ms)"],"step_count":2} {"level":"warn","ts":"2026-08-08T00:46:20.842232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.840018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-08T00:46:20.842327Z","caller":"traceutil/trace.go:171","msg":"trace[917438346] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1245; }","duration":"107.981062ms","start":"2026-08-08T00:46:20.734329Z","end":"2026-08-08T00:46:20.842311Z","steps":["trace[917438346] 'agreement among raft nodes before linearized reading' (duration: 107.753185ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T00:46:20.842385Z","caller":"traceutil/trace.go:171","msg":"trace[211557983] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"157.947094ms","start":"2026-08-08T00:46:20.684373Z","end":"2026-08-08T00:46:20.84232Z","steps":["trace[211557983] 'process raft request' (duration: 157.425097ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T00:46:20.979109Z","caller":"traceutil/trace.go:171","msg":"trace[1145417579] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"132.673709ms","start":"2026-08-08T00:46:20.84641Z","end":"2026-08-08T00:46:20.979084Z","steps":["trace[1145417579] 'process raft request' (duration: 109.801761ms)","trace[1145417579] 'compare' (duration: 22.751644ms)"],"step_count":2} {"level":"warn","ts":"2026-08-08T00:46:27.414311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.683599ms","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-08-08T00:46:27.414431Z","caller":"traceutil/trace.go:171","msg":"trace[735228127] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1297; }","duration":"524.830234ms","start":"2026-08-08T00:46:26.889572Z","end":"2026-08-08T00:46:27.414403Z","steps":["trace[735228127] 'range keys from in-memory index tree' (duration: 524.443941ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:46:27.414429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.858518ms","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-08-08T00:46:27.414489Z","caller":"traceutil/trace.go:171","msg":"trace[1569549709] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1297; }","duration":"530.954851ms","start":"2026-08-08T00:46:26.883516Z","end":"2026-08-08T00:46:27.41447Z","steps":["trace[1569549709] 'count revisions from in-memory index tree' (duration: 530.780286ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:46:27.414488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:46:26.889561Z","time spent":"524.910846ms","remote":"127.0.0.1:59182","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-08-08T00:46:27.414524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:46:26.883491Z","time spent":"531.023174ms","remote":"127.0.0.1:59332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":27,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-08-08T00:46:27.414522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.946385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-08T00:46:27.414602Z","caller":"traceutil/trace.go:171","msg":"trace[803150516] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1297; }","duration":"248.161652ms","start":"2026-08-08T00:46:27.166415Z","end":"2026-08-08T00:46:27.414577Z","steps":["trace[803150516] 'range keys from in-memory index tree' (duration: 247.724308ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:46:27.41478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.224208ms","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-08-08T00:46:27.414822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.331587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-08T00:46:27.414862Z","caller":"traceutil/trace.go:171","msg":"trace[538450936] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1297; }","duration":"530.340671ms","start":"2026-08-08T00:46:26.884503Z","end":"2026-08-08T00:46:27.414844Z","steps":["trace[538450936] 'range keys from in-memory index tree' (duration: 530.079173ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:46:27.414953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:46:26.884484Z","time spent":"530.451205ms","remote":"127.0.0.1:59182","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":"info","ts":"2026-08-08T00:46:27.414921Z","caller":"traceutil/trace.go:171","msg":"trace[1162125386] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1297; }","duration":"417.496902ms","start":"2026-08-08T00:46:26.997403Z","end":"2026-08-08T00:46:27.4149Z","steps":["trace[1162125386] 'range keys from in-memory index tree' (duration: 417.082289ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:46:27.415071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:46:26.997379Z","time spent":"417.671628ms","remote":"127.0.0.1:59182","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-08-08T00:46:27.415079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.700601ms","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-08-08T00:46:27.415213Z","caller":"traceutil/trace.go:171","msg":"trace[1863903933] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1297; }","duration":"520.905937ms","start":"2026-08-08T00:46:26.894294Z","end":"2026-08-08T00:46:27.4152Z","steps":["trace[1863903933] 'range keys from in-memory index tree' (duration: 519.911305ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:46:27.415251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:46:26.894275Z","time spent":"520.965109ms","remote":"127.0.0.1:59182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-08-08T00:46:27.940102Z","caller":"traceutil/trace.go:171","msg":"trace[713432973] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"521.126884ms","start":"2026-08-08T00:46:27.418914Z","end":"2026-08-08T00:46:27.940041Z","steps":["trace[713432973] 'process raft request' (duration: 520.888436ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:46:27.940366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:46:27.418897Z","time spent":"521.323201ms","remote":"127.0.0.1:59182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-08T00:46:28.172321Z","caller":"traceutil/trace.go:171","msg":"trace[1972658411] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"752.818335ms","start":"2026-08-08T00:46:27.419471Z","end":"2026-08-08T00:46:28.17229Z","steps":["trace[1972658411] 'process raft request' (duration: 752.307789ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:46:28.172599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:46:27.419445Z","time spent":"753.029362ms","remote":"127.0.0.1:59182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-08T00:46:28.172672Z","caller":"traceutil/trace.go:171","msg":"trace[304829548] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"751.908027ms","start":"2026-08-08T00:46:27.420734Z","end":"2026-08-08T00:46:28.172642Z","steps":["trace[304829548] 'process raft request' (duration: 751.175643ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T00:46:28.172581Z","caller":"traceutil/trace.go:171","msg":"trace[1111762803] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"753.047333ms","start":"2026-08-08T00:46:27.419404Z","end":"2026-08-08T00:46:28.172451Z","steps":["trace[1111762803] 'process raft request' (duration: 727.764867ms)","trace[1111762803] 'compare' (duration: 24.338055ms)"],"step_count":2} {"level":"warn","ts":"2026-08-08T00:46:28.173074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:46:27.419382Z","time spent":"753.610601ms","remote":"127.0.0.1:59182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-08T00:46:28.172825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:46:27.420716Z","time spent":"752.02413ms","remote":"127.0.0.1:59182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":492,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-08T00:46:28.17338Z","caller":"traceutil/trace.go:171","msg":"trace[1716546047] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"753.287161ms","start":"2026-08-08T00:46:27.420075Z","end":"2026-08-08T00:46:28.173362Z","steps":["trace[1716546047] 'process raft request' (duration: 751.803863ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:46:28.173493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:46:27.420013Z","time spent":"753.439946ms","remote":"127.0.0.1:59182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-08T00:46:28.178532Z","caller":"traceutil/trace.go:171","msg":"trace[855712971] linearizableReadLoop","detail":"{readStateIndex:1354; appliedIndex:1348; }","duration":"207.672547ms","start":"2026-08-08T00:46:27.970841Z","end":"2026-08-08T00:46:28.178514Z","steps":["trace[855712971] 'read index received' (duration: 176.191512ms)","trace[855712971] 'applied index is now lower than readState.Index' (duration: 31.480135ms)"],"step_count":2} {"level":"warn","ts":"2026-08-08T00:46:28.178671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.838122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-08-08T00:46:28.178694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.755592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-08T00:46:28.178715Z","caller":"traceutil/trace.go:171","msg":"trace[1521217909] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1303; }","duration":"207.924675ms","start":"2026-08-08T00:46:27.97078Z","end":"2026-08-08T00:46:28.178704Z","steps":["trace[1521217909] 'agreement among raft nodes before linearized reading' (duration: 207.796621ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T00:46:28.178742Z","caller":"traceutil/trace.go:171","msg":"trace[703249895] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1303; }","duration":"195.867666ms","start":"2026-08-08T00:46:27.982863Z","end":"2026-08-08T00:46:28.178731Z","steps":["trace[703249895] 'agreement among raft nodes before linearized reading' (duration: 195.780763ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T00:46:28.179238Z","caller":"traceutil/trace.go:171","msg":"trace[1270065239] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"438.393536ms","start":"2026-08-08T00:46:27.740157Z","end":"2026-08-08T00:46:28.178551Z","steps":["trace[1270065239] 'process raft request' (duration: 438.20665ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:46:28.179354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:46:27.74013Z","time spent":"439.149731ms","remote":"127.0.0.1:59182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"}