{"level":"warn","ts":"2026-07-02T15:21:39.229416Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-02T15:21:39.229727Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.67:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.67:2380","--initial-cluster=instance=https://199.19.213.67:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.67:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.67:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-02T15:21:39.22988Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-02T15:21:39.229957Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.67:2380"]} {"level":"info","ts":"2026-07-02T15:21:39.230005Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-02T15:21:39.230945Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.67:2379"]} {"level":"info","ts":"2026-07-02T15:21:39.231118Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.67:2380"],"listen-peer-urls":["https://199.19.213.67:2380"],"advertise-client-urls":["https://199.19.213.67:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.67:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.67:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-02T15:21:39.366027Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"134.603037ms"} {"level":"info","ts":"2026-07-02T15:21:39.602412Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fba6d6d8cd5f215c","cluster-id":"f3c660457a1c2c72"} {"level":"info","ts":"2026-07-02T15:21:39.602727Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fba6d6d8cd5f215c switched to configuration voters=()"} {"level":"info","ts":"2026-07-02T15:21:39.602816Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fba6d6d8cd5f215c became follower at term 0"} {"level":"info","ts":"2026-07-02T15:21:39.602937Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fba6d6d8cd5f215c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-02T15:21:39.602981Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fba6d6d8cd5f215c became follower at term 1"} {"level":"info","ts":"2026-07-02T15:21:39.603073Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fba6d6d8cd5f215c switched to configuration voters=(18133417176300724572)"} {"level":"warn","ts":"2026-07-02T15:21:39.715513Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-02T15:21:39.721773Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-02T15:21:39.748703Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-02T15:21:39.760729Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fba6d6d8cd5f215c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-02T15:21:39.761118Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fba6d6d8cd5f215c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-02T15:21:39.761431Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-02T15:21:39.761531Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-02T15:21:39.761572Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-02T15:21:39.772215Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fba6d6d8cd5f215c switched to configuration voters=(18133417176300724572)"} {"level":"info","ts":"2026-07-02T15:21:39.772444Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f3c660457a1c2c72","local-member-id":"fba6d6d8cd5f215c","added-peer-id":"fba6d6d8cd5f215c","added-peer-peer-urls":["https://199.19.213.67:2380"]} {"level":"info","ts":"2026-07-02T15:21:39.779181Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-02T15:21:39.779283Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.67:2380"} {"level":"info","ts":"2026-07-02T15:21:39.779318Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.67:2380"} {"level":"info","ts":"2026-07-02T15:21:39.779533Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fba6d6d8cd5f215c","initial-advertise-peer-urls":["https://199.19.213.67:2380"],"listen-peer-urls":["https://199.19.213.67:2380"],"advertise-client-urls":["https://199.19.213.67:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.67:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-02T15:21:39.779585Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-02T15:21:39.805013Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fba6d6d8cd5f215c is starting a new election at term 1"} {"level":"info","ts":"2026-07-02T15:21:39.805173Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fba6d6d8cd5f215c became pre-candidate at term 1"} {"level":"info","ts":"2026-07-02T15:21:39.805316Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fba6d6d8cd5f215c received MsgPreVoteResp from fba6d6d8cd5f215c at term 1"} {"level":"info","ts":"2026-07-02T15:21:39.805417Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fba6d6d8cd5f215c became candidate at term 2"} {"level":"info","ts":"2026-07-02T15:21:39.805683Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fba6d6d8cd5f215c received MsgVoteResp from fba6d6d8cd5f215c at term 2"} {"level":"info","ts":"2026-07-02T15:21:39.805783Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fba6d6d8cd5f215c became leader at term 2"} {"level":"info","ts":"2026-07-02T15:21:39.805888Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fba6d6d8cd5f215c elected leader fba6d6d8cd5f215c at term 2"} {"level":"info","ts":"2026-07-02T15:21:39.807477Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fba6d6d8cd5f215c","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.67:2379]}","request-path":"/0/members/fba6d6d8cd5f215c/attributes","cluster-id":"f3c660457a1c2c72","publish-timeout":"7s"} {"level":"info","ts":"2026-07-02T15:21:39.811514Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T15:21:39.812218Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-02T15:21:39.813071Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f3c660457a1c2c72","local-member-id":"fba6d6d8cd5f215c","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T15:21:39.813252Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T15:21:39.813329Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T15:21:39.815789Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-02T15:21:39.815827Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-02T15:21:39.816048Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-02T15:21:39.815728Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-02T15:21:39.818856Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.67:2379"} {"level":"warn","ts":"2026-07-02T15:21:42.116204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.715849ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-07-02T15:21:42.116402Z","caller":"traceutil/trace.go:171","msg":"trace[810487892] linearizableReadLoop","detail":"{readStateIndex:45; appliedIndex:43; }","duration":"537.965664ms","start":"2026-07-02T15:21:41.578419Z","end":"2026-07-02T15:21:42.116385Z","steps":["trace[810487892] 'read index received' (duration: 286.650928ms)","trace[810487892] 'applied index is now lower than readState.Index' (duration: 251.313766ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:21:42.116667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.234531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:1 size:338"} {"level":"info","ts":"2026-07-02T15:21:42.11672Z","caller":"traceutil/trace.go:171","msg":"trace[449230589] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:1; response_revision:41; }","duration":"538.320334ms","start":"2026-07-02T15:21:41.578381Z","end":"2026-07-02T15:21:42.116702Z","steps":["trace[449230589] 'agreement among raft nodes before linearized reading' (duration: 538.18025ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:21:42.116735Z","caller":"traceutil/trace.go:171","msg":"trace[1235906927] transaction","detail":"{read_only:false; response_revision:41; number_of_response:1; }","duration":"568.189188ms","start":"2026-07-02T15:21:41.548514Z","end":"2026-07-02T15:21:42.116703Z","steps":["trace[1235906927] 'process raft request' (duration: 567.815057ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:21:42.116757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:21:41.578365Z","time spent":"538.382296ms","remote":"127.0.0.1:37996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":1,"response size":362,"request content":"key:\"/registry/namespaces/default\" "} {"level":"info","ts":"2026-07-02T15:21:42.116752Z","caller":"traceutil/trace.go:171","msg":"trace[1091389347] transaction","detail":"{read_only:false; response_revision:40; number_of_response:1; }","duration":"570.00637ms","start":"2026-07-02T15:21:41.546675Z","end":"2026-07-02T15:21:42.116682Z","steps":["trace[1091389347] 'process raft request' (duration: 318.260542ms)","trace[1091389347] 'compare' (duration: 250.503763ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:21:42.116831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:21:41.548504Z","time spent":"568.294091ms","remote":"127.0.0.1:37996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":326,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-02T15:21:42.116933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:21:41.546656Z","time spent":"570.196265ms","remote":"127.0.0.1:38288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":715,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T15:21:42.29331Z","caller":"traceutil/trace.go:171","msg":"trace[1168558274] transaction","detail":"{read_only:false; response_revision:42; number_of_response:1; }","duration":"172.30989ms","start":"2026-07-02T15:21:42.12097Z","end":"2026-07-02T15:21:42.29328Z","steps":["trace[1168558274] 'process raft request' (duration: 172.127855ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:21:42.722791Z","caller":"traceutil/trace.go:171","msg":"trace[2136698577] transaction","detail":"{read_only:false; response_revision:62; number_of_response:1; }","duration":"136.912648ms","start":"2026-07-02T15:21:42.58582Z","end":"2026-07-02T15:21:42.722733Z","steps":["trace[2136698577] 'process raft request' (duration: 96.070601ms)","trace[2136698577] 'compare' (duration: 40.304622ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:21:42.723266Z","caller":"traceutil/trace.go:171","msg":"trace[953733536] transaction","detail":"{read_only:false; response_revision:64; number_of_response:1; }","duration":"136.65439ms","start":"2026-07-02T15:21:42.586573Z","end":"2026-07-02T15:21:42.723228Z","steps":["trace[953733536] 'process raft request' (duration: 136.270439ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:21:42.723323Z","caller":"traceutil/trace.go:171","msg":"trace[1990655679] transaction","detail":"{read_only:false; response_revision:63; number_of_response:1; }","duration":"137.02583ms","start":"2026-07-02T15:21:42.58626Z","end":"2026-07-02T15:21:42.723286Z","steps":["trace[1990655679] 'process raft request' (duration: 136.493715ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:21:42.723338Z","caller":"traceutil/trace.go:171","msg":"trace[42503735] transaction","detail":"{read_only:false; response_revision:65; number_of_response:1; }","duration":"128.948272ms","start":"2026-07-02T15:21:42.594366Z","end":"2026-07-02T15:21:42.723314Z","steps":["trace[42503735] 'process raft request' (duration: 128.546321ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:21:42.994743Z","caller":"traceutil/trace.go:171","msg":"trace[1615972666] transaction","detail":"{read_only:false; response_revision:67; number_of_response:1; }","duration":"267.030562ms","start":"2026-07-02T15:21:42.727674Z","end":"2026-07-02T15:21:42.994705Z","steps":["trace[1615972666] 'process raft request' (duration: 182.726374ms)","trace[1615972666] 'compare' (duration: 83.603408ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:21:42.99536Z","caller":"traceutil/trace.go:171","msg":"trace[1068748231] transaction","detail":"{read_only:false; response_revision:68; number_of_response:1; }","duration":"265.928231ms","start":"2026-07-02T15:21:42.729412Z","end":"2026-07-02T15:21:42.99534Z","steps":["trace[1068748231] 'process raft request' (duration: 265.256522ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:21:42.99561Z","caller":"traceutil/trace.go:171","msg":"trace[1525006533] linearizableReadLoop","detail":"{readStateIndex:72; appliedIndex:70; }","duration":"267.826555ms","start":"2026-07-02T15:21:42.727754Z","end":"2026-07-02T15:21:42.995581Z","steps":["trace[1525006533] 'read index received' (duration: 40.702033ms)","trace[1525006533] 'applied index is now lower than readState.Index' (duration: 227.122682ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:21:42.995792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.00495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18be823b767e775a\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-07-02T15:21:42.99586Z","caller":"traceutil/trace.go:171","msg":"trace[1152199301] range","detail":"{range_begin:/registry/events/default/instance.18be823b767e775a; range_end:; response_count:1; response_revision:69; }","duration":"268.134143ms","start":"2026-07-02T15:21:42.72771Z","end":"2026-07-02T15:21:42.995845Z","steps":["trace[1152199301] 'agreement among raft nodes before linearized reading' (duration: 267.955848ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:21:42.995938Z","caller":"traceutil/trace.go:171","msg":"trace[895032487] transaction","detail":"{read_only:false; response_revision:69; number_of_response:1; }","duration":"265.573861ms","start":"2026-07-02T15:21:42.730301Z","end":"2026-07-02T15:21:42.995875Z","steps":["trace[895032487] 'process raft request' (duration: 264.950633ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:21:43.000852Z","caller":"traceutil/trace.go:171","msg":"trace[334838293] transaction","detail":"{read_only:false; response_revision:70; number_of_response:1; }","duration":"105.335143ms","start":"2026-07-02T15:21:42.895486Z","end":"2026-07-02T15:21:43.000821Z","steps":["trace[334838293] 'process raft request' (duration: 105.088666ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:21:43.338565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.823338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18be823b767e775a\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-07-02T15:21:43.338673Z","caller":"traceutil/trace.go:171","msg":"trace[654585854] transaction","detail":"{read_only:false; response_revision:100; number_of_response:1; }","duration":"100.910069ms","start":"2026-07-02T15:21:43.237731Z","end":"2026-07-02T15:21:43.338641Z","steps":["trace[654585854] 'process raft request' (duration: 17.292974ms)","trace[654585854] 'compare' (duration: 83.405559ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:21:43.338687Z","caller":"traceutil/trace.go:171","msg":"trace[117916004] range","detail":"{range_begin:/registry/events/default/instance.18be823b767e775a; range_end:; response_count:1; response_revision:99; }","duration":"100.957521ms","start":"2026-07-02T15:21:43.237689Z","end":"2026-07-02T15:21:43.338646Z","steps":["trace[117916004] 'range keys from in-memory index tree' (duration: 100.714794ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:21:43.474095Z","caller":"traceutil/trace.go:171","msg":"trace[1035853808] transaction","detail":"{read_only:false; response_revision:103; number_of_response:1; }","duration":"112.670549ms","start":"2026-07-02T15:21:43.36138Z","end":"2026-07-02T15:21:43.47405Z","steps":["trace[1035853808] 'process raft request' (duration: 82.492353ms)","trace[1035853808] 'compare' (duration: 29.973981ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:21:43.794844Z","caller":"traceutil/trace.go:171","msg":"trace[1309950435] transaction","detail":"{read_only:false; response_revision:114; number_of_response:1; }","duration":"150.146281ms","start":"2026-07-02T15:21:43.644649Z","end":"2026-07-02T15:21:43.794796Z","steps":["trace[1309950435] 'process raft request' (duration: 126.59277ms)","trace[1309950435] 'compare' (duration: 23.278503ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:21:43.794861Z","caller":"traceutil/trace.go:171","msg":"trace[1055492276] transaction","detail":"{read_only:false; response_revision:115; number_of_response:1; }","duration":"149.39926ms","start":"2026-07-02T15:21:43.645436Z","end":"2026-07-02T15:21:43.794835Z","steps":["trace[1055492276] 'process raft request' (duration: 149.213615ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:21:44.416414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.590313ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-07-02T15:21:44.416619Z","caller":"traceutil/trace.go:171","msg":"trace[2032006823] linearizableReadLoop","detail":"{readStateIndex:128; appliedIndex:126; }","duration":"167.702307ms","start":"2026-07-02T15:21:44.248858Z","end":"2026-07-02T15:21:44.41656Z","steps":["trace[2032006823] 'read index received' (duration: 112.478483ms)","trace[2032006823] 'applied index is now lower than readState.Index' (duration: 55.222834ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:21:44.416707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.849282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-02T15:21:44.416741Z","caller":"traceutil/trace.go:171","msg":"trace[320372183] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:123; }","duration":"167.922494ms","start":"2026-07-02T15:21:44.248807Z","end":"2026-07-02T15:21:44.41673Z","steps":["trace[320372183] 'agreement among raft nodes before linearized reading' (duration: 167.849832ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:21:44.416741Z","caller":"traceutil/trace.go:171","msg":"trace[2108288785] transaction","detail":"{read_only:false; response_revision:122; number_of_response:1; }","duration":"544.717203ms","start":"2026-07-02T15:21:43.871983Z","end":"2026-07-02T15:21:44.4167Z","steps":["trace[2108288785] 'process raft request' (duration: 286.77254ms)","trace[2108288785] 'compare' (duration: 257.403858ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:21:44.416914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:21:43.871968Z","time spent":"544.866866ms","remote":"127.0.0.1:38212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":563,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T15:21:44.417005Z","caller":"traceutil/trace.go:171","msg":"trace[694965862] transaction","detail":"{read_only:false; response_revision:123; number_of_response:1; }","duration":"544.220729ms","start":"2026-07-02T15:21:43.872763Z","end":"2026-07-02T15:21:44.416983Z","steps":["trace[694965862] 'process raft request' (duration: 543.747975ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:21:44.417469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:21:43.872754Z","time spent":"544.469045ms","remote":"127.0.0.1:37950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":799,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-02T15:21:44.688476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.090955ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-02T15:21:44.688822Z","caller":"traceutil/trace.go:171","msg":"trace[1687346867] linearizableReadLoop","detail":"{readStateIndex:143; appliedIndex:141; }","duration":"168.942532ms","start":"2026-07-02T15:21:44.519859Z","end":"2026-07-02T15:21:44.688802Z","steps":["trace[1687346867] 'read index received' (duration: 57.276311ms)","trace[1687346867] 'applied index is now lower than readState.Index' (duration: 111.664041ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:21:44.688925Z","caller":"traceutil/trace.go:171","msg":"trace[4366262] transaction","detail":"{read_only:false; response_revision:137; number_of_response:1; }","duration":"172.814989ms","start":"2026-07-02T15:21:44.516016Z","end":"2026-07-02T15:21:44.688831Z","steps":["trace[4366262] 'process raft request' (duration: 61.23769ms)","trace[4366262] 'compare' (duration: 110.867739ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:21:44.688975Z","caller":"traceutil/trace.go:171","msg":"trace[516983805] transaction","detail":"{read_only:false; response_revision:138; number_of_response:1; }","duration":"171.199265ms","start":"2026-07-02T15:21:44.517734Z","end":"2026-07-02T15:21:44.688933Z","steps":["trace[516983805] 'process raft request' (duration: 170.904696ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:21:44.689283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.407345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:5890"} {"level":"info","ts":"2026-07-02T15:21:44.689341Z","caller":"traceutil/trace.go:171","msg":"trace[1838918029] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:138; }","duration":"169.495647ms","start":"2026-07-02T15:21:44.519833Z","end":"2026-07-02T15:21:44.689329Z","steps":["trace[1838918029] 'agreement among raft nodes before linearized reading' (duration: 169.029584ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:21:45.027449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.851913ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-02T15:21:45.027551Z","caller":"traceutil/trace.go:171","msg":"trace[1314569468] linearizableReadLoop","detail":"{readStateIndex:152; appliedIndex:151; }","duration":"111.389507ms","start":"2026-07-02T15:21:44.916143Z","end":"2026-07-02T15:21:45.027532Z","steps":["trace[1314569468] 'read index received' (duration: 119.963µs)","trace[1314569468] 'applied index is now lower than readState.Index' (duration: 111.268554ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:21:45.027595Z","caller":"traceutil/trace.go:171","msg":"trace[1937881488] transaction","detail":"{read_only:false; response_revision:147; number_of_response:1; }","duration":"255.740615ms","start":"2026-07-02T15:21:44.771834Z","end":"2026-07-02T15:21:45.027575Z","steps":["trace[1937881488] 'process raft request' (duration: 131.671036ms)","trace[1937881488] 'compare' (duration: 123.707709ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:21:45.02764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.502121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T15:21:45.027666Z","caller":"traceutil/trace.go:171","msg":"trace[851886421] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:147; }","duration":"111.593453ms","start":"2026-07-02T15:21:44.916063Z","end":"2026-07-02T15:21:45.027656Z","steps":["trace[851886421] 'agreement among raft nodes before linearized reading' (duration: 111.520641ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:21:48.451151Z","caller":"traceutil/trace.go:171","msg":"trace[261804129] transaction","detail":"{read_only:false; number_of_response:0; response_revision:297; }","duration":"112.034654ms","start":"2026-07-02T15:21:48.339088Z","end":"2026-07-02T15:21:48.451123Z","steps":["trace[261804129] 'process raft request' (duration: 98.022879ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:21:48.451222Z","caller":"traceutil/trace.go:171","msg":"trace[118531587] transaction","detail":"{read_only:false; number_of_response:0; response_revision:298; }","duration":"110.293497ms","start":"2026-07-02T15:21:48.340906Z","end":"2026-07-02T15:21:48.451199Z","steps":["trace[118531587] 'process raft request' (duration: 110.264436ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:21:48.451273Z","caller":"traceutil/trace.go:171","msg":"trace[774432787] transaction","detail":"{read_only:false; number_of_response:0; response_revision:298; }","duration":"110.916954ms","start":"2026-07-02T15:21:48.340344Z","end":"2026-07-02T15:21:48.451261Z","steps":["trace[774432787] 'process raft request' (duration: 110.77816ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:21:48.451444Z","caller":"traceutil/trace.go:171","msg":"trace[1706649902] transaction","detail":"{read_only:false; number_of_response:0; response_revision:298; }","duration":"110.959184ms","start":"2026-07-02T15:21:48.340478Z","end":"2026-07-02T15:21:48.451437Z","steps":["trace[1706649902] 'process raft request' (duration: 110.671997ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:21:48.45134Z","caller":"traceutil/trace.go:171","msg":"trace[2114098300] transaction","detail":"{read_only:false; response_revision:298; number_of_response:1; }","duration":"111.103398ms","start":"2026-07-02T15:21:48.340175Z","end":"2026-07-02T15:21:48.451279Z","steps":["trace[2114098300] 'process raft request' (duration: 110.863672ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:21:49.367785Z","caller":"traceutil/trace.go:171","msg":"trace[267646893] transaction","detail":"{read_only:false; number_of_response:0; response_revision:312; }","duration":"105.640006ms","start":"2026-07-02T15:21:49.262104Z","end":"2026-07-02T15:21:49.367744Z","steps":["trace[267646893] 'process raft request' (duration: 105.216295ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:21:49.382064Z","caller":"traceutil/trace.go:171","msg":"trace[956438024] linearizableReadLoop","detail":"{readStateIndex:325; appliedIndex:323; }","duration":"103.15339ms","start":"2026-07-02T15:21:49.278877Z","end":"2026-07-02T15:21:49.38203Z","steps":["trace[956438024] 'read index received' (duration: 88.649936ms)","trace[956438024] 'applied index is now lower than readState.Index' (duration: 14.502443ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:21:49.382173Z","caller":"traceutil/trace.go:171","msg":"trace[1110468997] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"111.028529ms","start":"2026-07-02T15:21:49.271118Z","end":"2026-07-02T15:21:49.382146Z","steps":["trace[1110468997] 'process raft request' (duration: 110.774272ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:21:49.382319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.418837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" ","response":"range_response_count:1 size:205"} {"level":"info","ts":"2026-07-02T15:21:49.382384Z","caller":"traceutil/trace.go:171","msg":"trace[1899582386] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/namespace-controller; range_end:; response_count:1; response_revision:313; }","duration":"103.54176ms","start":"2026-07-02T15:21:49.278825Z","end":"2026-07-02T15:21:49.382366Z","steps":["trace[1899582386] 'agreement among raft nodes before linearized reading' (duration: 103.367566ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:21:51.094767Z","caller":"traceutil/trace.go:171","msg":"trace[1873921889] transaction","detail":"{read_only:false; response_revision:332; number_of_response:1; }","duration":"120.316132ms","start":"2026-07-02T15:21:50.97442Z","end":"2026-07-02T15:21:51.094736Z","steps":["trace[1873921889] 'process raft request' (duration: 66.054403ms)","trace[1873921889] 'compare' (duration: 53.887559ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:21:51.095254Z","caller":"traceutil/trace.go:171","msg":"trace[397612144] linearizableReadLoop","detail":"{readStateIndex:345; appliedIndex:343; }","duration":"116.620965ms","start":"2026-07-02T15:21:50.978618Z","end":"2026-07-02T15:21:51.095239Z","steps":["trace[397612144] 'read index received' (duration: 61.992616ms)","trace[397612144] 'applied index is now lower than readState.Index' (duration: 54.627339ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:21:51.095361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.722498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T15:21:51.095425Z","caller":"traceutil/trace.go:171","msg":"trace[1998202116] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:0; response_revision:333; }","duration":"116.81347ms","start":"2026-07-02T15:21:50.9786Z","end":"2026-07-02T15:21:51.095414Z","steps":["trace[1998202116] 'agreement among raft nodes before linearized reading' (duration: 116.699697ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:21:51.095642Z","caller":"traceutil/trace.go:171","msg":"trace[881514768] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"118.940436ms","start":"2026-07-02T15:21:50.976689Z","end":"2026-07-02T15:21:51.09563Z","steps":["trace[881514768] 'process raft request' (duration: 118.012052ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:21:52.518318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.143251ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T15:21:52.51845Z","caller":"traceutil/trace.go:171","msg":"trace[306635443] linearizableReadLoop","detail":"{readStateIndex:363; appliedIndex:362; }","duration":"189.375012ms","start":"2026-07-02T15:21:52.329053Z","end":"2026-07-02T15:21:52.518428Z","steps":["trace[306635443] 'read index received' (duration: 49.7675ms)","trace[306635443] 'applied index is now lower than readState.Index' (duration: 139.605912ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:21:52.518564Z","caller":"traceutil/trace.go:171","msg":"trace[255894091] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"238.460785ms","start":"2026-07-02T15:21:52.280087Z","end":"2026-07-02T15:21:52.518547Z","steps":["trace[255894091] 'process raft request' (duration: 98.805411ms)","trace[255894091] 'compare' (duration: 138.993977ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:21:52.519036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.984128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-07-02T15:21:52.51908Z","caller":"traceutil/trace.go:171","msg":"trace[477288957] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:351; }","duration":"190.06386ms","start":"2026-07-02T15:21:52.329005Z","end":"2026-07-02T15:21:52.519068Z","steps":["trace[477288957] 'agreement among raft nodes before linearized reading' (duration: 189.984208ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:21:52.51915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.834539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-07-02T15:21:52.519222Z","caller":"traceutil/trace.go:171","msg":"trace[1125632882] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:351; }","duration":"139.960421ms","start":"2026-07-02T15:21:52.379244Z","end":"2026-07-02T15:21:52.519204Z","steps":["trace[1125632882] 'agreement among raft nodes before linearized reading' (duration: 139.807647ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:21:52.732399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.984538ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T15:21:52.732815Z","caller":"traceutil/trace.go:171","msg":"trace[822893469] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"118.17219ms","start":"2026-07-02T15:21:52.614615Z","end":"2026-07-02T15:21:52.732787Z","steps":["trace[822893469] 'compare' (duration: 112.844555ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:21:52.763899Z","caller":"traceutil/trace.go:171","msg":"trace[2116397719] linearizableReadLoop","detail":"{readStateIndex:368; appliedIndex:367; }","duration":"134.051089ms","start":"2026-07-02T15:21:52.629822Z","end":"2026-07-02T15:21:52.763873Z","steps":["trace[2116397719] 'read index received' (duration: 133.992658ms)","trace[2116397719] 'applied index is now lower than readState.Index' (duration: 57.561µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:21:52.764028Z","caller":"traceutil/trace.go:171","msg":"trace[442641707] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"142.648471ms","start":"2026-07-02T15:21:52.621354Z","end":"2026-07-02T15:21:52.764002Z","steps":["trace[442641707] 'process raft request' (duration: 142.393514ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:21:52.764237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.375908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-07-02T15:21:52.764366Z","caller":"traceutil/trace.go:171","msg":"trace[1411056337] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:356; }","duration":"134.529422ms","start":"2026-07-02T15:21:52.629805Z","end":"2026-07-02T15:21:52.764335Z","steps":["trace[1411056337] 'agreement among raft nodes before linearized reading' (duration: 134.231674ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:22:19.39674Z","caller":"traceutil/trace.go:171","msg":"trace[672811215] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"128.616514ms","start":"2026-07-02T15:22:19.268095Z","end":"2026-07-02T15:22:19.396712Z","steps":["trace[672811215] 'process raft request' (duration: 128.291687ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:22:35.914683Z","caller":"traceutil/trace.go:171","msg":"trace[1107188540] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"412.204762ms","start":"2026-07-02T15:22:35.502451Z","end":"2026-07-02T15:22:35.914656Z","steps":["trace[1107188540] 'process raft request' (duration: 412.06248ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:22:35.914837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:22:35.502419Z","time spent":"412.342365ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T15:22:35.915161Z","caller":"traceutil/trace.go:171","msg":"trace[1062307716] linearizableReadLoop","detail":"{readStateIndex:690; appliedIndex:690; }","duration":"400.798314ms","start":"2026-07-02T15:22:35.514332Z","end":"2026-07-02T15:22:35.91513Z","steps":["trace[1062307716] 'read index received' (duration: 400.774913ms)","trace[1062307716] 'applied index is now lower than readState.Index' (duration: 21.811µs)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:22:35.915431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.036828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-02T15:22:35.9155Z","caller":"traceutil/trace.go:171","msg":"trace[2072681226] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:660; }","duration":"401.204871ms","start":"2026-07-02T15:22:35.514276Z","end":"2026-07-02T15:22:35.915481Z","steps":["trace[2072681226] 'agreement among raft nodes before linearized reading' (duration: 400.955537ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:22:35.915553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:22:35.514255Z","time spent":"401.286673ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-02T15:22:40.449053Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2403971275591391408,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-02T15:22:40.460678Z","caller":"traceutil/trace.go:171","msg":"trace[1776922962] linearizableReadLoop","detail":"{readStateIndex:701; appliedIndex:700; }","duration":"511.863362ms","start":"2026-07-02T15:22:39.948778Z","end":"2026-07-02T15:22:40.460642Z","steps":["trace[1776922962] 'read index received' (duration: 511.554627ms)","trace[1776922962] 'applied index is now lower than readState.Index' (duration: 307.055µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:22:40.46075Z","caller":"traceutil/trace.go:171","msg":"trace[1686274503] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"519.213353ms","start":"2026-07-02T15:22:39.941494Z","end":"2026-07-02T15:22:40.460707Z","steps":["trace[1686274503] 'process raft request' (duration: 518.957778ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:22:40.460963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.152437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-02T15:22:40.460998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:22:39.941471Z","time spent":"519.368076ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T15:22:40.461093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.342628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-07-02T15:22:40.461091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.002115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-02T15:22:40.461018Z","caller":"traceutil/trace.go:171","msg":"trace[1135840798] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:670; }","duration":"512.249089ms","start":"2026-07-02T15:22:39.948753Z","end":"2026-07-02T15:22:40.461002Z","steps":["trace[1135840798] 'agreement among raft nodes before linearized reading' (duration: 512.061175ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:22:40.461179Z","caller":"traceutil/trace.go:171","msg":"trace[944256992] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:670; }","duration":"294.48601ms","start":"2026-07-02T15:22:40.166663Z","end":"2026-07-02T15:22:40.461149Z","steps":["trace[944256992] 'agreement among raft nodes before linearized reading' (duration: 294.253536ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:22:40.46118Z","caller":"traceutil/trace.go:171","msg":"trace[191961542] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:670; }","duration":"444.138578ms","start":"2026-07-02T15:22:40.017023Z","end":"2026-07-02T15:22:40.461162Z","steps":["trace[191961542] 'agreement among raft nodes before linearized reading' (duration: 443.862623ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:22:40.46123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:22:40.017007Z","time spent":"444.214139ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-02T15:22:40.461231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:22:39.948742Z","time spent":"512.458342ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-02T15:22:43.960337Z","caller":"traceutil/trace.go:171","msg":"trace[1050898543] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"535.405416ms","start":"2026-07-02T15:22:43.424906Z","end":"2026-07-02T15:22:43.960311Z","steps":["trace[1050898543] 'process raft request' (duration: 535.259314ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:22:43.960498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:22:43.424881Z","time spent":"535.527798ms","remote":"127.0.0.1:38050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T15:22:45.575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.099883298s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-02T15:22:45.57504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.689082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-02T15:22:45.575091Z","caller":"traceutil/trace.go:171","msg":"trace[1883643163] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:682; }","duration":"1.100041891s","start":"2026-07-02T15:22:44.475022Z","end":"2026-07-02T15:22:45.575064Z","steps":["trace[1883643163] 'range keys from in-memory index tree' (duration: 1.099571963s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:22:45.575071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.091846349s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-02T15:22:45.575143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:22:44.47499Z","time spent":"1.100143253s","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-02T15:22:45.575142Z","caller":"traceutil/trace.go:171","msg":"trace[434670049] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:682; }","duration":"583.854305ms","start":"2026-07-02T15:22:44.99126Z","end":"2026-07-02T15:22:45.575114Z","steps":["trace[434670049] 'count revisions from in-memory index tree' (duration: 583.608961ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:22:45.575175Z","caller":"traceutil/trace.go:171","msg":"trace[1083955617] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:682; }","duration":"1.092001052s","start":"2026-07-02T15:22:44.48314Z","end":"2026-07-02T15:22:45.575141Z","steps":["trace[1083955617] 'range keys from in-memory index tree' (duration: 1.091662966s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:22:45.575172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.09651066s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-02T15:22:45.575223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:22:44.483121Z","time spent":"1.092091543s","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-02T15:22:45.575256Z","caller":"traceutil/trace.go:171","msg":"trace[2094484367] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:682; }","duration":"1.096638372s","start":"2026-07-02T15:22:44.478596Z","end":"2026-07-02T15:22:45.575234Z","steps":["trace[2094484367] 'range keys from in-memory index tree' (duration: 1.096274285s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:22:45.575256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.161252217s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T15:22:45.575265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.09247231s","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-07-02T15:22:45.575301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:22:44.478575Z","time spent":"1.096716273s","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-02T15:22:45.575193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:22:44.991239Z","time spent":"583.942147ms","remote":"127.0.0.1:38106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":31,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"info","ts":"2026-07-02T15:22:45.575315Z","caller":"traceutil/trace.go:171","msg":"trace[888169524] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:682; }","duration":"1.161350129s","start":"2026-07-02T15:22:44.413948Z","end":"2026-07-02T15:22:45.575298Z","steps":["trace[888169524] 'range keys from in-memory index tree' (duration: 1.161114815s)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:22:45.575324Z","caller":"traceutil/trace.go:171","msg":"trace[1733231666] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:682; }","duration":"1.092571292s","start":"2026-07-02T15:22:44.482736Z","end":"2026-07-02T15:22:45.575308Z","steps":["trace[1733231666] 'range keys from in-memory index tree' (duration: 1.092263586s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:22:45.575402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:22:44.413918Z","time spent":"1.16143924s","remote":"127.0.0.1:37882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-02T15:22:45.5754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:22:44.482711Z","time spent":"1.092647593s","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-02T15:22:45.575044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.172865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T15:22:45.575485Z","caller":"traceutil/trace.go:171","msg":"trace[262918331] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:682; }","duration":"706.723105ms","start":"2026-07-02T15:22:44.868749Z","end":"2026-07-02T15:22:45.575459Z","steps":["trace[262918331] 'count revisions from in-memory index tree' (duration: 706.043253ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:22:45.57553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:22:44.868728Z","time spent":"706.786956ms","remote":"127.0.0.1:38168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-02T15:22:46.087879Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2403971275591391446,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-02T15:22:46.410515Z","caller":"traceutil/trace.go:171","msg":"trace[1004940495] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"828.992266ms","start":"2026-07-02T15:22:45.581463Z","end":"2026-07-02T15:22:46.410456Z","steps":["trace[1004940495] 'process raft request' (duration: 828.749272ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:22:46.410819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:22:45.581428Z","time spent":"829.26028ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T15:22:46.413778Z","caller":"traceutil/trace.go:171","msg":"trace[291998863] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"831.184692ms","start":"2026-07-02T15:22:45.582577Z","end":"2026-07-02T15:22:46.413762Z","steps":["trace[291998863] 'process raft request' (duration: 831.07646ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:22:46.413861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:22:45.582551Z","time spent":"831.267334ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T15:22:46.414154Z","caller":"traceutil/trace.go:171","msg":"trace[2010991780] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"831.393586ms","start":"2026-07-02T15:22:45.582736Z","end":"2026-07-02T15:22:46.414129Z","steps":["trace[2010991780] 'process raft request' (duration: 830.991559ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:22:46.414502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:22:45.582726Z","time spent":"831.60984ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T15:22:46.414905Z","caller":"traceutil/trace.go:171","msg":"trace[1195699939] linearizableReadLoop","detail":"{readStateIndex:719; appliedIndex:716; }","duration":"827.945467ms","start":"2026-07-02T15:22:45.586905Z","end":"2026-07-02T15:22:46.41485Z","steps":["trace[1195699939] 'read index received' (duration: 824.075111ms)","trace[1195699939] 'applied index is now lower than readState.Index' (duration: 3.866746ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:22:46.415055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"828.110769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T15:22:46.415136Z","caller":"traceutil/trace.go:171","msg":"trace[1362493480] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:686; }","duration":"828.222592ms","start":"2026-07-02T15:22:45.586866Z","end":"2026-07-02T15:22:46.415089Z","steps":["trace[1362493480] 'agreement among raft nodes before linearized reading' (duration: 828.090709ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:22:46.415177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:22:45.586855Z","time spent":"828.313153ms","remote":"127.0.0.1:37882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-02T15:22:46.415389Z","caller":"traceutil/trace.go:171","msg":"trace[1398949229] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"832.384103ms","start":"2026-07-02T15:22:45.582954Z","end":"2026-07-02T15:22:46.415338Z","steps":["trace[1398949229] 'process raft request' (duration: 831.128162ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:22:46.415628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:22:45.58292Z","time spent":"832.557406ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T15:22:51.796309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.117807ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T15:22:51.796404Z","caller":"traceutil/trace.go:171","msg":"trace[1191095976] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:755; }","duration":"193.224979ms","start":"2026-07-02T15:22:51.603164Z","end":"2026-07-02T15:22:51.796389Z","steps":["trace[1191095976] 'range keys from in-memory index tree' (duration: 193.103737ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:22:51.799513Z","caller":"traceutil/trace.go:171","msg":"trace[1019427574] linearizableReadLoop","detail":"{readStateIndex:790; appliedIndex:789; }","duration":"145.52158ms","start":"2026-07-02T15:22:51.653981Z","end":"2026-07-02T15:22:51.799502Z","steps":["trace[1019427574] 'read index received' (duration: 145.320976ms)","trace[1019427574] 'applied index is now lower than readState.Index' (duration: 200.024µs)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:22:51.799608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.623541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T15:22:51.79963Z","caller":"traceutil/trace.go:171","msg":"trace[624339456] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:756; }","duration":"145.688833ms","start":"2026-07-02T15:22:51.653932Z","end":"2026-07-02T15:22:51.799621Z","steps":["trace[624339456] 'agreement among raft nodes before linearized reading' (duration: 145.630982ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:22:51.799615Z","caller":"traceutil/trace.go:171","msg":"trace[804849284] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"165.378598ms","start":"2026-07-02T15:22:51.634201Z","end":"2026-07-02T15:22:51.799579Z","steps":["trace[804849284] 'process raft request' (duration: 165.151654ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:23:00.434807Z","caller":"traceutil/trace.go:171","msg":"trace[984402063] transaction","detail":"{read_only:false; response_revision:827; number_of_response:1; }","duration":"157.425111ms","start":"2026-07-02T15:23:00.27736Z","end":"2026-07-02T15:23:00.434785Z","steps":["trace[984402063] 'process raft request' (duration: 157.002764ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:04.183153Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.23131276s","expected-duration":"1s"} {"level":"info","ts":"2026-07-02T15:23:04.183681Z","caller":"traceutil/trace.go:171","msg":"trace[1517130264] transaction","detail":"{read_only:false; response_revision:846; number_of_response:1; }","duration":"1.231934859s","start":"2026-07-02T15:23:02.951723Z","end":"2026-07-02T15:23:04.183658Z","steps":["trace[1517130264] 'process raft request' (duration: 1.231735636s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:04.183895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:02.951695Z","time spent":"1.232065192s","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T15:23:04.243194Z","caller":"traceutil/trace.go:171","msg":"trace[1687558845] transaction","detail":"{read_only:false; response_revision:847; number_of_response:1; }","duration":"712.234081ms","start":"2026-07-02T15:23:03.530938Z","end":"2026-07-02T15:23:04.243172Z","steps":["trace[1687558845] 'process raft request' (duration: 712.085808ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:04.243497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:03.530919Z","time spent":"712.345253ms","remote":"127.0.0.1:38084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T15:23:11.46873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.122693ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-02T15:23:11.468881Z","caller":"traceutil/trace.go:171","msg":"trace[906808325] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"410.566753ms","start":"2026-07-02T15:23:11.058291Z","end":"2026-07-02T15:23:11.468857Z","steps":["trace[906808325] 'process raft request' (duration: 177.252977ms)","trace[906808325] 'compare' (duration: 232.752178ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:23:11.46895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:11.05827Z","time spent":"410.650154ms","remote":"127.0.0.1:37950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":719,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T15:23:11.666116Z","caller":"traceutil/trace.go:171","msg":"trace[2014343448] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"605.1683ms","start":"2026-07-02T15:23:11.06091Z","end":"2026-07-02T15:23:11.666078Z","steps":["trace[2014343448] 'process raft request' (duration: 577.233572ms)","trace[2014343448] 'compare' (duration: 27.671534ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:23:11.666297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.764835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"warn","ts":"2026-07-02T15:23:11.666353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:11.06089Z","time spent":"605.348483ms","remote":"127.0.0.1:38050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4761,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T15:23:11.666337Z","caller":"traceutil/trace.go:171","msg":"trace[1432223283] linearizableReadLoop","detail":"{readStateIndex:963; appliedIndex:962; }","duration":"345.467461ms","start":"2026-07-02T15:23:11.320496Z","end":"2026-07-02T15:23:11.665963Z","steps":["trace[1432223283] 'read index received' (duration: 317.658075ms)","trace[1432223283] 'applied index is now lower than readState.Index' (duration: 27.808566ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:23:11.666377Z","caller":"traceutil/trace.go:171","msg":"trace[665658235] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:924; }","duration":"345.904768ms","start":"2026-07-02T15:23:11.320454Z","end":"2026-07-02T15:23:11.666358Z","steps":["trace[665658235] 'agreement among raft nodes before linearized reading' (duration: 345.715845ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:11.666425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:11.320432Z","time spent":"345.983549ms","remote":"127.0.0.1:38060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":203,"request content":"key:\"/registry/serviceaccounts/kube-system/coredns\" "} {"level":"warn","ts":"2026-07-02T15:23:11.666502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.928869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-07-02T15:23:11.666652Z","caller":"traceutil/trace.go:171","msg":"trace[603109605] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:924; }","duration":"346.103911ms","start":"2026-07-02T15:23:11.320535Z","end":"2026-07-02T15:23:11.666639Z","steps":["trace[603109605] 'agreement among raft nodes before linearized reading' (duration: 345.906298ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:11.666697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:11.32053Z","time spent":"346.156232ms","remote":"127.0.0.1:38060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":203,"request content":"key:\"/registry/serviceaccounts/kube-system/coredns\" "} {"level":"info","ts":"2026-07-02T15:23:12.368461Z","caller":"traceutil/trace.go:171","msg":"trace[1468811088] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"691.255652ms","start":"2026-07-02T15:23:11.677176Z","end":"2026-07-02T15:23:12.368431Z","steps":["trace[1468811088] 'process raft request' (duration: 691.042099ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:12.368658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:11.677156Z","time spent":"691.394004ms","remote":"127.0.0.1:38050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4761,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T15:23:12.731095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.76618ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-02T15:23:12.731199Z","caller":"traceutil/trace.go:171","msg":"trace[468718206] linearizableReadLoop","detail":"{readStateIndex:965; appliedIndex:964; }","duration":"317.889938ms","start":"2026-07-02T15:23:12.413287Z","end":"2026-07-02T15:23:12.731177Z","steps":["trace[468718206] 'read index received' (duration: 38.521µs)","trace[468718206] 'applied index is now lower than readState.Index' (duration: 317.849677ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:23:12.731312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.01523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T15:23:12.731339Z","caller":"traceutil/trace.go:171","msg":"trace[280021947] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:925; }","duration":"318.0905ms","start":"2026-07-02T15:23:12.413239Z","end":"2026-07-02T15:23:12.731329Z","steps":["trace[280021947] 'agreement among raft nodes before linearized reading' (duration: 318.020449ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:12.731405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:12.413217Z","time spent":"318.176182ms","remote":"127.0.0.1:37882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-02T15:23:12.768988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.109079ms","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-07-02T15:23:12.769206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.526911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-02T15:23:12.76907Z","caller":"traceutil/trace.go:171","msg":"trace[1872806767] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:926; }","duration":"213.243601ms","start":"2026-07-02T15:23:12.555811Z","end":"2026-07-02T15:23:12.769055Z","steps":["trace[1872806767] 'agreement among raft nodes before linearized reading' (duration: 213.066939ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:12.769351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.522935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-02T15:23:12.769434Z","caller":"traceutil/trace.go:171","msg":"trace[1435025751] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:926; }","duration":"176.649657ms","start":"2026-07-02T15:23:12.592761Z","end":"2026-07-02T15:23:12.76941Z","steps":["trace[1435025751] 'agreement among raft nodes before linearized reading' (duration: 176.403524ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:23:12.769451Z","caller":"traceutil/trace.go:171","msg":"trace[1034604840] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"146.159074ms","start":"2026-07-02T15:23:12.623255Z","end":"2026-07-02T15:23:12.769414Z","steps":["trace[1034604840] 'process raft request' (duration: 145.170639ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:12.76947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.969402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-02T15:23:12.769312Z","caller":"traceutil/trace.go:171","msg":"trace[1505511854] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:926; }","duration":"284.680434ms","start":"2026-07-02T15:23:12.484611Z","end":"2026-07-02T15:23:12.769291Z","steps":["trace[1505511854] 'agreement among raft nodes before linearized reading' (duration: 284.358999ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:23:12.769532Z","caller":"traceutil/trace.go:171","msg":"trace[726489748] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:926; }","duration":"177.060513ms","start":"2026-07-02T15:23:12.592443Z","end":"2026-07-02T15:23:12.769504Z","steps":["trace[726489748] 'agreement among raft nodes before linearized reading' (duration: 176.915291ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:12.769808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.62382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-02T15:23:12.769898Z","caller":"traceutil/trace.go:171","msg":"trace[756474490] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:926; }","duration":"189.731772ms","start":"2026-07-02T15:23:12.580149Z","end":"2026-07-02T15:23:12.769881Z","steps":["trace[756474490] 'agreement among raft nodes before linearized reading' (duration: 188.703486ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:19.123245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.158317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T15:23:19.123323Z","caller":"traceutil/trace.go:171","msg":"trace[375260683] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1013; }","duration":"344.268058ms","start":"2026-07-02T15:23:18.779025Z","end":"2026-07-02T15:23:19.123293Z","steps":["trace[375260683] 'count revisions from in-memory index tree' (duration: 344.070826ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:19.123352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:18.779002Z","time spent":"344.34309ms","remote":"127.0.0.1:38062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"info","ts":"2026-07-02T15:23:19.173463Z","caller":"traceutil/trace.go:171","msg":"trace[628383209] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"386.979614ms","start":"2026-07-02T15:23:18.786429Z","end":"2026-07-02T15:23:19.173408Z","steps":["trace[628383209] 'process raft request' (duration: 386.781931ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:19.173626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:18.786412Z","time spent":"387.134216ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T15:23:19.17385Z","caller":"traceutil/trace.go:171","msg":"trace[164573130] linearizableReadLoop","detail":"{readStateIndex:1055; appliedIndex:1054; }","duration":"370.486815ms","start":"2026-07-02T15:23:18.802832Z","end":"2026-07-02T15:23:19.173319Z","steps":["trace[164573130] 'read index received' (duration: 370.289252ms)","trace[164573130] 'applied index is now lower than readState.Index' (duration: 196.823µs)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:23:19.174029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.798418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-02T15:23:19.174081Z","caller":"traceutil/trace.go:171","msg":"trace[236320241] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1014; }","duration":"365.863758ms","start":"2026-07-02T15:23:18.808182Z","end":"2026-07-02T15:23:19.174045Z","steps":["trace[236320241] 'agreement among raft nodes before linearized reading' (duration: 365.756817ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:19.174101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:18.808168Z","time spent":"365.928389ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-02T15:23:19.174259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.237856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-02T15:23:19.174277Z","caller":"traceutil/trace.go:171","msg":"trace[1696698544] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1014; }","duration":"351.277208ms","start":"2026-07-02T15:23:18.822996Z","end":"2026-07-02T15:23:19.174273Z","steps":["trace[1696698544] 'agreement among raft nodes before linearized reading' (duration: 351.230617ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:19.17426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.953502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-07-02T15:23:19.174514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.882044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-02T15:23:19.174514Z","caller":"traceutil/trace.go:171","msg":"trace[604328154] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1014; }","duration":"351.234607ms","start":"2026-07-02T15:23:18.823264Z","end":"2026-07-02T15:23:19.174499Z","steps":["trace[604328154] 'agreement among raft nodes before linearized reading' (duration: 350.891632ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:23:19.174535Z","caller":"traceutil/trace.go:171","msg":"trace[770514355] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1014; }","duration":"355.922044ms","start":"2026-07-02T15:23:18.818609Z","end":"2026-07-02T15:23:19.174531Z","steps":["trace[770514355] 'agreement among raft nodes before linearized reading' (duration: 355.879214ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:19.174547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:18.818598Z","time spent":"355.946425ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-02T15:23:19.174545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:18.823252Z","time spent":"351.286247ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-02T15:23:19.174561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.699913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-02T15:23:19.174676Z","caller":"traceutil/trace.go:171","msg":"trace[175990899] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1014; }","duration":"371.855055ms","start":"2026-07-02T15:23:18.802795Z","end":"2026-07-02T15:23:19.17465Z","steps":["trace[175990899] 'agreement among raft nodes before linearized reading' (duration: 370.754929ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:19.174744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:18.802783Z","time spent":"371.947176ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-02T15:23:19.17448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:18.822985Z","time spent":"351.491741ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-02T15:23:21.486218Z","caller":"traceutil/trace.go:171","msg":"trace[1923948052] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"296.715914ms","start":"2026-07-02T15:23:21.189477Z","end":"2026-07-02T15:23:21.486193Z","steps":["trace[1923948052] 'process raft request' (duration: 278.100988ms)","trace[1923948052] 'compare' (duration: 18.394033ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:23:21.486227Z","caller":"traceutil/trace.go:171","msg":"trace[529014045] linearizableReadLoop","detail":"{readStateIndex:1069; appliedIndex:1068; }","duration":"275.759214ms","start":"2026-07-02T15:23:21.210423Z","end":"2026-07-02T15:23:21.486183Z","steps":["trace[529014045] 'read index received' (duration: 257.202559ms)","trace[529014045] 'applied index is now lower than readState.Index' (duration: 18.555155ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:23:21.486412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.970987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-02T15:23:21.486494Z","caller":"traceutil/trace.go:171","msg":"trace[1754765176] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1028; }","duration":"276.092609ms","start":"2026-07-02T15:23:21.210382Z","end":"2026-07-02T15:23:21.486475Z","steps":["trace[1754765176] 'agreement among raft nodes before linearized reading' (duration: 275.893306ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:21.486703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.567991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-02T15:23:21.48694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.452419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-02T15:23:21.487015Z","caller":"traceutil/trace.go:171","msg":"trace[436054975] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1028; }","duration":"275.546282ms","start":"2026-07-02T15:23:21.211451Z","end":"2026-07-02T15:23:21.486998Z","steps":["trace[436054975] 'agreement among raft nodes before linearized reading' (duration: 274.940553ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:23:21.48681Z","caller":"traceutil/trace.go:171","msg":"trace[969504442] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1028; }","duration":"275.692304ms","start":"2026-07-02T15:23:21.211099Z","end":"2026-07-02T15:23:21.486791Z","steps":["trace[969504442] 'agreement among raft nodes before linearized reading' (duration: 275.390319ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:21.486536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.701954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-02T15:23:21.487736Z","caller":"traceutil/trace.go:171","msg":"trace[1932124660] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1028; }","duration":"276.928201ms","start":"2026-07-02T15:23:21.210789Z","end":"2026-07-02T15:23:21.487717Z","steps":["trace[1932124660] 'agreement among raft nodes before linearized reading' (duration: 275.583762ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:23:27.72613Z","caller":"traceutil/trace.go:171","msg":"trace[794245414] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"111.433577ms","start":"2026-07-02T15:23:27.614671Z","end":"2026-07-02T15:23:27.726104Z","steps":["trace[794245414] 'process raft request' (duration: 111.381766ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:23:27.726286Z","caller":"traceutil/trace.go:171","msg":"trace[505068317] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"111.6824ms","start":"2026-07-02T15:23:27.614554Z","end":"2026-07-02T15:23:27.726236Z","steps":["trace[505068317] 'process raft request' (duration: 111.373725ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:30.464113Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2403971275591392251,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T15:23:31.385967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"921.300364ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T15:23:31.386179Z","caller":"traceutil/trace.go:171","msg":"trace[965566019] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"918.440224ms","start":"2026-07-02T15:23:30.467722Z","end":"2026-07-02T15:23:31.386162Z","steps":["trace[965566019] 'process raft request' (duration: 918.363463ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:31.386296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:30.467702Z","time spent":"918.518255ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T15:23:31.386301Z","caller":"traceutil/trace.go:171","msg":"trace[25013700] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"1.774490456s","start":"2026-07-02T15:23:29.611747Z","end":"2026-07-02T15:23:31.386237Z","steps":["trace[25013700] 'process raft request' (duration: 852.829757ms)","trace[25013700] 'compare' (duration: 920.970779ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:23:31.386445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:29.611721Z","time spent":"1.774638298s","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T15:23:31.386473Z","caller":"traceutil/trace.go:171","msg":"trace[1539486433] linearizableReadLoop","detail":"{readStateIndex:1098; appliedIndex:1097; }","duration":"1.737110537s","start":"2026-07-02T15:23:29.64935Z","end":"2026-07-02T15:23:31.386461Z","steps":["trace[1539486433] 'read index received' (duration: 816.799257ms)","trace[1539486433] 'applied index is now lower than readState.Index' (duration: 920.30987ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:23:31.386664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.73732452s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-02T15:23:31.386695Z","caller":"traceutil/trace.go:171","msg":"trace[277605702] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1056; }","duration":"1.737396631s","start":"2026-07-02T15:23:29.649291Z","end":"2026-07-02T15:23:31.386687Z","steps":["trace[277605702] 'agreement among raft nodes before linearized reading' (duration: 1.73729492s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:31.386725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:29.64926Z","time spent":"1.737455042s","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-02T15:23:31.451845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"765.855794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T15:23:31.451934Z","caller":"traceutil/trace.go:171","msg":"trace[2051790976] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1057; }","duration":"766.000545ms","start":"2026-07-02T15:23:30.68592Z","end":"2026-07-02T15:23:31.45192Z","steps":["trace[2051790976] 'agreement among raft nodes before linearized reading' (duration: 765.836313ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:31.451841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.721345332s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-07-02T15:23:31.451926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.469836706s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T15:23:31.451969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:30.685904Z","time spent":"766.057646ms","remote":"127.0.0.1:38236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"info","ts":"2026-07-02T15:23:31.451994Z","caller":"traceutil/trace.go:171","msg":"trace[701332010] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1057; }","duration":"1.469967017s","start":"2026-07-02T15:23:29.982009Z","end":"2026-07-02T15:23:31.451976Z","steps":["trace[701332010] 'agreement among raft nodes before linearized reading' (duration: 1.469839755s)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:23:31.451979Z","caller":"traceutil/trace.go:171","msg":"trace[249990356] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1057; }","duration":"1.721552715s","start":"2026-07-02T15:23:29.730411Z","end":"2026-07-02T15:23:31.451964Z","steps":["trace[249990356] 'agreement among raft nodes before linearized reading' (duration: 1.721287531s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:31.451999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"981.008845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T15:23:31.45204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:29.981949Z","time spent":"1.470081249s","remote":"127.0.0.1:38008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":29,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-07-02T15:23:31.451943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.721391673s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-02T15:23:31.452078Z","caller":"traceutil/trace.go:171","msg":"trace[1687429367] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1057; }","duration":"1.721569975s","start":"2026-07-02T15:23:29.730498Z","end":"2026-07-02T15:23:31.452068Z","steps":["trace[1687429367] 'agreement among raft nodes before linearized reading' (duration: 1.721389172s)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:23:31.452051Z","caller":"traceutil/trace.go:171","msg":"trace[1575247298] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1057; }","duration":"981.135946ms","start":"2026-07-02T15:23:30.470901Z","end":"2026-07-02T15:23:31.452037Z","steps":["trace[1575247298] 'agreement among raft nodes before linearized reading' (duration: 980.989634ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:31.452096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:29.730467Z","time spent":"1.721623976s","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-02T15:23:31.452118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:30.470887Z","time spent":"981.220977ms","remote":"127.0.0.1:37882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-02T15:23:31.452043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:29.730382Z","time spent":"1.721650356s","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-02T15:23:31.45234Z","caller":"traceutil/trace.go:171","msg":"trace[689995547] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"234.198452ms","start":"2026-07-02T15:23:31.218124Z","end":"2026-07-02T15:23:31.452323Z","steps":["trace[689995547] 'process raft request' (duration: 233.437022ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:31.954309Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2403971275591392257,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T15:23:32.04283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.786726ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T15:23:32.042971Z","caller":"traceutil/trace.go:171","msg":"trace[1970503713] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1057; }","duration":"438.932128ms","start":"2026-07-02T15:23:31.60401Z","end":"2026-07-02T15:23:32.042942Z","steps":["trace[1970503713] 'range keys from in-memory index tree' (duration: 438.768806ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:23:32.043006Z","caller":"traceutil/trace.go:171","msg":"trace[1604000957] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"652.117841ms","start":"2026-07-02T15:23:31.39085Z","end":"2026-07-02T15:23:32.042968Z","steps":["trace[1604000957] 'process raft request' (duration: 567.854134ms)","trace[1604000957] 'compare' (duration: 83.857872ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:23:32.043214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:31.390833Z","time spent":"652.270583ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T15:23:32.043609Z","caller":"traceutil/trace.go:171","msg":"trace[2116055309] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"587.431291ms","start":"2026-07-02T15:23:31.456152Z","end":"2026-07-02T15:23:32.043584Z","steps":["trace[2116055309] 'process raft request' (duration: 586.755411ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:23:32.043619Z","caller":"traceutil/trace.go:171","msg":"trace[1219949382] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"586.852223ms","start":"2026-07-02T15:23:31.456749Z","end":"2026-07-02T15:23:32.043601Z","steps":["trace[1219949382] 'process raft request' (duration: 586.582459ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:32.043745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:31.456109Z","time spent":"587.556863ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T15:23:32.043746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:31.456732Z","time spent":"586.935844ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T15:23:32.04891Z","caller":"traceutil/trace.go:171","msg":"trace[325584624] linearizableReadLoop","detail":"{readStateIndex:1104; appliedIndex:1100; }","duration":"595.549962ms","start":"2026-07-02T15:23:31.453343Z","end":"2026-07-02T15:23:32.048892Z","steps":["trace[325584624] 'read index received' (duration: 505.424164ms)","trace[325584624] 'applied index is now lower than readState.Index' (duration: 90.124568ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:23:32.049132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.778698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-02T15:23:32.049088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.733475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T15:23:32.049168Z","caller":"traceutil/trace.go:171","msg":"trace[760849227] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1060; }","duration":"141.86641ms","start":"2026-07-02T15:23:31.907294Z","end":"2026-07-02T15:23:32.04916Z","steps":["trace[760849227] 'agreement among raft nodes before linearized reading' (duration: 141.776949ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:32.049225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.538148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-02T15:23:32.049298Z","caller":"traceutil/trace.go:171","msg":"trace[1891942218] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1060; }","duration":"437.641349ms","start":"2026-07-02T15:23:31.611617Z","end":"2026-07-02T15:23:32.049258Z","steps":["trace[1891942218] 'agreement among raft nodes before linearized reading' (duration: 437.400806ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:32.049362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:31.6116Z","time spent":"437.749841ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-02T15:23:32.049219Z","caller":"traceutil/trace.go:171","msg":"trace[489924472] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1060; }","duration":"595.883547ms","start":"2026-07-02T15:23:31.453292Z","end":"2026-07-02T15:23:32.049175Z","steps":["trace[489924472] 'agreement among raft nodes before linearized reading' (duration: 595.730195ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:32.04956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:31.453268Z","time spent":"596.279882ms","remote":"127.0.0.1:37872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-02T15:23:34.384132Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2403971275591392266,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T15:23:34.884498Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2403971275591392266,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T15:23:35.145302Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.340652142s","expected-duration":"1s"} {"level":"info","ts":"2026-07-02T15:23:35.145913Z","caller":"traceutil/trace.go:171","msg":"trace[408750903] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"1.341329811s","start":"2026-07-02T15:23:33.804549Z","end":"2026-07-02T15:23:35.145879Z","steps":["trace[408750903] 'process raft request' (duration: 1.341029927s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:35.146167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:33.804535Z","time spent":"1.341487463s","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T15:23:35.805742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000321949s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-02T15:23:35.805813Z","caller":"traceutil/trace.go:171","msg":"trace[2061574286] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00041604s","start":"2026-07-02T15:23:33.805382Z","end":"2026-07-02T15:23:35.805798Z","steps":["trace[2061574286] 'agreement among raft nodes before linearized reading' (duration: 2.000330319s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:35.805846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:33.80537Z","time spent":"2.000470052s","remote":"127.0.0.1:37872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/02 15:23:35 WARNING: [core] [Server #5] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-07-02T15:23:35.942475Z","caller":"traceutil/trace.go:171","msg":"trace[1896301630] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"2.132561833s","start":"2026-07-02T15:23:33.809885Z","end":"2026-07-02T15:23:35.942447Z","steps":["trace[1896301630] 'process raft request' (duration: 2.132117277s)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:23:35.942603Z","caller":"traceutil/trace.go:171","msg":"trace[1478144352] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"1.117554506s","start":"2026-07-02T15:23:34.825027Z","end":"2026-07-02T15:23:35.942582Z","steps":["trace[1478144352] 'process raft request' (duration: 1.117256272s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:35.94275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:34.824999Z","time spent":"1.117680669s","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T15:23:35.942775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:33.809867Z","time spent":"2.132815906s","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T15:23:35.942449Z","caller":"traceutil/trace.go:171","msg":"trace[270105651] linearizableReadLoop","detail":"{readStateIndex:1108; appliedIndex:1105; }","duration":"2.136994593s","start":"2026-07-02T15:23:33.805396Z","end":"2026-07-02T15:23:35.94239Z","steps":["trace[270105651] 'read index received' (duration: 1.340330747s)","trace[270105651] 'applied index is now lower than readState.Index' (duration: 796.662596ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:23:35.945395Z","caller":"traceutil/trace.go:171","msg":"trace[916447565] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"136.800025ms","start":"2026-07-02T15:23:35.808564Z","end":"2026-07-02T15:23:35.945364Z","steps":["trace[916447565] 'process raft request' (duration: 136.677324ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:35.94554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.289289044s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-02T15:23:35.94564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.435826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T15:23:35.94569Z","caller":"traceutil/trace.go:171","msg":"trace[1225077441] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1065; }","duration":"132.493458ms","start":"2026-07-02T15:23:35.813182Z","end":"2026-07-02T15:23:35.945676Z","steps":["trace[1225077441] 'agreement among raft nodes before linearized reading' (duration: 132.416456ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:35.94571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.487345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T15:23:35.945754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.266183999s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-02T15:23:35.945752Z","caller":"traceutil/trace.go:171","msg":"trace[196024162] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1065; }","duration":"322.580305ms","start":"2026-07-02T15:23:35.62316Z","end":"2026-07-02T15:23:35.94574Z","steps":["trace[196024162] 'agreement among raft nodes before linearized reading' (duration: 322.507554ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:23:35.945786Z","caller":"traceutil/trace.go:171","msg":"trace[877999721] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1065; }","duration":"1.26621876s","start":"2026-07-02T15:23:34.67956Z","end":"2026-07-02T15:23:35.945779Z","steps":["trace[877999721] 'agreement among raft nodes before linearized reading' (duration: 1.266168259s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:35.945808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:34.679474Z","time spent":"1.266328482s","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":31,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-07-02T15:23:35.945813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:35.623123Z","time spent":"322.672237ms","remote":"127.0.0.1:38304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":29,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"info","ts":"2026-07-02T15:23:35.945634Z","caller":"traceutil/trace.go:171","msg":"trace[1736394203] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1065; }","duration":"1.289443787s","start":"2026-07-02T15:23:34.656167Z","end":"2026-07-02T15:23:35.945611Z","steps":["trace[1736394203] 'agreement among raft nodes before linearized reading' (duration: 1.289269604s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:35.945957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:34.656144Z","time spent":"1.289794561s","remote":"127.0.0.1:38332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":2,"response size":31,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-07-02T15:23:35.946038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.887384475s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-02T15:23:35.946064Z","caller":"traceutil/trace.go:171","msg":"trace[1375350733] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1065; }","duration":"1.887431017s","start":"2026-07-02T15:23:34.058626Z","end":"2026-07-02T15:23:35.946057Z","steps":["trace[1375350733] 'agreement among raft nodes before linearized reading' (duration: 1.887341065s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:35.946084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:34.058616Z","time spent":"1.887461927s","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-02T15:23:35.946185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.744412149s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-02T15:23:35.945557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.89581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-02T15:23:35.94621Z","caller":"traceutil/trace.go:171","msg":"trace[1872821199] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1065; }","duration":"1.744491661s","start":"2026-07-02T15:23:34.20171Z","end":"2026-07-02T15:23:35.946202Z","steps":["trace[1872821199] 'agreement among raft nodes before linearized reading' (duration: 1.74445617s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:35.946229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:34.201681Z","time spent":"1.744543771s","remote":"127.0.0.1:38350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":31,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-07-02T15:23:35.946238Z","caller":"traceutil/trace.go:171","msg":"trace[1276165858] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1065; }","duration":"138.636141ms","start":"2026-07-02T15:23:35.807585Z","end":"2026-07-02T15:23:35.946221Z","steps":["trace[1276165858] 'agreement among raft nodes before linearized reading' (duration: 137.884031ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:35.946722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.896890375s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-02T15:23:35.946755Z","caller":"traceutil/trace.go:171","msg":"trace[788266329] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1065; }","duration":"1.896934895s","start":"2026-07-02T15:23:34.049811Z","end":"2026-07-02T15:23:35.946746Z","steps":["trace[788266329] 'agreement among raft nodes before linearized reading' (duration: 1.896840964s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:35.946759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.898419946s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-02T15:23:35.946779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:34.049806Z","time spent":"1.896967296s","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-02T15:23:35.946816Z","caller":"traceutil/trace.go:171","msg":"trace[1346355606] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1065; }","duration":"1.898490587s","start":"2026-07-02T15:23:34.048309Z","end":"2026-07-02T15:23:35.946799Z","steps":["trace[1346355606] 'agreement among raft nodes before linearized reading' (duration: 1.898363725s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:35.946876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:34.048141Z","time spent":"1.89872074s","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-02T15:23:35.945644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.89729737s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-02T15:23:35.947116Z","caller":"traceutil/trace.go:171","msg":"trace[1147139413] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1065; }","duration":"1.898842982s","start":"2026-07-02T15:23:34.048253Z","end":"2026-07-02T15:23:35.947096Z","steps":["trace[1147139413] 'agreement among raft nodes before linearized reading' (duration: 1.897095488s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:35.947186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:34.048239Z","time spent":"1.898922063s","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-02T15:23:35.94761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.795826179s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-02T15:23:35.947649Z","caller":"traceutil/trace.go:171","msg":"trace[1998320717] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1065; }","duration":"1.797169637s","start":"2026-07-02T15:23:34.150471Z","end":"2026-07-02T15:23:35.947641Z","steps":["trace[1998320717] 'agreement among raft nodes before linearized reading' (duration: 1.795802209s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:35.94768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:34.150461Z","time spent":"1.797203739s","remote":"127.0.0.1:38284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":31,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-07-02T15:23:38.549484Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2403971275591392289,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T15:23:39.049677Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2403971275591392289,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T15:23:39.579652Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2403971275591392289,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T15:23:39.579941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.166909773s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-02T15:23:39.580049Z","caller":"traceutil/trace.go:171","msg":"trace[1623957030] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.167072425s","start":"2026-07-02T15:23:37.412962Z","end":"2026-07-02T15:23:39.580035Z","steps":["trace[1623957030] 'agreement among raft nodes before linearized reading' (duration: 2.166944363s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:39.580083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:37.412942Z","time spent":"2.167133727s","remote":"127.0.0.1:37882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/02 15:23:39 WARNING: [core] [Server #5] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-02T15:23:39.644617Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.629315777s","expected-duration":"1s"} {"level":"info","ts":"2026-07-02T15:23:39.64505Z","caller":"traceutil/trace.go:171","msg":"trace[1652155900] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"2.629830194s","start":"2026-07-02T15:23:37.015192Z","end":"2026-07-02T15:23:39.645022Z","steps":["trace[1652155900] 'process raft request' (duration: 2.629664381s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:39.645209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:37.015172Z","time spent":"2.629933785s","remote":"127.0.0.1:38084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2914,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T15:23:39.682465Z","caller":"traceutil/trace.go:171","msg":"trace[2045501619] linearizableReadLoop","detail":"{readStateIndex:1117; appliedIndex:1115; }","duration":"2.269421365s","start":"2026-07-02T15:23:37.413014Z","end":"2026-07-02T15:23:39.682435Z","steps":["trace[2045501619] 'read index received' (duration: 2.231866903s)","trace[2045501619] 'applied index is now lower than readState.Index' (duration: 37.553312ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:23:39.682613Z","caller":"traceutil/trace.go:171","msg":"trace[1311346178] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"2.530534562s","start":"2026-07-02T15:23:37.152001Z","end":"2026-07-02T15:23:39.682536Z","steps":["trace[1311346178] 'process raft request' (duration: 2.530279819s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:39.68363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.056256083s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T15:23:39.68307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.736348263s","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-07-02T15:23:39.683144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.666398763s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-07-02T15:23:39.683782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:37.151979Z","time spent":"2.531655557s","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T15:23:39.683841Z","caller":"traceutil/trace.go:171","msg":"trace[274517337] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1072; }","duration":"1.667095242s","start":"2026-07-02T15:23:38.016731Z","end":"2026-07-02T15:23:39.683827Z","steps":["trace[274517337] 'agreement among raft nodes before linearized reading' (duration: 1.666381502s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:39.683871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:38.016727Z","time spent":"1.667135872s","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-02T15:23:39.683145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.727292041s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-02T15:23:39.683912Z","caller":"traceutil/trace.go:171","msg":"trace[1948043010] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1072; }","duration":"1.728096752s","start":"2026-07-02T15:23:37.9558Z","end":"2026-07-02T15:23:39.683897Z","steps":["trace[1948043010] 'agreement among raft nodes before linearized reading' (duration: 1.727145859s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:39.683955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:37.955794Z","time spent":"1.728151653s","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-02T15:23:39.683163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.950001292s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-02T15:23:39.684033Z","caller":"traceutil/trace.go:171","msg":"trace[1975618463] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1072; }","duration":"1.950969974s","start":"2026-07-02T15:23:37.733055Z","end":"2026-07-02T15:23:39.684025Z","steps":["trace[1975618463] 'agreement among raft nodes before linearized reading' (duration: 1.949942741s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:39.684059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:37.733029Z","time spent":"1.951019396s","remote":"127.0.0.1:38060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":45,"response size":31,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-07-02T15:23:39.683223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.666684425s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-02T15:23:39.684122Z","caller":"traceutil/trace.go:171","msg":"trace[2093287897] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1072; }","duration":"1.667599588s","start":"2026-07-02T15:23:38.016497Z","end":"2026-07-02T15:23:39.684097Z","steps":["trace[2093287897] 'agreement among raft nodes before linearized reading' (duration: 1.666642016s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:39.6842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:38.016482Z","time spent":"1.66770372s","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-02T15:23:39.683287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.668953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T15:23:39.684284Z","caller":"traceutil/trace.go:171","msg":"trace[1421523207] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1072; }","duration":"103.820659ms","start":"2026-07-02T15:23:39.580451Z","end":"2026-07-02T15:23:39.684272Z","steps":["trace[1421523207] 'agreement among raft nodes before linearized reading' (duration: 102.803685ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:39.683915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.667136362s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-07-02T15:23:39.684774Z","caller":"traceutil/trace.go:171","msg":"trace[1420415786] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1072; }","duration":"1.66773324s","start":"2026-07-02T15:23:38.016758Z","end":"2026-07-02T15:23:39.684491Z","steps":["trace[1420415786] 'agreement among raft nodes before linearized reading' (duration: 1.666138958s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:39.685021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:38.016754Z","time spent":"1.668252876s","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-02T15:23:39.688888Z","caller":"traceutil/trace.go:171","msg":"trace[383631995] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1072; }","duration":"1.737042853s","start":"2026-07-02T15:23:37.9467Z","end":"2026-07-02T15:23:39.683743Z","steps":["trace[383631995] 'agreement among raft nodes before linearized reading' (duration: 1.736262622s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:39.689107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:37.94666Z","time spent":"1.742303713s","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-02T15:23:39.683708Z","caller":"traceutil/trace.go:171","msg":"trace[1049185529] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1072; }","duration":"2.057337368s","start":"2026-07-02T15:23:37.626358Z","end":"2026-07-02T15:23:39.683695Z","steps":["trace[1049185529] 'agreement among raft nodes before linearized reading' (duration: 2.056262984s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:39.689574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:37.626339Z","time spent":"2.063219987s","remote":"127.0.0.1:37966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-07-02T15:23:40.293413Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2403971275591392300,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T15:23:40.793524Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2403971275591392300,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T15:23:41.294354Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2403971275591392300,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T15:23:41.794448Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2403971275591392300,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T15:23:42.294966Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2403971275591392300,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T15:23:42.795521Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2403971275591392300,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T15:23:43.296414Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2403971275591392300,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T15:23:43.41401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00078175s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-02T15:23:43.414073Z","caller":"traceutil/trace.go:171","msg":"trace[1195563438] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000898412s","start":"2026-07-02T15:23:41.41316Z","end":"2026-07-02T15:23:43.414059Z","steps":["trace[1195563438] 'agreement among raft nodes before linearized reading' (duration: 2.00080575s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:43.41411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:41.413146Z","time spent":"2.000956422s","remote":"127.0.0.1:37882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/02 15:23:43 WARNING: [core] [Server #5] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-02T15:23:43.797232Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2403971275591392300,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T15:23:43.841435Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"4.053339213s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-02T15:23:44.000789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.397481484s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T15:23:44.000932Z","caller":"traceutil/trace.go:171","msg":"trace[820977890] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1077; }","duration":"2.397770988s","start":"2026-07-02T15:23:41.603138Z","end":"2026-07-02T15:23:44.000909Z","steps":["trace[820977890] 'range keys from in-memory index tree' (duration: 2.397465704s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:44.002065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.50468ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T15:23:44.002598Z","caller":"traceutil/trace.go:171","msg":"trace[228844643] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"4.214572743s","start":"2026-07-02T15:23:39.788008Z","end":"2026-07-02T15:23:44.00258Z","steps":["trace[228844643] 'process raft request' (duration: 4.056490265s)","trace[228844643] 'compare' (duration: 157.409109ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:23:44.002701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:39.787987Z","time spent":"4.214658644s","remote":"127.0.0.1:37950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T15:23:44.004618Z","caller":"traceutil/trace.go:171","msg":"trace[375484148] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"2.565366546s","start":"2026-07-02T15:23:41.439208Z","end":"2026-07-02T15:23:44.004575Z","steps":["trace[375484148] 'process raft request' (duration: 2.562953774s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:44.004941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:41.439198Z","time spent":"2.565603149s","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T15:23:44.163783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.545397ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-02T15:23:44.164168Z","caller":"traceutil/trace.go:171","msg":"trace[712735670] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"2.474073742s","start":"2026-07-02T15:23:41.690067Z","end":"2026-07-02T15:23:44.164141Z","steps":["trace[712735670] 'process raft request' (duration: 2.473823629s)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:23:44.164187Z","caller":"traceutil/trace.go:171","msg":"trace[1801810565] linearizableReadLoop","detail":"{readStateIndex:1126; appliedIndex:1122; }","duration":"4.371188074s","start":"2026-07-02T15:23:39.792946Z","end":"2026-07-02T15:23:44.164134Z","steps":["trace[1801810565] 'read index received' (duration: 4.051563999s)","trace[1801810565] 'applied index is now lower than readState.Index' (duration: 319.622835ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:23:44.164304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:41.690043Z","time spent":"2.474174773s","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T15:23:44.205963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"792.027155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T15:23:44.206141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.927676554s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-07-02T15:23:44.20614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.927651134s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-02T15:23:44.206209Z","caller":"traceutil/trace.go:171","msg":"trace[1322529635] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1080; }","duration":"1.927734295s","start":"2026-07-02T15:23:42.278448Z","end":"2026-07-02T15:23:44.206183Z","steps":["trace[1322529635] 'agreement among raft nodes before linearized reading' (duration: 1.886293069s)","trace[1322529635] 'range keys from in-memory index tree' (duration: 41.348605ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:23:44.206211Z","caller":"traceutil/trace.go:171","msg":"trace[597858558] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1080; }","duration":"1.927718775s","start":"2026-07-02T15:23:42.278473Z","end":"2026-07-02T15:23:44.206192Z","steps":["trace[597858558] 'agreement among raft nodes before linearized reading' (duration: 1.886256639s)","trace[597858558] 'range keys from in-memory index tree' (duration: 41.297394ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:23:44.206258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:42.278446Z","time spent":"1.927802966s","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-02T15:23:44.20626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:42.278469Z","time spent":"1.927782126s","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-02T15:23:44.206417Z","caller":"traceutil/trace.go:171","msg":"trace[1150306402] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1080; }","duration":"792.302258ms","start":"2026-07-02T15:23:43.413861Z","end":"2026-07-02T15:23:44.206163Z","steps":["trace[1150306402] 'agreement among raft nodes before linearized reading' (duration: 750.870472ms)","trace[1150306402] 'range keys from in-memory index tree' (duration: 41.172192ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:23:44.206499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.92805483s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-02T15:23:44.206573Z","caller":"traceutil/trace.go:171","msg":"trace[1561780159] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1080; }","duration":"1.928148991s","start":"2026-07-02T15:23:42.278404Z","end":"2026-07-02T15:23:44.206553Z","steps":["trace[1561780159] 'agreement among raft nodes before linearized reading' (duration: 1.886347841s)","trace[1561780159] 'range keys from in-memory index tree' (duration: 41.683498ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:23:44.206622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:42.27839Z","time spent":"1.928220662s","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-02T15:23:44.206732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:43.413841Z","time spent":"792.699383ms","remote":"127.0.0.1:37872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-02T15:23:44.206968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.928462075s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-07-02T15:23:44.207007Z","caller":"traceutil/trace.go:171","msg":"trace[552644245] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1080; }","duration":"1.928509216s","start":"2026-07-02T15:23:42.278486Z","end":"2026-07-02T15:23:44.206996Z","steps":["trace[552644245] 'agreement among raft nodes before linearized reading' (duration: 1.886225179s)","trace[552644245] 'range keys from in-memory index tree' (duration: 42.190046ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:23:44.207037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:42.278484Z","time spent":"1.928545796s","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-02T15:23:44.207055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.725550687s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T15:23:44.207116Z","caller":"traceutil/trace.go:171","msg":"trace[1971610370] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1080; }","duration":"3.725647988s","start":"2026-07-02T15:23:40.48145Z","end":"2026-07-02T15:23:44.207098Z","steps":["trace[1971610370] 'agreement among raft nodes before linearized reading' (duration: 3.68324551s)","trace[1971610370] 'count revisions from in-memory index tree' (duration: 42.306587ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:23:44.206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.399767498s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-02T15:23:44.207159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:40.481431Z","time spent":"3.72571836s","remote":"127.0.0.1:38026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"info","ts":"2026-07-02T15:23:44.207193Z","caller":"traceutil/trace.go:171","msg":"trace[177697095] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1080; }","duration":"1.400982425s","start":"2026-07-02T15:23:42.806183Z","end":"2026-07-02T15:23:44.207165Z","steps":["trace[177697095] 'agreement among raft nodes before linearized reading' (duration: 1.358557846s)","trace[177697095] 'count revisions from in-memory index tree' (duration: 41.216303ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:23:44.206808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.413860986s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-x64cc\" ","response":"range_response_count:1 size:4128"} {"level":"warn","ts":"2026-07-02T15:23:44.207297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:42.806169Z","time spent":"1.401091316s","remote":"127.0.0.1:38318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":5,"response size":31,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"info","ts":"2026-07-02T15:23:44.207314Z","caller":"traceutil/trace.go:171","msg":"trace[400914502] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-x64cc; range_end:; response_count:1; response_revision:1080; }","duration":"4.414400134s","start":"2026-07-02T15:23:39.792897Z","end":"2026-07-02T15:23:44.207297Z","steps":["trace[400914502] 'agreement among raft nodes before linearized reading' (duration: 4.371341237s)","trace[400914502] 'range keys from in-memory index tree' (duration: 42.469689ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:23:44.207353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:39.792876Z","time spent":"4.414465574s","remote":"127.0.0.1:38050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":4152,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-x64cc\" "} {"level":"warn","ts":"2026-07-02T15:23:44.206043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.927423312s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-02T15:23:44.207516Z","caller":"traceutil/trace.go:171","msg":"trace[241149403] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1080; }","duration":"1.928932301s","start":"2026-07-02T15:23:42.27857Z","end":"2026-07-02T15:23:44.207503Z","steps":["trace[241149403] 'agreement among raft nodes before linearized reading' (duration: 1.886179379s)","trace[241149403] 'range keys from in-memory index tree' (duration: 41.142811ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:23:44.20756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:42.278565Z","time spent":"1.928984762s","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-02T15:23:44.34326Z","caller":"traceutil/trace.go:171","msg":"trace[827098472] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"132.439234ms","start":"2026-07-02T15:23:44.210795Z","end":"2026-07-02T15:23:44.343234Z","steps":["trace[827098472] 'process raft request' (duration: 129.999422ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:23:44.343471Z","caller":"traceutil/trace.go:171","msg":"trace[1263922307] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"131.636594ms","start":"2026-07-02T15:23:44.21182Z","end":"2026-07-02T15:23:44.343456Z","steps":["trace[1263922307] 'process raft request' (duration: 131.525412ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:23:44.343489Z","caller":"traceutil/trace.go:171","msg":"trace[248121401] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"130.948724ms","start":"2026-07-02T15:23:44.212527Z","end":"2026-07-02T15:23:44.343476Z","steps":["trace[248121401] 'process raft request' (duration: 130.903173ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:23:44.343488Z","caller":"traceutil/trace.go:171","msg":"trace[1822663487] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"131.922577ms","start":"2026-07-02T15:23:44.211531Z","end":"2026-07-02T15:23:44.343454Z","steps":["trace[1822663487] 'process raft request' (duration: 131.661143ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:23:44.34357Z","caller":"traceutil/trace.go:171","msg":"trace[60681371] linearizableReadLoop","detail":"{readStateIndex:1130; appliedIndex:1127; }","duration":"131.188697ms","start":"2026-07-02T15:23:44.212363Z","end":"2026-07-02T15:23:44.343552Z","steps":["trace[60681371] 'read index received' (duration: 90.549922ms)","trace[60681371] 'applied index is now lower than readState.Index' (duration: 40.637605ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:23:44.343617Z","caller":"traceutil/trace.go:171","msg":"trace[1093536738] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"130.683901ms","start":"2026-07-02T15:23:44.212924Z","end":"2026-07-02T15:23:44.343608Z","steps":["trace[1093536738] 'process raft request' (duration: 130.534639ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:44.343773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.39044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18be8257bb994e93\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-07-02T15:23:44.343829Z","caller":"traceutil/trace.go:171","msg":"trace[1523954509] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18be8257bb994e93; range_end:; response_count:1; response_revision:1086; }","duration":"131.471941ms","start":"2026-07-02T15:23:44.212338Z","end":"2026-07-02T15:23:44.34381Z","steps":["trace[1523954509] 'agreement among raft nodes before linearized reading' (duration: 131.297139ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:44.350757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.782348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T15:23:44.35082Z","caller":"traceutil/trace.go:171","msg":"trace[1139737374] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1086; }","duration":"135.90049ms","start":"2026-07-02T15:23:44.214905Z","end":"2026-07-02T15:23:44.350805Z","steps":["trace[1139737374] 'agreement among raft nodes before linearized reading' (duration: 135.787808ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:23:46.404024Z","caller":"traceutil/trace.go:171","msg":"trace[290252838] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"233.849631ms","start":"2026-07-02T15:23:46.170156Z","end":"2026-07-02T15:23:46.404006Z","steps":["trace[290252838] 'process raft request' (duration: 233.74405ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:23:46.43358Z","caller":"traceutil/trace.go:171","msg":"trace[2140394084] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"162.034321ms","start":"2026-07-02T15:23:46.271524Z","end":"2026-07-02T15:23:46.433559Z","steps":["trace[2140394084] 'process raft request' (duration: 161.889449ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:23:46.813936Z","caller":"traceutil/trace.go:171","msg":"trace[509041764] linearizableReadLoop","detail":"{readStateIndex:1144; appliedIndex:1139; }","duration":"357.403528ms","start":"2026-07-02T15:23:46.456497Z","end":"2026-07-02T15:23:46.813901Z","steps":["trace[509041764] 'read index received' (duration: 7.833723ms)","trace[509041764] 'applied index is now lower than readState.Index' (duration: 349.568115ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:23:46.814346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.821473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.67\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-02T15:23:46.814399Z","caller":"traceutil/trace.go:171","msg":"trace[1083334166] range","detail":"{range_begin:/registry/masterleases/199.19.213.67; range_end:; response_count:1; response_revision:1096; }","duration":"357.946155ms","start":"2026-07-02T15:23:46.456437Z","end":"2026-07-02T15:23:46.814383Z","steps":["trace[1083334166] 'agreement among raft nodes before linearized reading' (duration: 357.59389ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:46.814468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:46.456417Z","time spent":"358.038566ms","remote":"127.0.0.1:37904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.67\" "} {"level":"info","ts":"2026-07-02T15:23:46.815028Z","caller":"traceutil/trace.go:171","msg":"trace[2108234693] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"376.71713ms","start":"2026-07-02T15:23:46.438294Z","end":"2026-07-02T15:23:46.815011Z","steps":["trace[2108234693] 'process raft request' (duration: 375.209831ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:46.815238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:46.438272Z","time spent":"376.809282ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T15:23:46.815862Z","caller":"traceutil/trace.go:171","msg":"trace[1234452883] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"376.872513ms","start":"2026-07-02T15:23:46.43895Z","end":"2026-07-02T15:23:46.815822Z","steps":["trace[1234452883] 'process raft request' (duration: 374.907447ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:23:46.815901Z","caller":"traceutil/trace.go:171","msg":"trace[754600363] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"377.281849ms","start":"2026-07-02T15:23:46.438582Z","end":"2026-07-02T15:23:46.815864Z","steps":["trace[754600363] 'process raft request' (duration: 375.185931ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:46.816064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:46.438938Z","time spent":"377.017855ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T15:23:46.816121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:46.438572Z","time spent":"377.40711ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T15:23:46.816147Z","caller":"traceutil/trace.go:171","msg":"trace[1552316094] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"376.832183ms","start":"2026-07-02T15:23:46.438507Z","end":"2026-07-02T15:23:46.815339Z","steps":["trace[1552316094] 'process raft request' (duration: 375.216811ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:46.816345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:46.438482Z","time spent":"377.758614ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T15:23:47.237184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.265927ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T15:23:47.237306Z","caller":"traceutil/trace.go:171","msg":"trace[1140936005] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"312.942787ms","start":"2026-07-02T15:23:46.924343Z","end":"2026-07-02T15:23:47.237286Z","steps":["trace[1140936005] 'process raft request' (duration: 104.488687ms)","trace[1140936005] 'compare' (duration: 208.067145ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:23:47.237543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:46.924301Z","time spent":"313.20406ms","remote":"127.0.0.1:37904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T15:23:48.533099Z","caller":"traceutil/trace.go:171","msg":"trace[1892708598] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"122.487033ms","start":"2026-07-02T15:23:48.410582Z","end":"2026-07-02T15:23:48.533069Z","steps":["trace[1892708598] 'process raft request' (duration: 122.293681ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:23:49.064298Z","caller":"traceutil/trace.go:171","msg":"trace[1430512366] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"242.205839ms","start":"2026-07-02T15:23:48.822069Z","end":"2026-07-02T15:23:49.064275Z","steps":["trace[1430512366] 'process raft request' (duration: 242.060807ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:23:49.066267Z","caller":"traceutil/trace.go:171","msg":"trace[1531808963] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"242.596803ms","start":"2026-07-02T15:23:48.82365Z","end":"2026-07-02T15:23:49.066247Z","steps":["trace[1531808963] 'process raft request' (duration: 242.503802ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:23:49.066289Z","caller":"traceutil/trace.go:171","msg":"trace[2045839252] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"241.836894ms","start":"2026-07-02T15:23:48.82443Z","end":"2026-07-02T15:23:49.066267Z","steps":["trace[2045839252] 'process raft request' (duration: 241.759633ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:23:49.066244Z","caller":"traceutil/trace.go:171","msg":"trace[167820430] linearizableReadLoop","detail":"{readStateIndex:1152; appliedIndex:1148; }","duration":"123.17052ms","start":"2026-07-02T15:23:48.943051Z","end":"2026-07-02T15:23:49.066221Z","steps":["trace[167820430] 'read index received' (duration: 121.130034ms)","trace[167820430] 'applied index is now lower than readState.Index' (duration: 2.039596ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:23:49.066398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.313742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T15:23:49.066424Z","caller":"traceutil/trace.go:171","msg":"trace[1253676727] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"243.122341ms","start":"2026-07-02T15:23:48.823295Z","end":"2026-07-02T15:23:49.066417Z","steps":["trace[1253676727] 'process raft request' (duration: 242.747436ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:23:49.066455Z","caller":"traceutil/trace.go:171","msg":"trace[438570438] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1103; }","duration":"123.394133ms","start":"2026-07-02T15:23:48.943045Z","end":"2026-07-02T15:23:49.066439Z","steps":["trace[438570438] 'agreement among raft nodes before linearized reading' (duration: 123.277511ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:23:51.392912Z","caller":"traceutil/trace.go:171","msg":"trace[1833581254] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"316.35857ms","start":"2026-07-02T15:23:51.076526Z","end":"2026-07-02T15:23:51.392885Z","steps":["trace[1833581254] 'process raft request' (duration: 316.222589ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:51.393048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:51.076502Z","time spent":"316.476252ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T15:23:51.395569Z","caller":"traceutil/trace.go:171","msg":"trace[1556342048] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"317.339672ms","start":"2026-07-02T15:23:51.0782Z","end":"2026-07-02T15:23:51.395539Z","steps":["trace[1556342048] 'process raft request' (duration: 317.260071ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:23:51.395618Z","caller":"traceutil/trace.go:171","msg":"trace[1165939320] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"317.603506ms","start":"2026-07-02T15:23:51.077988Z","end":"2026-07-02T15:23:51.395592Z","steps":["trace[1165939320] 'process raft request' (duration: 317.322173ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:23:51.395746Z","caller":"traceutil/trace.go:171","msg":"trace[1017995667] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"316.671014ms","start":"2026-07-02T15:23:51.079064Z","end":"2026-07-02T15:23:51.395735Z","steps":["trace[1017995667] 'process raft request' (duration: 316.439911ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:51.395737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:51.078182Z","time spent":"317.468904ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T15:23:51.395776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.617549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-02T15:23:51.395777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:51.077976Z","time spent":"317.729718ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T15:23:51.395797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:51.079051Z","time spent":"316.719304ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T15:23:51.395812Z","caller":"traceutil/trace.go:171","msg":"trace[1451108945] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1109; }","duration":"149.708991ms","start":"2026-07-02T15:23:51.246096Z","end":"2026-07-02T15:23:51.395805Z","steps":["trace[1451108945] 'agreement among raft nodes before linearized reading' (duration: 149.595709ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:23:51.395653Z","caller":"traceutil/trace.go:171","msg":"trace[1518329909] linearizableReadLoop","detail":"{readStateIndex:1158; appliedIndex:1154; }","duration":"149.494908ms","start":"2026-07-02T15:23:51.246137Z","end":"2026-07-02T15:23:51.395632Z","steps":["trace[1518329909] 'read index received' (duration: 146.716572ms)","trace[1518329909] 'applied index is now lower than readState.Index' (duration: 2.777056ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:23:53.505336Z","caller":"traceutil/trace.go:171","msg":"trace[1841825861] linearizableReadLoop","detail":"{readStateIndex:1162; appliedIndex:1161; }","duration":"104.879965ms","start":"2026-07-02T15:23:53.400421Z","end":"2026-07-02T15:23:53.505301Z","steps":["trace[1841825861] 'read index received' (duration: 104.862714ms)","trace[1841825861] 'applied index is now lower than readState.Index' (duration: 13.431µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:23:53.505535Z","caller":"traceutil/trace.go:171","msg":"trace[2063085967] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"105.440483ms","start":"2026-07-02T15:23:53.39986Z","end":"2026-07-02T15:23:53.505301Z","steps":["trace[2063085967] 'process raft request' (duration: 105.311301ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:53.505572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.124218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-02T15:23:53.505614Z","caller":"traceutil/trace.go:171","msg":"trace[718409991] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1112; }","duration":"105.183339ms","start":"2026-07-02T15:23:53.400416Z","end":"2026-07-02T15:23:53.5056Z","steps":["trace[718409991] 'agreement among raft nodes before linearized reading' (duration: 105.010616ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:23:53.580496Z","caller":"traceutil/trace.go:171","msg":"trace[1067828181] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"179.130617ms","start":"2026-07-02T15:23:53.401317Z","end":"2026-07-02T15:23:53.580447Z","steps":["trace[1067828181] 'process raft request' (duration: 135.042502ms)","trace[1067828181] 'compare' (duration: 43.915433ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:23:53.58069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.37736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-07-02T15:23:53.580784Z","caller":"traceutil/trace.go:171","msg":"trace[1506289628] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1114; }","duration":"179.479371ms","start":"2026-07-02T15:23:53.401288Z","end":"2026-07-02T15:23:53.580768Z","steps":["trace[1506289628] 'agreement among raft nodes before linearized reading' (duration: 179.247378ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:54.866734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.121448ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T15:23:54.866867Z","caller":"traceutil/trace.go:171","msg":"trace[1968457416] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"189.504404ms","start":"2026-07-02T15:23:54.677337Z","end":"2026-07-02T15:23:54.866841Z","steps":["trace[1968457416] 'process raft request' (duration: 54.179693ms)","trace[1968457416] 'compare' (duration: 134.994887ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:23:57.077132Z","caller":"traceutil/trace.go:171","msg":"trace[2038463360] transaction","detail":"{read_only:false; response_revision:1124; number_of_response:1; }","duration":"439.029772ms","start":"2026-07-02T15:23:56.638082Z","end":"2026-07-02T15:23:57.077112Z","steps":["trace[2038463360] 'process raft request' (duration: 438.9093ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:57.077258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:56.638058Z","time spent":"439.140144ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T15:23:57.130791Z","caller":"traceutil/trace.go:171","msg":"trace[1506678517] linearizableReadLoop","detail":"{readStateIndex:1176; appliedIndex:1174; }","duration":"259.982242ms","start":"2026-07-02T15:23:56.870785Z","end":"2026-07-02T15:23:57.130767Z","steps":["trace[1506678517] 'read index received' (duration: 206.290741ms)","trace[1506678517] 'applied index is now lower than readState.Index' (duration: 53.690601ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:23:57.131023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.205355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-02T15:23:57.131064Z","caller":"traceutil/trace.go:171","msg":"trace[1049864067] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1125; }","duration":"260.309847ms","start":"2026-07-02T15:23:56.870742Z","end":"2026-07-02T15:23:57.131052Z","steps":["trace[1049864067] 'agreement among raft nodes before linearized reading' (duration: 260.111965ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:23:57.131116Z","caller":"traceutil/trace.go:171","msg":"trace[2089296738] transaction","detail":"{read_only:false; response_revision:1125; number_of_response:1; }","duration":"453.449334ms","start":"2026-07-02T15:23:56.677641Z","end":"2026-07-02T15:23:57.13109Z","steps":["trace[2089296738] 'process raft request' (duration: 453.012308ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:57.131253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:23:56.677623Z","time spent":"453.549016ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T15:23:57.258502Z","caller":"traceutil/trace.go:171","msg":"trace[1176778545] transaction","detail":"{read_only:false; response_revision:1126; number_of_response:1; }","duration":"121.210768ms","start":"2026-07-02T15:23:57.137263Z","end":"2026-07-02T15:23:57.258474Z","steps":["trace[1176778545] 'process raft request' (duration: 112.782051ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:23:57.830024Z","caller":"traceutil/trace.go:171","msg":"trace[1247685751] linearizableReadLoop","detail":"{readStateIndex:1180; appliedIndex:1179; }","duration":"180.634322ms","start":"2026-07-02T15:23:57.64936Z","end":"2026-07-02T15:23:57.829994Z","steps":["trace[1247685751] 'read index received' (duration: 162.602843ms)","trace[1247685751] 'applied index is now lower than readState.Index' (duration: 18.030469ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:23:57.830516Z","caller":"traceutil/trace.go:171","msg":"trace[863575662] transaction","detail":"{read_only:false; response_revision:1129; number_of_response:1; }","duration":"214.515931ms","start":"2026-07-02T15:23:57.615979Z","end":"2026-07-02T15:23:57.830494Z","steps":["trace[863575662] 'process raft request' (duration: 196.016367ms)","trace[863575662] 'compare' (duration: 17.835176ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:23:57.830754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.339031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-02T15:23:57.830858Z","caller":"traceutil/trace.go:171","msg":"trace[1456895206] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1129; }","duration":"181.521533ms","start":"2026-07-02T15:23:57.649315Z","end":"2026-07-02T15:23:57.830837Z","steps":["trace[1456895206] 'agreement among raft nodes before linearized reading' (duration: 181.097137ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:57.830954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.487267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T15:23:57.831013Z","caller":"traceutil/trace.go:171","msg":"trace[1738247683] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1129; }","duration":"149.561838ms","start":"2026-07-02T15:23:57.681435Z","end":"2026-07-02T15:23:57.830997Z","steps":["trace[1738247683] 'agreement among raft nodes before linearized reading' (duration: 149.436366ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:59.598868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.688397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T15:23:59.598929Z","caller":"traceutil/trace.go:171","msg":"trace[1478841985] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1132; }","duration":"186.781757ms","start":"2026-07-02T15:23:59.412134Z","end":"2026-07-02T15:23:59.598915Z","steps":["trace[1478841985] 'range keys from in-memory index tree' (duration: 186.625275ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:23:59.824878Z","caller":"traceutil/trace.go:171","msg":"trace[1588741051] linearizableReadLoop","detail":"{readStateIndex:1184; appliedIndex:1183; }","duration":"197.336791ms","start":"2026-07-02T15:23:59.627512Z","end":"2026-07-02T15:23:59.824849Z","steps":["trace[1588741051] 'read index received' (duration: 197.079938ms)","trace[1588741051] 'applied index is now lower than readState.Index' (duration: 255.723µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:23:59.825009Z","caller":"traceutil/trace.go:171","msg":"trace[1201286751] transaction","detail":"{read_only:false; response_revision:1133; number_of_response:1; }","duration":"222.111934ms","start":"2026-07-02T15:23:59.602861Z","end":"2026-07-02T15:23:59.824972Z","steps":["trace[1201286751] 'process raft request' (duration: 221.82642ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:23:59.825111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.593594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-02T15:23:59.825179Z","caller":"traceutil/trace.go:171","msg":"trace[1401262976] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1133; }","duration":"197.714066ms","start":"2026-07-02T15:23:59.627446Z","end":"2026-07-02T15:23:59.82516Z","steps":["trace[1401262976] 'agreement among raft nodes before linearized reading' (duration: 197.515203ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:23:59.996593Z","caller":"traceutil/trace.go:171","msg":"trace[628830462] linearizableReadLoop","detail":"{readStateIndex:1185; appliedIndex:1184; }","duration":"160.924421ms","start":"2026-07-02T15:23:59.835642Z","end":"2026-07-02T15:23:59.996566Z","steps":["trace[628830462] 'read index received' (duration: 86.771125ms)","trace[628830462] 'applied index is now lower than readState.Index' (duration: 74.152056ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:23:59.996734Z","caller":"traceutil/trace.go:171","msg":"trace[759859826] transaction","detail":"{read_only:false; response_revision:1134; number_of_response:1; }","duration":"167.323252ms","start":"2026-07-02T15:23:59.829366Z","end":"2026-07-02T15:23:59.996689Z","steps":["trace[759859826] 'process raft request' (duration: 93.116255ms)","trace[759859826] 'compare' (duration: 73.949624ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:23:59.996958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.302426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-02T15:23:59.997028Z","caller":"traceutil/trace.go:171","msg":"trace[960385648] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1134; }","duration":"161.417217ms","start":"2026-07-02T15:23:59.835594Z","end":"2026-07-02T15:23:59.997011Z","steps":["trace[960385648] 'agreement among raft nodes before linearized reading' (duration: 161.261455ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:24:01.53181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.545196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T15:24:01.531874Z","caller":"traceutil/trace.go:171","msg":"trace[260001011] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1137; }","duration":"117.658408ms","start":"2026-07-02T15:24:01.4142Z","end":"2026-07-02T15:24:01.531859Z","steps":["trace[260001011] 'range keys from in-memory index tree' (duration: 117.398614ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:24:01.531926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.502977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-02T15:24:01.531998Z","caller":"traceutil/trace.go:171","msg":"trace[226156726] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1137; }","duration":"188.634768ms","start":"2026-07-02T15:24:01.343344Z","end":"2026-07-02T15:24:01.531979Z","steps":["trace[226156726] 'range keys from in-memory index tree' (duration: 188.304325ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:24:02.198909Z","caller":"traceutil/trace.go:171","msg":"trace[1609161168] linearizableReadLoop","detail":"{readStateIndex:1192; appliedIndex:1191; }","duration":"117.461492ms","start":"2026-07-02T15:24:02.081416Z","end":"2026-07-02T15:24:02.198878Z","steps":["trace[1609161168] 'read index received' (duration: 117.127378ms)","trace[1609161168] 'applied index is now lower than readState.Index' (duration: 332.714µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:24:02.199024Z","caller":"traceutil/trace.go:171","msg":"trace[1336884997] transaction","detail":"{read_only:false; response_revision:1141; number_of_response:1; }","duration":"119.502387ms","start":"2026-07-02T15:24:02.079497Z","end":"2026-07-02T15:24:02.198999Z","steps":["trace[1336884997] 'process raft request' (duration: 119.195043ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:24:02.199167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.721355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-02T15:24:02.199236Z","caller":"traceutil/trace.go:171","msg":"trace[1167857841] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1141; }","duration":"117.829496ms","start":"2026-07-02T15:24:02.081392Z","end":"2026-07-02T15:24:02.199221Z","steps":["trace[1167857841] 'agreement among raft nodes before linearized reading' (duration: 117.612623ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:24:55.587023Z","caller":"traceutil/trace.go:171","msg":"trace[1653658830] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"106.01562ms","start":"2026-07-02T15:24:55.48064Z","end":"2026-07-02T15:24:55.586656Z","steps":["trace[1653658830] 'process raft request' (duration: 105.851758ms)"],"step_count":1}