{"level":"warn","ts":"2026-07-15T04:49:31.946492Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-15T04:49:31.946607Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.109: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.109:2380","--initial-cluster=instance=https://199.204.45.109:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.109:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.109:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-15T04:49:31.946695Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-15T04:49:31.946708Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-07-15T04:49:31.94673Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-15T04:49:31.947459Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"]} {"level":"info","ts":"2026-07-15T04:49:31.947538Z","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.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.109:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-15T04:49:31.951761Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.958477ms"} {"level":"info","ts":"2026-07-15T04:49:31.962448Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d71a1778b31d4ce","cluster-id":"aaca35652fabf9b2"} {"level":"info","ts":"2026-07-15T04:49:31.962584Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=()"} {"level":"info","ts":"2026-07-15T04:49:31.962617Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 0"} {"level":"info","ts":"2026-07-15T04:49:31.962648Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d71a1778b31d4ce [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-15T04:49:31.962674Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 1"} {"level":"info","ts":"2026-07-15T04:49:31.962744Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"warn","ts":"2026-07-15T04:49:31.970316Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-15T04:49:31.972132Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-15T04:49:31.973545Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-15T04:49:31.976865Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d71a1778b31d4ce","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-15T04:49:31.977036Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d71a1778b31d4ce","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-15T04:49:31.977232Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-15T04:49:31.977324Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-15T04:49:31.977647Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-15T04:49:31.980029Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"info","ts":"2026-07-15T04:49:31.980318Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","added-peer-id":"d71a1778b31d4ce","added-peer-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-07-15T04:49:31.983442Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-15T04:49:31.983664Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-07-15T04:49:31.983733Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-07-15T04:49:31.98419Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d71a1778b31d4ce","initial-advertise-peer-urls":["https://199.204.45.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-15T04:49:31.984244Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-15T04:49:32.163694Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce is starting a new election at term 1"} {"level":"info","ts":"2026-07-15T04:49:32.163743Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became pre-candidate at term 1"} {"level":"info","ts":"2026-07-15T04:49:32.163766Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgPreVoteResp from d71a1778b31d4ce at term 1"} {"level":"info","ts":"2026-07-15T04:49:32.163777Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became candidate at term 2"} {"level":"info","ts":"2026-07-15T04:49:32.163783Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgVoteResp from d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-07-15T04:49:32.163791Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became leader at term 2"} {"level":"info","ts":"2026-07-15T04:49:32.163798Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d71a1778b31d4ce elected leader d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-07-15T04:49:32.168924Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T04:49:32.170191Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d71a1778b31d4ce","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.109:2379]}","request-path":"/0/members/d71a1778b31d4ce/attributes","cluster-id":"aaca35652fabf9b2","publish-timeout":"7s"} {"level":"info","ts":"2026-07-15T04:49:32.170211Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-15T04:49:32.17024Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-15T04:49:32.170577Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-15T04:49:32.170621Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-15T04:49:32.170962Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T04:49:32.171053Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T04:49:32.171124Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T04:49:32.177492Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-15T04:49:32.178811Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.109:2379"} {"level":"info","ts":"2026-07-15T04:50:07.078975Z","caller":"traceutil/trace.go:171","msg":"trace[1716137702] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"194.213347ms","start":"2026-07-15T04:50:06.884733Z","end":"2026-07-15T04:50:07.078946Z","steps":["trace[1716137702] 'process raft request' (duration: 194.075342ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:50:10.21665Z","caller":"traceutil/trace.go:171","msg":"trace[2027765825] linearizableReadLoop","detail":"{readStateIndex:501; appliedIndex:500; }","duration":"321.159833ms","start":"2026-07-15T04:50:09.895463Z","end":"2026-07-15T04:50:10.216622Z","steps":["trace[2027765825] 'read index received' (duration: 320.981096ms)","trace[2027765825] 'applied index is now lower than readState.Index' (duration: 177.777µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T04:50:10.216993Z","caller":"traceutil/trace.go:171","msg":"trace[1029177039] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"361.844568ms","start":"2026-07-15T04:50:09.855119Z","end":"2026-07-15T04:50:10.216963Z","steps":["trace[1029177039] 'process raft request' (duration: 361.379101ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:50:10.217536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:50:09.855101Z","time spent":"361.927391ms","remote":"127.0.0.1:44376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T04:50:10.218285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.790222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-15T04:50:10.218411Z","caller":"traceutil/trace.go:171","msg":"trace[221354819] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:483; }","duration":"322.952857ms","start":"2026-07-15T04:50:09.895437Z","end":"2026-07-15T04:50:10.21839Z","steps":["trace[221354819] 'agreement among raft nodes before linearized reading' (duration: 322.728109ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:50:10.218474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:50:09.895429Z","time spent":"323.03503ms","remote":"127.0.0.1:44376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-15T04:50:10.21834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.246765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-15T04:50:10.218712Z","caller":"traceutil/trace.go:171","msg":"trace[102247637] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:483; }","duration":"241.67032ms","start":"2026-07-15T04:50:09.977031Z","end":"2026-07-15T04:50:10.218701Z","steps":["trace[102247637] 'agreement among raft nodes before linearized reading' (duration: 241.187684ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:50:10.715279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.123212ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-15T04:50:10.749615Z","caller":"traceutil/trace.go:171","msg":"trace[871548016] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"231.576034ms","start":"2026-07-15T04:50:10.518Z","end":"2026-07-15T04:50:10.749576Z","steps":["trace[871548016] 'process raft request' (duration: 231.285523ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:50:10.749726Z","caller":"traceutil/trace.go:171","msg":"trace[99000363] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"231.526742ms","start":"2026-07-15T04:50:10.518154Z","end":"2026-07-15T04:50:10.749681Z","steps":["trace[99000363] 'process raft request' (duration: 231.310374ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:50:10.749822Z","caller":"traceutil/trace.go:171","msg":"trace[341135434] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"210.28499ms","start":"2026-07-15T04:50:10.539513Z","end":"2026-07-15T04:50:10.749798Z","steps":["trace[341135434] 'process raft request' (duration: 210.234238ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:50:10.749941Z","caller":"traceutil/trace.go:171","msg":"trace[693091791] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"215.205714ms","start":"2026-07-15T04:50:10.534656Z","end":"2026-07-15T04:50:10.749862Z","steps":["trace[693091791] 'process raft request' (duration: 215.019468ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:50:10.750282Z","caller":"traceutil/trace.go:171","msg":"trace[890086788] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"231.896235ms","start":"2026-07-15T04:50:10.518354Z","end":"2026-07-15T04:50:10.750251Z","steps":["trace[890086788] 'process raft request' (duration: 231.172369ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:50:10.795046Z","caller":"traceutil/trace.go:171","msg":"trace[1481941805] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"246.263933ms","start":"2026-07-15T04:50:10.548738Z","end":"2026-07-15T04:50:10.795002Z","steps":["trace[1481941805] 'process raft request' (duration: 246.022875ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:50:10.795111Z","caller":"traceutil/trace.go:171","msg":"trace[414974406] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"245.156415ms","start":"2026-07-15T04:50:10.549921Z","end":"2026-07-15T04:50:10.795078Z","steps":["trace[414974406] 'process raft request' (duration: 244.964658ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:50:10.795205Z","caller":"traceutil/trace.go:171","msg":"trace[1108667306] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"241.626769ms","start":"2026-07-15T04:50:10.553541Z","end":"2026-07-15T04:50:10.795168Z","steps":["trace[1108667306] 'process raft request' (duration: 241.403471ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:50:10.795261Z","caller":"traceutil/trace.go:171","msg":"trace[941097072] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"222.384039ms","start":"2026-07-15T04:50:10.572867Z","end":"2026-07-15T04:50:10.795251Z","steps":["trace[941097072] 'process raft request' (duration: 222.13912ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:50:10.795417Z","caller":"traceutil/trace.go:171","msg":"trace[993800403] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"212.81908ms","start":"2026-07-15T04:50:10.582591Z","end":"2026-07-15T04:50:10.79541Z","steps":["trace[993800403] 'process raft request' (duration: 212.484899ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:50:10.796019Z","caller":"traceutil/trace.go:171","msg":"trace[1599450415] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"195.401684ms","start":"2026-07-15T04:50:10.600597Z","end":"2026-07-15T04:50:10.795998Z","steps":["trace[1599450415] 'process raft request' (duration: 195.072383ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:50:10.886121Z","caller":"traceutil/trace.go:171","msg":"trace[374110443] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"125.563303ms","start":"2026-07-15T04:50:10.760514Z","end":"2026-07-15T04:50:10.886077Z","steps":["trace[374110443] 'process raft request' (duration: 125.243912ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:50:10.88636Z","caller":"traceutil/trace.go:171","msg":"trace[219410425] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"118.70646ms","start":"2026-07-15T04:50:10.767631Z","end":"2026-07-15T04:50:10.886337Z","steps":["trace[219410425] 'process raft request' (duration: 118.635388ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:50:10.886851Z","caller":"traceutil/trace.go:171","msg":"trace[1598002598] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"126.29626ms","start":"2026-07-15T04:50:10.760543Z","end":"2026-07-15T04:50:10.886839Z","steps":["trace[1598002598] 'process raft request' (duration: 125.410478ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:50:10.88713Z","caller":"traceutil/trace.go:171","msg":"trace[850940812] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"125.75532ms","start":"2026-07-15T04:50:10.761357Z","end":"2026-07-15T04:50:10.887112Z","steps":["trace[850940812] 'process raft request' (duration: 124.736454ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:50:10.887155Z","caller":"traceutil/trace.go:171","msg":"trace[732471187] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"123.006292ms","start":"2026-07-15T04:50:10.764137Z","end":"2026-07-15T04:50:10.887144Z","steps":["trace[732471187] 'process raft request' (duration: 122.030047ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:50:10.887331Z","caller":"traceutil/trace.go:171","msg":"trace[33706149] transaction","detail":"{read_only:false; number_of_response:1; response_revision:503; }","duration":"120.291357ms","start":"2026-07-15T04:50:10.76703Z","end":"2026-07-15T04:50:10.887321Z","steps":["trace[33706149] 'process raft request' (duration: 119.186317ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:50:10.887399Z","caller":"traceutil/trace.go:171","msg":"trace[2121016427] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"126.629851ms","start":"2026-07-15T04:50:10.760753Z","end":"2026-07-15T04:50:10.887383Z","steps":["trace[2121016427] 'process raft request' (duration: 125.241021ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:50:10.887739Z","caller":"traceutil/trace.go:171","msg":"trace[1283967268] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"126.919641ms","start":"2026-07-15T04:50:10.760806Z","end":"2026-07-15T04:50:10.887726Z","steps":["trace[1283967268] 'process raft request' (duration: 125.22066ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:50:10.941168Z","caller":"traceutil/trace.go:171","msg":"trace[1873827667] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"128.142984ms","start":"2026-07-15T04:50:10.812982Z","end":"2026-07-15T04:50:10.941125Z","steps":["trace[1873827667] 'process raft request' (duration: 127.956707ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:50:10.941316Z","caller":"traceutil/trace.go:171","msg":"trace[512190866] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"127.966378ms","start":"2026-07-15T04:50:10.813332Z","end":"2026-07-15T04:50:10.941298Z","steps":["trace[512190866] 'process raft request' (duration: 127.73159ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:50:10.941807Z","caller":"traceutil/trace.go:171","msg":"trace[1500893352] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"120.422921ms","start":"2026-07-15T04:50:10.821363Z","end":"2026-07-15T04:50:10.941785Z","steps":["trace[1500893352] 'process raft request' (duration: 119.898883ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:50:11.006362Z","caller":"traceutil/trace.go:171","msg":"trace[1448596417] transaction","detail":"{read_only:false; number_of_response:0; response_revision:508; }","duration":"113.684971ms","start":"2026-07-15T04:50:10.892654Z","end":"2026-07-15T04:50:11.006339Z","steps":["trace[1448596417] 'process raft request' (duration: 113.582438ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:50:11.006893Z","caller":"traceutil/trace.go:171","msg":"trace[631012604] transaction","detail":"{read_only:false; number_of_response:0; response_revision:508; }","duration":"115.890679ms","start":"2026-07-15T04:50:10.890985Z","end":"2026-07-15T04:50:11.006876Z","steps":["trace[631012604] 'process raft request' (duration: 87.396242ms)","trace[631012604] 'compare' (duration: 27.738341ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T04:50:11.007056Z","caller":"traceutil/trace.go:171","msg":"trace[246592609] transaction","detail":"{read_only:false; number_of_response:0; response_revision:508; }","duration":"115.90915ms","start":"2026-07-15T04:50:10.891138Z","end":"2026-07-15T04:50:11.007047Z","steps":["trace[246592609] 'process raft request' (duration: 115.04314ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:50:11.007176Z","caller":"traceutil/trace.go:171","msg":"trace[515479249] transaction","detail":"{read_only:false; number_of_response:0; response_revision:508; }","duration":"114.578792ms","start":"2026-07-15T04:50:10.89259Z","end":"2026-07-15T04:50:11.007169Z","steps":["trace[515479249] 'process raft request' (duration: 113.625779ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:50:11.007711Z","caller":"traceutil/trace.go:171","msg":"trace[750747423] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"108.319581ms","start":"2026-07-15T04:50:10.899372Z","end":"2026-07-15T04:50:11.007692Z","steps":["trace[750747423] 'process raft request' (duration: 106.890991ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:50:11.00811Z","caller":"traceutil/trace.go:171","msg":"trace[1422298209] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"108.663133ms","start":"2026-07-15T04:50:10.899433Z","end":"2026-07-15T04:50:11.008096Z","steps":["trace[1422298209] 'process raft request' (duration: 107.900177ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:50:11.008291Z","caller":"traceutil/trace.go:171","msg":"trace[1189244565] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"106.01667ms","start":"2026-07-15T04:50:10.902265Z","end":"2026-07-15T04:50:11.008282Z","steps":["trace[1189244565] 'process raft request' (duration: 105.15505ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:50:11.313866Z","caller":"traceutil/trace.go:171","msg":"trace[1497241169] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"104.442877ms","start":"2026-07-15T04:50:11.209373Z","end":"2026-07-15T04:50:11.313816Z","steps":["trace[1497241169] 'process raft request' (duration: 56.184197ms)","trace[1497241169] 'compare' (duration: 48.006361ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T04:50:17.801091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.148312935s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-15T04:50:17.801188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.992806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-lrgr7\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-15T04:50:17.801282Z","caller":"traceutil/trace.go:171","msg":"trace[603787339] range","detail":"{range_begin:/registry/pods/kube-system/cilium-lrgr7; range_end:; response_count:1; response_revision:581; }","duration":"439.142071ms","start":"2026-07-15T04:50:17.362119Z","end":"2026-07-15T04:50:17.801261Z","steps":["trace[603787339] 'range keys from in-memory index tree' (duration: 438.768809ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:50:17.801327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:50:17.362102Z","time spent":"439.215983ms","remote":"127.0.0.1:44276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-lrgr7\" "} {"level":"warn","ts":"2026-07-15T04:50:17.801415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.177953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-15T04:50:17.801492Z","caller":"traceutil/trace.go:171","msg":"trace[2101527117] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:581; }","duration":"561.313986ms","start":"2026-07-15T04:50:17.240157Z","end":"2026-07-15T04:50:17.801471Z","steps":["trace[2101527117] 'range keys from in-memory index tree' (duration: 560.972686ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:50:17.801521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.963908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-15T04:50:17.80153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:50:17.240125Z","time spent":"561.396609ms","remote":"127.0.0.1:44376","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":"info","ts":"2026-07-15T04:50:17.801566Z","caller":"traceutil/trace.go:171","msg":"trace[130180097] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:581; }","duration":"389.05783ms","start":"2026-07-15T04:50:17.412498Z","end":"2026-07-15T04:50:17.801556Z","steps":["trace[130180097] 'range keys from in-memory index tree' (duration: 388.901735ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:50:17.8016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:50:17.41248Z","time spent":"389.112492ms","remote":"127.0.0.1:44084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-15T04:50:17.801208Z","caller":"traceutil/trace.go:171","msg":"trace[2133284414] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:581; }","duration":"1.148499781s","start":"2026-07-15T04:50:16.652681Z","end":"2026-07-15T04:50:17.80118Z","steps":["trace[2133284414] 'range keys from in-memory index tree' (duration: 1.148215102s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:50:17.801657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:50:16.652659Z","time spent":"1.148989327s","remote":"127.0.0.1:44300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-07-15T04:50:18.769597Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334369033555230546,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-15T04:50:19.270826Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334369033555230546,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-15T04:50:19.288699Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.482187411s","expected-duration":"1s"} {"level":"info","ts":"2026-07-15T04:50:19.289742Z","caller":"traceutil/trace.go:171","msg":"trace[1723668899] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"1.482989617s","start":"2026-07-15T04:50:17.806415Z","end":"2026-07-15T04:50:19.289405Z","steps":["trace[1723668899] 'process raft request' (duration: 1.482621685s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:50:19.290029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:50:17.806399Z","time spent":"1.483538695s","remote":"127.0.0.1:44376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T04:50:19.575446Z","caller":"traceutil/trace.go:171","msg":"trace[621287420] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"1.757170947s","start":"2026-07-15T04:50:17.818255Z","end":"2026-07-15T04:50:19.575426Z","steps":["trace[621287420] 'process raft request' (duration: 1.668776092s)","trace[621287420] 'compare' (duration: 88.217719ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T04:50:19.575562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:50:17.81823Z","time spent":"1.757268869s","remote":"127.0.0.1:44276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T04:50:19.575931Z","caller":"traceutil/trace.go:171","msg":"trace[1009036013] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"1.736490465s","start":"2026-07-15T04:50:17.839432Z","end":"2026-07-15T04:50:19.575922Z","steps":["trace[1009036013] 'process raft request' (duration: 1.735946208s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:50:19.575978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:50:17.839416Z","time spent":"1.736538477s","remote":"127.0.0.1:44376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T04:50:19.578923Z","caller":"traceutil/trace.go:171","msg":"trace[1440253890] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:607; }","duration":"1.309373975s","start":"2026-07-15T04:50:18.269527Z","end":"2026-07-15T04:50:19.578901Z","steps":["trace[1440253890] 'read index received' (duration: 1.019578164s)","trace[1440253890] 'applied index is now lower than readState.Index' (duration: 289.794751ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T04:50:19.579044Z","caller":"traceutil/trace.go:171","msg":"trace[81239817] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"1.37728069s","start":"2026-07-15T04:50:18.201754Z","end":"2026-07-15T04:50:19.579035Z","steps":["trace[81239817] 'process raft request' (duration: 1.374833021s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:50:19.579145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:50:18.201725Z","time spent":"1.377349343s","remote":"127.0.0.1:44184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036109,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T04:50:19.579415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.306354656s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-15T04:50:19.579457Z","caller":"traceutil/trace.go:171","msg":"trace[2066951063] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:585; }","duration":"1.306422309s","start":"2026-07-15T04:50:18.273023Z","end":"2026-07-15T04:50:19.579445Z","steps":["trace[2066951063] 'agreement among raft nodes before linearized reading' (duration: 1.306274004s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:50:19.579477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.164197408s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T04:50:19.579516Z","caller":"traceutil/trace.go:171","msg":"trace[280852636] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:585; }","duration":"1.164236801s","start":"2026-07-15T04:50:18.41527Z","end":"2026-07-15T04:50:19.579507Z","steps":["trace[280852636] 'agreement among raft nodes before linearized reading' (duration: 1.164176658s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:50:19.579544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.260249933s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-15T04:50:19.579552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:50:18.415209Z","time spent":"1.164337214s","remote":"127.0.0.1:44088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-15T04:50:19.579565Z","caller":"traceutil/trace.go:171","msg":"trace[1571735115] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:585; }","duration":"1.260288014s","start":"2026-07-15T04:50:18.319271Z","end":"2026-07-15T04:50:19.579559Z","steps":["trace[1571735115] 'agreement among raft nodes before linearized reading' (duration: 1.260231712s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:50:19.579586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:50:18.31926Z","time spent":"1.260321925s","remote":"127.0.0.1:44376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-15T04:50:19.579489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:50:18.273016Z","time spent":"1.306465901s","remote":"127.0.0.1:44376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-15T04:50:19.579751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.310229443s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-15T04:50:19.579779Z","caller":"traceutil/trace.go:171","msg":"trace[1262576989] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:585; }","duration":"1.310296036s","start":"2026-07-15T04:50:18.269474Z","end":"2026-07-15T04:50:19.57977Z","steps":["trace[1262576989] 'agreement among raft nodes before linearized reading' (duration: 1.310195003s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:50:19.579812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:50:18.269453Z","time spent":"1.310352807s","remote":"127.0.0.1:44376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-15T04:50:20.089351Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334369033555230553,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-15T04:50:20.590463Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334369033555230553,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-15T04:50:21.061701Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.476768886s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-15T04:50:21.588792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000492445s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-15T04:50:21.588929Z","caller":"traceutil/trace.go:171","msg":"trace[652613475] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00066726s","start":"2026-07-15T04:50:19.588234Z","end":"2026-07-15T04:50:21.588901Z","steps":["trace[652613475] 'agreement among raft nodes before linearized reading' (duration: 2.000507385s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:50:21.588993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:50:19.588226Z","time spent":"2.000754473s","remote":"127.0.0.1:44088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/15 04:50:21 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-15T04:50:22.102702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.040451131s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-15T04:50:22.102881Z","caller":"traceutil/trace.go:171","msg":"trace[455380994] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"2.518024302s","start":"2026-07-15T04:50:19.584836Z","end":"2026-07-15T04:50:22.10286Z","steps":["trace[455380994] 'process raft request' (duration: 1.477325933s)","trace[455380994] 'compare' (duration: 1.039980607s)"],"step_count":2} {"level":"warn","ts":"2026-07-15T04:50:22.10295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:50:19.584816Z","time spent":"2.518101275s","remote":"127.0.0.1:44376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T04:50:22.103328Z","caller":"traceutil/trace.go:171","msg":"trace[1849635466] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"2.518140335s","start":"2026-07-15T04:50:19.585129Z","end":"2026-07-15T04:50:22.103269Z","steps":["trace[1849635466] 'process raft request' (duration: 2.51767862s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:50:22.103675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:50:19.585113Z","time spent":"2.518435764s","remote":"127.0.0.1:44376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T04:50:22.104465Z","caller":"traceutil/trace.go:171","msg":"trace[799402586] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"2.519308791s","start":"2026-07-15T04:50:19.585142Z","end":"2026-07-15T04:50:22.104451Z","steps":["trace[799402586] 'process raft request' (duration: 2.517943068s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:50:22.104535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:50:19.585122Z","time spent":"2.519373454s","remote":"127.0.0.1:44376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T04:50:22.105552Z","caller":"traceutil/trace.go:171","msg":"trace[1546209912] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"2.474294488s","start":"2026-07-15T04:50:19.630394Z","end":"2026-07-15T04:50:22.104688Z","steps":["trace[1546209912] 'process raft request' (duration: 2.474003099s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:50:22.105726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:50:19.630363Z","time spent":"2.475284508s","remote":"127.0.0.1:44300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":818,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-15T04:50:22.147962Z","caller":"traceutil/trace.go:171","msg":"trace[1023893348] linearizableReadLoop","detail":"{readStateIndex:616; appliedIndex:611; }","duration":"2.559674915s","start":"2026-07-15T04:50:19.588257Z","end":"2026-07-15T04:50:22.147932Z","steps":["trace[1023893348] 'read index received' (duration: 1.473916973s)","trace[1023893348] 'applied index is now lower than readState.Index' (duration: 1.085756552s)"],"step_count":2} {"level":"warn","ts":"2026-07-15T04:50:22.14828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"853.530105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-15T04:50:22.148299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.471329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T04:50:22.148408Z","caller":"traceutil/trace.go:171","msg":"trace[339129195] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:589; }","duration":"559.633085ms","start":"2026-07-15T04:50:21.58875Z","end":"2026-07-15T04:50:22.148383Z","steps":["trace[339129195] 'agreement among raft nodes before linearized reading' (duration: 559.450108ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:50:22.148342Z","caller":"traceutil/trace.go:171","msg":"trace[1816635825] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:589; }","duration":"853.654459ms","start":"2026-07-15T04:50:21.294672Z","end":"2026-07-15T04:50:22.148327Z","steps":["trace[1816635825] 'agreement among raft nodes before linearized reading' (duration: 853.438372ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:50:22.148279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.139442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-15T04:50:22.14857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:50:21.294647Z","time spent":"853.881657ms","remote":"127.0.0.1:44376","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":"info","ts":"2026-07-15T04:50:22.148632Z","caller":"traceutil/trace.go:171","msg":"trace[1390717304] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:589; }","duration":"394.550366ms","start":"2026-07-15T04:50:21.75407Z","end":"2026-07-15T04:50:22.148621Z","steps":["trace[1390717304] 'agreement among raft nodes before linearized reading' (duration: 394.141703ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:50:22.148678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:50:21.754044Z","time spent":"394.624978ms","remote":"127.0.0.1:44088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-15T04:50:22.148459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:50:21.588728Z","time spent":"559.722908ms","remote":"127.0.0.1:44084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-15T04:50:22.744558Z","caller":"traceutil/trace.go:171","msg":"trace[168827363] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"172.529262ms","start":"2026-07-15T04:50:22.572002Z","end":"2026-07-15T04:50:22.744532Z","steps":["trace[168827363] 'process raft request' (duration: 171.621384ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:50:23.981659Z","caller":"traceutil/trace.go:171","msg":"trace[1578093529] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"291.861769ms","start":"2026-07-15T04:50:23.689769Z","end":"2026-07-15T04:50:23.981631Z","steps":["trace[1578093529] 'process raft request' (duration: 291.414125ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:50:27.592396Z","caller":"traceutil/trace.go:171","msg":"trace[179211959] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"160.350376ms","start":"2026-07-15T04:50:27.432016Z","end":"2026-07-15T04:50:27.592367Z","steps":["trace[179211959] 'process raft request' (duration: 160.1391ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:50:27.853466Z","caller":"traceutil/trace.go:171","msg":"trace[930909953] linearizableReadLoop","detail":"{readStateIndex:709; appliedIndex:708; }","duration":"196.24281ms","start":"2026-07-15T04:50:27.657196Z","end":"2026-07-15T04:50:27.853439Z","steps":["trace[930909953] 'read index received' (duration: 154.944515ms)","trace[930909953] 'applied index is now lower than readState.Index' (duration: 41.296775ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T04:50:27.853589Z","caller":"traceutil/trace.go:171","msg":"trace[1932909680] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"368.047058ms","start":"2026-07-15T04:50:27.485499Z","end":"2026-07-15T04:50:27.853546Z","steps":["trace[1932909680] 'process raft request' (duration: 326.682351ms)","trace[1932909680] 'compare' (duration: 40.77326ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T04:50:27.853739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:50:27.485464Z","time spent":"368.190393ms","remote":"127.0.0.1:44134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187246,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-15T04:50:27.853986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.770945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-07-15T04:50:27.85404Z","caller":"traceutil/trace.go:171","msg":"trace[1865549054] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:680; }","duration":"196.883309ms","start":"2026-07-15T04:50:27.657142Z","end":"2026-07-15T04:50:27.854025Z","steps":["trace[1865549054] 'agreement among raft nodes before linearized reading' (duration: 196.415945ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:50:28.185796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.967457ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-15T04:50:28.185991Z","caller":"traceutil/trace.go:171","msg":"trace[1404883753] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"298.030826ms","start":"2026-07-15T04:50:27.887939Z","end":"2026-07-15T04:50:28.18597Z","steps":["trace[1404883753] 'process raft request' (duration: 182.808711ms)","trace[1404883753] 'compare' (duration: 114.667208ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T04:50:28.187199Z","caller":"traceutil/trace.go:171","msg":"trace[621513529] linearizableReadLoop","detail":"{readStateIndex:711; appliedIndex:709; }","duration":"128.954911ms","start":"2026-07-15T04:50:28.058227Z","end":"2026-07-15T04:50:28.187182Z","steps":["trace[621513529] 'read index received' (duration: 12.432128ms)","trace[621513529] 'applied index is now lower than readState.Index' (duration: 116.521713ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T04:50:28.187303Z","caller":"traceutil/trace.go:171","msg":"trace[748629774] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"272.830011ms","start":"2026-07-15T04:50:27.914463Z","end":"2026-07-15T04:50:28.187293Z","steps":["trace[748629774] 'process raft request' (duration: 272.496231ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:50:28.187612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.423565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-07-15T04:50:28.187656Z","caller":"traceutil/trace.go:171","msg":"trace[42312777] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:682; }","duration":"129.474837ms","start":"2026-07-15T04:50:28.058168Z","end":"2026-07-15T04:50:28.187642Z","steps":["trace[42312777] 'agreement among raft nodes before linearized reading' (duration: 129.306902ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:50:28.18793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.264635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-07-15T04:50:28.188016Z","caller":"traceutil/trace.go:171","msg":"trace[1326208376] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:682; }","duration":"114.393189ms","start":"2026-07-15T04:50:28.073603Z","end":"2026-07-15T04:50:28.187996Z","steps":["trace[1326208376] 'agreement among raft nodes before linearized reading' (duration: 114.177953ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:50:28.649793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.837747ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-15T04:50:28.650134Z","caller":"traceutil/trace.go:171","msg":"trace[1957515297] linearizableReadLoop","detail":"{readStateIndex:715; appliedIndex:712; }","duration":"445.45088ms","start":"2026-07-15T04:50:28.204654Z","end":"2026-07-15T04:50:28.650105Z","steps":["trace[1957515297] 'read index received' (duration: 68.819379ms)","trace[1957515297] 'applied index is now lower than readState.Index' (duration: 376.630291ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T04:50:28.650104Z","caller":"traceutil/trace.go:171","msg":"trace[1345373238] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"456.663363ms","start":"2026-07-15T04:50:28.193383Z","end":"2026-07-15T04:50:28.650047Z","steps":["trace[1345373238] 'process raft request' (duration: 158.445916ms)","trace[1345373238] 'compare' (duration: 297.625129ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T04:50:28.650335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:50:28.193369Z","time spent":"456.841909ms","remote":"127.0.0.1:44376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T04:50:28.651146Z","caller":"traceutil/trace.go:171","msg":"trace[1008149101] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"456.740895ms","start":"2026-07-15T04:50:28.19434Z","end":"2026-07-15T04:50:28.651081Z","steps":["trace[1008149101] 'process raft request' (duration: 455.696133ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:50:28.651376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.697598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-15T04:50:28.651435Z","caller":"traceutil/trace.go:171","msg":"trace[1826977383] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:686; }","duration":"446.793751ms","start":"2026-07-15T04:50:28.204628Z","end":"2026-07-15T04:50:28.651422Z","steps":["trace[1826977383] 'agreement among raft nodes before linearized reading' (duration: 446.592635ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:50:28.651467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:50:28.204617Z","time spent":"446.844172ms","remote":"127.0.0.1:44376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-15T04:50:28.651467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:50:28.194324Z","time spent":"457.030904ms","remote":"127.0.0.1:44376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T04:50:28.651602Z","caller":"traceutil/trace.go:171","msg":"trace[1991080910] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"457.88508ms","start":"2026-07-15T04:50:28.193706Z","end":"2026-07-15T04:50:28.651591Z","steps":["trace[1991080910] 'process raft request' (duration: 456.21796ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:50:28.651817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:50:28.193694Z","time spent":"458.072755ms","remote":"127.0.0.1:44376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T04:50:28.662785Z","caller":"traceutil/trace.go:171","msg":"trace[1775549461] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"381.857945ms","start":"2026-07-15T04:50:28.280913Z","end":"2026-07-15T04:50:28.662771Z","steps":["trace[1775549461] 'process raft request' (duration: 381.740932ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:50:28.662905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:50:28.280889Z","time spent":"381.970119ms","remote":"127.0.0.1:44134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188237,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T04:50:28.663048Z","caller":"traceutil/trace.go:171","msg":"trace[2120986531] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"386.411661ms","start":"2026-07-15T04:50:28.276626Z","end":"2026-07-15T04:50:28.663038Z","steps":["trace[2120986531] 'process raft request' (duration: 385.747601ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:50:28.663133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:50:28.276608Z","time spent":"386.489734ms","remote":"127.0.0.1:44134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188471,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T04:50:29.494768Z","caller":"traceutil/trace.go:171","msg":"trace[926195095] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"1.044020116s","start":"2026-07-15T04:50:28.45072Z","end":"2026-07-15T04:50:29.49474Z","steps":["trace[926195095] 'process raft request' (duration: 1.043792159s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:50:29.494909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:50:28.449904Z","time spent":"1.044936583s","remote":"127.0.0.1:44170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":696,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-15T04:50:29.495553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.038254274s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188252"} {"level":"warn","ts":"2026-07-15T04:50:29.495539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.081483606s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T04:50:29.495603Z","caller":"traceutil/trace.go:171","msg":"trace[225374056] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:690; }","duration":"1.038356077s","start":"2026-07-15T04:50:28.457234Z","end":"2026-07-15T04:50:29.49559Z","steps":["trace[225374056] 'agreement among raft nodes before linearized reading' (duration: 1.038149021s)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:50:29.495623Z","caller":"traceutil/trace.go:171","msg":"trace[131576239] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:690; }","duration":"1.08162044s","start":"2026-07-15T04:50:28.413983Z","end":"2026-07-15T04:50:29.495603Z","steps":["trace[131576239] 'agreement among raft nodes before linearized reading' (duration: 1.081458925s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:50:29.495638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:50:28.457216Z","time spent":"1.038413878s","remote":"127.0.0.1:44134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":1,"response size":188275,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-07-15T04:50:29.495678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:50:28.413949Z","time spent":"1.081719723s","remote":"127.0.0.1:44084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-15T04:50:29.495611Z","caller":"traceutil/trace.go:171","msg":"trace[550577148] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"839.897796ms","start":"2026-07-15T04:50:28.655686Z","end":"2026-07-15T04:50:29.495584Z","steps":["trace[550577148] 'process raft request' (duration: 838.98693ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:50:29.496614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.234911301s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-07-15T04:50:29.496692Z","caller":"traceutil/trace.go:171","msg":"trace[191175111] linearizableReadLoop","detail":"{readStateIndex:718; appliedIndex:715; }","duration":"846.485401ms","start":"2026-07-15T04:50:28.650175Z","end":"2026-07-15T04:50:29.496661Z","steps":["trace[191175111] 'read index received' (duration: 12.239743ms)","trace[191175111] 'applied index is now lower than readState.Index' (duration: 832.935499ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T04:50:29.49683Z","caller":"traceutil/trace.go:171","msg":"trace[331976874] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:690; }","duration":"1.235456037s","start":"2026-07-15T04:50:28.261203Z","end":"2026-07-15T04:50:29.496659Z","steps":["trace[331976874] 'agreement among raft nodes before linearized reading' (duration: 1.234766786s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:50:29.496911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:50:28.261186Z","time spent":"1.235709535s","remote":"127.0.0.1:44134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":1,"response size":10400,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"warn","ts":"2026-07-15T04:50:29.497067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:50:28.655661Z","time spent":"840.207175ms","remote":"127.0.0.1:44376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T04:50:29.600613Z","caller":"traceutil/trace.go:171","msg":"trace[1565645787] linearizableReadLoop","detail":"{readStateIndex:720; appliedIndex:719; }","duration":"103.85425ms","start":"2026-07-15T04:50:29.496736Z","end":"2026-07-15T04:50:29.60059Z","steps":["trace[1565645787] 'read index received' (duration: 101.685276ms)","trace[1565645787] 'applied index is now lower than readState.Index' (duration: 2.168224ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T04:50:29.6007Z","caller":"traceutil/trace.go:171","msg":"trace[23985674] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"838.815346ms","start":"2026-07-15T04:50:28.761855Z","end":"2026-07-15T04:50:29.60067Z","steps":["trace[23985674] 'process raft request' (duration: 836.645332ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:50:29.600861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"889.675163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188495"} {"level":"info","ts":"2026-07-15T04:50:29.600915Z","caller":"traceutil/trace.go:171","msg":"trace[211178256] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:691; }","duration":"889.789726ms","start":"2026-07-15T04:50:28.711116Z","end":"2026-07-15T04:50:29.600906Z","steps":["trace[211178256] 'agreement among raft nodes before linearized reading' (duration: 889.57741ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:50:29.60091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:50:28.761824Z","time spent":"838.96046ms","remote":"127.0.0.1:44134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T04:50:29.600942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:50:28.711095Z","time spent":"889.840418ms","remote":"127.0.0.1:44134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":1,"response size":188518,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-07-15T04:50:29.600934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.695594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-lrgr7\" ","response":"range_response_count:1 size:18516"} {"level":"info","ts":"2026-07-15T04:50:29.601019Z","caller":"traceutil/trace.go:171","msg":"trace[672341335] range","detail":"{range_begin:/registry/pods/kube-system/cilium-lrgr7; range_end:; response_count:1; response_revision:691; }","duration":"167.811027ms","start":"2026-07-15T04:50:29.433185Z","end":"2026-07-15T04:50:29.600996Z","steps":["trace[672341335] 'agreement among raft nodes before linearized reading' (duration: 167.501598ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:50:29.607322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.594323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T04:50:29.607349Z","caller":"traceutil/trace.go:171","msg":"trace[1394350267] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:692; }","duration":"101.636543ms","start":"2026-07-15T04:50:29.505706Z","end":"2026-07-15T04:50:29.607342Z","steps":["trace[1394350267] 'agreement among raft nodes before linearized reading' (duration: 101.568422ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:50:29.607336Z","caller":"traceutil/trace.go:171","msg":"trace[21486737] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"106.196869ms","start":"2026-07-15T04:50:29.501117Z","end":"2026-07-15T04:50:29.607313Z","steps":["trace[21486737] 'process raft request' (duration: 106.071545ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:50:29.782724Z","caller":"traceutil/trace.go:171","msg":"trace[552532735] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"165.908022ms","start":"2026-07-15T04:50:29.616795Z","end":"2026-07-15T04:50:29.782703Z","steps":["trace[552532735] 'process raft request' (duration: 159.438821ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:50:29.785871Z","caller":"traceutil/trace.go:171","msg":"trace[1675178000] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"127.381371ms","start":"2026-07-15T04:50:29.658462Z","end":"2026-07-15T04:50:29.785844Z","steps":["trace[1675178000] 'process raft request' (duration: 127.253647ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:50:30.0152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.172588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T04:50:30.01527Z","caller":"traceutil/trace.go:171","msg":"trace[1961256368] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:694; }","duration":"118.266731ms","start":"2026-07-15T04:50:29.896992Z","end":"2026-07-15T04:50:30.015258Z","steps":["trace[1961256368] 'range keys from in-memory index tree' (duration: 118.124595ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:50:30.015462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.983101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T04:50:30.01549Z","caller":"traceutil/trace.go:171","msg":"trace[53942520] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:694; }","duration":"119.043293ms","start":"2026-07-15T04:50:29.896437Z","end":"2026-07-15T04:50:30.01548Z","steps":["trace[53942520] 'count revisions from in-memory index tree' (duration: 118.884888ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:50:30.015589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.086084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T04:50:30.015611Z","caller":"traceutil/trace.go:171","msg":"trace[1385080136] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:694; }","duration":"119.109285ms","start":"2026-07-15T04:50:29.896496Z","end":"2026-07-15T04:50:30.015605Z","steps":["trace[1385080136] 'range keys from in-memory index tree' (duration: 119.004082ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:50:30.015871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.789795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T04:50:30.015889Z","caller":"traceutil/trace.go:171","msg":"trace[1980118428] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:694; }","duration":"101.844837ms","start":"2026-07-15T04:50:29.914039Z","end":"2026-07-15T04:50:30.015884Z","steps":["trace[1980118428] 'range keys from in-memory index tree' (duration: 101.727203ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:50:30.01596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.2349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T04:50:30.01652Z","caller":"traceutil/trace.go:171","msg":"trace[90441273] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:694; }","duration":"102.249729ms","start":"2026-07-15T04:50:29.914198Z","end":"2026-07-15T04:50:30.016447Z","steps":["trace[90441273] 'count revisions from in-memory index tree' (duration: 101.135986ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:50:30.016046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.658021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T04:50:30.016748Z","caller":"traceutil/trace.go:171","msg":"trace[1011901605] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:694; }","duration":"102.373632ms","start":"2026-07-15T04:50:29.914364Z","end":"2026-07-15T04:50:30.016737Z","steps":["trace[1011901605] 'range keys from in-memory index tree' (duration: 101.61368ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:50:30.230106Z","caller":"traceutil/trace.go:171","msg":"trace[826955688] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"105.820827ms","start":"2026-07-15T04:50:30.124257Z","end":"2026-07-15T04:50:30.230078Z","steps":["trace[826955688] 'process raft request' (duration: 105.605131ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:50:35.637727Z","caller":"traceutil/trace.go:171","msg":"trace[1187677882] linearizableReadLoop","detail":"{readStateIndex:769; appliedIndex:768; }","duration":"154.059474ms","start":"2026-07-15T04:50:35.483648Z","end":"2026-07-15T04:50:35.637708Z","steps":["trace[1187677882] 'read index received' (duration: 153.899709ms)","trace[1187677882] 'applied index is now lower than readState.Index' (duration: 158.455µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T04:50:35.637806Z","caller":"traceutil/trace.go:171","msg":"trace[86423071] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"176.768855ms","start":"2026-07-15T04:50:35.460998Z","end":"2026-07-15T04:50:35.637766Z","steps":["trace[86423071] 'process raft request' (duration: 176.58034ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:50:35.637959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.30098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.109\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-15T04:50:35.638002Z","caller":"traceutil/trace.go:171","msg":"trace[1214940987] range","detail":"{range_begin:/registry/masterleases/199.204.45.109; range_end:; response_count:1; response_revision:738; }","duration":"154.394463ms","start":"2026-07-15T04:50:35.483597Z","end":"2026-07-15T04:50:35.637991Z","steps":["trace[1214940987] 'agreement among raft nodes before linearized reading' (duration: 154.233928ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:50:35.638039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.268165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-15T04:50:35.638124Z","caller":"traceutil/trace.go:171","msg":"trace[582862989] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:738; }","duration":"115.387989ms","start":"2026-07-15T04:50:35.522714Z","end":"2026-07-15T04:50:35.638102Z","steps":["trace[582862989] 'agreement among raft nodes before linearized reading' (duration: 115.152542ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:50:36.490103Z","caller":"traceutil/trace.go:171","msg":"trace[631677290] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"177.751438ms","start":"2026-07-15T04:50:36.312325Z","end":"2026-07-15T04:50:36.490077Z","steps":["trace[631677290] 'process raft request' (duration: 177.566073ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:50:40.159748Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334369033555230891,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-15T04:50:40.257571Z","caller":"traceutil/trace.go:171","msg":"trace[451616243] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"775.162961ms","start":"2026-07-15T04:50:39.482384Z","end":"2026-07-15T04:50:40.257547Z","steps":["trace[451616243] 'process raft request' (duration: 775.034047ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:50:40.257715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:50:39.482361Z","time spent":"775.266524ms","remote":"127.0.0.1:44332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T04:50:40.261664Z","caller":"traceutil/trace.go:171","msg":"trace[1338101523] linearizableReadLoop","detail":"{readStateIndex:801; appliedIndex:800; }","duration":"603.012778ms","start":"2026-07-15T04:50:39.658627Z","end":"2026-07-15T04:50:40.26164Z","steps":["trace[1338101523] 'read index received' (duration: 598.898009ms)","trace[1338101523] 'applied index is now lower than readState.Index' (duration: 4.113789ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T04:50:40.26174Z","caller":"traceutil/trace.go:171","msg":"trace[1267147102] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"257.818992ms","start":"2026-07-15T04:50:40.003896Z","end":"2026-07-15T04:50:40.261715Z","steps":["trace[1267147102] 'process raft request' (duration: 257.613856ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:50:40.262097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.457499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-15T04:50:40.262212Z","caller":"traceutil/trace.go:171","msg":"trace[1407384321] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:768; }","duration":"603.629905ms","start":"2026-07-15T04:50:39.658562Z","end":"2026-07-15T04:50:40.262192Z","steps":["trace[1407384321] 'agreement among raft nodes before linearized reading' (duration: 603.234514ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:50:40.262524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:50:39.658536Z","time spent":"603.970834ms","remote":"127.0.0.1:44376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-15T04:50:46.724395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.21561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-15T04:50:46.724529Z","caller":"traceutil/trace.go:171","msg":"trace[2122975877] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:833; }","duration":"197.394754ms","start":"2026-07-15T04:50:46.527115Z","end":"2026-07-15T04:50:46.72451Z","steps":["trace[2122975877] 'range keys from in-memory index tree' (duration: 197.033126ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:50:46.724549Z","caller":"traceutil/trace.go:171","msg":"trace[613511269] transaction","detail":"{read_only:false; response_revision:834; number_of_response:1; }","duration":"171.597472ms","start":"2026-07-15T04:50:46.552915Z","end":"2026-07-15T04:50:46.724512Z","steps":["trace[613511269] 'process raft request' (duration: 154.834447ms)","trace[613511269] 'compare' (duration: 16.487418ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T04:50:47.065242Z","caller":"traceutil/trace.go:171","msg":"trace[297030360] linearizableReadLoop","detail":"{readStateIndex:871; appliedIndex:869; }","duration":"335.171119ms","start":"2026-07-15T04:50:46.730045Z","end":"2026-07-15T04:50:47.065216Z","steps":["trace[297030360] 'read index received' (duration: 76.121806ms)","trace[297030360] 'applied index is now lower than readState.Index' (duration: 259.048353ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T04:50:47.06552Z","caller":"traceutil/trace.go:171","msg":"trace[561665941] transaction","detail":"{read_only:false; response_revision:836; number_of_response:1; }","duration":"335.597869ms","start":"2026-07-15T04:50:46.729883Z","end":"2026-07-15T04:50:47.065481Z","steps":["trace[561665941] 'process raft request' (duration: 288.62898ms)","trace[561665941] 'compare' (duration: 46.58445ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T04:50:47.065645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.521687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-15T04:50:47.065756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.788257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4919"} {"level":"warn","ts":"2026-07-15T04:50:47.065749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.378402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-15T04:50:47.06581Z","caller":"traceutil/trace.go:171","msg":"trace[719171447] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:836; }","duration":"185.88172ms","start":"2026-07-15T04:50:46.879912Z","end":"2026-07-15T04:50:47.065794Z","steps":["trace[719171447] 'agreement among raft nodes before linearized reading' (duration: 185.723816ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:50:47.065829Z","caller":"traceutil/trace.go:171","msg":"trace[1934676636] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:836; }","duration":"331.496965ms","start":"2026-07-15T04:50:46.734313Z","end":"2026-07-15T04:50:47.065809Z","steps":["trace[1934676636] 'agreement among raft nodes before linearized reading' (duration: 331.26359ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:50:47.065789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:50:46.729867Z","time spent":"335.744193ms","remote":"127.0.0.1:44376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T04:50:47.06576Z","caller":"traceutil/trace.go:171","msg":"trace[1501422226] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:836; }","duration":"335.727123ms","start":"2026-07-15T04:50:46.730014Z","end":"2026-07-15T04:50:47.065741Z","steps":["trace[1501422226] 'agreement among raft nodes before linearized reading' (duration: 335.402485ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:50:47.065879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:50:46.734294Z","time spent":"331.573107ms","remote":"127.0.0.1:44376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-15T04:50:47.066212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:50:46.730003Z","time spent":"336.191014ms","remote":"127.0.0.1:44376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-15T04:50:47.298056Z","caller":"traceutil/trace.go:171","msg":"trace[501247661] transaction","detail":"{read_only:false; response_revision:838; number_of_response:1; }","duration":"227.206653ms","start":"2026-07-15T04:50:47.070824Z","end":"2026-07-15T04:50:47.29803Z","steps":["trace[501247661] 'process raft request' (duration: 222.882825ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:50:47.298491Z","caller":"traceutil/trace.go:171","msg":"trace[1587050841] transaction","detail":"{read_only:false; response_revision:839; number_of_response:1; }","duration":"224.692289ms","start":"2026-07-15T04:50:47.073787Z","end":"2026-07-15T04:50:47.298479Z","steps":["trace[1587050841] 'process raft request' (duration: 224.181837ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:50:47.613745Z","caller":"traceutil/trace.go:171","msg":"trace[1441704034] linearizableReadLoop","detail":"{readStateIndex:877; appliedIndex:875; }","duration":"229.487911ms","start":"2026-07-15T04:50:47.384233Z","end":"2026-07-15T04:50:47.613721Z","steps":["trace[1441704034] 'read index received' (duration: 229.166093ms)","trace[1441704034] 'applied index is now lower than readState.Index' (duration: 321.028µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T04:50:47.613821Z","caller":"traceutil/trace.go:171","msg":"trace[583936158] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"307.588471ms","start":"2026-07-15T04:50:47.306227Z","end":"2026-07-15T04:50:47.613815Z","steps":["trace[583936158] 'process raft request' (duration: 307.247323ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:50:47.613904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:50:47.306217Z","time spent":"307.620772ms","remote":"127.0.0.1:33890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2317,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T04:50:47.613611Z","caller":"traceutil/trace.go:171","msg":"trace[1055547681] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"309.907339ms","start":"2026-07-15T04:50:47.303618Z","end":"2026-07-15T04:50:47.613526Z","steps":["trace[1055547681] 'process raft request' (duration: 309.730205ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:50:47.613969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.719386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-rm22h\" ","response":"range_response_count:1 size:3871"} {"level":"info","ts":"2026-07-15T04:50:47.614042Z","caller":"traceutil/trace.go:171","msg":"trace[1016260269] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-rm22h; range_end:; response_count:1; response_revision:842; }","duration":"229.826669ms","start":"2026-07-15T04:50:47.384198Z","end":"2026-07-15T04:50:47.614024Z","steps":["trace[1016260269] 'agreement among raft nodes before linearized reading' (duration: 229.625164ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:50:47.614066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:50:47.303602Z","time spent":"310.388261ms","remote":"127.0.0.1:44276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3856,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T04:50:47.616056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.848816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T04:50:47.616113Z","caller":"traceutil/trace.go:171","msg":"trace[1216764209] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:843; }","duration":"201.938588ms","start":"2026-07-15T04:50:47.414161Z","end":"2026-07-15T04:50:47.6161Z","steps":["trace[1216764209] 'agreement among raft nodes before linearized reading' (duration: 201.837806ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:50:47.616162Z","caller":"traceutil/trace.go:171","msg":"trace[917132927] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"231.889001ms","start":"2026-07-15T04:50:47.384235Z","end":"2026-07-15T04:50:47.616124Z","steps":["trace[917132927] 'process raft request' (duration: 231.693166ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:50:47.616344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.599627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-07-15T04:50:47.616425Z","caller":"traceutil/trace.go:171","msg":"trace[2018601470] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:843; }","duration":"110.696309ms","start":"2026-07-15T04:50:47.50571Z","end":"2026-07-15T04:50:47.616406Z","steps":["trace[2018601470] 'agreement among raft nodes before linearized reading' (duration: 110.508974ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:50:47.892603Z","caller":"traceutil/trace.go:171","msg":"trace[816467660] transaction","detail":"{read_only:false; response_revision:845; number_of_response:1; }","duration":"268.515019ms","start":"2026-07-15T04:50:47.624067Z","end":"2026-07-15T04:50:47.892582Z","steps":["trace[816467660] 'process raft request' (duration: 267.536125ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:50:48.126712Z","caller":"traceutil/trace.go:171","msg":"trace[1333353016] linearizableReadLoop","detail":"{readStateIndex:881; appliedIndex:880; }","duration":"218.792926ms","start":"2026-07-15T04:50:47.907879Z","end":"2026-07-15T04:50:48.126672Z","steps":["trace[1333353016] 'read index received' (duration: 210.426698ms)","trace[1333353016] 'applied index is now lower than readState.Index' (duration: 8.365058ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T04:50:48.126835Z","caller":"traceutil/trace.go:171","msg":"trace[2015978180] transaction","detail":"{read_only:false; response_revision:846; number_of_response:1; }","duration":"224.707324ms","start":"2026-07-15T04:50:47.902099Z","end":"2026-07-15T04:50:48.126806Z","steps":["trace[2015978180] 'process raft request' (duration: 216.182082ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:50:48.127345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.443942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-07-15T04:50:48.127439Z","caller":"traceutil/trace.go:171","msg":"trace[1086774329] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:846; }","duration":"219.579565ms","start":"2026-07-15T04:50:47.907838Z","end":"2026-07-15T04:50:48.127418Z","steps":["trace[1086774329] 'agreement among raft nodes before linearized reading' (duration: 219.032722ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:50:48.915729Z","caller":"traceutil/trace.go:171","msg":"trace[1725477542] linearizableReadLoop","detail":"{readStateIndex:886; appliedIndex:885; }","duration":"105.717412ms","start":"2026-07-15T04:50:48.809989Z","end":"2026-07-15T04:50:48.915707Z","steps":["trace[1725477542] 'read index received' (duration: 87.318484ms)","trace[1725477542] 'applied index is now lower than readState.Index' (duration: 18.397758ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T04:50:48.915922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.917267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-15T04:50:48.915907Z","caller":"traceutil/trace.go:171","msg":"trace[1502689007] transaction","detail":"{read_only:false; response_revision:851; number_of_response:1; }","duration":"178.532705ms","start":"2026-07-15T04:50:48.737343Z","end":"2026-07-15T04:50:48.915876Z","steps":["trace[1502689007] 'process raft request' (duration: 159.954433ms)","trace[1502689007] 'compare' (duration: 18.177671ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T04:50:48.915954Z","caller":"traceutil/trace.go:171","msg":"trace[285488743] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:851; }","duration":"105.991209ms","start":"2026-07-15T04:50:48.809951Z","end":"2026-07-15T04:50:48.915943Z","steps":["trace[285488743] 'agreement among raft nodes before linearized reading' (duration: 105.841445ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:50:49.297087Z","caller":"traceutil/trace.go:171","msg":"trace[800567082] transaction","detail":"{read_only:false; response_revision:853; number_of_response:1; }","duration":"175.70693ms","start":"2026-07-15T04:50:49.121354Z","end":"2026-07-15T04:50:49.297061Z","steps":["trace[800567082] 'process raft request' (duration: 175.504615ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:50:49.334598Z","caller":"traceutil/trace.go:171","msg":"trace[681294623] transaction","detail":"{read_only:false; response_revision:854; number_of_response:1; }","duration":"213.187175ms","start":"2026-07-15T04:50:49.121386Z","end":"2026-07-15T04:50:49.334574Z","steps":["trace[681294623] 'process raft request' (duration: 202.633324ms)","trace[681294623] 'compare' (duration: 10.395107ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T04:50:54.675255Z","caller":"traceutil/trace.go:171","msg":"trace[632935762] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"137.70792ms","start":"2026-07-15T04:50:54.53752Z","end":"2026-07-15T04:50:54.675228Z","steps":["trace[632935762] 'process raft request' (duration: 116.768393ms)","trace[632935762] 'compare' (duration: 20.810064ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T04:50:55.297103Z","caller":"traceutil/trace.go:171","msg":"trace[1115760709] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"218.137068ms","start":"2026-07-15T04:50:55.078945Z","end":"2026-07-15T04:50:55.297082Z","steps":["trace[1115760709] 'process raft request' (duration: 176.098308ms)","trace[1115760709] 'compare' (duration: 41.835576ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T04:50:55.297122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.719235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-15T04:50:55.297173Z","caller":"traceutil/trace.go:171","msg":"trace[1302140828] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:941; }","duration":"258.808798ms","start":"2026-07-15T04:50:55.03835Z","end":"2026-07-15T04:50:55.297159Z","steps":["trace[1302140828] 'range keys from in-memory index tree' (duration: 258.567291ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:50:56.389425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.856978ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T04:50:56.389611Z","caller":"traceutil/trace.go:171","msg":"trace[988954416] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:946; }","duration":"677.058882ms","start":"2026-07-15T04:50:55.712528Z","end":"2026-07-15T04:50:56.389587Z","steps":["trace[988954416] 'range keys from in-memory index tree' (duration: 676.824957ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:50:56.390073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"830.628242ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-15T04:50:56.390188Z","caller":"traceutil/trace.go:171","msg":"trace[1286562688] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"870.673615ms","start":"2026-07-15T04:50:55.519489Z","end":"2026-07-15T04:50:56.390163Z","steps":["trace[1286562688] 'process raft request' (duration: 39.8703ms)","trace[1286562688] 'compare' (duration: 830.303853ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T04:50:56.39031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:50:55.519467Z","time spent":"870.755547ms","remote":"127.0.0.1:44104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T04:51:05.778627Z","caller":"traceutil/trace.go:171","msg":"trace[545788095] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"275.659923ms","start":"2026-07-15T04:51:05.502945Z","end":"2026-07-15T04:51:05.778605Z","steps":["trace[545788095] 'process raft request' (duration: 275.568942ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:51:05.778614Z","caller":"traceutil/trace.go:171","msg":"trace[2070844210] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"275.789677ms","start":"2026-07-15T04:51:05.502794Z","end":"2026-07-15T04:51:05.778584Z","steps":["trace[2070844210] 'process raft request' (duration: 191.698036ms)","trace[2070844210] 'compare' (duration: 83.771944ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T04:51:05.778675Z","caller":"traceutil/trace.go:171","msg":"trace[850472583] linearizableReadLoop","detail":"{readStateIndex:1022; appliedIndex:1019; }","duration":"275.625243ms","start":"2026-07-15T04:51:05.503003Z","end":"2026-07-15T04:51:05.778629Z","steps":["trace[850472583] 'read index received' (duration: 21.671493ms)","trace[850472583] 'applied index is now lower than readState.Index' (duration: 253.94956ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T04:51:05.778889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.393405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-07-15T04:51:05.778933Z","caller":"traceutil/trace.go:171","msg":"trace[783369209] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:984; }","duration":"252.486787ms","start":"2026-07-15T04:51:05.526432Z","end":"2026-07-15T04:51:05.778919Z","steps":["trace[783369209] 'agreement among raft nodes before linearized reading' (duration: 252.368985ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:51:05.778926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.822997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-15T04:51:05.778984Z","caller":"traceutil/trace.go:171","msg":"trace[937253123] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:984; }","duration":"275.998291ms","start":"2026-07-15T04:51:05.502969Z","end":"2026-07-15T04:51:05.778967Z","steps":["trace[937253123] 'agreement among raft nodes before linearized reading' (duration: 275.767966ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:52:15.469115Z","caller":"traceutil/trace.go:171","msg":"trace[1202380234] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"170.414966ms","start":"2026-07-15T04:52:15.298665Z","end":"2026-07-15T04:52:15.46908Z","steps":["trace[1202380234] 'process raft request' (duration: 170.257683ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:52:15.609648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.937777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.109\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-15T04:52:15.609709Z","caller":"traceutil/trace.go:171","msg":"trace[454265625] range","detail":"{range_begin:/registry/masterleases/199.204.45.109; range_end:; response_count:1; response_revision:1215; }","duration":"121.041699ms","start":"2026-07-15T04:52:15.488653Z","end":"2026-07-15T04:52:15.609695Z","steps":["trace[454265625] 'range keys from in-memory index tree' (duration: 120.781765ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:52:16.661964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.839613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-15T04:52:16.662023Z","caller":"traceutil/trace.go:171","msg":"trace[887650412] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1219; }","duration":"215.955794ms","start":"2026-07-15T04:52:16.446055Z","end":"2026-07-15T04:52:16.662011Z","steps":["trace[887650412] 'range keys from in-memory index tree' (duration: 215.67761ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:52:17.230939Z","caller":"traceutil/trace.go:171","msg":"trace[1644721230] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"130.230006ms","start":"2026-07-15T04:52:17.100634Z","end":"2026-07-15T04:52:17.230864Z","steps":["trace[1644721230] 'process raft request' (duration: 130.035003ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:52:17.283854Z","caller":"traceutil/trace.go:171","msg":"trace[935049339] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"136.335418ms","start":"2026-07-15T04:52:17.1475Z","end":"2026-07-15T04:52:17.283836Z","steps":["trace[935049339] 'process raft request' (duration: 136.242917ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:52:19.461894Z","caller":"traceutil/trace.go:171","msg":"trace[159039019] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"115.010893ms","start":"2026-07-15T04:52:19.34685Z","end":"2026-07-15T04:52:19.461861Z","steps":["trace[159039019] 'process raft request' (duration: 114.8249ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:52:20.301225Z","caller":"traceutil/trace.go:171","msg":"trace[1511768038] linearizableReadLoop","detail":"{readStateIndex:1297; appliedIndex:1296; }","duration":"110.667971ms","start":"2026-07-15T04:52:20.190533Z","end":"2026-07-15T04:52:20.301201Z","steps":["trace[1511768038] 'read index received' (duration: 82.751139ms)","trace[1511768038] 'applied index is now lower than readState.Index' (duration: 27.915762ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T04:52:20.301321Z","caller":"traceutil/trace.go:171","msg":"trace[1218174127] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"129.937122ms","start":"2026-07-15T04:52:20.171335Z","end":"2026-07-15T04:52:20.301272Z","steps":["trace[1218174127] 'process raft request' (duration: 101.972249ms)","trace[1218174127] 'compare' (duration: 27.555367ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T04:52:20.301439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.889654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T04:52:20.301486Z","caller":"traceutil/trace.go:171","msg":"trace[163759730] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1242; }","duration":"110.994586ms","start":"2026-07-15T04:52:20.190482Z","end":"2026-07-15T04:52:20.301476Z","steps":["trace[163759730] 'agreement among raft nodes before linearized reading' (duration: 110.877824ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:52:25.980849Z","caller":"traceutil/trace.go:171","msg":"trace[1949089705] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"101.638492ms","start":"2026-07-15T04:52:25.879177Z","end":"2026-07-15T04:52:25.980816Z","steps":["trace[1949089705] 'process raft request' (duration: 49.78784ms)","trace[1949089705] 'compare' (duration: 51.624009ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T04:52:26.198774Z","caller":"traceutil/trace.go:171","msg":"trace[959302110] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"177.525564ms","start":"2026-07-15T04:52:26.021215Z","end":"2026-07-15T04:52:26.198741Z","steps":["trace[959302110] 'process raft request' (duration: 160.904638ms)","trace[959302110] 'compare' (duration: 16.451824ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T04:52:36.433512Z","caller":"traceutil/trace.go:171","msg":"trace[1383679607] linearizableReadLoop","detail":"{readStateIndex:1380; appliedIndex:1379; }","duration":"279.553626ms","start":"2026-07-15T04:52:36.153938Z","end":"2026-07-15T04:52:36.433491Z","steps":["trace[1383679607] 'read index received' (duration: 279.395214ms)","trace[1383679607] 'applied index is now lower than readState.Index' (duration: 156.942µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T04:52:36.433585Z","caller":"traceutil/trace.go:171","msg":"trace[1290130271] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"773.198545ms","start":"2026-07-15T04:52:35.660339Z","end":"2026-07-15T04:52:36.433538Z","steps":["trace[1290130271] 'process raft request' (duration: 773.017242ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:52:36.433767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:52:35.660317Z","time spent":"773.360997ms","remote":"127.0.0.1:44376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T04:52:36.433923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.511641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-15T04:52:36.434001Z","caller":"traceutil/trace.go:171","msg":"trace[1012449794] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1320; }","duration":"188.635423ms","start":"2026-07-15T04:52:36.245347Z","end":"2026-07-15T04:52:36.433983Z","steps":["trace[1012449794] 'agreement among raft nodes before linearized reading' (duration: 188.43944ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:52:36.434155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.84584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T04:52:36.434356Z","caller":"traceutil/trace.go:171","msg":"trace[1188946598] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1320; }","duration":"280.452599ms","start":"2026-07-15T04:52:36.153887Z","end":"2026-07-15T04:52:36.434339Z","steps":["trace[1188946598] 'agreement among raft nodes before linearized reading' (duration: 279.78869ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:52:41.534676Z","caller":"traceutil/trace.go:171","msg":"trace[1710377620] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"130.295737ms","start":"2026-07-15T04:52:41.404353Z","end":"2026-07-15T04:52:41.534648Z","steps":["trace[1710377620] 'process raft request' (duration: 130.095244ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:52:44.648081Z","caller":"traceutil/trace.go:171","msg":"trace[739929417] transaction","detail":"{read_only:false; response_revision:1352; number_of_response:1; }","duration":"107.483952ms","start":"2026-07-15T04:52:44.540573Z","end":"2026-07-15T04:52:44.648057Z","steps":["trace[739929417] 'process raft request' (duration: 105.395493ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:52:44.648481Z","caller":"traceutil/trace.go:171","msg":"trace[1514329683] linearizableReadLoop","detail":"{readStateIndex:1415; appliedIndex:1413; }","duration":"102.705464ms","start":"2026-07-15T04:52:44.545744Z","end":"2026-07-15T04:52:44.648449Z","steps":["trace[1514329683] 'read index received' (duration: 100.238459ms)","trace[1514329683] 'applied index is now lower than readState.Index' (duration: 2.465385ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T04:52:44.648631Z","caller":"traceutil/trace.go:171","msg":"trace[1952932578] transaction","detail":"{read_only:false; response_revision:1353; number_of_response:1; }","duration":"107.888468ms","start":"2026-07-15T04:52:44.540699Z","end":"2026-07-15T04:52:44.648588Z","steps":["trace[1952932578] 'process raft request' (duration: 107.545644ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:52:44.648657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.893578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:certificate-controller\" ","response":"range_response_count:1 size:1142"} {"level":"info","ts":"2026-07-15T04:52:44.648938Z","caller":"traceutil/trace.go:171","msg":"trace[1964482609] range","detail":"{range_begin:/registry/clusterroles/system:controller:certificate-controller; range_end:; response_count:1; response_revision:1353; }","duration":"103.201182ms","start":"2026-07-15T04:52:44.545718Z","end":"2026-07-15T04:52:44.64892Z","steps":["trace[1964482609] 'agreement among raft nodes before linearized reading' (duration: 102.853727ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:53:14.351042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.197431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-rm22h\" ","response":"range_response_count:1 size:4905"} {"level":"info","ts":"2026-07-15T04:53:14.351128Z","caller":"traceutil/trace.go:171","msg":"trace[813828553] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-rm22h; range_end:; response_count:1; response_revision:1449; }","duration":"345.329612ms","start":"2026-07-15T04:53:14.005781Z","end":"2026-07-15T04:53:14.351111Z","steps":["trace[813828553] 'range keys from in-memory index tree' (duration: 345.012968ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:53:14.351168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:53:14.005753Z","time spent":"345.407153ms","remote":"127.0.0.1:44276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4928,"request content":"key:\"/registry/pods/kube-system/coredns-67659f764b-rm22h\" "}