{"level":"warn","ts":"2026-05-21T03:03:37.512526Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-21T03:03:37.512658Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.222: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.222:2380","--initial-cluster=instance=https://199.204.45.222:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.222:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.222: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-05-21T03:03:37.512767Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-21T03:03:37.512796Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.222:2380"]} {"level":"info","ts":"2026-05-21T03:03:37.512825Z","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-05-21T03:03:37.514042Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"]} {"level":"info","ts":"2026-05-21T03:03:37.514177Z","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.222:2380"],"listen-peer-urls":["https://199.204.45.222:2380"],"advertise-client-urls":["https://199.204.45.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.222: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-05-21T03:03:37.518226Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.675388ms"} {"level":"info","ts":"2026-05-21T03:03:37.524081Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"380f6760faea5f9e","cluster-id":"b87e8aca83a17eab"} {"level":"info","ts":"2026-05-21T03:03:37.524154Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=()"} {"level":"info","ts":"2026-05-21T03:03:37.524176Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became follower at term 0"} {"level":"info","ts":"2026-05-21T03:03:37.52419Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 380f6760faea5f9e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-21T03:03:37.5242Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became follower at term 1"} {"level":"info","ts":"2026-05-21T03:03:37.524242Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=(4039561056998809502)"} {"level":"warn","ts":"2026-05-21T03:03:37.531351Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-21T03:03:37.532753Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-21T03:03:37.534008Z","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-05-21T03:03:37.536703Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"380f6760faea5f9e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-21T03:03:37.537019Z","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-05-21T03:03:37.537103Z","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-05-21T03:03:37.53712Z","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-05-21T03:03:37.537392Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"380f6760faea5f9e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-21T03:03:37.54054Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=(4039561056998809502)"} {"level":"info","ts":"2026-05-21T03:03:37.540752Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b87e8aca83a17eab","local-member-id":"380f6760faea5f9e","added-peer-id":"380f6760faea5f9e","added-peer-peer-urls":["https://199.204.45.222:2380"]} {"level":"info","ts":"2026-05-21T03:03:37.542422Z","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-05-21T03:03:37.542627Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.222:2380"} {"level":"info","ts":"2026-05-21T03:03:37.542695Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.222:2380"} {"level":"info","ts":"2026-05-21T03:03:37.543087Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"380f6760faea5f9e","initial-advertise-peer-urls":["https://199.204.45.222:2380"],"listen-peer-urls":["https://199.204.45.222:2380"],"advertise-client-urls":["https://199.204.45.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-21T03:03:37.543236Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-21T03:03:38.324563Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e is starting a new election at term 1"} {"level":"info","ts":"2026-05-21T03:03:38.324607Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became pre-candidate at term 1"} {"level":"info","ts":"2026-05-21T03:03:38.324622Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e received MsgPreVoteResp from 380f6760faea5f9e at term 1"} {"level":"info","ts":"2026-05-21T03:03:38.324634Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became candidate at term 2"} {"level":"info","ts":"2026-05-21T03:03:38.32464Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e received MsgVoteResp from 380f6760faea5f9e at term 2"} {"level":"info","ts":"2026-05-21T03:03:38.324648Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became leader at term 2"} {"level":"info","ts":"2026-05-21T03:03:38.324659Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 380f6760faea5f9e elected leader 380f6760faea5f9e at term 2"} {"level":"info","ts":"2026-05-21T03:03:38.325654Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"380f6760faea5f9e","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.222:2379]}","request-path":"/0/members/380f6760faea5f9e/attributes","cluster-id":"b87e8aca83a17eab","publish-timeout":"7s"} {"level":"info","ts":"2026-05-21T03:03:38.325689Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-21T03:03:38.325714Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T03:03:38.325859Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-21T03:03:38.325965Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-21T03:03:38.325974Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-21T03:03:38.326688Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b87e8aca83a17eab","local-member-id":"380f6760faea5f9e","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T03:03:38.326784Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T03:03:38.326812Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T03:03:38.329073Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.222:2379"} {"level":"info","ts":"2026-05-21T03:03:38.32955Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-05-21T03:03:41.73771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.242644ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-21T03:03:41.737922Z","caller":"traceutil/trace.go:171","msg":"trace[145680299] transaction","detail":"{read_only:false; response_revision:173; number_of_response:1; }","duration":"671.039762ms","start":"2026-05-21T03:03:41.066863Z","end":"2026-05-21T03:03:41.737903Z","steps":["trace[145680299] 'process raft request' (duration: 26.06042ms)","trace[145680299] 'compare' (duration: 644.05398ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:03:41.737999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:03:41.066853Z","time spent":"671.118863ms","remote":"127.0.0.1:37628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T03:03:42.539359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.488539ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-21T03:03:42.5395Z","caller":"traceutil/trace.go:171","msg":"trace[1844510275] linearizableReadLoop","detail":"{readStateIndex:178; appliedIndex:177; }","duration":"934.76244ms","start":"2026-05-21T03:03:41.604715Z","end":"2026-05-21T03:03:42.539477Z","steps":["trace[1844510275] 'read index received' (duration: 338.030775ms)","trace[1844510275] 'applied index is now lower than readState.Index' (duration: 596.730365ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:03:42.539537Z","caller":"traceutil/trace.go:171","msg":"trace[1018781383] transaction","detail":"{read_only:false; response_revision:174; number_of_response:1; }","duration":"1.230530632s","start":"2026-05-21T03:03:41.308965Z","end":"2026-05-21T03:03:42.539495Z","steps":["trace[1018781383] 'process raft request' (duration: 633.808297ms)","trace[1018781383] 'compare' (duration: 596.293044ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:03:42.539705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"934.971086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T03:03:42.539735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"876.629559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T03:03:42.53981Z","caller":"traceutil/trace.go:171","msg":"trace[1977646290] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:174; }","duration":"935.125849ms","start":"2026-05-21T03:03:41.604658Z","end":"2026-05-21T03:03:42.539784Z","steps":["trace[1977646290] 'agreement among raft nodes before linearized reading' (duration: 934.923635ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:03:42.539862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:03:41.604629Z","time spent":"935.224581ms","remote":"127.0.0.1:37838","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-05-21T03:03:42.539867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"798.553752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:certificate-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T03:03:42.539945Z","caller":"traceutil/trace.go:171","msg":"trace[19893619] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:certificate-controller; range_end:; response_count:0; response_revision:174; }","duration":"798.691336ms","start":"2026-05-21T03:03:41.741233Z","end":"2026-05-21T03:03:42.539924Z","steps":["trace[19893619] 'agreement among raft nodes before linearized reading' (duration: 798.43799ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:03:42.540003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:03:41.741213Z","time spent":"798.781128ms","remote":"127.0.0.1:37628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/clusterrolebindings/system:controller:certificate-controller\" "} {"level":"info","ts":"2026-05-21T03:03:42.539822Z","caller":"traceutil/trace.go:171","msg":"trace[1809636332] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:174; }","duration":"876.781472ms","start":"2026-05-21T03:03:41.663025Z","end":"2026-05-21T03:03:42.539806Z","steps":["trace[1809636332] 'agreement among raft nodes before linearized reading' (duration: 876.575467ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:03:42.540086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:03:41.663013Z","time spent":"877.064359ms","remote":"127.0.0.1:37840","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-05-21T03:03:42.539734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:03:41.30894Z","time spent":"1.230700836s","remote":"127.0.0.1:37484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T03:04:14.734214Z","caller":"traceutil/trace.go:171","msg":"trace[1267491717] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"160.097058ms","start":"2026-05-21T03:04:14.574089Z","end":"2026-05-21T03:04:14.734186Z","steps":["trace[1267491717] 'process raft request' (duration: 121.206641ms)","trace[1267491717] 'compare' (duration: 38.687202ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:04:14.734359Z","caller":"traceutil/trace.go:171","msg":"trace[636359871] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"159.923143ms","start":"2026-05-21T03:04:14.574404Z","end":"2026-05-21T03:04:14.734327Z","steps":["trace[636359871] 'process raft request' (duration: 159.692928ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:04:14.735012Z","caller":"traceutil/trace.go:171","msg":"trace[148065950] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"129.191365ms","start":"2026-05-21T03:04:14.605806Z","end":"2026-05-21T03:04:14.734997Z","steps":["trace[148065950] 'process raft request' (duration: 129.092153ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:04:14.735482Z","caller":"traceutil/trace.go:171","msg":"trace[284165748] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"156.060719ms","start":"2026-05-21T03:04:14.579407Z","end":"2026-05-21T03:04:14.735468Z","steps":["trace[284165748] 'process raft request' (duration: 155.097025ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:04:14.73561Z","caller":"traceutil/trace.go:171","msg":"trace[1181643049] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"156.167192ms","start":"2026-05-21T03:04:14.579432Z","end":"2026-05-21T03:04:14.735599Z","steps":["trace[1181643049] 'process raft request' (duration: 155.331522ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:04:14.735714Z","caller":"traceutil/trace.go:171","msg":"trace[973667872] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"156.208803ms","start":"2026-05-21T03:04:14.579495Z","end":"2026-05-21T03:04:14.735704Z","steps":["trace[973667872] 'process raft request' (duration: 155.329441ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:04:14.735812Z","caller":"traceutil/trace.go:171","msg":"trace[989936471] transaction","detail":"{read_only:false; number_of_response:0; response_revision:503; }","duration":"154.085271ms","start":"2026-05-21T03:04:14.58172Z","end":"2026-05-21T03:04:14.735805Z","steps":["trace[989936471] 'process raft request' (duration: 153.144578ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:04:14.76799Z","caller":"traceutil/trace.go:171","msg":"trace[321071092] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"101.916091ms","start":"2026-05-21T03:04:14.666047Z","end":"2026-05-21T03:04:14.767964Z","steps":["trace[321071092] 'process raft request' (duration: 101.779208ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:04:14.768956Z","caller":"traceutil/trace.go:171","msg":"trace[2057732928] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"121.153269ms","start":"2026-05-21T03:04:14.647795Z","end":"2026-05-21T03:04:14.768949Z","steps":["trace[2057732928] 'process raft request' (duration: 119.792616ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:04:14.769398Z","caller":"traceutil/trace.go:171","msg":"trace[1887591891] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"111.211588ms","start":"2026-05-21T03:04:14.658176Z","end":"2026-05-21T03:04:14.769387Z","steps":["trace[1887591891] 'process raft request' (duration: 109.519097ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:04:15.804899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.080518ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-21T03:04:15.805124Z","caller":"traceutil/trace.go:171","msg":"trace[735636195] linearizableReadLoop","detail":"{readStateIndex:527; appliedIndex:524; }","duration":"1.036271679s","start":"2026-05-21T03:04:14.768836Z","end":"2026-05-21T03:04:15.805108Z","steps":["trace[735636195] 'read index received' (duration: 380.863143ms)","trace[735636195] 'applied index is now lower than readState.Index' (duration: 655.407556ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:04:15.805345Z","caller":"traceutil/trace.go:171","msg":"trace[382834133] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"1.090699794s","start":"2026-05-21T03:04:14.714633Z","end":"2026-05-21T03:04:15.805333Z","steps":["trace[382834133] 'process raft request' (duration: 435.119823ms)","trace[382834133] 'compare' (duration: 654.853433ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:04:15.805399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:04:14.714607Z","time spent":"1.090765696s","remote":"127.0.0.1:37888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T03:04:15.805496Z","caller":"traceutil/trace.go:171","msg":"trace[703064087] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"1.049922652s","start":"2026-05-21T03:04:14.755528Z","end":"2026-05-21T03:04:15.805451Z","steps":["trace[703064087] 'process raft request' (duration: 1.049535582s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:04:15.805617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.068624916s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha1.gateway.networking.x-k8s.io\" ","response":"range_response_count:1 size:1019"} {"level":"info","ts":"2026-05-21T03:04:15.805659Z","caller":"traceutil/trace.go:171","msg":"trace[355613679] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1alpha1.gateway.networking.x-k8s.io; range_end:; response_count:1; response_revision:510; }","duration":"1.068720249s","start":"2026-05-21T03:04:14.736919Z","end":"2026-05-21T03:04:15.80564Z","steps":["trace[355613679] 'agreement among raft nodes before linearized reading' (duration: 1.068652538s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:04:15.805691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:04:14.736889Z","time spent":"1.068793951s","remote":"127.0.0.1:37794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1042,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha1.gateway.networking.x-k8s.io\" "} {"level":"info","ts":"2026-05-21T03:04:15.805687Z","caller":"traceutil/trace.go:171","msg":"trace[1940719424] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"1.051699405s","start":"2026-05-21T03:04:14.753964Z","end":"2026-05-21T03:04:15.805663Z","steps":["trace[1940719424] 'process raft request' (duration: 1.051033129s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:04:15.805883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:04:14.755517Z","time spent":"1.050050914s","remote":"127.0.0.1:37888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10846,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T03:04:15.805962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:04:14.753932Z","time spent":"1.051802218s","remote":"127.0.0.1:37888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T03:04:15.821564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.030034387s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T03:04:15.821638Z","caller":"traceutil/trace.go:171","msg":"trace[1058073522] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:514; }","duration":"1.03016728s","start":"2026-05-21T03:04:14.791454Z","end":"2026-05-21T03:04:15.821621Z","steps":["trace[1058073522] 'agreement among raft nodes before linearized reading' (duration: 1.030042507s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:04:15.821677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:04:14.791428Z","time spent":"1.030241253s","remote":"127.0.0.1:37840","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-05-21T03:04:15.821951Z","caller":"traceutil/trace.go:171","msg":"trace[499088169] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"1.014386496s","start":"2026-05-21T03:04:14.80755Z","end":"2026-05-21T03:04:15.821937Z","steps":["trace[499088169] 'process raft request' (duration: 1.013830212s)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:04:15.821947Z","caller":"traceutil/trace.go:171","msg":"trace[410823087] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"1.015277918s","start":"2026-05-21T03:04:14.806629Z","end":"2026-05-21T03:04:15.821907Z","steps":["trace[410823087] 'process raft request' (duration: 1.014661353s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:04:15.822033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:04:14.807538Z","time spent":"1.014439268s","remote":"127.0.0.1:37888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T03:04:15.822103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:04:14.806597Z","time spent":"1.015420021s","remote":"127.0.0.1:37888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T03:04:15.822161Z","caller":"traceutil/trace.go:171","msg":"trace[1219847717] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"936.873049ms","start":"2026-05-21T03:04:14.885279Z","end":"2026-05-21T03:04:15.822152Z","steps":["trace[1219847717] 'process raft request' (duration: 936.165742ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:04:15.822209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:04:14.885254Z","time spent":"936.9295ms","remote":"127.0.0.1:37550","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-05-21T03:04:15.822438Z","caller":"traceutil/trace.go:171","msg":"trace[983927385] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"1.044555641s","start":"2026-05-21T03:04:14.777867Z","end":"2026-05-21T03:04:15.822423Z","steps":["trace[983927385] 'process raft request' (duration: 1.04330907s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:04:15.822501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:04:14.777822Z","time spent":"1.044644133s","remote":"127.0.0.1:37550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T03:04:21.908801Z","caller":"traceutil/trace.go:171","msg":"trace[1919749161] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:609; }","duration":"117.336527ms","start":"2026-05-21T03:04:21.791437Z","end":"2026-05-21T03:04:21.908773Z","steps":["trace[1919749161] 'read index received' (duration: 117.274146ms)","trace[1919749161] 'applied index is now lower than readState.Index' (duration: 61.141µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:04:21.908927Z","caller":"traceutil/trace.go:171","msg":"trace[564222943] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"130.891917ms","start":"2026-05-21T03:04:21.778005Z","end":"2026-05-21T03:04:21.908897Z","steps":["trace[564222943] 'process raft request' (duration: 130.6092ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:04:21.90906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.583353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T03:04:21.90954Z","caller":"traceutil/trace.go:171","msg":"trace[1571340533] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:592; }","duration":"118.106935ms","start":"2026-05-21T03:04:21.791411Z","end":"2026-05-21T03:04:21.909518Z","steps":["trace[1571340533] 'agreement among raft nodes before linearized reading' (duration: 117.555882ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:04:22.036661Z","caller":"traceutil/trace.go:171","msg":"trace[1786865415] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:610; }","duration":"127.294519ms","start":"2026-05-21T03:04:21.909313Z","end":"2026-05-21T03:04:22.036607Z","steps":["trace[1786865415] 'read index received' (duration: 105.074078ms)","trace[1786865415] 'applied index is now lower than readState.Index' (duration: 22.219521ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:04:22.036761Z","caller":"traceutil/trace.go:171","msg":"trace[779076701] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"199.342853ms","start":"2026-05-21T03:04:21.837382Z","end":"2026-05-21T03:04:22.036724Z","steps":["trace[779076701] 'process raft request' (duration: 181.440917ms)","trace[779076701] 'compare' (duration: 17.440185ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:04:22.037009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.18017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-21T03:04:22.037029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.18259ms","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-05-21T03:04:22.03708Z","caller":"traceutil/trace.go:171","msg":"trace[1762172731] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:593; }","duration":"183.300093ms","start":"2026-05-21T03:04:21.853743Z","end":"2026-05-21T03:04:22.037043Z","steps":["trace[1762172731] 'agreement among raft nodes before linearized reading' (duration: 182.981785ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:04:22.037084Z","caller":"traceutil/trace.go:171","msg":"trace[246385583] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:593; }","duration":"151.306774ms","start":"2026-05-21T03:04:21.885761Z","end":"2026-05-21T03:04:22.037067Z","steps":["trace[246385583] 'agreement among raft nodes before linearized reading' (duration: 151.029467ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:04:22.511268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.818198ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-21T03:04:22.511393Z","caller":"traceutil/trace.go:171","msg":"trace[1343692545] linearizableReadLoop","detail":"{readStateIndex:614; appliedIndex:613; }","duration":"414.568373ms","start":"2026-05-21T03:04:22.096798Z","end":"2026-05-21T03:04:22.511367Z","steps":["trace[1343692545] 'read index received' (duration: 36.642722ms)","trace[1343692545] 'applied index is now lower than readState.Index' (duration: 377.924651ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:04:22.511681Z","caller":"traceutil/trace.go:171","msg":"trace[1893071832] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"420.742593ms","start":"2026-05-21T03:04:22.090773Z","end":"2026-05-21T03:04:22.511516Z","steps":["trace[1893071832] 'process raft request' (duration: 95.592627ms)","trace[1893071832] 'compare' (duration: 324.632354ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:04:22.511837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.032924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-21T03:04:22.511912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:04:22.090746Z","time spent":"421.04104ms","remote":"127.0.0.1:37532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T03:04:22.511881Z","caller":"traceutil/trace.go:171","msg":"trace[360589727] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:596; }","duration":"415.102696ms","start":"2026-05-21T03:04:22.096767Z","end":"2026-05-21T03:04:22.51187Z","steps":["trace[360589727] 'agreement among raft nodes before linearized reading' (duration: 414.86151ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:04:22.512029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:04:22.096754Z","time spent":"415.26202ms","remote":"127.0.0.1:37550","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-05-21T03:04:22.512091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.369065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-21T03:04:22.512192Z","caller":"traceutil/trace.go:171","msg":"trace[1410119474] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:596; }","duration":"266.59425ms","start":"2026-05-21T03:04:22.245578Z","end":"2026-05-21T03:04:22.512172Z","steps":["trace[1410119474] 'agreement among raft nodes before linearized reading' (duration: 266.256882ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:04:22.672949Z","caller":"traceutil/trace.go:171","msg":"trace[422557825] linearizableReadLoop","detail":"{readStateIndex:616; appliedIndex:615; }","duration":"154.87168ms","start":"2026-05-21T03:04:22.518043Z","end":"2026-05-21T03:04:22.672915Z","steps":["trace[422557825] 'read index received' (duration: 12.222087ms)","trace[422557825] 'applied index is now lower than readState.Index' (duration: 142.648433ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:04:22.673097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.034665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-05-21T03:04:22.673133Z","caller":"traceutil/trace.go:171","msg":"trace[1428523514] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:598; }","duration":"155.105306ms","start":"2026-05-21T03:04:22.518014Z","end":"2026-05-21T03:04:22.67312Z","steps":["trace[1428523514] 'agreement among raft nodes before linearized reading' (duration: 155.017244ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:04:22.673216Z","caller":"traceutil/trace.go:171","msg":"trace[1378725617] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"156.387757ms","start":"2026-05-21T03:04:22.516783Z","end":"2026-05-21T03:04:22.673171Z","steps":["trace[1378725617] 'process raft request' (duration: 95.156906ms)","trace[1378725617] 'compare' (duration: 60.847812ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:04:23.710133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"892.480288ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-21T03:04:23.710227Z","caller":"traceutil/trace.go:171","msg":"trace[206425696] linearizableReadLoop","detail":"{readStateIndex:617; appliedIndex:616; }","duration":"941.793738ms","start":"2026-05-21T03:04:22.768417Z","end":"2026-05-21T03:04:23.710211Z","steps":["trace[206425696] 'read index received' (duration: 49.152227ms)","trace[206425696] 'applied index is now lower than readState.Index' (duration: 892.640121ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:04:23.710389Z","caller":"traceutil/trace.go:171","msg":"trace[1870310812] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"1.028197023s","start":"2026-05-21T03:04:22.68218Z","end":"2026-05-21T03:04:23.710377Z","steps":["trace[1870310812] 'process raft request' (duration: 135.405687ms)","trace[1870310812] 'compare' (duration: 892.15878ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:04:23.710443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:04:22.682155Z","time spent":"1.028260083s","remote":"127.0.0.1:37484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T03:04:23.710809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"917.938087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T03:04:23.710884Z","caller":"traceutil/trace.go:171","msg":"trace[1845477337] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:599; }","duration":"918.04082ms","start":"2026-05-21T03:04:22.792826Z","end":"2026-05-21T03:04:23.710867Z","steps":["trace[1845477337] 'agreement among raft nodes before linearized reading' (duration: 917.889297ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:04:23.710907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"942.475245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.222\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-05-21T03:04:23.710927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:04:22.792815Z","time spent":"918.104792ms","remote":"127.0.0.1:37838","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-05-21T03:04:23.710988Z","caller":"traceutil/trace.go:171","msg":"trace[780348774] range","detail":"{range_begin:/registry/masterleases/199.204.45.222; range_end:; response_count:1; response_revision:599; }","duration":"942.609128ms","start":"2026-05-21T03:04:22.768361Z","end":"2026-05-21T03:04:23.710971Z","steps":["trace[780348774] 'agreement among raft nodes before linearized reading' (duration: 942.338531ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:04:23.711048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:04:22.76834Z","time spent":"942.698361ms","remote":"127.0.0.1:37882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.222\" "} {"level":"warn","ts":"2026-05-21T03:04:24.294373Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6890118514096814402,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T03:04:24.417605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:04:23.712451Z","time spent":"705.148247ms","remote":"127.0.0.1:37882","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-21T03:04:24.419242Z","caller":"traceutil/trace.go:171","msg":"trace[1880108481] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"704.733268ms","start":"2026-05-21T03:04:23.714486Z","end":"2026-05-21T03:04:24.419219Z","steps":["trace[1880108481] 'process raft request' (duration: 704.517742ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:04:24.419283Z","caller":"traceutil/trace.go:171","msg":"trace[1848052785] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"704.486702ms","start":"2026-05-21T03:04:23.714774Z","end":"2026-05-21T03:04:24.419261Z","steps":["trace[1848052785] 'process raft request' (duration: 704.359229ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:04:24.419288Z","caller":"traceutil/trace.go:171","msg":"trace[1914466558] linearizableReadLoop","detail":"{readStateIndex:622; appliedIndex:617; }","duration":"625.591191ms","start":"2026-05-21T03:04:23.793672Z","end":"2026-05-21T03:04:24.419263Z","steps":["trace[1914466558] 'read index received' (duration: 564.937434ms)","trace[1914466558] 'applied index is now lower than readState.Index' (duration: 60.652787ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:04:24.419338Z","caller":"traceutil/trace.go:171","msg":"trace[1062524933] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"702.929723ms","start":"2026-05-21T03:04:23.716381Z","end":"2026-05-21T03:04:24.419311Z","steps":["trace[1062524933] 'process raft request' (duration: 702.789399ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:04:24.419389Z","caller":"traceutil/trace.go:171","msg":"trace[141951511] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"702.227737ms","start":"2026-05-21T03:04:23.717151Z","end":"2026-05-21T03:04:24.419379Z","steps":["trace[141951511] 'process raft request' (duration: 702.072363ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:04:24.419402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:04:23.714765Z","time spent":"704.574474ms","remote":"127.0.0.1:37904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T03:04:24.419483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:04:23.716365Z","time spent":"703.045016ms","remote":"127.0.0.1:37532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T03:04:24.419476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.805576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T03:04:24.419576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:04:23.717134Z","time spent":"702.280127ms","remote":"127.0.0.1:37484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T03:04:24.419598Z","caller":"traceutil/trace.go:171","msg":"trace[1753376567] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:603; }","duration":"625.954039ms","start":"2026-05-21T03:04:23.793627Z","end":"2026-05-21T03:04:24.419581Z","steps":["trace[1753376567] 'agreement among raft nodes before linearized reading' (duration: 625.786585ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:04:24.420331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:04:23.793608Z","time spent":"626.706258ms","remote":"127.0.0.1:37838","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-05-21T03:04:24.420408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.69672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-21T03:04:24.420425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.323028ms","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-05-21T03:04:24.420467Z","caller":"traceutil/trace.go:171","msg":"trace[931936659] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:604; }","duration":"283.384719ms","start":"2026-05-21T03:04:24.137071Z","end":"2026-05-21T03:04:24.420455Z","steps":["trace[931936659] 'agreement among raft nodes before linearized reading' (duration: 283.291606ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:04:24.420464Z","caller":"traceutil/trace.go:171","msg":"trace[2086799200] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:604; }","duration":"346.798313ms","start":"2026-05-21T03:04:24.07365Z","end":"2026-05-21T03:04:24.420449Z","steps":["trace[2086799200] 'agreement among raft nodes before linearized reading' (duration: 346.619628ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:04:24.420508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:04:24.073619Z","time spent":"346.877985ms","remote":"127.0.0.1:37550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-21T03:04:24.419384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:04:23.714469Z","time spent":"704.821619ms","remote":"127.0.0.1:37904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T03:04:26.282878Z","caller":"traceutil/trace.go:171","msg":"trace[74699866] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"224.934127ms","start":"2026-05-21T03:04:26.057913Z","end":"2026-05-21T03:04:26.282847Z","steps":["trace[74699866] 'process raft request' (duration: 224.281491ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:04:40.232782Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6890118514096814704,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T03:04:40.733884Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6890118514096814704,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T03:04:41.137705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.430905ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-21T03:04:41.198131Z","caller":"traceutil/trace.go:171","msg":"trace[1912731673] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"1.319288159s","start":"2026-05-21T03:04:39.878789Z","end":"2026-05-21T03:04:41.198077Z","steps":["trace[1912731673] 'process raft request' (duration: 1.31889271s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:04:41.198377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:04:39.878763Z","time spent":"1.319503285s","remote":"127.0.0.1:37532","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-05-21T03:04:41.198406Z","caller":"traceutil/trace.go:171","msg":"trace[446860779] linearizableReadLoop","detail":"{readStateIndex:780; appliedIndex:777; }","duration":"1.466138505s","start":"2026-05-21T03:04:39.732244Z","end":"2026-05-21T03:04:41.198382Z","steps":["trace[446860779] 'read index received' (duration: 1.151020223s)","trace[446860779] 'applied index is now lower than readState.Index' (duration: 315.117372ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:04:41.198633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.40470543s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T03:04:41.198772Z","caller":"traceutil/trace.go:171","msg":"trace[1291899627] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:757; }","duration":"1.404782682s","start":"2026-05-21T03:04:39.793882Z","end":"2026-05-21T03:04:41.198665Z","steps":["trace[1291899627] 'agreement among raft nodes before linearized reading' (duration: 1.4047005s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:04:41.198814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:04:39.793864Z","time spent":"1.404942005s","remote":"127.0.0.1:37838","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-05-21T03:04:41.199037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.46678729s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-21T03:04:41.199101Z","caller":"traceutil/trace.go:171","msg":"trace[2099601064] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:757; }","duration":"1.466967475s","start":"2026-05-21T03:04:39.732119Z","end":"2026-05-21T03:04:41.199086Z","steps":["trace[2099601064] 'agreement among raft nodes before linearized reading' (duration: 1.466640677s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:04:41.199201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:04:39.732098Z","time spent":"1.467093307s","remote":"127.0.0.1:37550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-21T03:04:41.199406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.794801ms","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-05-21T03:04:41.1995Z","caller":"traceutil/trace.go:171","msg":"trace[499892377] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:757; }","duration":"594.906303ms","start":"2026-05-21T03:04:40.60456Z","end":"2026-05-21T03:04:41.199467Z","steps":["trace[499892377] 'agreement among raft nodes before linearized reading' (duration: 594.736809ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:04:41.199549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:04:40.604546Z","time spent":"594.979896ms","remote":"127.0.0.1:37550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-21T03:04:41.19963Z","caller":"traceutil/trace.go:171","msg":"trace[805001032] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"643.282932ms","start":"2026-05-21T03:04:40.556256Z","end":"2026-05-21T03:04:41.199539Z","steps":["trace[805001032] 'process raft request' (duration: 641.97373ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:04:41.19971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.818547ms","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-05-21T03:04:41.199734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.380037ms","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-05-21T03:04:41.19977Z","caller":"traceutil/trace.go:171","msg":"trace[1202909463] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:757; }","duration":"455.93298ms","start":"2026-05-21T03:04:40.743822Z","end":"2026-05-21T03:04:41.199755Z","steps":["trace[1202909463] 'agreement among raft nodes before linearized reading' (duration: 454.868064ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:04:41.199797Z","caller":"traceutil/trace.go:171","msg":"trace[1602755363] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:757; }","duration":"699.47609ms","start":"2026-05-21T03:04:40.500301Z","end":"2026-05-21T03:04:41.199778Z","steps":["trace[1602755363] 'agreement among raft nodes before linearized reading' (duration: 699.287175ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:04:41.19981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:04:40.743796Z","time spent":"456.004332ms","remote":"127.0.0.1:37550","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-05-21T03:04:41.199852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:04:40.500281Z","time spent":"699.558941ms","remote":"127.0.0.1:37550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-21T03:04:41.199117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.609679ms","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-05-21T03:04:41.199935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:04:40.55623Z","time spent":"643.429525ms","remote":"127.0.0.1:37550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T03:04:41.199974Z","caller":"traceutil/trace.go:171","msg":"trace[746221733] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:757; }","duration":"690.499051ms","start":"2026-05-21T03:04:40.509459Z","end":"2026-05-21T03:04:41.199958Z","steps":["trace[746221733] 'agreement among raft nodes before linearized reading' (duration: 689.498477ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:04:41.200032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:04:40.509448Z","time spent":"690.573323ms","remote":"127.0.0.1:37550","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-05-21T03:04:41.199557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.247381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-chrk8\" ","response":"range_response_count:1 size:5050"} {"level":"info","ts":"2026-05-21T03:04:41.200206Z","caller":"traceutil/trace.go:171","msg":"trace[2103466651] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-chrk8; range_end:; response_count:1; response_revision:757; }","duration":"413.915458ms","start":"2026-05-21T03:04:40.786267Z","end":"2026-05-21T03:04:41.200182Z","steps":["trace[2103466651] 'agreement among raft nodes before linearized reading' (duration: 413.049916ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:04:41.200279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:04:40.786253Z","time spent":"414.00954ms","remote":"127.0.0.1:37484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":5073,"request content":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-chrk8\" "} {"level":"warn","ts":"2026-05-21T03:04:59.494129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.287099ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T03:04:59.494277Z","caller":"traceutil/trace.go:171","msg":"trace[2092725833] linearizableReadLoop","detail":"{readStateIndex:946; appliedIndex:945; }","duration":"123.408294ms","start":"2026-05-21T03:04:59.370846Z","end":"2026-05-21T03:04:59.494254Z","steps":["trace[2092725833] 'read index received' (duration: 18.760657ms)","trace[2092725833] 'applied index is now lower than readState.Index' (duration: 104.646107ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:04:59.494532Z","caller":"traceutil/trace.go:171","msg":"trace[582736304] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"124.44418ms","start":"2026-05-21T03:04:59.369998Z","end":"2026-05-21T03:04:59.494442Z","steps":["trace[582736304] 'process raft request' (duration: 19.628608ms)","trace[582736304] 'compare' (duration: 103.345806ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:04:59.494553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.683261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-21T03:04:59.494626Z","caller":"traceutil/trace.go:171","msg":"trace[1875356981] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:918; }","duration":"123.805814ms","start":"2026-05-21T03:04:59.370806Z","end":"2026-05-21T03:04:59.494611Z","steps":["trace[1875356981] 'agreement among raft nodes before linearized reading' (duration: 123.537358ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:04:59.494736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.184621ms","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-05-21T03:04:59.49478Z","caller":"traceutil/trace.go:171","msg":"trace[889250553] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:918; }","duration":"121.251812ms","start":"2026-05-21T03:04:59.373514Z","end":"2026-05-21T03:04:59.494766Z","steps":["trace[889250553] 'agreement among raft nodes before linearized reading' (duration: 121.082578ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:04:59.494955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.856291ms","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-05-21T03:04:59.495072Z","caller":"traceutil/trace.go:171","msg":"trace[1444606082] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:918; }","duration":"116.022505ms","start":"2026-05-21T03:04:59.379033Z","end":"2026-05-21T03:04:59.495056Z","steps":["trace[1444606082] 'agreement among raft nodes before linearized reading' (duration: 115.674216ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:05:17.586979Z","caller":"traceutil/trace.go:171","msg":"trace[678548753] linearizableReadLoop","detail":"{readStateIndex:1012; appliedIndex:1011; }","duration":"139.341429ms","start":"2026-05-21T03:05:17.447613Z","end":"2026-05-21T03:05:17.586954Z","steps":["trace[678548753] 'read index received' (duration: 139.093253ms)","trace[678548753] 'applied index is now lower than readState.Index' (duration: 247.036µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:05:17.587427Z","caller":"traceutil/trace.go:171","msg":"trace[542942273] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"551.806525ms","start":"2026-05-21T03:05:17.035571Z","end":"2026-05-21T03:05:17.587378Z","steps":["trace[542942273] 'process raft request' (duration: 551.20003ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:05:17.587607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:05:17.035552Z","time spent":"551.971629ms","remote":"127.0.0.1:37550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T03:05:17.58802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.387753ms","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-05-21T03:05:17.588888Z","caller":"traceutil/trace.go:171","msg":"trace[665627286] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:980; }","duration":"141.288445ms","start":"2026-05-21T03:05:17.447574Z","end":"2026-05-21T03:05:17.588863Z","steps":["trace[665627286] 'agreement among raft nodes before linearized reading' (duration: 139.616965ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:05:18.13404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.896544ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T03:05:18.134132Z","caller":"traceutil/trace.go:171","msg":"trace[259854739] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"541.880031ms","start":"2026-05-21T03:05:17.592236Z","end":"2026-05-21T03:05:18.134116Z","steps":["trace[259854739] 'process raft request' (duration: 431.820033ms)","trace[259854739] 'compare' (duration: 109.766461ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:05:18.134183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:05:17.592217Z","time spent":"541.943922ms","remote":"127.0.0.1:37550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T03:05:18.135802Z","caller":"traceutil/trace.go:171","msg":"trace[129047944] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"543.440627ms","start":"2026-05-21T03:05:17.592336Z","end":"2026-05-21T03:05:18.135777Z","steps":["trace[129047944] 'process raft request' (duration: 543.235132ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:05:18.135937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:05:17.592314Z","time spent":"543.568921ms","remote":"127.0.0.1:37550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T03:05:18.135993Z","caller":"traceutil/trace.go:171","msg":"trace[8233940] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"543.553551ms","start":"2026-05-21T03:05:17.592414Z","end":"2026-05-21T03:05:18.135968Z","steps":["trace[8233940] 'process raft request' (duration: 543.308425ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:05:18.136144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:05:17.592404Z","time spent":"543.663483ms","remote":"127.0.0.1:37550","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-05-21T03:05:18.136398Z","caller":"traceutil/trace.go:171","msg":"trace[1157001576] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"541.048352ms","start":"2026-05-21T03:05:17.595334Z","end":"2026-05-21T03:05:18.136383Z","steps":["trace[1157001576] 'process raft request' (duration: 540.55619ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:05:18.13646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:05:17.595326Z","time spent":"541.103443ms","remote":"127.0.0.1:37550","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-05-21T03:05:18.136587Z","caller":"traceutil/trace.go:171","msg":"trace[785400301] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"541.240236ms","start":"2026-05-21T03:05:17.595338Z","end":"2026-05-21T03:05:18.136579Z","steps":["trace[785400301] 'process raft request' (duration: 541.005861ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:05:18.13663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:05:17.59533Z","time spent":"541.276937ms","remote":"127.0.0.1:37550","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-05-21T03:05:40.361561Z","caller":"traceutil/trace.go:171","msg":"trace[607308092] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"111.184605ms","start":"2026-05-21T03:05:40.250337Z","end":"2026-05-21T03:05:40.361522Z","steps":["trace[607308092] 'process raft request' (duration: 55.829748ms)","trace[607308092] 'compare' (duration: 55.116541ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:05:40.361804Z","caller":"traceutil/trace.go:171","msg":"trace[915130126] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"110.370125ms","start":"2026-05-21T03:05:40.251413Z","end":"2026-05-21T03:05:40.361783Z","steps":["trace[915130126] 'process raft request' (duration: 110.061238ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:05:40.362159Z","caller":"traceutil/trace.go:171","msg":"trace[2046629107] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"110.728454ms","start":"2026-05-21T03:05:40.251408Z","end":"2026-05-21T03:05:40.362136Z","steps":["trace[2046629107] 'process raft request' (duration: 110.286503ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:05:40.362179Z","caller":"traceutil/trace.go:171","msg":"trace[1841216614] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"110.662932ms","start":"2026-05-21T03:05:40.251492Z","end":"2026-05-21T03:05:40.362155Z","steps":["trace[1841216614] 'process raft request' (duration: 110.505168ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:05:40.362155Z","caller":"traceutil/trace.go:171","msg":"trace[699126529] linearizableReadLoop","detail":"{readStateIndex:1094; appliedIndex:1090; }","duration":"105.405428ms","start":"2026-05-21T03:05:40.256726Z","end":"2026-05-21T03:05:40.362132Z","steps":["trace[699126529] 'read index received' (duration: 49.484408ms)","trace[699126529] 'applied index is now lower than readState.Index' (duration: 55.92006ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:05:40.362352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.602943ms","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-05-21T03:05:40.362421Z","caller":"traceutil/trace.go:171","msg":"trace[793629870] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1057; }","duration":"105.720486ms","start":"2026-05-21T03:05:40.256688Z","end":"2026-05-21T03:05:40.362409Z","steps":["trace[793629870] 'agreement among raft nodes before linearized reading' (duration: 105.524901ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:05:40.616551Z","caller":"traceutil/trace.go:171","msg":"trace[911633846] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"250.423411ms","start":"2026-05-21T03:05:40.366097Z","end":"2026-05-21T03:05:40.61652Z","steps":["trace[911633846] 'process raft request' (duration: 189.842771ms)","trace[911633846] 'compare' (duration: 60.353674ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:05:40.984226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.881715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T03:05:40.984308Z","caller":"traceutil/trace.go:171","msg":"trace[1341766830] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1058; }","duration":"191.000939ms","start":"2026-05-21T03:05:40.793287Z","end":"2026-05-21T03:05:40.984288Z","steps":["trace[1341766830] 'range keys from in-memory index tree' (duration: 190.815754ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:05:42.254421Z","caller":"traceutil/trace.go:171","msg":"trace[924881420] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"192.967094ms","start":"2026-05-21T03:05:42.061406Z","end":"2026-05-21T03:05:42.254373Z","steps":["trace[924881420] 'process raft request' (duration: 192.746919ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:05:42.523098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.107818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-21T03:05:42.523198Z","caller":"traceutil/trace.go:171","msg":"trace[737215028] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1060; }","duration":"157.243782ms","start":"2026-05-21T03:05:42.36593Z","end":"2026-05-21T03:05:42.523174Z","steps":["trace[737215028] 'range keys from in-memory index tree' (duration: 156.939385ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:05:42.523231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.620307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-21T03:05:42.523257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.56657ms","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-05-21T03:05:42.52329Z","caller":"traceutil/trace.go:171","msg":"trace[713884980] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1060; }","duration":"156.71465ms","start":"2026-05-21T03:05:42.36656Z","end":"2026-05-21T03:05:42.523275Z","steps":["trace[713884980] 'range keys from in-memory index tree' (duration: 156.478284ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:05:42.523317Z","caller":"traceutil/trace.go:171","msg":"trace[1583554556] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1060; }","duration":"157.670012ms","start":"2026-05-21T03:05:42.365631Z","end":"2026-05-21T03:05:42.523301Z","steps":["trace[1583554556] 'range keys from in-memory index tree' (duration: 157.424116ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:05:42.523482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.683353ms","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-05-21T03:05:42.523511Z","caller":"traceutil/trace.go:171","msg":"trace[2117183141] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1060; }","duration":"157.766334ms","start":"2026-05-21T03:05:42.365737Z","end":"2026-05-21T03:05:42.523504Z","steps":["trace[2117183141] 'range keys from in-memory index tree' (duration: 157.59112ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:05:42.703675Z","caller":"traceutil/trace.go:171","msg":"trace[753149499] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"176.002214ms","start":"2026-05-21T03:05:42.527648Z","end":"2026-05-21T03:05:42.70365Z","steps":["trace[753149499] 'process raft request' (duration: 175.673816ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:05:42.703673Z","caller":"traceutil/trace.go:171","msg":"trace[1305197048] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"175.359049ms","start":"2026-05-21T03:05:42.528291Z","end":"2026-05-21T03:05:42.70365Z","steps":["trace[1305197048] 'process raft request' (duration: 175.074422ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:05:42.703981Z","caller":"traceutil/trace.go:171","msg":"trace[1101755983] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"176.359202ms","start":"2026-05-21T03:05:42.527436Z","end":"2026-05-21T03:05:42.703795Z","steps":["trace[1101755983] 'process raft request' (duration: 175.766458ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:05:42.884116Z","caller":"traceutil/trace.go:171","msg":"trace[630777821] linearizableReadLoop","detail":"{readStateIndex:1102; appliedIndex:1101; }","duration":"111.331458ms","start":"2026-05-21T03:05:42.772748Z","end":"2026-05-21T03:05:42.884079Z","steps":["trace[630777821] 'read index received' (duration: 17.960564ms)","trace[630777821] 'applied index is now lower than readState.Index' (duration: 93.369604ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:05:42.884674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.761148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.222\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-21T03:05:42.884759Z","caller":"traceutil/trace.go:171","msg":"trace[322582224] range","detail":"{range_begin:/registry/masterleases/199.204.45.222; range_end:; response_count:1; response_revision:1065; }","duration":"112.040414ms","start":"2026-05-21T03:05:42.772692Z","end":"2026-05-21T03:05:42.884733Z","steps":["trace[322582224] 'agreement among raft nodes before linearized reading' (duration: 111.625405ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:05:42.885594Z","caller":"traceutil/trace.go:171","msg":"trace[1602970697] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"175.514974ms","start":"2026-05-21T03:05:42.708928Z","end":"2026-05-21T03:05:42.884443Z","steps":["trace[1602970697] 'process raft request' (duration: 81.908334ms)","trace[1602970697] 'compare' (duration: 93.048816ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:05:58.669099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.201815ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-21T03:05:58.670101Z","caller":"traceutil/trace.go:171","msg":"trace[1621637188] transaction","detail":"{read_only:false; response_revision:1163; number_of_response:1; }","duration":"361.537984ms","start":"2026-05-21T03:05:58.308541Z","end":"2026-05-21T03:05:58.670079Z","steps":["trace[1621637188] 'process raft request' (duration: 360.647493ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:05:58.670214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:05:58.308519Z","time spent":"361.629706ms","remote":"127.0.0.1:37550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T03:05:58.673463Z","caller":"traceutil/trace.go:171","msg":"trace[1850146007] transaction","detail":"{read_only:false; response_revision:1164; number_of_response:1; }","duration":"352.045959ms","start":"2026-05-21T03:05:58.3214Z","end":"2026-05-21T03:05:58.673446Z","steps":["trace[1850146007] 'process raft request' (duration: 351.918156ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:05:58.673562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:05:58.321373Z","time spent":"352.132502ms","remote":"127.0.0.1:37550","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-05-21T03:06:01.042877Z","caller":"traceutil/trace.go:171","msg":"trace[843661358] linearizableReadLoop","detail":"{readStateIndex:1213; appliedIndex:1212; }","duration":"360.814557ms","start":"2026-05-21T03:06:00.682038Z","end":"2026-05-21T03:06:01.042853Z","steps":["trace[843661358] 'read index received' (duration: 360.708024ms)","trace[843661358] 'applied index is now lower than readState.Index' (duration: 105.543µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:06:01.042953Z","caller":"traceutil/trace.go:171","msg":"trace[1390892186] transaction","detail":"{read_only:false; response_revision:1170; number_of_response:1; }","duration":"367.653328ms","start":"2026-05-21T03:06:00.675261Z","end":"2026-05-21T03:06:01.042914Z","steps":["trace[1390892186] 'process raft request' (duration: 367.405482ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:06:01.043092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:06:00.67524Z","time spent":"367.779722ms","remote":"127.0.0.1:37550","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-05-21T03:06:01.04309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.037142ms","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-05-21T03:06:01.043414Z","caller":"traceutil/trace.go:171","msg":"trace[115665155] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1170; }","duration":"361.39076ms","start":"2026-05-21T03:06:00.682006Z","end":"2026-05-21T03:06:01.043397Z","steps":["trace[115665155] 'agreement among raft nodes before linearized reading' (duration: 360.96967ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:06:01.043452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:06:00.681998Z","time spent":"361.443311ms","remote":"127.0.0.1:37550","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-05-21T03:06:01.043198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.374976ms","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-05-21T03:06:01.04351Z","caller":"traceutil/trace.go:171","msg":"trace[1205014209] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1170; }","duration":"263.725085ms","start":"2026-05-21T03:06:00.779778Z","end":"2026-05-21T03:06:01.043503Z","steps":["trace[1205014209] 'agreement among raft nodes before linearized reading' (duration: 263.336726ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:06:01.043207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.606309ms","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-05-21T03:06:01.043577Z","caller":"traceutil/trace.go:171","msg":"trace[219183945] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1170; }","duration":"262.990548ms","start":"2026-05-21T03:06:00.780581Z","end":"2026-05-21T03:06:01.043571Z","steps":["trace[219183945] 'agreement among raft nodes before linearized reading' (duration: 262.550947ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:06:01.04319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.383924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T03:06:01.043666Z","caller":"traceutil/trace.go:171","msg":"trace[225712158] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1170; }","duration":"251.899156ms","start":"2026-05-21T03:06:00.791761Z","end":"2026-05-21T03:06:01.04366Z","steps":["trace[225712158] 'agreement among raft nodes before linearized reading' (duration: 251.356283ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:06:01.043262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.693021ms","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-05-21T03:06:01.043765Z","caller":"traceutil/trace.go:171","msg":"trace[1836930196] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1170; }","duration":"263.213023ms","start":"2026-05-21T03:06:00.780543Z","end":"2026-05-21T03:06:01.043756Z","steps":["trace[1836930196] 'agreement among raft nodes before linearized reading' (duration: 262.599388ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:06:13.281685Z","caller":"traceutil/trace.go:171","msg":"trace[2128894523] transaction","detail":"{read_only:false; response_revision:1212; number_of_response:1; }","duration":"144.946641ms","start":"2026-05-21T03:06:13.136714Z","end":"2026-05-21T03:06:13.281661Z","steps":["trace[2128894523] 'process raft request' (duration: 92.430562ms)","trace[2128894523] 'compare' (duration: 52.323925ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:06:13.281813Z","caller":"traceutil/trace.go:171","msg":"trace[557022007] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"144.48468ms","start":"2026-05-21T03:06:13.137296Z","end":"2026-05-21T03:06:13.281781Z","steps":["trace[557022007] 'process raft request' (duration: 144.273315ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:06:13.281996Z","caller":"traceutil/trace.go:171","msg":"trace[1437189954] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"141.292145ms","start":"2026-05-21T03:06:13.140692Z","end":"2026-05-21T03:06:13.281984Z","steps":["trace[1437189954] 'process raft request' (duration: 141.249474ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:06:13.282139Z","caller":"traceutil/trace.go:171","msg":"trace[1456412221] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"142.940854ms","start":"2026-05-21T03:06:13.13916Z","end":"2026-05-21T03:06:13.282101Z","steps":["trace[1456412221] 'process raft request' (duration: 142.447752ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:06:17.02523Z","caller":"traceutil/trace.go:171","msg":"trace[1212093263] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"178.228947ms","start":"2026-05-21T03:06:16.846981Z","end":"2026-05-21T03:06:17.02521Z","steps":["trace[1212093263] 'process raft request' (duration: 178.115235ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:06:17.744338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.48722ms","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-05-21T03:06:17.744471Z","caller":"traceutil/trace.go:171","msg":"trace[1917622613] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1239; }","duration":"580.641903ms","start":"2026-05-21T03:06:17.163795Z","end":"2026-05-21T03:06:17.744437Z","steps":["trace[1917622613] 'range keys from in-memory index tree' (duration: 580.323725ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:06:17.744526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:06:17.163781Z","time spent":"580.729075ms","remote":"127.0.0.1:37550","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-05-21T03:06:17.744551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.641294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T03:06:17.744638Z","caller":"traceutil/trace.go:171","msg":"trace[439444039] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1239; }","duration":"420.769397ms","start":"2026-05-21T03:06:17.32384Z","end":"2026-05-21T03:06:17.744609Z","steps":["trace[439444039] 'count revisions from in-memory index tree' (duration: 420.576642ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:06:17.744698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:06:17.323826Z","time spent":"420.860059ms","remote":"127.0.0.1:50328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-21T03:06:17.744917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.386906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-21T03:06:17.745004Z","caller":"traceutil/trace.go:171","msg":"trace[107698769] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1239; }","duration":"448.57118ms","start":"2026-05-21T03:06:17.296409Z","end":"2026-05-21T03:06:17.74498Z","steps":["trace[107698769] 'range keys from in-memory index tree' (duration: 448.190171ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:06:17.745037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.400523ms","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-05-21T03:06:17.745058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:06:17.296387Z","time spent":"448.658862ms","remote":"127.0.0.1:37550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-21T03:06:17.745067Z","caller":"traceutil/trace.go:171","msg":"trace[1526473937] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1239; }","duration":"447.442944ms","start":"2026-05-21T03:06:17.297614Z","end":"2026-05-21T03:06:17.745057Z","steps":["trace[1526473937] 'range keys from in-memory index tree' (duration: 447.29151ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:06:17.745125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:06:17.297608Z","time spent":"447.510265ms","remote":"127.0.0.1:37550","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-05-21T03:06:17.74524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.734117ms","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-05-21T03:06:17.745267Z","caller":"traceutil/trace.go:171","msg":"trace[803466887] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1239; }","duration":"446.793938ms","start":"2026-05-21T03:06:17.298462Z","end":"2026-05-21T03:06:17.745256Z","steps":["trace[803466887] 'range keys from in-memory index tree' (duration: 446.557133ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:06:17.745291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:06:17.298442Z","time spent":"446.84294ms","remote":"127.0.0.1:37550","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-05-21T03:06:17.745383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.872256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-5fvv5\" ","response":"range_response_count:1 size:11061"} {"level":"info","ts":"2026-05-21T03:06:17.745423Z","caller":"traceutil/trace.go:171","msg":"trace[1553783361] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-5fvv5; range_end:; response_count:1; response_revision:1239; }","duration":"565.935148ms","start":"2026-05-21T03:06:17.179475Z","end":"2026-05-21T03:06:17.74541Z","steps":["trace[1553783361] 'range keys from in-memory index tree' (duration: 565.737214ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:06:17.745456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:06:17.179461Z","time spent":"565.987039ms","remote":"127.0.0.1:37484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":11084,"request content":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-5fvv5\" "} {"level":"warn","ts":"2026-05-21T03:06:17.745518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.508591ms","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-05-21T03:06:17.745546Z","caller":"traceutil/trace.go:171","msg":"trace[877354529] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1239; }","duration":"446.548793ms","start":"2026-05-21T03:06:17.298988Z","end":"2026-05-21T03:06:17.745537Z","steps":["trace[877354529] 'range keys from in-memory index tree' (duration: 446.388829ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:06:17.745571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:06:17.298981Z","time spent":"446.583934ms","remote":"127.0.0.1:37550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-21T03:06:18.294323Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6890118514096815798,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-21T03:06:18.338838Z","caller":"traceutil/trace.go:171","msg":"trace[396513134] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"589.208674ms","start":"2026-05-21T03:06:17.74961Z","end":"2026-05-21T03:06:18.338819Z","steps":["trace[396513134] 'process raft request' (duration: 589.115451ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:06:18.338997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:06:17.749602Z","time spent":"589.323797ms","remote":"127.0.0.1:37550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T03:06:18.338827Z","caller":"traceutil/trace.go:171","msg":"trace[1027472906] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"589.208294ms","start":"2026-05-21T03:06:17.749589Z","end":"2026-05-21T03:06:18.338798Z","steps":["trace[1027472906] 'process raft request' (duration: 588.974609ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:06:18.339118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:06:17.749569Z","time spent":"589.503361ms","remote":"127.0.0.1:37550","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-05-21T03:06:18.509534Z","caller":"traceutil/trace.go:171","msg":"trace[481097207] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"759.759282ms","start":"2026-05-21T03:06:17.749758Z","end":"2026-05-21T03:06:18.509517Z","steps":["trace[481097207] 'process raft request' (duration: 759.606038ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:06:18.509682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:06:17.749704Z","time spent":"759.908055ms","remote":"127.0.0.1:37550","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-05-21T03:06:18.50983Z","caller":"traceutil/trace.go:171","msg":"trace[1099662890] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"759.100107ms","start":"2026-05-21T03:06:17.750713Z","end":"2026-05-21T03:06:18.509814Z","steps":["trace[1099662890] 'process raft request' (duration: 758.763759ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:06:18.509948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:06:17.750674Z","time spent":"759.219618ms","remote":"127.0.0.1:37550","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-05-21T03:06:18.510062Z","caller":"traceutil/trace.go:171","msg":"trace[483532926] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"758.985623ms","start":"2026-05-21T03:06:17.751069Z","end":"2026-05-21T03:06:18.510055Z","steps":["trace[483532926] 'process raft request' (duration: 758.698146ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:06:18.510088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:06:17.75106Z","time spent":"759.012394ms","remote":"127.0.0.1:37550","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-05-21T03:06:18.510179Z","caller":"traceutil/trace.go:171","msg":"trace[834062673] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"754.527648ms","start":"2026-05-21T03:06:17.755647Z","end":"2026-05-21T03:06:18.510174Z","steps":["trace[834062673] 'process raft request' (duration: 754.284892ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:06:18.510207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:06:17.755638Z","time spent":"754.555209ms","remote":"127.0.0.1:37484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11375,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T03:06:18.54208Z","caller":"traceutil/trace.go:171","msg":"trace[385928705] linearizableReadLoop","detail":"{readStateIndex:1292; appliedIndex:1285; }","duration":"748.539287ms","start":"2026-05-21T03:06:17.793522Z","end":"2026-05-21T03:06:18.542062Z","steps":["trace[385928705] 'read index received' (duration: 545.100517ms)","trace[385928705] 'applied index is now lower than readState.Index' (duration: 203.43788ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:06:18.542194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.665361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T03:06:18.542223Z","caller":"traceutil/trace.go:171","msg":"trace[1025448681] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1245; }","duration":"748.748082ms","start":"2026-05-21T03:06:17.79347Z","end":"2026-05-21T03:06:18.542218Z","steps":["trace[1025448681] 'agreement among raft nodes before linearized reading' (duration: 748.683ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:06:18.542245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:06:17.793446Z","time spent":"748.794294ms","remote":"127.0.0.1:37838","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-05-21T03:06:18.542228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.955651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T03:06:18.542286Z","caller":"traceutil/trace.go:171","msg":"trace[1935966635] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1245; }","duration":"710.060683ms","start":"2026-05-21T03:06:17.83221Z","end":"2026-05-21T03:06:18.542271Z","steps":["trace[1935966635] 'agreement among raft nodes before linearized reading' (duration: 709.959641ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:06:18.542331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:06:17.832189Z","time spent":"710.132675ms","remote":"127.0.0.1:37484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":13,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-05-21T03:06:19.939878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.36332ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-21T03:06:19.939953Z","caller":"traceutil/trace.go:171","msg":"trace[1686187485] linearizableReadLoop","detail":"{readStateIndex:1300; appliedIndex:1299; }","duration":"148.328005ms","start":"2026-05-21T03:06:19.791613Z","end":"2026-05-21T03:06:19.939941Z","steps":["trace[1686187485] 'read index received' (duration: 40.31µs)","trace[1686187485] 'applied index is now lower than readState.Index' (duration: 148.286635ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:06:19.940024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.414347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T03:06:19.940039Z","caller":"traceutil/trace.go:171","msg":"trace[789751560] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1253; }","duration":"148.469189ms","start":"2026-05-21T03:06:19.791564Z","end":"2026-05-21T03:06:19.940034Z","steps":["trace[789751560] 'agreement among raft nodes before linearized reading' (duration: 148.407497ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:06:19.940224Z","caller":"traceutil/trace.go:171","msg":"trace[2038796450] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"551.844017ms","start":"2026-05-21T03:06:19.388371Z","end":"2026-05-21T03:06:19.940215Z","steps":["trace[2038796450] 'process raft request' (duration: 279.091048ms)","trace[2038796450] 'compare' (duration: 272.088104ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:06:19.940257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:06:19.388357Z","time spent":"551.884308ms","remote":"127.0.0.1:37550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T03:06:19.9708Z","caller":"traceutil/trace.go:171","msg":"trace[928467297] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"122.57367ms","start":"2026-05-21T03:06:19.848202Z","end":"2026-05-21T03:06:19.970776Z","steps":["trace[928467297] 'process raft request' (duration: 122.462448ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:06:20.417203Z","caller":"traceutil/trace.go:171","msg":"trace[1914005] linearizableReadLoop","detail":"{readStateIndex:1302; appliedIndex:1300; }","duration":"471.714453ms","start":"2026-05-21T03:06:19.945471Z","end":"2026-05-21T03:06:20.417185Z","steps":["trace[1914005] 'read index received' (duration: 25.13311ms)","trace[1914005] 'applied index is now lower than readState.Index' (duration: 446.580673ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:06:20.417299Z","caller":"traceutil/trace.go:171","msg":"trace[1865196653] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"472.126274ms","start":"2026-05-21T03:06:19.945119Z","end":"2026-05-21T03:06:20.417246Z","steps":["trace[1865196653] 'process raft request' (duration: 454.637763ms)","trace[1865196653] 'compare' (duration: 17.337397ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:06:20.417392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.913159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-05-21T03:06:20.417415Z","caller":"traceutil/trace.go:171","msg":"trace[1848849016] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1255; }","duration":"471.997991ms","start":"2026-05-21T03:06:19.945409Z","end":"2026-05-21T03:06:20.417407Z","steps":["trace[1848849016] 'agreement among raft nodes before linearized reading' (duration: 471.865867ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:06:20.417434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:06:19.9454Z","time spent":"472.028871ms","remote":"127.0.0.1:37550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-21T03:06:20.417439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:06:19.945098Z","time spent":"472.274427ms","remote":"127.0.0.1:37904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":773,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T03:06:20.594508Z","caller":"traceutil/trace.go:171","msg":"trace[56281416] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"619.433345ms","start":"2026-05-21T03:06:19.975041Z","end":"2026-05-21T03:06:20.594474Z","steps":["trace[56281416] 'process raft request' (duration: 554.170032ms)","trace[56281416] 'compare' (duration: 65.11554ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:06:20.594614Z","caller":"traceutil/trace.go:171","msg":"trace[1288722231] linearizableReadLoop","detail":"{readStateIndex:1303; appliedIndex:1302; }","duration":"177.318057ms","start":"2026-05-21T03:06:20.417254Z","end":"2026-05-21T03:06:20.594572Z","steps":["trace[1288722231] 'read index received' (duration: 111.952151ms)","trace[1288722231] 'applied index is now lower than readState.Index' (duration: 65.360615ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:06:20.594667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:06:19.975024Z","time spent":"619.567208ms","remote":"127.0.0.1:37904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":836,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T03:06:20.594767Z","caller":"traceutil/trace.go:171","msg":"trace[171700937] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"171.883769ms","start":"2026-05-21T03:06:20.422648Z","end":"2026-05-21T03:06:20.594531Z","steps":["trace[171700937] 'process raft request' (duration: 171.821467ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:06:20.594901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.794186ms","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-05-21T03:06:20.594925Z","caller":"traceutil/trace.go:171","msg":"trace[461887065] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1257; }","duration":"251.850678ms","start":"2026-05-21T03:06:20.343069Z","end":"2026-05-21T03:06:20.594919Z","steps":["trace[461887065] 'agreement among raft nodes before linearized reading' (duration: 251.798357ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:06:20.59484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.860554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-21T03:06:20.595013Z","caller":"traceutil/trace.go:171","msg":"trace[88540809] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1257; }","duration":"251.08425ms","start":"2026-05-21T03:06:20.343925Z","end":"2026-05-21T03:06:20.595009Z","steps":["trace[88540809] 'agreement among raft nodes before linearized reading' (duration: 250.785853ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:06:20.775779Z","caller":"traceutil/trace.go:171","msg":"trace[2146467412] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"174.701185ms","start":"2026-05-21T03:06:20.601059Z","end":"2026-05-21T03:06:20.77576Z","steps":["trace[2146467412] 'process raft request' (duration: 174.671065ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:06:20.775815Z","caller":"traceutil/trace.go:171","msg":"trace[454376171] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"176.262502ms","start":"2026-05-21T03:06:20.599526Z","end":"2026-05-21T03:06:20.775788Z","steps":["trace[454376171] 'process raft request' (duration: 104.630269ms)","trace[454376171] 'compare' (duration: 71.422778ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:06:20.775938Z","caller":"traceutil/trace.go:171","msg":"trace[1870211959] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"175.245597ms","start":"2026-05-21T03:06:20.600686Z","end":"2026-05-21T03:06:20.775932Z","steps":["trace[1870211959] 'process raft request' (duration: 174.995621ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:06:20.776008Z","caller":"traceutil/trace.go:171","msg":"trace[1582708081] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"175.047084ms","start":"2026-05-21T03:06:20.600936Z","end":"2026-05-21T03:06:20.775984Z","steps":["trace[1582708081] 'process raft request' (duration: 174.771187ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:06:21.466215Z","caller":"traceutil/trace.go:171","msg":"trace[236238306] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"262.017675ms","start":"2026-05-21T03:06:21.204179Z","end":"2026-05-21T03:06:21.466196Z","steps":["trace[236238306] 'process raft request' (duration: 261.896212ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:06:21.839292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.209757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T03:06:21.839372Z","caller":"traceutil/trace.go:171","msg":"trace[1962243354] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1263; }","duration":"258.345561ms","start":"2026-05-21T03:06:21.581007Z","end":"2026-05-21T03:06:21.839352Z","steps":["trace[1962243354] 'count revisions from in-memory index tree' (duration: 258.103255ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:06:29.416489Z","caller":"traceutil/trace.go:171","msg":"trace[982599974] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"193.492987ms","start":"2026-05-21T03:06:29.222957Z","end":"2026-05-21T03:06:29.41645Z","steps":["trace[982599974] 'process raft request' (duration: 191.305296ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:06:30.242548Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6890118514096815956,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T03:06:30.570146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.784225ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T03:06:30.570346Z","caller":"traceutil/trace.go:171","msg":"trace[1580276405] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"1.148300422s","start":"2026-05-21T03:06:29.421989Z","end":"2026-05-21T03:06:30.57029Z","steps":["trace[1580276405] 'process raft request' (duration: 982.270771ms)","trace[1580276405] 'compare' (duration: 165.53667ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:06:30.57045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:06:29.421964Z","time spent":"1.148438485s","remote":"127.0.0.1:37550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T03:06:30.573472Z","caller":"traceutil/trace.go:171","msg":"trace[1064306284] linearizableReadLoop","detail":"{readStateIndex:1376; appliedIndex:1374; }","duration":"831.346274ms","start":"2026-05-21T03:06:29.742103Z","end":"2026-05-21T03:06:30.573449Z","steps":["trace[1064306284] 'read index received' (duration: 662.169159ms)","trace[1064306284] 'applied index is now lower than readState.Index' (duration: 169.176125ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:06:30.573614Z","caller":"traceutil/trace.go:171","msg":"trace[53447081] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"1.072566523s","start":"2026-05-21T03:06:29.501019Z","end":"2026-05-21T03:06:30.573585Z","steps":["trace[53447081] 'process raft request' (duration: 1.072302896s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:06:30.573694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.969667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T03:06:30.57371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"831.60447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-05-21T03:06:30.573735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:06:29.500981Z","time spent":"1.072693286s","remote":"127.0.0.1:37550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T03:06:30.573813Z","caller":"traceutil/trace.go:171","msg":"trace[406904194] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1327; }","duration":"780.12149ms","start":"2026-05-21T03:06:29.793669Z","end":"2026-05-21T03:06:30.57379Z","steps":["trace[406904194] 'agreement among raft nodes before linearized reading' (duration: 779.958787ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:06:30.573944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:06:29.793651Z","time spent":"780.274985ms","remote":"127.0.0.1:37838","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-05-21T03:06:30.574062Z","caller":"traceutil/trace.go:171","msg":"trace[1487126207] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1327; }","duration":"831.739513ms","start":"2026-05-21T03:06:29.742048Z","end":"2026-05-21T03:06:30.573788Z","steps":["trace[1487126207] 'agreement among raft nodes before linearized reading' (duration: 831.527768ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:06:30.574062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.603071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T03:06:30.574191Z","caller":"traceutil/trace.go:171","msg":"trace[613623221] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1327; }","duration":"711.823646ms","start":"2026-05-21T03:06:29.862341Z","end":"2026-05-21T03:06:30.574164Z","steps":["trace[613623221] 'agreement among raft nodes before linearized reading' (duration: 711.429847ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:06:30.574134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:06:29.742024Z","time spent":"832.091022ms","remote":"127.0.0.1:37550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-05-21T03:06:30.574296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:06:29.862318Z","time spent":"711.957629ms","remote":"127.0.0.1:56570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":4,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"info","ts":"2026-05-21T03:10:05.692743Z","caller":"traceutil/trace.go:171","msg":"trace[1015290117] transaction","detail":"{read_only:false; response_revision:2336; number_of_response:1; }","duration":"102.281211ms","start":"2026-05-21T03:10:05.590421Z","end":"2026-05-21T03:10:05.692702Z","steps":["trace[1015290117] 'process raft request' (duration: 102.051346ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:10:13.072023Z","caller":"traceutil/trace.go:171","msg":"trace[535938684] linearizableReadLoop","detail":"{readStateIndex:2471; appliedIndex:2470; }","duration":"246.841984ms","start":"2026-05-21T03:10:12.825157Z","end":"2026-05-21T03:10:13.071999Z","steps":["trace[535938684] 'read index received' (duration: 246.658709ms)","trace[535938684] 'applied index is now lower than readState.Index' (duration: 181.945µs)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:10:13.072228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.051528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T03:10:13.072324Z","caller":"traceutil/trace.go:171","msg":"trace[806849112] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:2376; }","duration":"247.218142ms","start":"2026-05-21T03:10:12.825081Z","end":"2026-05-21T03:10:13.0723Z","steps":["trace[806849112] 'agreement among raft nodes before linearized reading' (duration: 247.042968ms)"],"step_count":1}