{"level":"warn","ts":"2026-03-18T16:45:27.252753Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-18T16:45:27.252861Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.70: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://162.253.55.70:2380","--initial-cluster=instance=https://162.253.55.70:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.70:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.70: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-03-18T16:45:27.252942Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-18T16:45:27.252955Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.70:2380"]} {"level":"info","ts":"2026-03-18T16:45:27.252971Z","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-03-18T16:45:27.253693Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.70:2379"]} {"level":"info","ts":"2026-03-18T16:45:27.253785Z","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://162.253.55.70:2380"],"listen-peer-urls":["https://162.253.55.70:2380"],"advertise-client-urls":["https://162.253.55.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.70: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-03-18T16:45:27.261466Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.377781ms"} {"level":"info","ts":"2026-03-18T16:45:27.272387Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"23887246632736aa","cluster-id":"62cfeca3ef46d3ef"} {"level":"info","ts":"2026-03-18T16:45:27.27247Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa switched to configuration voters=()"} {"level":"info","ts":"2026-03-18T16:45:27.272496Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became follower at term 0"} {"level":"info","ts":"2026-03-18T16:45:27.272517Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 23887246632736aa [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-18T16:45:27.272526Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became follower at term 1"} {"level":"info","ts":"2026-03-18T16:45:27.27257Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa switched to configuration voters=(2560422034796918442)"} {"level":"warn","ts":"2026-03-18T16:45:27.286095Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-18T16:45:27.287827Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-18T16:45:27.289162Z","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-03-18T16:45:27.2916Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"23887246632736aa","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-18T16:45:27.291845Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"23887246632736aa","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-18T16:45:27.291997Z","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-03-18T16:45:27.292045Z","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-03-18T16:45:27.292056Z","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-03-18T16:45:27.293454Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa switched to configuration voters=(2560422034796918442)"} {"level":"info","ts":"2026-03-18T16:45:27.294755Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"62cfeca3ef46d3ef","local-member-id":"23887246632736aa","added-peer-id":"23887246632736aa","added-peer-peer-urls":["https://162.253.55.70:2380"]} {"level":"info","ts":"2026-03-18T16:45:27.301036Z","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-03-18T16:45:27.301269Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.70:2380"} {"level":"info","ts":"2026-03-18T16:45:27.301432Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.70:2380"} {"level":"info","ts":"2026-03-18T16:45:27.301524Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"23887246632736aa","initial-advertise-peer-urls":["https://162.253.55.70:2380"],"listen-peer-urls":["https://162.253.55.70:2380"],"advertise-client-urls":["https://162.253.55.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-18T16:45:27.301586Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-18T16:45:27.574342Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa is starting a new election at term 1"} {"level":"info","ts":"2026-03-18T16:45:27.57443Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became pre-candidate at term 1"} {"level":"info","ts":"2026-03-18T16:45:27.574472Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa received MsgPreVoteResp from 23887246632736aa at term 1"} {"level":"info","ts":"2026-03-18T16:45:27.574495Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became candidate at term 2"} {"level":"info","ts":"2026-03-18T16:45:27.574516Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa received MsgVoteResp from 23887246632736aa at term 2"} {"level":"info","ts":"2026-03-18T16:45:27.574543Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became leader at term 2"} {"level":"info","ts":"2026-03-18T16:45:27.574565Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 23887246632736aa elected leader 23887246632736aa at term 2"} {"level":"info","ts":"2026-03-18T16:45:27.575477Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T16:45:27.575741Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-18T16:45:27.575627Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"23887246632736aa","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.70:2379]}","request-path":"/0/members/23887246632736aa/attributes","cluster-id":"62cfeca3ef46d3ef","publish-timeout":"7s"} {"level":"info","ts":"2026-03-18T16:45:27.575661Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-18T16:45:27.576117Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-18T16:45:27.576698Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-18T16:45:27.579234Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.70:2379"} {"level":"info","ts":"2026-03-18T16:45:27.579376Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-18T16:45:27.579755Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"62cfeca3ef46d3ef","local-member-id":"23887246632736aa","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T16:45:27.579886Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T16:45:27.579925Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-03-18T16:45:30.703146Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939133455307991514,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-18T16:45:30.729051Z","caller":"traceutil/trace.go:171","msg":"trace[2072612984] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:75; }","duration":"526.691657ms","start":"2026-03-18T16:45:30.202326Z","end":"2026-03-18T16:45:30.729018Z","steps":["trace[2072612984] 'read index received' (duration: 526.38623ms)","trace[2072612984] 'applied index is now lower than readState.Index' (duration: 304.347µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T16:45:30.729212Z","caller":"traceutil/trace.go:171","msg":"trace[1543874096] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"528.06998ms","start":"2026-03-18T16:45:30.201086Z","end":"2026-03-18T16:45:30.729156Z","steps":["trace[1543874096] 'process raft request' (duration: 527.712762ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:45:30.729407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.045069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-18T16:45:30.729505Z","caller":"traceutil/trace.go:171","msg":"trace[1649245429] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:72; }","duration":"435.222653ms","start":"2026-03-18T16:45:30.294263Z","end":"2026-03-18T16:45:30.729486Z","steps":["trace[1649245429] 'agreement among raft nodes before linearized reading' (duration: 435.037849ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:45:30.729543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:45:30.294237Z","time spent":"435.296906ms","remote":"127.0.0.1:57306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-18T16:45:30.729405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.052175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-18T16:45:30.729696Z","caller":"traceutil/trace.go:171","msg":"trace[68455782] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:72; }","duration":"527.378563ms","start":"2026-03-18T16:45:30.202264Z","end":"2026-03-18T16:45:30.729642Z","steps":["trace[68455782] 'agreement among raft nodes before linearized reading' (duration: 526.908902ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:45:30.729853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:45:30.20223Z","time spent":"527.566868ms","remote":"127.0.0.1:57630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/system:aggregate-to-admin\" "} {"level":"warn","ts":"2026-03-18T16:45:30.729902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:45:30.201061Z","time spent":"528.263425ms","remote":"127.0.0.1:57652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-18T16:45:30.730092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.106264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-18T16:45:30.730315Z","caller":"traceutil/trace.go:171","msg":"trace[1466207534] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:72; }","duration":"429.391952ms","start":"2026-03-18T16:45:30.300856Z","end":"2026-03-18T16:45:30.730248Z","steps":["trace[1466207534] 'agreement among raft nodes before linearized reading' (duration: 428.600902ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:45:30.73045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:45:30.300842Z","time spent":"429.593956ms","remote":"127.0.0.1:57322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-18T16:45:31.997312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"917.569924ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-03-18T16:45:31.997456Z","caller":"traceutil/trace.go:171","msg":"trace[1725798270] linearizableReadLoop","detail":"{readStateIndex:77; appliedIndex:76; }","duration":"1.260919684s","start":"2026-03-18T16:45:30.736516Z","end":"2026-03-18T16:45:31.997435Z","steps":["trace[1725798270] 'read index received' (duration: 342.772146ms)","trace[1725798270] 'applied index is now lower than readState.Index' (duration: 918.146268ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T16:45:31.997551Z","caller":"traceutil/trace.go:171","msg":"trace[82784556] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"1.261169159s","start":"2026-03-18T16:45:30.736369Z","end":"2026-03-18T16:45:31.997539Z","steps":["trace[82784556] 'process raft request' (duration: 343.019992ms)","trace[82784556] 'compare' (duration: 917.40779ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T16:45:31.99763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:45:30.73635Z","time spent":"1.261240212s","remote":"127.0.0.1:57652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-18T16:45:31.997755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.590901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-18T16:45:31.997781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.261233611s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-18T16:45:31.99784Z","caller":"traceutil/trace.go:171","msg":"trace[525938880] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:73; }","duration":"596.746205ms","start":"2026-03-18T16:45:31.401073Z","end":"2026-03-18T16:45:31.99782Z","steps":["trace[525938880] 'agreement among raft nodes before linearized reading' (duration: 596.52856ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:45:31.99788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:45:31.401047Z","time spent":"596.826057ms","remote":"127.0.0.1:57322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-18T16:45:31.99789Z","caller":"traceutil/trace.go:171","msg":"trace[464952079] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-edit; range_end:; response_count:0; response_revision:73; }","duration":"1.261404485s","start":"2026-03-18T16:45:30.73646Z","end":"2026-03-18T16:45:31.997864Z","steps":["trace[464952079] 'agreement among raft nodes before linearized reading' (duration: 1.261139259s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:45:31.997934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:45:30.736439Z","time spent":"1.261487867s","remote":"127.0.0.1:57630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/system:aggregate-to-edit\" "} {"level":"warn","ts":"2026-03-18T16:45:31.997804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.456312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-18T16:45:31.998051Z","caller":"traceutil/trace.go:171","msg":"trace[1584642109] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:73; }","duration":"703.751999ms","start":"2026-03-18T16:45:31.294281Z","end":"2026-03-18T16:45:31.998033Z","steps":["trace[1584642109] 'agreement among raft nodes before linearized reading' (duration: 703.34895ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:45:31.998137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:45:31.294258Z","time spent":"703.865732ms","remote":"127.0.0.1:57306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-18T16:45:32.418387Z","caller":"traceutil/trace.go:171","msg":"trace[2085847050] linearizableReadLoop","detail":"{readStateIndex:78; appliedIndex:77; }","duration":"192.747928ms","start":"2026-03-18T16:45:32.225604Z","end":"2026-03-18T16:45:32.418352Z","steps":["trace[2085847050] 'read index received' (duration: 191.752784ms)","trace[2085847050] 'applied index is now lower than readState.Index' (duration: 994.173µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T16:45:32.418522Z","caller":"traceutil/trace.go:171","msg":"trace[1633550346] transaction","detail":"{read_only:false; response_revision:74; number_of_response:1; }","duration":"405.537274ms","start":"2026-03-18T16:45:32.012977Z","end":"2026-03-18T16:45:32.418514Z","steps":["trace[1633550346] 'process raft request' (duration: 404.50539ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:45:32.418618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:45:32.012945Z","time spent":"405.600786ms","remote":"127.0.0.1:57630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-18T16:45:32.418789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.208469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-18T16:45:32.418811Z","caller":"traceutil/trace.go:171","msg":"trace[266480532] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:74; }","duration":"193.332031ms","start":"2026-03-18T16:45:32.225471Z","end":"2026-03-18T16:45:32.418804Z","steps":["trace[266480532] 'agreement among raft nodes before linearized reading' (duration: 193.26196ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:45:32.419113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.606002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-18T16:45:32.419218Z","caller":"traceutil/trace.go:171","msg":"trace[141860283] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:74; }","duration":"123.784927ms","start":"2026-03-18T16:45:32.295408Z","end":"2026-03-18T16:45:32.419193Z","steps":["trace[141860283] 'agreement among raft nodes before linearized reading' (duration: 123.594651ms)"],"step_count":1} 2026/03/18 16:45:34 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-03-18T16:45:34.930491Z","caller":"traceutil/trace.go:171","msg":"trace[3104819] transaction","detail":"{read_only:false; response_revision:238; number_of_response:1; }","duration":"107.727591ms","start":"2026-03-18T16:45:34.822738Z","end":"2026-03-18T16:45:34.930465Z","steps":["trace[3104819] 'process raft request' (duration: 107.574168ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T16:45:57.638595Z","caller":"traceutil/trace.go:171","msg":"trace[364474984] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"267.947412ms","start":"2026-03-18T16:45:57.370606Z","end":"2026-03-18T16:45:57.638554Z","steps":["trace[364474984] 'process raft request' (duration: 216.093929ms)","trace[364474984] 'compare' (duration: 51.675728ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T16:45:57.725844Z","caller":"traceutil/trace.go:171","msg":"trace[498843635] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"354.933745ms","start":"2026-03-18T16:45:57.370885Z","end":"2026-03-18T16:45:57.725819Z","steps":["trace[498843635] 'process raft request' (duration: 354.729091ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:45:57.726002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:45:57.370859Z","time spent":"355.063148ms","remote":"127.0.0.1:57630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2364,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-18T16:45:57.88916Z","caller":"traceutil/trace.go:171","msg":"trace[2110609525] transaction","detail":"{read_only:false; response_revision:438; number_of_response:1; }","duration":"158.054276ms","start":"2026-03-18T16:45:57.731062Z","end":"2026-03-18T16:45:57.889117Z","steps":["trace[2110609525] 'process raft request' (duration: 109.068505ms)","trace[2110609525] 'compare' (duration: 48.819097ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T16:45:58.295944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.790357ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T16:45:58.296315Z","caller":"traceutil/trace.go:171","msg":"trace[237234070] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"319.362892ms","start":"2026-03-18T16:45:57.97693Z","end":"2026-03-18T16:45:58.296293Z","steps":["trace[237234070] 'process raft request' (duration: 319.27752ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T16:45:58.29633Z","caller":"traceutil/trace.go:171","msg":"trace[213374655] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"319.856244ms","start":"2026-03-18T16:45:57.976441Z","end":"2026-03-18T16:45:58.296297Z","steps":["trace[213374655] 'process raft request' (duration: 319.70351ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:45:58.296443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:45:57.976921Z","time spent":"319.466105ms","remote":"127.0.0.1:57802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4294,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T16:45:58.296546Z","caller":"traceutil/trace.go:171","msg":"trace[2056337190] linearizableReadLoop","detail":"{readStateIndex:471; appliedIndex:468; }","duration":"319.69215ms","start":"2026-03-18T16:45:57.976768Z","end":"2026-03-18T16:45:58.29646Z","steps":["trace[2056337190] 'read index received' (duration: 27.055362ms)","trace[2056337190] 'applied index is now lower than readState.Index' (duration: 292.633398ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T16:45:58.296691Z","caller":"traceutil/trace.go:171","msg":"trace[1596501187] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"315.802615ms","start":"2026-03-18T16:45:57.980877Z","end":"2026-03-18T16:45:58.296679Z","steps":["trace[1596501187] 'process raft request' (duration: 315.376855ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:45:58.296769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:45:57.976432Z","time spent":"320.08564ms","remote":"127.0.0.1:57476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3622,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T16:45:58.296431Z","caller":"traceutil/trace.go:171","msg":"trace[1553263486] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"320.756247ms","start":"2026-03-18T16:45:57.975636Z","end":"2026-03-18T16:45:58.296392Z","steps":["trace[1553263486] 'process raft request' (duration: 320.431569ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:45:58.296916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.13915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/cilium-operator\" ","response":"range_response_count:1 size:4335"} {"level":"warn","ts":"2026-03-18T16:45:58.296931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:45:57.975624Z","time spent":"321.273829ms","remote":"127.0.0.1:57384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":736,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-18T16:45:58.296957Z","caller":"traceutil/trace.go:171","msg":"trace[1882714443] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:455; }","duration":"320.209433ms","start":"2026-03-18T16:45:57.976739Z","end":"2026-03-18T16:45:58.296948Z","steps":["trace[1882714443] 'agreement among raft nodes before linearized reading' (duration: 320.10466ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:45:58.296986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:45:57.976729Z","time spent":"320.251003ms","remote":"127.0.0.1:57758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":4357,"request content":"key:\"/registry/deployments/kube-system/cilium-operator\" "} {"level":"warn","ts":"2026-03-18T16:45:58.29681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:45:57.980857Z","time spent":"315.870777ms","remote":"127.0.0.1:57384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":671,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-18T16:45:58.297619Z","caller":"traceutil/trace.go:171","msg":"trace[968846041] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"322.282234ms","start":"2026-03-18T16:45:57.973903Z","end":"2026-03-18T16:45:58.296185Z","steps":["trace[968846041] 'process raft request' (duration: 29.899543ms)","trace[968846041] 'compare' (duration: 291.631262ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T16:45:58.298003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:45:57.973892Z","time spent":"324.004156ms","remote":"127.0.0.1:57788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14448,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T16:45:58.30971Z","caller":"traceutil/trace.go:171","msg":"trace[1239904162] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"278.792388ms","start":"2026-03-18T16:45:58.030893Z","end":"2026-03-18T16:45:58.309686Z","steps":["trace[1239904162] 'process raft request' (duration: 278.356287ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:45:58.309822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.078481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-wkqc6\" ","response":"range_response_count:1 size:15076"} {"level":"warn","ts":"2026-03-18T16:45:58.309855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.4002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:520"} {"level":"info","ts":"2026-03-18T16:45:58.309894Z","caller":"traceutil/trace.go:171","msg":"trace[1252129403] range","detail":"{range_begin:/registry/pods/kube-system/cilium-wkqc6; range_end:; response_count:1; response_revision:456; }","duration":"329.376608ms","start":"2026-03-18T16:45:57.980498Z","end":"2026-03-18T16:45:58.309874Z","steps":["trace[1252129403] 'agreement among raft nodes before linearized reading' (duration: 329.237145ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T16:45:58.309907Z","caller":"traceutil/trace.go:171","msg":"trace[2055979287] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:456; }","duration":"179.516482ms","start":"2026-03-18T16:45:58.130375Z","end":"2026-03-18T16:45:58.309891Z","steps":["trace[2055979287] 'agreement among raft nodes before linearized reading' (duration: 179.324248ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:45:58.30994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:45:57.980488Z","time spent":"329.44441ms","remote":"127.0.0.1:57476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":15098,"request content":"key:\"/registry/pods/kube-system/cilium-wkqc6\" "} {"level":"info","ts":"2026-03-18T16:45:58.71839Z","caller":"traceutil/trace.go:171","msg":"trace[1046755620] linearizableReadLoop","detail":"{readStateIndex:483; appliedIndex:482; }","duration":"161.689895ms","start":"2026-03-18T16:45:58.556671Z","end":"2026-03-18T16:45:58.718361Z","steps":["trace[1046755620] 'read index received' (duration: 161.524341ms)","trace[1046755620] 'applied index is now lower than readState.Index' (duration: 164.534µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T16:45:58.71846Z","caller":"traceutil/trace.go:171","msg":"trace[325257896] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"163.915819ms","start":"2026-03-18T16:45:58.554501Z","end":"2026-03-18T16:45:58.718417Z","steps":["trace[325257896] 'process raft request' (duration: 163.694784ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:45:58.718743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.693358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-18T16:45:58.718819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.144537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-18T16:45:58.71895Z","caller":"traceutil/trace.go:171","msg":"trace[1564395373] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:464; }","duration":"162.314071ms","start":"2026-03-18T16:45:58.556615Z","end":"2026-03-18T16:45:58.718929Z","steps":["trace[1564395373] 'agreement among raft nodes before linearized reading' (duration: 161.915491ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T16:45:58.718978Z","caller":"traceutil/trace.go:171","msg":"trace[1127088799] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:464; }","duration":"157.948953ms","start":"2026-03-18T16:45:58.560972Z","end":"2026-03-18T16:45:58.718921Z","steps":["trace[1127088799] 'agreement among raft nodes before linearized reading' (duration: 157.577935ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:46:00.856907Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939133455307992347,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-18T16:46:01.064573Z","caller":"traceutil/trace.go:171","msg":"trace[382660256] linearizableReadLoop","detail":"{readStateIndex:488; appliedIndex:487; }","duration":"708.17126ms","start":"2026-03-18T16:46:00.35637Z","end":"2026-03-18T16:46:01.064541Z","steps":["trace[382660256] 'read index received' (duration: 708.008126ms)","trace[382660256] 'applied index is now lower than readState.Index' (duration: 162.034µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T16:46:01.064645Z","caller":"traceutil/trace.go:171","msg":"trace[1600466940] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"784.8016ms","start":"2026-03-18T16:46:00.279824Z","end":"2026-03-18T16:46:01.064626Z","steps":["trace[1600466940] 'process raft request' (duration: 784.546735ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:46:01.064809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:46:00.279789Z","time spent":"784.914823ms","remote":"127.0.0.1:57552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T16:46:01.064925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.007896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-03-18T16:46:01.06491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.576908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T16:46:01.064971Z","caller":"traceutil/trace.go:171","msg":"trace[106965644] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:469; }","duration":"335.076567ms","start":"2026-03-18T16:46:00.729882Z","end":"2026-03-18T16:46:01.064959Z","steps":["trace[106965644] 'agreement among raft nodes before linearized reading' (duration: 334.987345ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:46:01.065007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:46:00.729874Z","time spent":"335.126958ms","remote":"127.0.0.1:57552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-18T16:46:01.065018Z","caller":"traceutil/trace.go:171","msg":"trace[1391210958] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:469; }","duration":"503.757051ms","start":"2026-03-18T16:46:00.561235Z","end":"2026-03-18T16:46:01.064992Z","steps":["trace[1391210958] 'agreement among raft nodes before linearized reading' (duration: 503.560807ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:46:01.06502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.16834ms","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-03-18T16:46:01.065064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:46:00.561206Z","time spent":"503.849134ms","remote":"127.0.0.1:57322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-18T16:46:01.065108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.748362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-18T16:46:01.065136Z","caller":"traceutil/trace.go:171","msg":"trace[1650873385] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:469; }","duration":"708.826324ms","start":"2026-03-18T16:46:00.356301Z","end":"2026-03-18T16:46:01.065128Z","steps":["trace[1650873385] 'agreement among raft nodes before linearized reading' (duration: 708.416695ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T16:46:01.065119Z","caller":"traceutil/trace.go:171","msg":"trace[834113731] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:469; }","duration":"340.277613ms","start":"2026-03-18T16:46:00.724816Z","end":"2026-03-18T16:46:01.065094Z","steps":["trace[834113731] 'agreement among raft nodes before linearized reading' (duration: 340.110139ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:46:01.065163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:46:00.356273Z","time spent":"708.884375ms","remote":"127.0.0.1:57552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-18T16:46:01.065183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:46:00.724752Z","time spent":"340.422025ms","remote":"127.0.0.1:57552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-18T16:46:05.26882Z","caller":"traceutil/trace.go:171","msg":"trace[133075260] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"129.029464ms","start":"2026-03-18T16:46:05.139756Z","end":"2026-03-18T16:46:05.268785Z","steps":["trace[133075260] 'process raft request' (duration: 128.747678ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T16:46:05.310847Z","caller":"traceutil/trace.go:171","msg":"trace[1681104036] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"168.108071ms","start":"2026-03-18T16:46:05.142708Z","end":"2026-03-18T16:46:05.310816Z","steps":["trace[1681104036] 'process raft request' (duration: 168.06958ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T16:46:05.310881Z","caller":"traceutil/trace.go:171","msg":"trace[853950744] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"170.640158ms","start":"2026-03-18T16:46:05.140195Z","end":"2026-03-18T16:46:05.310835Z","steps":["trace[853950744] 'process raft request' (duration: 170.370482ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T16:46:05.310924Z","caller":"traceutil/trace.go:171","msg":"trace[1573409283] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"168.204443ms","start":"2026-03-18T16:46:05.142693Z","end":"2026-03-18T16:46:05.310897Z","steps":["trace[1573409283] 'process raft request' (duration: 168.04624ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T16:46:08.363679Z","caller":"traceutil/trace.go:171","msg":"trace[231855313] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"199.295951ms","start":"2026-03-18T16:46:08.164344Z","end":"2026-03-18T16:46:08.36364Z","steps":["trace[231855313] 'process raft request' (duration: 199.089866ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:46:08.819127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.612264ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-03-18T16:46:08.819253Z","caller":"traceutil/trace.go:171","msg":"trace[1899993750] linearizableReadLoop","detail":"{readStateIndex:513; appliedIndex:512; }","duration":"256.293667ms","start":"2026-03-18T16:46:08.562937Z","end":"2026-03-18T16:46:08.819231Z","steps":["trace[1899993750] 'read index received' (duration: 71.479208ms)","trace[1899993750] 'applied index is now lower than readState.Index' (duration: 184.813369ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T16:46:08.819364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.40884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T16:46:08.819405Z","caller":"traceutil/trace.go:171","msg":"trace[207928909] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:492; }","duration":"256.510172ms","start":"2026-03-18T16:46:08.56288Z","end":"2026-03-18T16:46:08.81939Z","steps":["trace[207928909] 'agreement among raft nodes before linearized reading' (duration: 256.40092ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:46:09.827433Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939133455307992409,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-18T16:46:10.328522Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939133455307992409,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-18T16:46:10.363136Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.075745905s","expected-duration":"1s"} {"level":"info","ts":"2026-03-18T16:46:10.363608Z","caller":"traceutil/trace.go:171","msg":"trace[931530394] linearizableReadLoop","detail":"{readStateIndex:514; appliedIndex:513; }","duration":"1.036799629s","start":"2026-03-18T16:46:09.32678Z","end":"2026-03-18T16:46:10.363579Z","steps":["trace[931530394] 'read index received' (duration: 1.036496502s)","trace[931530394] 'applied index is now lower than readState.Index' (duration: 301.907µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T16:46:10.363654Z","caller":"traceutil/trace.go:171","msg":"trace[1770488038] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"1.076379239s","start":"2026-03-18T16:46:09.287229Z","end":"2026-03-18T16:46:10.363609Z","steps":["trace[1770488038] 'process raft request' (duration: 1.076174054s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:46:10.363775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.036983833s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-18T16:46:10.363809Z","caller":"traceutil/trace.go:171","msg":"trace[4050644] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:493; }","duration":"1.037066996s","start":"2026-03-18T16:46:09.326733Z","end":"2026-03-18T16:46:10.3638Z","steps":["trace[4050644] 'agreement among raft nodes before linearized reading' (duration: 1.036963983s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:46:10.363829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:46:09.326718Z","time spent":"1.037106386s","remote":"127.0.0.1:57552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-18T16:46:10.363825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:46:09.287203Z","time spent":"1.076506842s","remote":"127.0.0.1:57552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T16:46:10.363826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.034946509s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-18T16:46:10.363831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.255373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-18T16:46:10.363871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.031901381s","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-03-18T16:46:10.363895Z","caller":"traceutil/trace.go:171","msg":"trace[727836496] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:493; }","duration":"801.323595ms","start":"2026-03-18T16:46:09.562555Z","end":"2026-03-18T16:46:10.363878Z","steps":["trace[727836496] 'agreement among raft nodes before linearized reading' (duration: 801.224852ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T16:46:10.363907Z","caller":"traceutil/trace.go:171","msg":"trace[1899089076] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:493; }","duration":"1.035066211s","start":"2026-03-18T16:46:09.328801Z","end":"2026-03-18T16:46:10.363867Z","steps":["trace[1899089076] 'agreement among raft nodes before linearized reading' (duration: 1.034945268s)"],"step_count":1} {"level":"info","ts":"2026-03-18T16:46:10.363916Z","caller":"traceutil/trace.go:171","msg":"trace[1860647105] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:493; }","duration":"1.031968652s","start":"2026-03-18T16:46:09.331934Z","end":"2026-03-18T16:46:10.363903Z","steps":["trace[1860647105] 'agreement among raft nodes before linearized reading' (duration: 1.03188594s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:46:10.36395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:46:09.331924Z","time spent":"1.032018593s","remote":"127.0.0.1:57552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-18T16:46:10.363945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:46:09.562477Z","time spent":"801.455078ms","remote":"127.0.0.1:57306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-18T16:46:10.364053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:46:09.32878Z","time spent":"1.035255655s","remote":"127.0.0.1:57552","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":"info","ts":"2026-03-18T16:46:12.658337Z","caller":"traceutil/trace.go:171","msg":"trace[1010246591] linearizableReadLoop","detail":"{readStateIndex:530; appliedIndex:529; }","duration":"290.654122ms","start":"2026-03-18T16:46:12.367648Z","end":"2026-03-18T16:46:12.658302Z","steps":["trace[1010246591] 'read index received' (duration: 288.261678ms)","trace[1010246591] 'applied index is now lower than readState.Index' (duration: 2.391064ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T16:46:12.658373Z","caller":"traceutil/trace.go:171","msg":"trace[710685727] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"359.231226ms","start":"2026-03-18T16:46:12.299108Z","end":"2026-03-18T16:46:12.65834Z","steps":["trace[710685727] 'process raft request' (duration: 356.774932ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:46:12.658504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:46:12.299087Z","time spent":"359.3622ms","remote":"127.0.0.1:57552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-18T16:46:12.65864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.961059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-03-18T16:46:12.658669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.826818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-18T16:46:12.658767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.685785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-18T16:46:12.658799Z","caller":"traceutil/trace.go:171","msg":"trace[412431803] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:509; }","duration":"282.723875ms","start":"2026-03-18T16:46:12.376067Z","end":"2026-03-18T16:46:12.658791Z","steps":["trace[412431803] 'agreement among raft nodes before linearized reading' (duration: 282.641574ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T16:46:12.658807Z","caller":"traceutil/trace.go:171","msg":"trace[1030959455] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:509; }","duration":"282.95265ms","start":"2026-03-18T16:46:12.37578Z","end":"2026-03-18T16:46:12.658733Z","steps":["trace[1030959455] 'agreement among raft nodes before linearized reading' (duration: 282.752506ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T16:46:12.6587Z","caller":"traceutil/trace.go:171","msg":"trace[143940643] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:509; }","duration":"291.090262ms","start":"2026-03-18T16:46:12.367591Z","end":"2026-03-18T16:46:12.658681Z","steps":["trace[143940643] 'agreement among raft nodes before linearized reading' (duration: 290.914318ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:46:12.658828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.198024ms","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-03-18T16:46:12.658909Z","caller":"traceutil/trace.go:171","msg":"trace[1486101762] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:509; }","duration":"282.320177ms","start":"2026-03-18T16:46:12.376568Z","end":"2026-03-18T16:46:12.658888Z","steps":["trace[1486101762] 'agreement among raft nodes before linearized reading' (duration: 282.123872ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T16:46:13.236743Z","caller":"traceutil/trace.go:171","msg":"trace[1130707506] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"120.227133ms","start":"2026-03-18T16:46:13.116485Z","end":"2026-03-18T16:46:13.236713Z","steps":["trace[1130707506] 'process raft request' (duration: 67.788717ms)","trace[1130707506] 'compare' (duration: 52.119609ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T16:46:28.877525Z","caller":"traceutil/trace.go:171","msg":"trace[1562164280] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"104.284908ms","start":"2026-03-18T16:46:28.7732Z","end":"2026-03-18T16:46:28.877485Z","steps":["trace[1562164280] 'process raft request' (duration: 102.563581ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:46:31.383839Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939133455307992757,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-18T16:46:31.576008Z","caller":"traceutil/trace.go:171","msg":"trace[1963088220] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"800.840602ms","start":"2026-03-18T16:46:30.774807Z","end":"2026-03-18T16:46:31.575647Z","steps":["trace[1963088220] 'process raft request' (duration: 800.044775ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:46:31.576181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"692.991076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-18T16:46:31.576223Z","caller":"traceutil/trace.go:171","msg":"trace[1507742246] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:679; }","duration":"693.099578ms","start":"2026-03-18T16:46:30.883113Z","end":"2026-03-18T16:46:31.576213Z","steps":["trace[1507742246] 'agreement among raft nodes before linearized reading' (duration: 692.630507ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:46:31.576231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:46:30.774787Z","time spent":"801.376505ms","remote":"127.0.0.1:57552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T16:46:31.576251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:46:30.883088Z","time spent":"693.15878ms","remote":"127.0.0.1:57552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-18T16:46:31.575555Z","caller":"traceutil/trace.go:171","msg":"trace[1798389292] linearizableReadLoop","detail":"{readStateIndex:706; appliedIndex:705; }","duration":"692.362082ms","start":"2026-03-18T16:46:30.883176Z","end":"2026-03-18T16:46:31.575539Z","steps":["trace[1798389292] 'read index received' (duration: 691.619805ms)","trace[1798389292] 'applied index is now lower than readState.Index' (duration: 741.697µs)"],"step_count":2} {"level":"warn","ts":"2026-03-18T16:46:31.576534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.78726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-18T16:46:31.576584Z","caller":"traceutil/trace.go:171","msg":"trace[507457949] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:679; }","duration":"669.904472ms","start":"2026-03-18T16:46:30.906669Z","end":"2026-03-18T16:46:31.576573Z","steps":["trace[507457949] 'agreement among raft nodes before linearized reading' (duration: 669.767429ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:46:31.576626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:46:30.906642Z","time spent":"669.975273ms","remote":"127.0.0.1:57552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-18T16:46:31.576699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.399629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-18T16:46:31.577471Z","caller":"traceutil/trace.go:171","msg":"trace[851340758] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:679; }","duration":"669.187377ms","start":"2026-03-18T16:46:30.908273Z","end":"2026-03-18T16:46:31.57746Z","steps":["trace[851340758] 'agreement among raft nodes before linearized reading' (duration: 668.377999ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:46:31.577505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:46:30.908265Z","time spent":"669.231697ms","remote":"127.0.0.1:57552","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-03-18T16:46:31.57686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.557969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-18T16:46:31.577563Z","caller":"traceutil/trace.go:171","msg":"trace[1117432165] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:679; }","duration":"668.323137ms","start":"2026-03-18T16:46:30.909234Z","end":"2026-03-18T16:46:31.577557Z","steps":["trace[1117432165] 'agreement among raft nodes before linearized reading' (duration: 667.414347ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:46:31.577584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:46:30.909225Z","time spent":"668.354847ms","remote":"127.0.0.1:57552","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-03-18T16:46:32.329243Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939133455307992761,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-18T16:46:32.816064Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.223601702s","expected-duration":"1s"} {"level":"info","ts":"2026-03-18T16:46:32.816661Z","caller":"traceutil/trace.go:171","msg":"trace[554609354] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"1.224252077s","start":"2026-03-18T16:46:31.592383Z","end":"2026-03-18T16:46:32.816635Z","steps":["trace[554609354] 'process raft request' (duration: 1.22396029s)"],"step_count":1} {"level":"info","ts":"2026-03-18T16:46:32.816809Z","caller":"traceutil/trace.go:171","msg":"trace[322649743] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"1.224399761s","start":"2026-03-18T16:46:31.592391Z","end":"2026-03-18T16:46:32.816791Z","steps":["trace[322649743] 'process raft request' (duration: 1.224168635s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:46:32.817223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:46:31.592375Z","time spent":"1.224783019s","remote":"127.0.0.1:57552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T16:46:32.817624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:46:31.592365Z","time spent":"1.224706847s","remote":"127.0.0.1:57552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T16:46:32.886177Z","caller":"traceutil/trace.go:171","msg":"trace[2101313822] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"1.293742712s","start":"2026-03-18T16:46:31.592413Z","end":"2026-03-18T16:46:32.886155Z","steps":["trace[2101313822] 'process raft request' (duration: 1.254363576s)","trace[2101313822] 'compare' (duration: 39.217722ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T16:46:32.886207Z","caller":"traceutil/trace.go:171","msg":"trace[1065426224] linearizableReadLoop","detail":"{readStateIndex:711; appliedIndex:706; }","duration":"1.066169612s","start":"2026-03-18T16:46:31.820022Z","end":"2026-03-18T16:46:32.886191Z","steps":["trace[1065426224] 'read index received' (duration: 996.265558ms)","trace[1065426224] 'applied index is now lower than readState.Index' (duration: 69.903204ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T16:46:32.886275Z","caller":"traceutil/trace.go:171","msg":"trace[1447884836] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"1.293847324s","start":"2026-03-18T16:46:31.592413Z","end":"2026-03-18T16:46:32.88626Z","steps":["trace[1447884836] 'process raft request' (duration: 1.293701481s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:46:32.886291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.066257274s","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-03-18T16:46:32.88632Z","caller":"traceutil/trace.go:171","msg":"trace[1034882664] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:684; }","duration":"1.066331185s","start":"2026-03-18T16:46:31.81998Z","end":"2026-03-18T16:46:32.886311Z","steps":["trace[1034882664] 'agreement among raft nodes before linearized reading' (duration: 1.066264543s)"],"step_count":1} {"level":"info","ts":"2026-03-18T16:46:32.88636Z","caller":"traceutil/trace.go:171","msg":"trace[926356950] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"1.043453167s","start":"2026-03-18T16:46:31.8429Z","end":"2026-03-18T16:46:32.886353Z","steps":["trace[926356950] 'process raft request' (duration: 1.043257293s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:46:32.886376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:46:31.819958Z","time spent":"1.066398157s","remote":"127.0.0.1:57714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":29,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-03-18T16:46:32.886398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:46:31.842878Z","time spent":"1.043501488s","remote":"127.0.0.1:57552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T16:46:32.886314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:46:31.592395Z","time spent":"1.293841424s","remote":"127.0.0.1:57552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T16:46:32.886559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:46:31.592395Z","time spent":"1.294125921s","remote":"127.0.0.1:57552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T16:46:33.423869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:46:33.106139Z","time spent":"317.725362ms","remote":"127.0.0.1:57332","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-18T16:46:33.923806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.662311ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T16:46:33.923889Z","caller":"traceutil/trace.go:171","msg":"trace[737595047] linearizableReadLoop","detail":"{readStateIndex:713; appliedIndex:712; }","duration":"362.674454ms","start":"2026-03-18T16:46:33.561194Z","end":"2026-03-18T16:46:33.923868Z","steps":["trace[737595047] 'read index received' (duration: 143.868959ms)","trace[737595047] 'applied index is now lower than readState.Index' (duration: 218.804755ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T16:46:33.923946Z","caller":"traceutil/trace.go:171","msg":"trace[1517220490] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"499.045214ms","start":"2026-03-18T16:46:33.424894Z","end":"2026-03-18T16:46:33.923939Z","steps":["trace[1517220490] 'process raft request' (duration: 280.190369ms)","trace[1517220490] 'compare' (duration: 218.407155ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T16:46:33.923987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:46:33.424873Z","time spent":"499.094116ms","remote":"127.0.0.1:57332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T16:46:33.924347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.649194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-18T16:46:33.924429Z","caller":"traceutil/trace.go:171","msg":"trace[26876596] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:685; }","duration":"103.775667ms","start":"2026-03-18T16:46:33.820635Z","end":"2026-03-18T16:46:33.92441Z","steps":["trace[26876596] 'agreement among raft nodes before linearized reading' (duration: 103.608173ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:46:33.924418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.234286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T16:46:33.924472Z","caller":"traceutil/trace.go:171","msg":"trace[991769455] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:685; }","duration":"363.288287ms","start":"2026-03-18T16:46:33.561176Z","end":"2026-03-18T16:46:33.924464Z","steps":["trace[991769455] 'agreement among raft nodes before linearized reading' (duration: 362.931229ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:46:33.924491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:46:33.561127Z","time spent":"363.359218ms","remote":"127.0.0.1:57306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-18T16:46:33.924929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.247925ms","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-03-18T16:46:33.92502Z","caller":"traceutil/trace.go:171","msg":"trace[1594657554] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:685; }","duration":"345.367277ms","start":"2026-03-18T16:46:33.57963Z","end":"2026-03-18T16:46:33.924998Z","steps":["trace[1594657554] 'agreement among raft nodes before linearized reading' (duration: 344.598651ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:46:33.925071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:46:33.57962Z","time spent":"345.438609ms","remote":"127.0.0.1:57552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-18T16:46:57.403002Z","caller":"traceutil/trace.go:171","msg":"trace[698523084] linearizableReadLoop","detail":"{readStateIndex:787; appliedIndex:786; }","duration":"468.448084ms","start":"2026-03-18T16:46:56.934532Z","end":"2026-03-18T16:46:57.40298Z","steps":["trace[698523084] 'read index received' (duration: 468.224839ms)","trace[698523084] 'applied index is now lower than readState.Index' (duration: 222.415µs)"],"step_count":2} {"level":"warn","ts":"2026-03-18T16:46:57.403483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.250638ms","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-03-18T16:46:57.403589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.052138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-03-18T16:46:57.403777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.925458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-18T16:46:57.403852Z","caller":"traceutil/trace.go:171","msg":"trace[1026820681] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"606.258858ms","start":"2026-03-18T16:46:56.797571Z","end":"2026-03-18T16:46:57.40383Z","steps":["trace[1026820681] 'process raft request' (duration: 605.244865ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T16:46:57.403856Z","caller":"traceutil/trace.go:171","msg":"trace[467821748] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:755; }","duration":"408.00706ms","start":"2026-03-18T16:46:56.995838Z","end":"2026-03-18T16:46:57.403845Z","steps":["trace[467821748] 'agreement among raft nodes before linearized reading' (duration: 407.802605ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:46:57.404045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:46:56.797543Z","time spent":"606.419121ms","remote":"127.0.0.1:57552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T16:46:57.403671Z","caller":"traceutil/trace.go:171","msg":"trace[1253843921] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:755; }","duration":"469.192771ms","start":"2026-03-18T16:46:56.934466Z","end":"2026-03-18T16:46:57.403659Z","steps":["trace[1253843921] 'agreement among raft nodes before linearized reading' (duration: 468.892814ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:46:57.404103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.820907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-18T16:46:57.404168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:46:56.934443Z","time spent":"469.706671ms","remote":"127.0.0.1:57552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-18T16:46:57.404237Z","caller":"traceutil/trace.go:171","msg":"trace[183503468] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:755; }","duration":"413.028352ms","start":"2026-03-18T16:46:56.991175Z","end":"2026-03-18T16:46:57.404203Z","steps":["trace[183503468] 'agreement among raft nodes before linearized reading' (duration: 412.593212ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:46:57.404308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:46:56.991149Z","time spent":"413.147725ms","remote":"127.0.0.1:57552","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":"info","ts":"2026-03-18T16:46:57.40361Z","caller":"traceutil/trace.go:171","msg":"trace[278688187] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:755; }","duration":"467.402481ms","start":"2026-03-18T16:46:56.936183Z","end":"2026-03-18T16:46:57.403585Z","steps":["trace[278688187] 'agreement among raft nodes before linearized reading' (duration: 467.080874ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:46:57.404506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:46:56.936173Z","time spent":"468.323652ms","remote":"127.0.0.1:57552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-18T16:46:57.404897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:46:56.995781Z","time spent":"408.324998ms","remote":"127.0.0.1:57552","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-03-18T16:46:57.703237Z","caller":"traceutil/trace.go:171","msg":"trace[1109927860] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"290.926468ms","start":"2026-03-18T16:46:57.412282Z","end":"2026-03-18T16:46:57.703208Z","steps":["trace[1109927860] 'process raft request' (duration: 226.868484ms)","trace[1109927860] 'compare' (duration: 63.923451ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T16:46:57.711132Z","caller":"traceutil/trace.go:171","msg":"trace[455009563] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"286.706454ms","start":"2026-03-18T16:46:57.424402Z","end":"2026-03-18T16:46:57.711109Z","steps":["trace[455009563] 'process raft request' (duration: 286.408107ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T16:46:57.711374Z","caller":"traceutil/trace.go:171","msg":"trace[814796387] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"286.929198ms","start":"2026-03-18T16:46:57.424436Z","end":"2026-03-18T16:46:57.711366Z","steps":["trace[814796387] 'process raft request' (duration: 286.508539ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T16:46:57.711472Z","caller":"traceutil/trace.go:171","msg":"trace[246915611] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"286.97175ms","start":"2026-03-18T16:46:57.424494Z","end":"2026-03-18T16:46:57.711466Z","steps":["trace[246915611] 'process raft request' (duration: 286.759115ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:46:57.711805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.082735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T16:46:57.711803Z","caller":"traceutil/trace.go:171","msg":"trace[1734823012] linearizableReadLoop","detail":"{readStateIndex:791; appliedIndex:787; }","duration":"149.651786ms","start":"2026-03-18T16:46:57.561694Z","end":"2026-03-18T16:46:57.711346Z","steps":["trace[1734823012] 'read index received' (duration: 77.473601ms)","trace[1734823012] 'applied index is now lower than readState.Index' (duration: 72.176675ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T16:46:57.711871Z","caller":"traceutil/trace.go:171","msg":"trace[1902622518] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:759; }","duration":"150.203978ms","start":"2026-03-18T16:46:57.561653Z","end":"2026-03-18T16:46:57.711857Z","steps":["trace[1902622518] 'agreement among raft nodes before linearized reading' (duration: 149.941863ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:47:00.215498Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939133455307992991,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-18T16:47:00.327062Z","caller":"traceutil/trace.go:171","msg":"trace[98822861] linearizableReadLoop","detail":"{readStateIndex:794; appliedIndex:793; }","duration":"612.199411ms","start":"2026-03-18T16:46:59.714839Z","end":"2026-03-18T16:47:00.327038Z","steps":["trace[98822861] 'read index received' (duration: 612.030537ms)","trace[98822861] 'applied index is now lower than readState.Index' (duration: 167.734µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T16:47:00.327222Z","caller":"traceutil/trace.go:171","msg":"trace[1984423585] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"612.62743ms","start":"2026-03-18T16:46:59.714498Z","end":"2026-03-18T16:47:00.327125Z","steps":["trace[1984423585] 'process raft request' (duration: 612.370574ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:47:00.327338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.408532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-18T16:47:00.327378Z","caller":"traceutil/trace.go:171","msg":"trace[1005318610] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:761; }","duration":"611.502724ms","start":"2026-03-18T16:46:59.715866Z","end":"2026-03-18T16:47:00.327368Z","steps":["trace[1005318610] 'agreement among raft nodes before linearized reading' (duration: 611.3065ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:47:00.327389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.529328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-18T16:47:00.327516Z","caller":"traceutil/trace.go:171","msg":"trace[944955274] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:761; }","duration":"612.657991ms","start":"2026-03-18T16:46:59.714792Z","end":"2026-03-18T16:47:00.32745Z","steps":["trace[944955274] 'agreement among raft nodes before linearized reading' (duration: 612.376035ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:47:00.327559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:46:59.714756Z","time spent":"612.794273ms","remote":"127.0.0.1:57552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-18T16:47:00.327558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.711997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-03-18T16:47:00.327396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.364836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-18T16:47:00.32756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:46:59.714462Z","time spent":"612.920036ms","remote":"127.0.0.1:57552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T16:47:00.327593Z","caller":"traceutil/trace.go:171","msg":"trace[904341165] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:761; }","duration":"610.769309ms","start":"2026-03-18T16:46:59.716813Z","end":"2026-03-18T16:47:00.327582Z","steps":["trace[904341165] 'agreement among raft nodes before linearized reading' (duration: 610.629486ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:47:00.327623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:46:59.716809Z","time spent":"610.8083ms","remote":"127.0.0.1:57552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-18T16:47:00.327621Z","caller":"traceutil/trace.go:171","msg":"trace[1642687020] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:761; }","duration":"165.637362ms","start":"2026-03-18T16:47:00.161961Z","end":"2026-03-18T16:47:00.327598Z","steps":["trace[1642687020] 'agreement among raft nodes before linearized reading' (duration: 165.266334ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:47:00.327414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:46:59.715859Z","time spent":"611.546335ms","remote":"127.0.0.1:57552","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-03-18T16:47:00.790674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.44728ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T16:47:00.790882Z","caller":"traceutil/trace.go:171","msg":"trace[2096401833] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"457.679805ms","start":"2026-03-18T16:47:00.333188Z","end":"2026-03-18T16:47:00.790868Z","steps":["trace[2096401833] 'process raft request' (duration: 457.632364ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:47:00.790931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:47:00.333178Z","time spent":"457.730937ms","remote":"127.0.0.1:57552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T16:47:00.790951Z","caller":"traceutil/trace.go:171","msg":"trace[1429431952] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"457.790058ms","start":"2026-03-18T16:47:00.333117Z","end":"2026-03-18T16:47:00.790907Z","steps":["trace[1429431952] 'process raft request' (duration: 457.655835ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T16:47:00.791041Z","caller":"traceutil/trace.go:171","msg":"trace[86007102] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"457.222905ms","start":"2026-03-18T16:47:00.333784Z","end":"2026-03-18T16:47:00.791007Z","steps":["trace[86007102] 'process raft request' (duration: 457.059992ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:47:00.791214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:47:00.333772Z","time spent":"457.347079ms","remote":"127.0.0.1:57552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T16:47:00.791278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:47:00.333103Z","time spent":"457.935171ms","remote":"127.0.0.1:57552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T16:47:00.790919Z","caller":"traceutil/trace.go:171","msg":"trace[2012297832] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"458.309969ms","start":"2026-03-18T16:47:00.332598Z","end":"2026-03-18T16:47:00.790908Z","steps":["trace[2012297832] 'process raft request' (duration: 327.559082ms)","trace[2012297832] 'compare' (duration: 130.354758ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T16:47:00.791886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:47:00.332561Z","time spent":"459.291582ms","remote":"127.0.0.1:57552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T16:47:07.002818Z","caller":"traceutil/trace.go:171","msg":"trace[920839182] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"100.734039ms","start":"2026-03-18T16:47:06.90204Z","end":"2026-03-18T16:47:07.002774Z","steps":["trace[920839182] 'process raft request' (duration: 100.244268ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T16:47:07.126078Z","caller":"traceutil/trace.go:171","msg":"trace[999511056] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"118.325141ms","start":"2026-03-18T16:47:07.007734Z","end":"2026-03-18T16:47:07.126059Z","steps":["trace[999511056] 'process raft request' (duration: 109.510075ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T16:47:07.126442Z","caller":"traceutil/trace.go:171","msg":"trace[1564085705] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"117.536413ms","start":"2026-03-18T16:47:07.008849Z","end":"2026-03-18T16:47:07.126386Z","steps":["trace[1564085705] 'process raft request' (duration: 117.181725ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:47:12.670816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.398027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T16:47:12.67095Z","caller":"traceutil/trace.go:171","msg":"trace[1585782882] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:844; }","duration":"107.556831ms","start":"2026-03-18T16:47:12.563366Z","end":"2026-03-18T16:47:12.670923Z","steps":["trace[1585782882] 'range keys from in-memory index tree' (duration: 107.253254ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:47:13.334235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.959797ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"info","ts":"2026-03-18T16:47:13.334417Z","caller":"traceutil/trace.go:171","msg":"trace[535064477] linearizableReadLoop","detail":"{readStateIndex:884; appliedIndex:882; }","duration":"176.430691ms","start":"2026-03-18T16:47:13.157971Z","end":"2026-03-18T16:47:13.334402Z","steps":["trace[535064477] 'read index received' (duration: 29.269599ms)","trace[535064477] 'applied index is now lower than readState.Index' (duration: 147.160062ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T16:47:13.334734Z","caller":"traceutil/trace.go:171","msg":"trace[1504737493] transaction","detail":"{read_only:false; response_revision:846; number_of_response:1; }","duration":"201.21559ms","start":"2026-03-18T16:47:13.13351Z","end":"2026-03-18T16:47:13.334726Z","steps":["trace[1504737493] 'process raft request' (duration: 200.809901ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:47:13.334896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.915741ms","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-03-18T16:47:13.334924Z","caller":"traceutil/trace.go:171","msg":"trace[1231847772] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:846; }","duration":"176.963302ms","start":"2026-03-18T16:47:13.157953Z","end":"2026-03-18T16:47:13.334916Z","steps":["trace[1231847772] 'agreement among raft nodes before linearized reading' (duration: 176.87518ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:47:13.335057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.94042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-03-18T16:47:13.33508Z","caller":"traceutil/trace.go:171","msg":"trace[1290834801] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:846; }","duration":"175.97551ms","start":"2026-03-18T16:47:13.159099Z","end":"2026-03-18T16:47:13.335075Z","steps":["trace[1290834801] 'agreement among raft nodes before linearized reading' (duration: 175.911459ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:47:16.689894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.797439ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-18T16:47:16.689958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.93559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-18T16:47:16.690032Z","caller":"traceutil/trace.go:171","msg":"trace[1951730065] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:853; }","duration":"537.959193ms","start":"2026-03-18T16:47:16.152051Z","end":"2026-03-18T16:47:16.69001Z","steps":["trace[1951730065] 'range keys from in-memory index tree' (duration: 537.775029ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T16:47:16.690082Z","caller":"traceutil/trace.go:171","msg":"trace[1261176892] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:853; }","duration":"153.053062ms","start":"2026-03-18T16:47:16.536974Z","end":"2026-03-18T16:47:16.690027Z","steps":["trace[1261176892] 'range keys from in-memory index tree' (duration: 152.629983ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:47:16.690299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.278832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T16:47:16.690387Z","caller":"traceutil/trace.go:171","msg":"trace[410369957] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:853; }","duration":"326.437846ms","start":"2026-03-18T16:47:16.363925Z","end":"2026-03-18T16:47:16.690363Z","steps":["trace[410369957] 'count revisions from in-memory index tree' (duration: 326.18485ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:47:16.690425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:47:16.363899Z","time spent":"326.518148ms","remote":"127.0.0.1:57538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":27,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-03-18T16:47:16.690638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.266623443s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-18T16:47:16.69063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.266701835s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-03-18T16:47:16.690675Z","caller":"traceutil/trace.go:171","msg":"trace[953266081] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:853; }","duration":"1.266685634s","start":"2026-03-18T16:47:15.423979Z","end":"2026-03-18T16:47:16.690665Z","steps":["trace[953266081] 'range keys from in-memory index tree' (duration: 1.266500851s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:47:16.690215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.347452765s","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-03-18T16:47:16.690691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.127868139s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T16:47:16.690716Z","caller":"traceutil/trace.go:171","msg":"trace[262380042] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:853; }","duration":"1.266798647s","start":"2026-03-18T16:47:15.423896Z","end":"2026-03-18T16:47:16.690694Z","steps":["trace[262380042] 'range keys from in-memory index tree' (duration: 1.266412468s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:47:16.690921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:47:15.423827Z","time spent":"1.267056173s","remote":"127.0.0.1:57552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":453,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-18T16:47:16.690718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:47:15.423972Z","time spent":"1.266728996s","remote":"127.0.0.1:57552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-18T16:47:16.6908Z","caller":"traceutil/trace.go:171","msg":"trace[444014799] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:853; }","duration":"1.128012382s","start":"2026-03-18T16:47:15.562763Z","end":"2026-03-18T16:47:16.690776Z","steps":["trace[444014799] 'range keys from in-memory index tree' (duration: 1.127724734s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:47:16.691083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:47:15.56275Z","time spent":"1.128320099s","remote":"127.0.0.1:57306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-18T16:47:16.690795Z","caller":"traceutil/trace.go:171","msg":"trace[1580549653] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:853; }","duration":"1.348035208s","start":"2026-03-18T16:47:15.342673Z","end":"2026-03-18T16:47:16.690709Z","steps":["trace[1580549653] 'range keys from in-memory index tree' (duration: 1.347255861s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:47:16.691207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:47:15.342656Z","time spent":"1.348515458s","remote":"127.0.0.1:57552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-18T16:47:19.060182Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939133455307993223,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-18T16:47:19.561088Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939133455307993223,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-18T16:47:19.907964Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.185348458s","expected-duration":"1s"} {"level":"info","ts":"2026-03-18T16:47:19.978748Z","caller":"traceutil/trace.go:171","msg":"trace[2141379743] transaction","detail":"{read_only:false; response_revision:859; number_of_response:1; }","duration":"2.256215148s","start":"2026-03-18T16:47:17.722488Z","end":"2026-03-18T16:47:19.978704Z","steps":["trace[2141379743] 'process raft request' (duration: 2.185712195s)","trace[2141379743] 'compare' (duration: 68.274633ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T16:47:19.978961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:47:17.722456Z","time spent":"2.256386972s","remote":"127.0.0.1:57552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T16:47:20.56025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000436947s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-03-18T16:47:20.560338Z","caller":"traceutil/trace.go:171","msg":"trace[1790605306] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000593201s","start":"2026-03-18T16:47:18.559727Z","end":"2026-03-18T16:47:20.56032Z","steps":["trace[1790605306] 'agreement among raft nodes before linearized reading' (duration: 2.000466358s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:47:20.560385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:47:18.559707Z","time spent":"2.000668592s","remote":"127.0.0.1:57306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/03/18 16:47:20 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-18T16:47:20.942405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"823.498162ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-03-18T16:47:20.942516Z","caller":"traceutil/trace.go:171","msg":"trace[1546733648] linearizableReadLoop","detail":"{readStateIndex:898; appliedIndex:896; }","duration":"2.382725091s","start":"2026-03-18T16:47:18.559769Z","end":"2026-03-18T16:47:20.942494Z","steps":["trace[1546733648] 'read index received' (duration: 1.348449448s)","trace[1546733648] 'applied index is now lower than readState.Index' (duration: 1.034273693s)"],"step_count":2} {"level":"warn","ts":"2026-03-18T16:47:20.942803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.214634575s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-18T16:47:20.942836Z","caller":"traceutil/trace.go:171","msg":"trace[807154334] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:859; }","duration":"2.214707027s","start":"2026-03-18T16:47:18.72811Z","end":"2026-03-18T16:47:20.942817Z","steps":["trace[807154334] 'agreement among raft nodes before linearized reading' (duration: 2.214537493s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:47:20.942864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:47:18.728091Z","time spent":"2.214765688s","remote":"127.0.0.1:57552","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-03-18T16:47:20.943093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.200047071s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-18T16:47:20.943111Z","caller":"traceutil/trace.go:171","msg":"trace[1458880500] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:859; }","duration":"2.200087972s","start":"2026-03-18T16:47:18.743018Z","end":"2026-03-18T16:47:20.943106Z","steps":["trace[1458880500] 'agreement among raft nodes before linearized reading' (duration: 2.200024361s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:47:20.943127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:47:18.743006Z","time spent":"2.200117903s","remote":"127.0.0.1:57552","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-03-18T16:47:20.943178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.783451708s","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-03-18T16:47:20.943248Z","caller":"traceutil/trace.go:171","msg":"trace[1496339065] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:859; }","duration":"1.783552741s","start":"2026-03-18T16:47:19.159675Z","end":"2026-03-18T16:47:20.943228Z","steps":["trace[1496339065] 'agreement among raft nodes before linearized reading' (duration: 1.783342816s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:47:20.943258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.2004788s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-03-18T16:47:20.943281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:47:19.159662Z","time spent":"1.783613492s","remote":"127.0.0.1:57552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-18T16:47:20.943368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.201024244s","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-03-18T16:47:20.943395Z","caller":"traceutil/trace.go:171","msg":"trace[970066363] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:859; }","duration":"2.201080314s","start":"2026-03-18T16:47:18.742308Z","end":"2026-03-18T16:47:20.943389Z","steps":["trace[970066363] 'agreement among raft nodes before linearized reading' (duration: 2.200990282s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:47:20.943416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:47:18.742293Z","time spent":"2.201117776s","remote":"127.0.0.1:57552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-18T16:47:20.943495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.927117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T16:47:20.943523Z","caller":"traceutil/trace.go:171","msg":"trace[291177128] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:859; }","duration":"382.985228ms","start":"2026-03-18T16:47:20.560529Z","end":"2026-03-18T16:47:20.943515Z","steps":["trace[291177128] 'agreement among raft nodes before linearized reading' (duration: 382.942017ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:47:20.943541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:47:20.560515Z","time spent":"383.0218ms","remote":"127.0.0.1:57322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-18T16:47:20.943553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"845.410627ms","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-03-18T16:47:20.943578Z","caller":"traceutil/trace.go:171","msg":"trace[1871457100] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:859; }","duration":"845.467078ms","start":"2026-03-18T16:47:20.098104Z","end":"2026-03-18T16:47:20.943571Z","steps":["trace[1871457100] 'agreement among raft nodes before linearized reading' (duration: 845.376606ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:47:20.943597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:47:20.098088Z","time spent":"845.503589ms","remote":"127.0.0.1:57498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":42,"response size":29,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"info","ts":"2026-03-18T16:47:20.943287Z","caller":"traceutil/trace.go:171","msg":"trace[1535468537] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:859; }","duration":"2.200520231s","start":"2026-03-18T16:47:18.74276Z","end":"2026-03-18T16:47:20.943281Z","steps":["trace[1535468537] 'agreement among raft nodes before linearized reading' (duration: 2.200446659s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:47:20.943647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:47:18.742743Z","time spent":"2.20089634s","remote":"127.0.0.1:57552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-18T16:47:37.063606Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939133455307993386,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-18T16:47:37.315597Z","caller":"traceutil/trace.go:171","msg":"trace[1500060849] linearizableReadLoop","detail":"{readStateIndex:968; appliedIndex:967; }","duration":"752.496849ms","start":"2026-03-18T16:47:36.563078Z","end":"2026-03-18T16:47:37.315575Z","steps":["trace[1500060849] 'read index received' (duration: 752.264243ms)","trace[1500060849] 'applied index is now lower than readState.Index' (duration: 231.696µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T16:47:37.315696Z","caller":"traceutil/trace.go:171","msg":"trace[2101368247] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"817.004248ms","start":"2026-03-18T16:47:36.498685Z","end":"2026-03-18T16:47:37.31569Z","steps":["trace[2101368247] 'process raft request' (duration: 816.738952ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:47:37.31593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.640534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-03-18T16:47:37.315963Z","caller":"traceutil/trace.go:171","msg":"trace[157113594] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:926; }","duration":"258.676124ms","start":"2026-03-18T16:47:37.057278Z","end":"2026-03-18T16:47:37.315954Z","steps":["trace[157113594] 'agreement among raft nodes before linearized reading' (duration: 258.539091ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:47:37.316197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"753.130332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T16:47:37.316226Z","caller":"traceutil/trace.go:171","msg":"trace[1012928999] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:926; }","duration":"753.214574ms","start":"2026-03-18T16:47:36.563005Z","end":"2026-03-18T16:47:37.316219Z","steps":["trace[1012928999] 'agreement among raft nodes before linearized reading' (duration: 753.162783ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:47:37.316242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:47:36.562974Z","time spent":"753.264175ms","remote":"127.0.0.1:57322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-18T16:47:37.316073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.659516ms","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-03-18T16:47:37.316306Z","caller":"traceutil/trace.go:171","msg":"trace[216730948] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:926; }","duration":"273.945092ms","start":"2026-03-18T16:47:37.04233Z","end":"2026-03-18T16:47:37.316275Z","steps":["trace[216730948] 'agreement among raft nodes before linearized reading' (duration: 273.490182ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:47:37.31667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.634764ms","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-03-18T16:47:37.316696Z","caller":"traceutil/trace.go:171","msg":"trace[931085703] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:926; }","duration":"263.671954ms","start":"2026-03-18T16:47:37.053015Z","end":"2026-03-18T16:47:37.316687Z","steps":["trace[931085703] 'agreement among raft nodes before linearized reading' (duration: 263.607213ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:47:37.31688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.36502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-03-18T16:47:37.315962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.319271ms","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-03-18T16:47:37.316935Z","caller":"traceutil/trace.go:171","msg":"trace[2075254255] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:926; }","duration":"269.439502ms","start":"2026-03-18T16:47:37.047475Z","end":"2026-03-18T16:47:37.316914Z","steps":["trace[2075254255] 'agreement among raft nodes before linearized reading' (duration: 269.277259ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:47:37.315771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:47:36.498666Z","time spent":"817.05015ms","remote":"127.0.0.1:57384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":891,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-18T16:47:37.316975Z","caller":"traceutil/trace.go:171","msg":"trace[394133447] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:926; }","duration":"266.384674ms","start":"2026-03-18T16:47:37.050571Z","end":"2026-03-18T16:47:37.316956Z","steps":["trace[394133447] 'agreement among raft nodes before linearized reading' (duration: 265.25738ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T16:47:37.9331Z","caller":"traceutil/trace.go:171","msg":"trace[508487025] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"612.421064ms","start":"2026-03-18T16:47:37.320654Z","end":"2026-03-18T16:47:37.933075Z","steps":["trace[508487025] 'process raft request' (duration: 552.719961ms)","trace[508487025] 'compare' (duration: 59.389666ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T16:47:37.93321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:47:37.320637Z","time spent":"612.517987ms","remote":"127.0.0.1:57552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T16:47:37.939503Z","caller":"traceutil/trace.go:171","msg":"trace[1861087649] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"610.257496ms","start":"2026-03-18T16:47:37.329223Z","end":"2026-03-18T16:47:37.939481Z","steps":["trace[1861087649] 'process raft request' (duration: 610.199374ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T16:47:37.939546Z","caller":"traceutil/trace.go:171","msg":"trace[510921963] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"616.088045ms","start":"2026-03-18T16:47:37.323417Z","end":"2026-03-18T16:47:37.939505Z","steps":["trace[510921963] 'process raft request' (duration: 615.930332ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:47:37.939632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:47:37.32919Z","time spent":"610.374838ms","remote":"127.0.0.1:57552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T16:47:37.93975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:47:37.32341Z","time spent":"616.227218ms","remote":"127.0.0.1:57552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T16:47:37.939789Z","caller":"traceutil/trace.go:171","msg":"trace[1018196655] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"610.44946ms","start":"2026-03-18T16:47:37.329331Z","end":"2026-03-18T16:47:37.93978Z","steps":["trace[1018196655] 'process raft request' (duration: 610.115593ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:47:37.939838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:47:37.329323Z","time spent":"610.494061ms","remote":"127.0.0.1:57552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T16:47:37.940045Z","caller":"traceutil/trace.go:171","msg":"trace[96533131] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"608.929306ms","start":"2026-03-18T16:47:37.331083Z","end":"2026-03-18T16:47:37.940012Z","steps":["trace[96533131] 'process raft request' (duration: 608.66397ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:47:37.940152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.141775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T16:47:37.940199Z","caller":"traceutil/trace.go:171","msg":"trace[1222999486] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:932; }","duration":"380.223727ms","start":"2026-03-18T16:47:37.559967Z","end":"2026-03-18T16:47:37.940191Z","steps":["trace[1222999486] 'agreement among raft nodes before linearized reading' (duration: 380.151776ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:47:37.940219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:47:37.55995Z","time spent":"380.264278ms","remote":"127.0.0.1:57306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-18T16:47:37.940241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:47:37.331072Z","time spent":"609.063829ms","remote":"127.0.0.1:57552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T16:47:37.940264Z","caller":"traceutil/trace.go:171","msg":"trace[1378611685] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"333.163514ms","start":"2026-03-18T16:47:37.607089Z","end":"2026-03-18T16:47:37.940253Z","steps":["trace[1378611685] 'process raft request' (duration: 332.867807ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T16:47:37.940101Z","caller":"traceutil/trace.go:171","msg":"trace[107449412] linearizableReadLoop","detail":"{readStateIndex:973; appliedIndex:968; }","duration":"380.073793ms","start":"2026-03-18T16:47:37.560018Z","end":"2026-03-18T16:47:37.940092Z","steps":["trace[107449412] 'read index received' (duration: 313.511348ms)","trace[107449412] 'applied index is now lower than readState.Index' (duration: 66.561725ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T16:47:37.94034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:47:37.607069Z","time spent":"333.234316ms","remote":"127.0.0.1:57468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5778,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T16:47:38.862606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.61472ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"warn","ts":"2026-03-18T16:47:38.862957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.528883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T16:47:38.862743Z","caller":"traceutil/trace.go:171","msg":"trace[2047982272] linearizableReadLoop","detail":"{readStateIndex:981; appliedIndex:980; }","duration":"301.305999ms","start":"2026-03-18T16:47:38.561415Z","end":"2026-03-18T16:47:38.862721Z","steps":["trace[2047982272] 'read index received' (duration: 37.811µs)","trace[2047982272] 'applied index is now lower than readState.Index' (duration: 301.266228ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T16:47:38.863015Z","caller":"traceutil/trace.go:171","msg":"trace[1580170306] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:938; }","duration":"301.637436ms","start":"2026-03-18T16:47:38.561365Z","end":"2026-03-18T16:47:38.863002Z","steps":["trace[1580170306] 'agreement among raft nodes before linearized reading' (duration: 301.510003ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:47:38.863061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:47:38.561342Z","time spent":"301.706247ms","remote":"127.0.0.1:57306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-18T16:47:40.235042Z","caller":"traceutil/trace.go:171","msg":"trace[912353050] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"292.929073ms","start":"2026-03-18T16:47:39.942068Z","end":"2026-03-18T16:47:40.234997Z","steps":["trace[912353050] 'process raft request' (duration: 292.731669ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T16:47:40.237053Z","caller":"traceutil/trace.go:171","msg":"trace[1080362076] linearizableReadLoop","detail":"{readStateIndex:982; appliedIndex:982; }","duration":"292.589104ms","start":"2026-03-18T16:47:39.944448Z","end":"2026-03-18T16:47:40.237037Z","steps":["trace[1080362076] 'read index received' (duration: 292.583424ms)","trace[1080362076] 'applied index is now lower than readState.Index' (duration: 4.77µs)"],"step_count":2} {"level":"warn","ts":"2026-03-18T16:47:40.237395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.920272ms","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-03-18T16:47:40.237489Z","caller":"traceutil/trace.go:171","msg":"trace[843649578] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:939; }","duration":"293.074356ms","start":"2026-03-18T16:47:39.944402Z","end":"2026-03-18T16:47:40.237476Z","steps":["trace[843649578] 'agreement among raft nodes before linearized reading' (duration: 292.784509ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:47:40.254346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.330611ms","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-03-18T16:47:40.254639Z","caller":"traceutil/trace.go:171","msg":"trace[643648515] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:940; }","duration":"302.675129ms","start":"2026-03-18T16:47:39.951946Z","end":"2026-03-18T16:47:40.254622Z","steps":["trace[643648515] 'agreement among raft nodes before linearized reading' (duration: 302.26445ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:47:40.254759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:47:39.951923Z","time spent":"302.821122ms","remote":"127.0.0.1:57552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-18T16:47:40.254412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.748929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-18T16:47:40.254901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.558972ms","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-03-18T16:47:40.254983Z","caller":"traceutil/trace.go:171","msg":"trace[143674747] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:940; }","duration":"265.342641ms","start":"2026-03-18T16:47:39.989605Z","end":"2026-03-18T16:47:40.254948Z","steps":["trace[143674747] 'agreement among raft nodes before linearized reading' (duration: 264.642526ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T16:47:40.255059Z","caller":"traceutil/trace.go:171","msg":"trace[903581859] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:940; }","duration":"300.864349ms","start":"2026-03-18T16:47:39.954089Z","end":"2026-03-18T16:47:40.254953Z","steps":["trace[903581859] 'agreement among raft nodes before linearized reading' (duration: 300.204505ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:47:40.255479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:47:39.954069Z","time spent":"301.129724ms","remote":"127.0.0.1:57552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-18T16:47:40.34678Z","caller":"traceutil/trace.go:171","msg":"trace[1235492647] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"105.772615ms","start":"2026-03-18T16:47:40.240969Z","end":"2026-03-18T16:47:40.346741Z","steps":["trace[1235492647] 'process raft request' (duration: 67.69115ms)","trace[1235492647] 'compare' (duration: 37.855069ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T16:47:40.440603Z","caller":"traceutil/trace.go:171","msg":"trace[1857664135] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"181.058134ms","start":"2026-03-18T16:47:40.259507Z","end":"2026-03-18T16:47:40.440565Z","steps":["trace[1857664135] 'process raft request' (duration: 180.810208ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T16:47:40.440611Z","caller":"traceutil/trace.go:171","msg":"trace[620317672] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"179.960038ms","start":"2026-03-18T16:47:40.260618Z","end":"2026-03-18T16:47:40.440578Z","steps":["trace[620317672] 'process raft request' (duration: 179.874776ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T16:47:40.443128Z","caller":"traceutil/trace.go:171","msg":"trace[2140880634] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"182.047865ms","start":"2026-03-18T16:47:40.261012Z","end":"2026-03-18T16:47:40.44306Z","steps":["trace[2140880634] 'process raft request' (duration: 179.519959ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T16:47:42.365453Z","caller":"traceutil/trace.go:171","msg":"trace[979703051] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"113.624167ms","start":"2026-03-18T16:47:42.251792Z","end":"2026-03-18T16:47:42.365416Z","steps":["trace[979703051] 'process raft request' (duration: 113.206018ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T16:48:48.971739Z","caller":"traceutil/trace.go:171","msg":"trace[1358002312] linearizableReadLoop","detail":"{readStateIndex:1304; appliedIndex:1303; }","duration":"113.678804ms","start":"2026-03-18T16:48:48.858028Z","end":"2026-03-18T16:48:48.971707Z","steps":["trace[1358002312] 'read index received' (duration: 112.56898ms)","trace[1358002312] 'applied index is now lower than readState.Index' (duration: 1.108324ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T16:48:48.97182Z","caller":"traceutil/trace.go:171","msg":"trace[673530801] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"114.522291ms","start":"2026-03-18T16:48:48.857263Z","end":"2026-03-18T16:48:48.971785Z","steps":["trace[673530801] 'process raft request' (duration: 113.316296ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:48:48.9721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.962048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-18T16:48:48.972095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.033812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-18T16:48:48.972172Z","caller":"traceutil/trace.go:171","msg":"trace[521911386] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1247; }","duration":"112.0879ms","start":"2026-03-18T16:48:48.860064Z","end":"2026-03-18T16:48:48.972152Z","steps":["trace[521911386] 'agreement among raft nodes before linearized reading' (duration: 111.887836ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T16:48:48.972203Z","caller":"traceutil/trace.go:171","msg":"trace[110119539] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1247; }","duration":"114.157336ms","start":"2026-03-18T16:48:48.858014Z","end":"2026-03-18T16:48:48.972172Z","steps":["trace[110119539] 'agreement among raft nodes before linearized reading' (duration: 113.88849ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:48:48.972496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.835427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-18T16:48:48.972611Z","caller":"traceutil/trace.go:171","msg":"trace[1302100498] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1247; }","duration":"112.917589ms","start":"2026-03-18T16:48:48.859631Z","end":"2026-03-18T16:48:48.972549Z","steps":["trace[1302100498] 'agreement among raft nodes before linearized reading' (duration: 112.294085ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T16:50:55.47246Z","caller":"traceutil/trace.go:171","msg":"trace[1248398434] transaction","detail":"{read_only:false; response_revision:1785; number_of_response:1; }","duration":"101.827854ms","start":"2026-03-18T16:50:55.370607Z","end":"2026-03-18T16:50:55.472435Z","steps":["trace[1248398434] 'process raft request' (duration: 101.429886ms)"],"step_count":1}