{"level":"warn","ts":"2026-08-21T02:30:02.203566Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-21T02:30:02.203659Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.124: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.124:2380","--initial-cluster=instance=https://199.19.213.124:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.124:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.124:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-08-21T02:30:02.203724Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-21T02:30:02.203733Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.124:2380"]} {"level":"info","ts":"2026-08-21T02:30:02.203754Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-21T02:30:02.204459Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.124:2379"]} {"level":"info","ts":"2026-08-21T02:30:02.204605Z","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.124:2380"],"listen-peer-urls":["https://199.19.213.124:2380"],"advertise-client-urls":["https://199.19.213.124:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.124:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.124:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-08-21T02:30:02.212203Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.372742ms"} {"level":"info","ts":"2026-08-21T02:30:02.220862Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6894f7e0c0041bce","cluster-id":"db11c089a44de10a"} {"level":"info","ts":"2026-08-21T02:30:02.220933Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6894f7e0c0041bce switched to configuration voters=()"} {"level":"info","ts":"2026-08-21T02:30:02.220956Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6894f7e0c0041bce became follower at term 0"} {"level":"info","ts":"2026-08-21T02:30:02.220967Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6894f7e0c0041bce [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-21T02:30:02.220997Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6894f7e0c0041bce became follower at term 1"} {"level":"info","ts":"2026-08-21T02:30:02.221051Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6894f7e0c0041bce switched to configuration voters=(7535920621163912142)"} {"level":"warn","ts":"2026-08-21T02:30:02.234371Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-21T02:30:02.23604Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-21T02:30:02.237242Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-08-21T02:30:02.239514Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6894f7e0c0041bce","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-21T02:30:02.240363Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6894f7e0c0041bce","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-21T02:30:02.240151Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-21T02:30:02.240597Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-21T02:30:02.240631Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-21T02:30:02.243266Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-21T02:30:02.243312Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6894f7e0c0041bce switched to configuration voters=(7535920621163912142)"} {"level":"info","ts":"2026-08-21T02:30:02.243527Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.124:2380"} {"level":"info","ts":"2026-08-21T02:30:02.24358Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"db11c089a44de10a","local-member-id":"6894f7e0c0041bce","added-peer-id":"6894f7e0c0041bce","added-peer-peer-urls":["https://199.19.213.124:2380"]} {"level":"info","ts":"2026-08-21T02:30:02.243602Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.124:2380"} {"level":"info","ts":"2026-08-21T02:30:02.244192Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6894f7e0c0041bce","initial-advertise-peer-urls":["https://199.19.213.124:2380"],"listen-peer-urls":["https://199.19.213.124:2380"],"advertise-client-urls":["https://199.19.213.124:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.124:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-21T02:30:02.24423Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-21T02:30:03.122273Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6894f7e0c0041bce is starting a new election at term 1"} {"level":"info","ts":"2026-08-21T02:30:03.122335Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6894f7e0c0041bce became pre-candidate at term 1"} {"level":"info","ts":"2026-08-21T02:30:03.122398Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6894f7e0c0041bce received MsgPreVoteResp from 6894f7e0c0041bce at term 1"} {"level":"info","ts":"2026-08-21T02:30:03.122429Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6894f7e0c0041bce became candidate at term 2"} {"level":"info","ts":"2026-08-21T02:30:03.122442Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6894f7e0c0041bce received MsgVoteResp from 6894f7e0c0041bce at term 2"} {"level":"info","ts":"2026-08-21T02:30:03.12246Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6894f7e0c0041bce became leader at term 2"} {"level":"info","ts":"2026-08-21T02:30:03.122475Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6894f7e0c0041bce elected leader 6894f7e0c0041bce at term 2"} {"level":"info","ts":"2026-08-21T02:30:03.124505Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6894f7e0c0041bce","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.124:2379]}","request-path":"/0/members/6894f7e0c0041bce/attributes","cluster-id":"db11c089a44de10a","publish-timeout":"7s"} {"level":"info","ts":"2026-08-21T02:30:03.124557Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-21T02:30:03.124597Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-21T02:30:03.124819Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-21T02:30:03.125Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-21T02:30:03.125055Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-21T02:30:03.127869Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"db11c089a44de10a","local-member-id":"6894f7e0c0041bce","cluster-version":"3.5"} {"level":"info","ts":"2026-08-21T02:30:03.127989Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-21T02:30:03.128025Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-21T02:30:03.128766Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-21T02:30:03.130544Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.124:2379"} {"level":"warn","ts":"2026-08-21T02:30:06.859387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.335571ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-21T02:30:06.859605Z","caller":"traceutil/trace.go:171","msg":"trace[1938579362] linearizableReadLoop","detail":"{readStateIndex:207; appliedIndex:205; }","duration":"242.383267ms","start":"2026-08-21T02:30:06.617205Z","end":"2026-08-21T02:30:06.859588Z","steps":["trace[1938579362] 'read index received' (duration: 50.303087ms)","trace[1938579362] 'applied index is now lower than readState.Index' (duration: 192.07919ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:30:06.859645Z","caller":"traceutil/trace.go:171","msg":"trace[1940906649] transaction","detail":"{read_only:false; response_revision:200; number_of_response:1; }","duration":"402.937843ms","start":"2026-08-21T02:30:06.456671Z","end":"2026-08-21T02:30:06.859609Z","steps":["trace[1940906649] 'process raft request' (duration: 210.825542ms)","trace[1940906649] 'compare' (duration: 191.187228ms)"],"step_count":2} {"level":"warn","ts":"2026-08-21T02:30:06.85976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.015185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-21T02:30:06.859791Z","caller":"traceutil/trace.go:171","msg":"trace[469940259] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:201; }","duration":"188.102346ms","start":"2026-08-21T02:30:06.67168Z","end":"2026-08-21T02:30:06.859782Z","steps":["trace[469940259] 'agreement among raft nodes before linearized reading' (duration: 188.011105ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:30:06.859811Z","caller":"traceutil/trace.go:171","msg":"trace[1417777805] transaction","detail":"{read_only:false; response_revision:201; number_of_response:1; }","duration":"395.386117ms","start":"2026-08-21T02:30:06.464402Z","end":"2026-08-21T02:30:06.859788Z","steps":["trace[1417777805] 'process raft request' (duration: 395.128334ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:30:06.859968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:30:06.464389Z","time spent":"395.501359ms","remote":"127.0.0.1:35018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":625,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-21T02:30:06.860041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:30:06.456626Z","time spent":"403.232798ms","remote":"127.0.0.1:35274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-21T02:30:06.860071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.817374ms","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-08-21T02:30:06.860269Z","caller":"traceutil/trace.go:171","msg":"trace[1587434687] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:201; }","duration":"243.100067ms","start":"2026-08-21T02:30:06.617148Z","end":"2026-08-21T02:30:06.860248Z","steps":["trace[1587434687] 'agreement among raft nodes before linearized reading' (duration: 242.55441ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:30:07.258524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.032418ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-21T02:30:07.258646Z","caller":"traceutil/trace.go:171","msg":"trace[710575457] transaction","detail":"{read_only:false; response_revision:202; number_of_response:1; }","duration":"394.762218ms","start":"2026-08-21T02:30:06.863866Z","end":"2026-08-21T02:30:07.258629Z","steps":["trace[710575457] 'process raft request' (duration: 179.528357ms)","trace[710575457] 'compare' (duration: 214.849666ms)"],"step_count":2} {"level":"warn","ts":"2026-08-21T02:30:07.258735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:30:06.863852Z","time spent":"394.860549ms","remote":"127.0.0.1:35018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":642,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-21T02:30:07.260147Z","caller":"traceutil/trace.go:171","msg":"trace[31749236] transaction","detail":"{read_only:false; response_revision:205; number_of_response:1; }","duration":"394.17691ms","start":"2026-08-21T02:30:06.86595Z","end":"2026-08-21T02:30:07.260127Z","steps":["trace[31749236] 'process raft request' (duration: 394.11234ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:30:07.260272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:30:06.865939Z","time spent":"394.281902ms","remote":"127.0.0.1:35196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-21T02:30:07.260327Z","caller":"traceutil/trace.go:171","msg":"trace[775024866] transaction","detail":"{read_only:false; response_revision:203; number_of_response:1; }","duration":"395.346076ms","start":"2026-08-21T02:30:06.864955Z","end":"2026-08-21T02:30:07.260301Z","steps":["trace[775024866] 'process raft request' (duration: 394.970271ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:30:07.26039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:30:06.864941Z","time spent":"395.419017ms","remote":"127.0.0.1:35196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-21T02:30:07.260502Z","caller":"traceutil/trace.go:171","msg":"trace[426368456] transaction","detail":"{read_only:false; response_revision:204; number_of_response:1; }","duration":"395.038182ms","start":"2026-08-21T02:30:06.865452Z","end":"2026-08-21T02:30:07.260491Z","steps":["trace[426368456] 'process raft request' (duration: 394.571005ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:30:07.260559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:30:06.865438Z","time spent":"395.087042ms","remote":"127.0.0.1:35036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1342,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-21T02:30:40.899185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.307862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:538"} {"level":"info","ts":"2026-08-21T02:30:40.899282Z","caller":"traceutil/trace.go:171","msg":"trace[1235149789] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:527; }","duration":"163.458489ms","start":"2026-08-21T02:30:40.735802Z","end":"2026-08-21T02:30:40.899261Z","steps":["trace[1235149789] 'range keys from in-memory index tree' (duration: 163.140935ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:30:40.899317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.510911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:520"} {"level":"warn","ts":"2026-08-21T02:30:40.899344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.240945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329952"} {"level":"info","ts":"2026-08-21T02:30:40.899401Z","caller":"traceutil/trace.go:171","msg":"trace[2110256416] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:527; }","duration":"163.631776ms","start":"2026-08-21T02:30:40.73575Z","end":"2026-08-21T02:30:40.899382Z","steps":["trace[2110256416] 'range keys from in-memory index tree' (duration: 163.430938ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:30:40.899434Z","caller":"traceutil/trace.go:171","msg":"trace[1431113725] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:527; }","duration":"209.387291ms","start":"2026-08-21T02:30:40.690025Z","end":"2026-08-21T02:30:40.899412Z","steps":["trace[1431113725] 'range keys from in-memory index tree' (duration: 209.065757ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:30:42.354222Z","caller":"traceutil/trace.go:171","msg":"trace[1446827549] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"115.479626ms","start":"2026-08-21T02:30:42.238725Z","end":"2026-08-21T02:30:42.354204Z","steps":["trace[1446827549] 'process raft request' (duration: 115.34206ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:30:48.927673Z","caller":"traceutil/trace.go:171","msg":"trace[263163863] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"112.685279ms","start":"2026-08-21T02:30:48.814966Z","end":"2026-08-21T02:30:48.927652Z","steps":["trace[263163863] 'process raft request' (duration: 110.766802ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:30:49.378006Z","caller":"traceutil/trace.go:171","msg":"trace[921331635] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"435.601612ms","start":"2026-08-21T02:30:48.942379Z","end":"2026-08-21T02:30:49.377981Z","steps":["trace[921331635] 'process raft request' (duration: 435.347391ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:30:49.378314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:30:48.942364Z","time spent":"435.719477ms","remote":"127.0.0.1:35114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-21T02:30:49.388867Z","caller":"traceutil/trace.go:171","msg":"trace[1438954707] linearizableReadLoop","detail":"{readStateIndex:669; appliedIndex:667; }","duration":"336.791679ms","start":"2026-08-21T02:30:49.052031Z","end":"2026-08-21T02:30:49.388823Z","steps":["trace[1438954707] 'read index received' (duration: 325.824618ms)","trace[1438954707] 'applied index is now lower than readState.Index' (duration: 10.966031ms)"],"step_count":2} {"level":"warn","ts":"2026-08-21T02:30:49.389049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.000717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-21T02:30:49.389033Z","caller":"traceutil/trace.go:171","msg":"trace[1843981146] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"407.011066ms","start":"2026-08-21T02:30:48.981995Z","end":"2026-08-21T02:30:49.389006Z","steps":["trace[1843981146] 'process raft request' (duration: 406.708333ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:30:49.389114Z","caller":"traceutil/trace.go:171","msg":"trace[43149571] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:645; }","duration":"337.091511ms","start":"2026-08-21T02:30:49.051989Z","end":"2026-08-21T02:30:49.389081Z","steps":["trace[43149571] 'agreement among raft nodes before linearized reading' (duration: 336.972076ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:30:49.389142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:30:48.98197Z","time spent":"407.12453ms","remote":"127.0.0.1:35164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-21T02:30:49.389154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:30:49.051971Z","time spent":"337.175325ms","remote":"127.0.0.1:34914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-21T02:30:49.659093Z","caller":"traceutil/trace.go:171","msg":"trace[906318008] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:669; }","duration":"164.29695ms","start":"2026-08-21T02:30:49.494777Z","end":"2026-08-21T02:30:49.659074Z","steps":["trace[906318008] 'read index received' (duration: 164.105262ms)","trace[906318008] 'applied index is now lower than readState.Index' (duration: 190.838µs)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:30:49.659205Z","caller":"traceutil/trace.go:171","msg":"trace[411540766] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"255.14884ms","start":"2026-08-21T02:30:49.404033Z","end":"2026-08-21T02:30:49.659182Z","steps":["trace[411540766] 'process raft request' (duration: 254.905731ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:30:49.659865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.041309ms","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-08-21T02:30:49.659852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.06127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-21T02:30:49.659945Z","caller":"traceutil/trace.go:171","msg":"trace[161690813] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:646; }","duration":"165.204786ms","start":"2026-08-21T02:30:49.494717Z","end":"2026-08-21T02:30:49.659922Z","steps":["trace[161690813] 'agreement among raft nodes before linearized reading' (duration: 164.461396ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:30:49.65993Z","caller":"traceutil/trace.go:171","msg":"trace[2082428198] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:646; }","duration":"128.144243ms","start":"2026-08-21T02:30:49.531762Z","end":"2026-08-21T02:30:49.659907Z","steps":["trace[2082428198] 'agreement among raft nodes before linearized reading' (duration: 127.435774ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:30:54.240508Z","caller":"traceutil/trace.go:171","msg":"trace[1949600265] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"182.038934ms","start":"2026-08-21T02:30:54.058444Z","end":"2026-08-21T02:30:54.240483Z","steps":["trace[1949600265] 'process raft request' (duration: 181.857476ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:19.343331Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003714952776709903,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-21T02:32:19.84428Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003714952776709903,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-21T02:32:20.344895Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003714952776709903,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-21T02:32:20.845262Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003714952776709903,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-21T02:32:21.05155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000703112s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-21T02:32:21.051636Z","caller":"traceutil/trace.go:171","msg":"trace[347498809] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000809305s","start":"2026-08-21T02:32:19.050806Z","end":"2026-08-21T02:32:21.051615Z","steps":["trace[347498809] 'agreement among raft nodes before linearized reading' (duration: 2.000697202s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:21.051685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:19.050749Z","time spent":"2.000926327s","remote":"127.0.0.1:34926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/21 02:32:21 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-21T02:32:21.346299Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003714952776709903,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-21T02:32:21.846551Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003714952776709903,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-21T02:32:22.347457Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003714952776709903,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-21T02:32:22.848057Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003714952776709903,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-21T02:32:23.002485Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"4.803233003s","expected-duration":"1s"} {"level":"warn","ts":"2026-08-21T02:32:23.052003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000165473s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-21T02:32:23.052094Z","caller":"traceutil/trace.go:171","msg":"trace[1403538680] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000307016s","start":"2026-08-21T02:32:21.051764Z","end":"2026-08-21T02:32:23.052071Z","steps":["trace[1403538680] 'agreement among raft nodes before linearized reading' (duration: 2.000195054s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:23.05214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:21.051747Z","time spent":"2.000384118s","remote":"127.0.0.1:34914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/21 02:32:23 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-21T02:32:23.221599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.942148586s","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-08-21T02:32:23.221698Z","caller":"traceutil/trace.go:171","msg":"trace[439562152] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1179; }","duration":"4.942261379s","start":"2026-08-21T02:32:18.279419Z","end":"2026-08-21T02:32:23.22168Z","steps":["trace[439562152] 'range keys from in-memory index tree' (duration: 4.942114906s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:23.222729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.393924ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-21T02:32:23.222857Z","caller":"traceutil/trace.go:171","msg":"trace[2038462475] transaction","detail":"{read_only:false; response_revision:1180; number_of_response:1; }","duration":"5.023723463s","start":"2026-08-21T02:32:18.199108Z","end":"2026-08-21T02:32:23.222832Z","steps":["trace[2038462475] 'process raft request' (duration: 4.804131134s)","trace[2038462475] 'compare' (duration: 218.712089ms)"],"step_count":2} {"level":"warn","ts":"2026-08-21T02:32:23.222945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:18.199063Z","time spent":"5.023838655s","remote":"127.0.0.1:35196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-21T02:32:23.273766Z","caller":"traceutil/trace.go:171","msg":"trace[1103670905] transaction","detail":"{read_only:false; response_revision:1181; number_of_response:1; }","duration":"4.331013535s","start":"2026-08-21T02:32:18.942719Z","end":"2026-08-21T02:32:23.273732Z","steps":["trace[1103670905] 'process raft request' (duration: 4.33076762s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:23.273918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:18.942694Z","time spent":"4.33114641s","remote":"127.0.0.1:35196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-21T02:32:23.364774Z","caller":"traceutil/trace.go:171","msg":"trace[280038310] transaction","detail":"{read_only:false; response_revision:1182; number_of_response:1; }","duration":"1.844632451s","start":"2026-08-21T02:32:21.520108Z","end":"2026-08-21T02:32:23.36474Z","steps":["trace[280038310] 'process raft request' (duration: 1.844471318s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:23.364797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:23.054822Z","time spent":"309.970799ms","remote":"127.0.0.1:35018","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-08-21T02:32:23.364838Z","caller":"traceutil/trace.go:171","msg":"trace[1224024008] linearizableReadLoop","detail":"{readStateIndex:1228; appliedIndex:1224; }","duration":"4.522056127s","start":"2026-08-21T02:32:18.84275Z","end":"2026-08-21T02:32:23.364806Z","steps":["trace[1224024008] 'read index received' (duration: 4.159956896s)","trace[1224024008] 'applied index is now lower than readState.Index' (duration: 362.097441ms)"],"step_count":2} {"level":"warn","ts":"2026-08-21T02:32:23.364901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:21.520089Z","time spent":"1.844749253s","remote":"127.0.0.1:35196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-21T02:32:23.365016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.522257212s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-21T02:32:23.365059Z","caller":"traceutil/trace.go:171","msg":"trace[952317223] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1182; }","duration":"4.522350333s","start":"2026-08-21T02:32:18.842701Z","end":"2026-08-21T02:32:23.365051Z","steps":["trace[952317223] 'agreement among raft nodes before linearized reading' (duration: 4.522268932s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:23.365096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:18.842681Z","time spent":"4.522407605s","remote":"127.0.0.1:46620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":4,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-08-21T02:32:23.365107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.389558486s","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-08-21T02:32:23.36513Z","caller":"traceutil/trace.go:171","msg":"trace[1712997614] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1182; }","duration":"4.389611267s","start":"2026-08-21T02:32:18.975511Z","end":"2026-08-21T02:32:23.365123Z","steps":["trace[1712997614] 'agreement among raft nodes before linearized reading' (duration: 4.389508495s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:23.365152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:18.975494Z","time spent":"4.389653958s","remote":"127.0.0.1:35196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-21T02:32:23.365213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.625637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-21T02:32:23.365233Z","caller":"traceutil/trace.go:171","msg":"trace[43490922] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1182; }","duration":"305.664167ms","start":"2026-08-21T02:32:23.059562Z","end":"2026-08-21T02:32:23.365226Z","steps":["trace[43490922] 'agreement among raft nodes before linearized reading' (duration: 305.635467ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:23.365252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:23.059552Z","time spent":"305.696718ms","remote":"127.0.0.1:34926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-21T02:32:23.365363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.663801581s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5548"} {"level":"warn","ts":"2026-08-21T02:32:23.365402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.386622916s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-21T02:32:23.365418Z","caller":"traceutil/trace.go:171","msg":"trace[932727932] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1182; }","duration":"3.663896413s","start":"2026-08-21T02:32:19.701505Z","end":"2026-08-21T02:32:23.365401Z","steps":["trace[932727932] 'agreement among raft nodes before linearized reading' (duration: 3.663747829s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:23.36542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.197860846s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-08-21T02:32:23.365513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.030428297s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-21T02:32:23.365529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.473861316s","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-08-21T02:32:23.365548Z","caller":"traceutil/trace.go:171","msg":"trace[1085535008] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1182; }","duration":"1.030498168s","start":"2026-08-21T02:32:22.335037Z","end":"2026-08-21T02:32:23.365536Z","steps":["trace[1085535008] 'agreement among raft nodes before linearized reading' (duration: 1.030449087s)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:32:23.365561Z","caller":"traceutil/trace.go:171","msg":"trace[2076810131] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1182; }","duration":"2.473932658s","start":"2026-08-21T02:32:20.891623Z","end":"2026-08-21T02:32:23.365555Z","steps":["trace[2076810131] 'agreement among raft nodes before linearized reading' (duration: 2.473883527s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:23.365591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:22.335014Z","time spent":"1.030569969s","remote":"127.0.0.1:35342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-08-21T02:32:23.365609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:20.8916Z","time spent":"2.474002239s","remote":"127.0.0.1:35372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-08-21T02:32:23.365814Z","caller":"traceutil/trace.go:171","msg":"trace[375006052] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1182; }","duration":"3.198306128s","start":"2026-08-21T02:32:20.167482Z","end":"2026-08-21T02:32:23.365788Z","steps":["trace[375006052] 'agreement among raft nodes before linearized reading' (duration: 3.197718305s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:23.365909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:20.167437Z","time spent":"3.198453171s","remote":"127.0.0.1:35196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-21T02:32:23.365453Z","caller":"traceutil/trace.go:171","msg":"trace[591706018] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1182; }","duration":"4.38674812s","start":"2026-08-21T02:32:18.978671Z","end":"2026-08-21T02:32:23.365419Z","steps":["trace[591706018] 'agreement among raft nodes before linearized reading' (duration: 4.386687078s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:23.36618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:18.978654Z","time spent":"4.387515967s","remote":"127.0.0.1:35196","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-08-21T02:32:23.365455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:19.701484Z","time spent":"3.663963774s","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":5571,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-08-21T02:32:23.365468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.537710882s","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-08-21T02:32:23.366356Z","caller":"traceutil/trace.go:171","msg":"trace[1955582659] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1182; }","duration":"3.538622305s","start":"2026-08-21T02:32:19.827718Z","end":"2026-08-21T02:32:23.36634Z","steps":["trace[1955582659] 'agreement among raft nodes before linearized reading' (duration: 3.537652832s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:23.366418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:19.827698Z","time spent":"3.538703167s","remote":"127.0.0.1:35196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-21T02:32:25.624949Z","caller":"traceutil/trace.go:171","msg":"trace[864530525] transaction","detail":"{read_only:false; response_revision:1191; number_of_response:1; }","duration":"210.702348ms","start":"2026-08-21T02:32:25.414219Z","end":"2026-08-21T02:32:25.624921Z","steps":["trace[864530525] 'process raft request' (duration: 196.649032ms)","trace[864530525] 'compare' (duration: 13.77815ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:32:25.625197Z","caller":"traceutil/trace.go:171","msg":"trace[877485636] transaction","detail":"{read_only:false; response_revision:1192; number_of_response:1; }","duration":"210.515864ms","start":"2026-08-21T02:32:25.414656Z","end":"2026-08-21T02:32:25.625172Z","steps":["trace[877485636] 'process raft request' (duration: 210.215377ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:32:25.62553Z","caller":"traceutil/trace.go:171","msg":"trace[722871765] transaction","detail":"{read_only:false; response_revision:1193; number_of_response:1; }","duration":"210.147915ms","start":"2026-08-21T02:32:25.415316Z","end":"2026-08-21T02:32:25.625464Z","steps":["trace[722871765] 'process raft request' (duration: 209.779847ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:53.384009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.362902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1465"} {"level":"info","ts":"2026-08-21T02:32:53.384106Z","caller":"traceutil/trace.go:171","msg":"trace[1335145215] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:1307; }","duration":"193.515367ms","start":"2026-08-21T02:32:53.190569Z","end":"2026-08-21T02:32:53.384084Z","steps":["trace[1335145215] 'range keys from in-memory index tree' (duration: 193.108887ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:53.923867Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003714952776710244,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-21T02:32:54.424067Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003714952776710244,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-21T02:32:54.925019Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003714952776710244,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-21T02:32:55.42615Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003714952776710244,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-21T02:32:55.927015Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003714952776710244,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-21T02:32:56.406512Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.986312448s","expected-duration":"1s"} {"level":"info","ts":"2026-08-21T02:32:56.407054Z","caller":"traceutil/trace.go:171","msg":"trace[1898922832] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"2.986923631s","start":"2026-08-21T02:32:53.420103Z","end":"2026-08-21T02:32:56.407027Z","steps":["trace[1898922832] 'process raft request' (duration: 2.986692516s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:56.407211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:53.420078Z","time spent":"2.987050373s","remote":"127.0.0.1:35196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-21T02:32:56.418592Z","caller":"traceutil/trace.go:171","msg":"trace[1982327229] linearizableReadLoop","detail":"{readStateIndex:1363; appliedIndex:1361; }","duration":"2.995353141s","start":"2026-08-21T02:32:53.423219Z","end":"2026-08-21T02:32:56.418572Z","steps":["trace[1982327229] 'read index received' (duration: 2.983522207s)","trace[1982327229] 'applied index is now lower than readState.Index' (duration: 11.829894ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:32:56.418635Z","caller":"traceutil/trace.go:171","msg":"trace[1756742564] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"2.201694924s","start":"2026-08-21T02:32:54.216907Z","end":"2026-08-21T02:32:56.418602Z","steps":["trace[1756742564] 'process raft request' (duration: 2.201506219s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:56.418866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:54.216886Z","time spent":"2.201914138s","remote":"127.0.0.1:35196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-21T02:32:56.4189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.36832024s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-21T02:32:56.419015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.651165632s","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-08-21T02:32:56.419033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.132101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.124\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-08-21T02:32:56.418966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"778.983918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-21T02:32:56.419078Z","caller":"traceutil/trace.go:171","msg":"trace[1216861825] range","detail":"{range_begin:/registry/masterleases/199.19.213.124; range_end:; response_count:1; response_revision:1309; }","duration":"290.207612ms","start":"2026-08-21T02:32:56.128862Z","end":"2026-08-21T02:32:56.41907Z","steps":["trace[1216861825] 'agreement among raft nodes before linearized reading' (duration: 290.044078ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:32:56.419083Z","caller":"traceutil/trace.go:171","msg":"trace[1923721756] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1309; }","duration":"2.651279516s","start":"2026-08-21T02:32:53.767786Z","end":"2026-08-21T02:32:56.419066Z","steps":["trace[1923721756] 'agreement among raft nodes before linearized reading' (duration: 2.651098302s)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:32:56.41903Z","caller":"traceutil/trace.go:171","msg":"trace[1690720486] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1309; }","duration":"1.368502144s","start":"2026-08-21T02:32:55.050512Z","end":"2026-08-21T02:32:56.419014Z","steps":["trace[1690720486] 'agreement among raft nodes before linearized reading' (duration: 1.368335751s)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:32:56.419126Z","caller":"traceutil/trace.go:171","msg":"trace[1442369842] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1309; }","duration":"779.159941ms","start":"2026-08-21T02:32:55.639921Z","end":"2026-08-21T02:32:56.419081Z","steps":["trace[1442369842] 'agreement among raft nodes before linearized reading' (duration: 778.950047ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:56.419129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:53.767773Z","time spent":"2.651343737s","remote":"127.0.0.1:35196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-21T02:32:56.418858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.995623397s","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-08-21T02:32:56.419197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:55.639904Z","time spent":"779.277274ms","remote":"127.0.0.1:50242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"warn","ts":"2026-08-21T02:32:56.419162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:55.050494Z","time spent":"1.368659157s","remote":"127.0.0.1:34926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-21T02:32:56.419276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.652891971s","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-08-21T02:32:56.419329Z","caller":"traceutil/trace.go:171","msg":"trace[916871644] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1309; }","duration":"2.652973642s","start":"2026-08-21T02:32:53.76634Z","end":"2026-08-21T02:32:56.419314Z","steps":["trace[916871644] 'agreement among raft nodes before linearized reading' (duration: 2.65284874s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:56.419336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.655722391s","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-08-21T02:32:56.41936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:53.766318Z","time spent":"2.653036064s","remote":"127.0.0.1:35196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-21T02:32:56.419392Z","caller":"traceutil/trace.go:171","msg":"trace[1018168056] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1309; }","duration":"2.655805324s","start":"2026-08-21T02:32:53.763572Z","end":"2026-08-21T02:32:56.419378Z","steps":["trace[1018168056] 'agreement among raft nodes before linearized reading' (duration: 2.65565838s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:56.419449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:53.763557Z","time spent":"2.655882895s","remote":"127.0.0.1:35196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-21T02:32:56.419208Z","caller":"traceutil/trace.go:171","msg":"trace[2023949259] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1309; }","duration":"2.995993045s","start":"2026-08-21T02:32:53.423204Z","end":"2026-08-21T02:32:56.419197Z","steps":["trace[2023949259] 'agreement among raft nodes before linearized reading' (duration: 2.995487024s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:56.419597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:53.423162Z","time spent":"2.996424045s","remote":"127.0.0.1:35196","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-08-21T02:32:56.41914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.4179368s","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-08-21T02:32:56.419725Z","caller":"traceutil/trace.go:171","msg":"trace[380766917] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1309; }","duration":"2.418574714s","start":"2026-08-21T02:32:54.001134Z","end":"2026-08-21T02:32:56.419709Z","steps":["trace[380766917] 'agreement among raft nodes before linearized reading' (duration: 2.417882399s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:56.419784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:54.001117Z","time spent":"2.418653355s","remote":"127.0.0.1:35350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-08-21T02:32:56.420076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.049795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-21T02:32:56.420104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.848949812s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-21T02:32:56.42016Z","caller":"traceutil/trace.go:171","msg":"trace[357813281] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1309; }","duration":"460.304711ms","start":"2026-08-21T02:32:55.959812Z","end":"2026-08-21T02:32:56.420117Z","steps":["trace[357813281] 'agreement among raft nodes before linearized reading' (duration: 458.983482ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:32:56.420203Z","caller":"traceutil/trace.go:171","msg":"trace[1212156364] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1309; }","duration":"2.850312152s","start":"2026-08-21T02:32:53.569865Z","end":"2026-08-21T02:32:56.420177Z","steps":["trace[1212156364] 'agreement among raft nodes before linearized reading' (duration: 2.848892252s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:56.420274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:53.569846Z","time spent":"2.850418224s","remote":"127.0.0.1:35196","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-08-21T02:32:56.420208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:55.959755Z","time spent":"460.440623ms","remote":"127.0.0.1:50176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-08-21T02:32:58.214406Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.779145648s","expected-duration":"1s"} {"level":"info","ts":"2026-08-21T02:32:58.236874Z","caller":"traceutil/trace.go:171","msg":"trace[1616613465] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"1.812449361s","start":"2026-08-21T02:32:56.424405Z","end":"2026-08-21T02:32:58.236854Z","steps":["trace[1616613465] 'process raft request' (duration: 1.812352889s)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:32:58.2369Z","caller":"traceutil/trace.go:171","msg":"trace[301610926] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"1.812991271s","start":"2026-08-21T02:32:56.423881Z","end":"2026-08-21T02:32:58.236872Z","steps":["trace[301610926] 'process raft request' (duration: 1.790784557s)","trace[301610926] 'compare' (duration: 21.54506ms)"],"step_count":2} {"level":"warn","ts":"2026-08-21T02:32:58.237003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:56.424393Z","time spent":"1.812547153s","remote":"127.0.0.1:35196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-21T02:32:58.237034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:56.423861Z","time spent":"1.813099894s","remote":"127.0.0.1:35196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-21T02:32:58.237131Z","caller":"traceutil/trace.go:171","msg":"trace[70313165] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"1.812011701s","start":"2026-08-21T02:32:56.425111Z","end":"2026-08-21T02:32:58.237122Z","steps":["trace[70313165] 'process raft request' (duration: 1.811808067s)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:32:58.237131Z","caller":"traceutil/trace.go:171","msg":"trace[1096973650] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"289.960562ms","start":"2026-08-21T02:32:57.947148Z","end":"2026-08-21T02:32:58.237109Z","steps":["trace[1096973650] 'process raft request' (duration: 289.925102ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:58.237171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:56.425098Z","time spent":"1.812051702s","remote":"127.0.0.1:35196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-21T02:32:58.237271Z","caller":"traceutil/trace.go:171","msg":"trace[876388079] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"1.812258907s","start":"2026-08-21T02:32:56.425001Z","end":"2026-08-21T02:32:58.23726Z","steps":["trace[876388079] 'process raft request' (duration: 1.811827088s)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:32:58.237277Z","caller":"traceutil/trace.go:171","msg":"trace[48815064] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"1.811254445s","start":"2026-08-21T02:32:56.426013Z","end":"2026-08-21T02:32:58.237268Z","steps":["trace[48815064] 'process raft request' (duration: 1.810978649s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:58.237315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:56.424977Z","time spent":"1.812315817s","remote":"127.0.0.1:35196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-21T02:32:58.237373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:56.426005Z","time spent":"1.811301426s","remote":"127.0.0.1:35196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-21T02:32:58.237459Z","caller":"traceutil/trace.go:171","msg":"trace[1760422136] linearizableReadLoop","detail":"{readStateIndex:1369; appliedIndex:1364; }","duration":"1.807002333s","start":"2026-08-21T02:32:56.430447Z","end":"2026-08-21T02:32:58.23745Z","steps":["trace[1760422136] 'read index received' (duration: 4.924705ms)","trace[1760422136] 'applied index is now lower than readState.Index' (duration: 1.802076368s)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:32:58.237536Z","caller":"traceutil/trace.go:171","msg":"trace[812710632] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"1.801353992s","start":"2026-08-21T02:32:56.43617Z","end":"2026-08-21T02:32:58.237524Z","steps":["trace[812710632] 'process raft request' (duration: 1.800867252s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:58.237584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:56.436141Z","time spent":"1.801412595s","remote":"127.0.0.1:34964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-21T02:32:58.237811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.807357301s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-21T02:32:58.237844Z","caller":"traceutil/trace.go:171","msg":"trace[1889935088] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1316; }","duration":"1.807411363s","start":"2026-08-21T02:32:56.430423Z","end":"2026-08-21T02:32:58.237834Z","steps":["trace[1889935088] 'agreement among raft nodes before linearized reading' (duration: 1.807327451s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:58.23787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:56.430413Z","time spent":"1.807451123s","remote":"127.0.0.1:34926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-21T02:32:58.237897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.644956259s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-21T02:32:58.237959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.809844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2476"} {"level":"info","ts":"2026-08-21T02:32:58.237963Z","caller":"traceutil/trace.go:171","msg":"trace[1766382291] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1316; }","duration":"1.645069821s","start":"2026-08-21T02:32:56.592876Z","end":"2026-08-21T02:32:58.237946Z","steps":["trace[1766382291] 'agreement among raft nodes before linearized reading' (duration: 1.644936809s)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:32:58.237988Z","caller":"traceutil/trace.go:171","msg":"trace[290209546] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1316; }","duration":"146.876915ms","start":"2026-08-21T02:32:58.091103Z","end":"2026-08-21T02:32:58.23798Z","steps":["trace[290209546] 'agreement among raft nodes before linearized reading' (duration: 146.765802ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:58.238006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"964.718228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-21T02:32:58.238007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:56.592856Z","time spent":"1.645140333s","remote":"127.0.0.1:35138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":45,"response size":30,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"info","ts":"2026-08-21T02:32:58.238048Z","caller":"traceutil/trace.go:171","msg":"trace[1671831990] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1316; }","duration":"964.79044ms","start":"2026-08-21T02:32:57.273242Z","end":"2026-08-21T02:32:58.238032Z","steps":["trace[1671831990] 'agreement among raft nodes before linearized reading' (duration: 964.732419ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:58.238079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:57.273222Z","time spent":"964.848661ms","remote":"127.0.0.1:46632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-08-21T02:32:58.550492Z","caller":"traceutil/trace.go:171","msg":"trace[874927485] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"209.070223ms","start":"2026-08-21T02:32:58.341383Z","end":"2026-08-21T02:32:58.550453Z","steps":["trace[874927485] 'process raft request' (duration: 208.95381ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:32:58.611598Z","caller":"traceutil/trace.go:171","msg":"trace[1651030019] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"198.666971ms","start":"2026-08-21T02:32:58.412908Z","end":"2026-08-21T02:32:58.611575Z","steps":["trace[1651030019] 'process raft request' (duration: 198.536247ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:33:00.372668Z","caller":"traceutil/trace.go:171","msg":"trace[1152436639] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"126.263124ms","start":"2026-08-21T02:33:00.246381Z","end":"2026-08-21T02:33:00.372644Z","steps":["trace[1152436639] 'process raft request' (duration: 126.141051ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:33:00.373056Z","caller":"traceutil/trace.go:171","msg":"trace[873443826] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"126.576501ms","start":"2026-08-21T02:33:00.246457Z","end":"2026-08-21T02:33:00.373034Z","steps":["trace[873443826] 'process raft request' (duration: 126.149282ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:33:00.373322Z","caller":"traceutil/trace.go:171","msg":"trace[1667023896] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"126.492189ms","start":"2026-08-21T02:33:00.246809Z","end":"2026-08-21T02:33:00.373301Z","steps":["trace[1667023896] 'process raft request' (duration: 126.189182ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:33:00.373413Z","caller":"traceutil/trace.go:171","msg":"trace[486663957] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"126.098501ms","start":"2026-08-21T02:33:00.247303Z","end":"2026-08-21T02:33:00.373402Z","steps":["trace[486663957] 'process raft request' (duration: 125.769194ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:33:02.564272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.944144ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-21T02:33:02.564612Z","caller":"traceutil/trace.go:171","msg":"trace[1603060183] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"145.03123ms","start":"2026-08-21T02:33:02.419558Z","end":"2026-08-21T02:33:02.564589Z","steps":["trace[1603060183] 'process raft request' (duration: 144.800506ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:33:02.564614Z","caller":"traceutil/trace.go:171","msg":"trace[1085687864] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"144.917377ms","start":"2026-08-21T02:33:02.419674Z","end":"2026-08-21T02:33:02.564592Z","steps":["trace[1085687864] 'process raft request' (duration: 144.751984ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:33:02.564627Z","caller":"traceutil/trace.go:171","msg":"trace[1706722475] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"145.140483ms","start":"2026-08-21T02:33:02.419459Z","end":"2026-08-21T02:33:02.5646Z","steps":["trace[1706722475] 'process raft request' (duration: 37.796831ms)","trace[1706722475] 'compare' (duration: 106.78133ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:33:02.564695Z","caller":"traceutil/trace.go:171","msg":"trace[304601295] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"144.966109ms","start":"2026-08-21T02:33:02.419713Z","end":"2026-08-21T02:33:02.564679Z","steps":["trace[304601295] 'process raft request' (duration: 144.736045ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:33:06.894079Z","caller":"traceutil/trace.go:171","msg":"trace[2093696781] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"207.356337ms","start":"2026-08-21T02:33:06.686683Z","end":"2026-08-21T02:33:06.89404Z","steps":["trace[2093696781] 'process raft request' (duration: 138.45487ms)","trace[2093696781] 'compare' (duration: 68.653002ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:33:08.800498Z","caller":"traceutil/trace.go:171","msg":"trace[392109607] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"128.948918ms","start":"2026-08-21T02:33:08.671525Z","end":"2026-08-21T02:33:08.800474Z","steps":["trace[392109607] 'process raft request' (duration: 85.378597ms)","trace[392109607] 'compare' (duration: 42.966918ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:33:08.998847Z","caller":"traceutil/trace.go:171","msg":"trace[1219392311] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"193.96126ms","start":"2026-08-21T02:33:08.804861Z","end":"2026-08-21T02:33:08.998823Z","steps":["trace[1219392311] 'process raft request' (duration: 188.346453ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:33:09.033845Z","caller":"traceutil/trace.go:171","msg":"trace[588055493] transaction","detail":"{read_only:false; response_revision:1352; number_of_response:1; }","duration":"227.95683ms","start":"2026-08-21T02:33:08.80586Z","end":"2026-08-21T02:33:09.033817Z","steps":["trace[588055493] 'process raft request' (duration: 227.539512ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:33:09.034024Z","caller":"traceutil/trace.go:171","msg":"trace[825915633] transaction","detail":"{read_only:false; response_revision:1353; number_of_response:1; }","duration":"134.605385ms","start":"2026-08-21T02:33:08.89939Z","end":"2026-08-21T02:33:09.033996Z","steps":["trace[825915633] 'process raft request' (duration: 134.269039ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:33:16.316331Z","caller":"traceutil/trace.go:171","msg":"trace[1864006780] transaction","detail":"{read_only:false; response_revision:1383; number_of_response:1; }","duration":"183.683481ms","start":"2026-08-21T02:33:16.132629Z","end":"2026-08-21T02:33:16.316312Z","steps":["trace[1864006780] 'process raft request' (duration: 183.261432ms)"],"step_count":1}