{"level":"warn","ts":"2026-07-08T15:27:03.111365Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-08T15:27:03.111649Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.100:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.100:2380","--initial-cluster=instance=https://199.19.213.100:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.100:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.100:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-08T15:27:03.111792Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-08T15:27:03.111808Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.100:2380"]} {"level":"info","ts":"2026-07-08T15:27:03.111833Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-08T15:27:03.113068Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.100:2379"]} {"level":"info","ts":"2026-07-08T15:27:03.113163Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.100:2380"],"listen-peer-urls":["https://199.19.213.100:2380"],"advertise-client-urls":["https://199.19.213.100:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.100:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.100:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-08T15:27:03.122499Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.25801ms"} {"level":"info","ts":"2026-07-08T15:27:03.138953Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"79e65b4197a2a1a","cluster-id":"bba89cbb2a6fc22a"} {"level":"info","ts":"2026-07-08T15:27:03.139105Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a switched to configuration voters=()"} {"level":"info","ts":"2026-07-08T15:27:03.139149Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a became follower at term 0"} {"level":"info","ts":"2026-07-08T15:27:03.139167Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 79e65b4197a2a1a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-08T15:27:03.139178Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a became follower at term 1"} {"level":"info","ts":"2026-07-08T15:27:03.139243Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a switched to configuration voters=(548988028781734426)"} {"level":"warn","ts":"2026-07-08T15:27:03.150787Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-08T15:27:03.153088Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-08T15:27:03.153961Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-08T15:27:03.156412Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"79e65b4197a2a1a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-08T15:27:03.159668Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-08T15:27:03.159864Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-08T15:27:03.159884Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-08T15:27:03.159781Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"79e65b4197a2a1a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-08T15:27:03.162048Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a switched to configuration voters=(548988028781734426)"} {"level":"info","ts":"2026-07-08T15:27:03.162985Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"bba89cbb2a6fc22a","local-member-id":"79e65b4197a2a1a","added-peer-id":"79e65b4197a2a1a","added-peer-peer-urls":["https://199.19.213.100:2380"]} {"level":"info","ts":"2026-07-08T15:27:03.162988Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-08T15:27:03.163026Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.100:2380"} {"level":"info","ts":"2026-07-08T15:27:03.163249Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.100:2380"} {"level":"info","ts":"2026-07-08T15:27:03.163648Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"79e65b4197a2a1a","initial-advertise-peer-urls":["https://199.19.213.100:2380"],"listen-peer-urls":["https://199.19.213.100:2380"],"advertise-client-urls":["https://199.19.213.100:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.100:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-08T15:27:03.163705Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-08T15:27:03.839818Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a is starting a new election at term 1"} {"level":"info","ts":"2026-07-08T15:27:03.839891Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a became pre-candidate at term 1"} {"level":"info","ts":"2026-07-08T15:27:03.839912Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a received MsgPreVoteResp from 79e65b4197a2a1a at term 1"} {"level":"info","ts":"2026-07-08T15:27:03.839929Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a became candidate at term 2"} {"level":"info","ts":"2026-07-08T15:27:03.839935Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a received MsgVoteResp from 79e65b4197a2a1a at term 2"} {"level":"info","ts":"2026-07-08T15:27:03.839948Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a became leader at term 2"} {"level":"info","ts":"2026-07-08T15:27:03.839957Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 79e65b4197a2a1a elected leader 79e65b4197a2a1a at term 2"} {"level":"info","ts":"2026-07-08T15:27:03.841195Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"79e65b4197a2a1a","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.100:2379]}","request-path":"/0/members/79e65b4197a2a1a/attributes","cluster-id":"bba89cbb2a6fc22a","publish-timeout":"7s"} {"level":"info","ts":"2026-07-08T15:27:03.841358Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-08T15:27:03.841891Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T15:27:03.842234Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-08T15:27:03.844477Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-08T15:27:03.844713Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"bba89cbb2a6fc22a","local-member-id":"79e65b4197a2a1a","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T15:27:03.844758Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-08T15:27:03.844859Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-08T15:27:03.844968Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T15:27:03.845014Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T15:27:03.848109Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.100:2379"} {"level":"warn","ts":"2026-07-08T15:27:07.50057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.442349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.100\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T15:27:07.500689Z","caller":"traceutil/trace.go:171","msg":"trace[1736134319] range","detail":"{range_begin:/registry/masterleases/199.19.213.100; range_end:; response_count:0; response_revision:194; }","duration":"286.595695ms","start":"2026-07-08T15:27:07.214072Z","end":"2026-07-08T15:27:07.500667Z","steps":["trace[1736134319] 'range keys from in-memory index tree' (duration: 286.391707ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:27:07.709196Z","caller":"traceutil/trace.go:171","msg":"trace[278937685] transaction","detail":"{read_only:false; response_revision:195; number_of_response:1; }","duration":"374.004134ms","start":"2026-07-08T15:27:07.335168Z","end":"2026-07-08T15:27:07.709172Z","steps":["trace[278937685] 'process raft request' (duration: 373.863518ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:27:07.709845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:27:07.335142Z","time spent":"374.159721ms","remote":"127.0.0.1:35144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T15:27:07.732012Z","caller":"traceutil/trace.go:171","msg":"trace[1773285622] transaction","detail":"{read_only:false; response_revision:196; number_of_response:1; }","duration":"349.450608ms","start":"2026-07-08T15:27:07.382529Z","end":"2026-07-08T15:27:07.731979Z","steps":["trace[1773285622] 'process raft request' (duration: 349.211878ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:27:07.732172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:27:07.382494Z","time spent":"349.605694ms","remote":"127.0.0.1:35094","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1069,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-08T15:27:08.281246Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3033912406263063779,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-08T15:27:08.782105Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3033912406263063779,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-08T15:27:09.283283Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3033912406263063779,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-08T15:27:09.710968Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.933568086s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-08T15:27:11.300139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.588636856s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-08T15:27:11.300243Z","caller":"traceutil/trace.go:171","msg":"trace[699418324] linearizableReadLoop","detail":"{readStateIndex:203; appliedIndex:202; }","duration":"3.519964312s","start":"2026-07-08T15:27:07.78026Z","end":"2026-07-08T15:27:11.300225Z","steps":["trace[699418324] 'read index received' (duration: 1.930989611s)","trace[699418324] 'applied index is now lower than readState.Index' (duration: 1.588973561s)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:27:11.300326Z","caller":"traceutil/trace.go:171","msg":"trace[741437130] transaction","detail":"{read_only:false; response_revision:198; number_of_response:1; }","duration":"3.562948977s","start":"2026-07-08T15:27:07.737366Z","end":"2026-07-08T15:27:11.300315Z","steps":["trace[741437130] 'process raft request' (duration: 1.973886733s)","trace[741437130] 'compare' (duration: 1.588270031s)"],"step_count":2} {"level":"warn","ts":"2026-07-08T15:27:11.30038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:27:07.737354Z","time spent":"3.56300383s","remote":"127.0.0.1:35322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":342,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-08T15:27:11.300622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.520354618s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T15:27:11.300659Z","caller":"traceutil/trace.go:171","msg":"trace[280775420] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:0; response_revision:198; }","duration":"3.520432262s","start":"2026-07-08T15:27:07.780216Z","end":"2026-07-08T15:27:11.300649Z","steps":["trace[280775420] 'agreement among raft nodes before linearized reading' (duration: 3.520333807s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:27:11.300691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:27:07.780197Z","time spent":"3.520486904s","remote":"127.0.0.1:35138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":0,"response size":28,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-07-08T15:27:11.801007Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3033912406263063788,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-08T15:27:12.301897Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3033912406263063788,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-08T15:27:12.802695Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3033912406263063788,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-08T15:27:12.929414Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.212794701s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-08T15:27:13.734593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.998493708s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-08T15:27:13.734976Z","caller":"traceutil/trace.go:171","msg":"trace[260444896] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"4.998909265s","start":"2026-07-08T15:27:08.735932Z","end":"2026-07-08T15:27:13.734842Z","steps":["trace[260444896] 'agreement among raft nodes before linearized reading' (duration: 4.998537069s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:27:13.735286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:27:08.735901Z","time spent":"4.999308472s","remote":"127.0.0.1:35250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/07/08 15:27:13 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-08T15:27:14.201126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.271201522s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-08T15:27:14.201303Z","caller":"traceutil/trace.go:171","msg":"trace[1373346764] transaction","detail":"{read_only:false; response_revision:199; number_of_response:1; }","duration":"4.587411804s","start":"2026-07-08T15:27:09.613865Z","end":"2026-07-08T15:27:14.201277Z","steps":["trace[1373346764] 'process raft request' (duration: 3.3159529s)","trace[1373346764] 'compare' (duration: 1.270970832s)"],"step_count":2} {"level":"warn","ts":"2026-07-08T15:27:14.201596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:27:09.61383Z","time spent":"4.587729177s","remote":"127.0.0.1:35144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-08T15:27:14.20306Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.273424399s","expected-duration":"1s"} {"level":"info","ts":"2026-07-08T15:27:14.203927Z","caller":"traceutil/trace.go:171","msg":"trace[1175339319] transaction","detail":"{read_only:false; response_revision:200; number_of_response:1; }","duration":"3.597915979s","start":"2026-07-08T15:27:10.605938Z","end":"2026-07-08T15:27:14.203854Z","steps":["trace[1175339319] 'process raft request' (duration: 3.597615295s)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:27:14.203935Z","caller":"traceutil/trace.go:171","msg":"trace[1294029908] linearizableReadLoop","detail":"{readStateIndex:206; appliedIndex:203; }","duration":"2.903610036s","start":"2026-07-08T15:27:11.300284Z","end":"2026-07-08T15:27:14.203894Z","steps":["trace[1294029908] 'read index received' (duration: 1.629380952s)","trace[1294029908] 'applied index is now lower than readState.Index' (duration: 1.274227634s)"],"step_count":2} {"level":"warn","ts":"2026-07-08T15:27:14.204127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:27:10.605904Z","time spent":"3.598124267s","remote":"127.0.0.1:35144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5882,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-08T15:27:14.204232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.38128165s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T15:27:14.204296Z","caller":"traceutil/trace.go:171","msg":"trace[1490689475] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:202; }","duration":"4.381389734s","start":"2026-07-08T15:27:09.82289Z","end":"2026-07-08T15:27:14.20428Z","steps":["trace[1490689475] 'agreement among raft nodes before linearized reading' (duration: 4.381217057s)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:27:14.204308Z","caller":"traceutil/trace.go:171","msg":"trace[464363597] transaction","detail":"{read_only:false; response_revision:202; number_of_response:1; }","duration":"1.317366758s","start":"2026-07-08T15:27:12.886918Z","end":"2026-07-08T15:27:14.204284Z","steps":["trace[464363597] 'process raft request' (duration: 1.316917039s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:27:14.204318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.115255603s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-08T15:27:14.204359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:27:09.822873Z","time spent":"4.381473328s","remote":"127.0.0.1:35250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-08T15:27:14.204378Z","caller":"traceutil/trace.go:171","msg":"trace[2067141341] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:202; }","duration":"5.115359899s","start":"2026-07-08T15:27:09.088996Z","end":"2026-07-08T15:27:14.204356Z","steps":["trace[2067141341] 'agreement among raft nodes before linearized reading' (duration: 5.115277395s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:27:14.204388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:27:12.886881Z","time spent":"1.317468954s","remote":"127.0.0.1:35144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3965,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-08T15:27:14.204423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:27:09.088965Z","time spent":"5.115449232s","remote":"127.0.0.1:35250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-08T15:27:14.204235Z","caller":"traceutil/trace.go:171","msg":"trace[735161763] transaction","detail":"{read_only:false; response_revision:201; number_of_response:1; }","duration":"2.899636507s","start":"2026-07-08T15:27:11.304576Z","end":"2026-07-08T15:27:14.204212Z","steps":["trace[735161763] 'process raft request' (duration: 2.899190508s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:27:14.204497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:27:11.304558Z","time spent":"2.899903768s","remote":"127.0.0.1:35326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-08T15:27:14.204228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.496546762s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T15:27:14.204589Z","caller":"traceutil/trace.go:171","msg":"trace[1605125753] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:202; }","duration":"4.497017692s","start":"2026-07-08T15:27:09.707561Z","end":"2026-07-08T15:27:14.204579Z","steps":["trace[1605125753] 'agreement among raft nodes before linearized reading' (duration: 4.496541692s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:27:14.20462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:27:09.707531Z","time spent":"4.497082685s","remote":"127.0.0.1:35250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-08T15:27:14.237171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.9336884s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-08T15:27:14.237275Z","caller":"traceutil/trace.go:171","msg":"trace[1900429302] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:202; }","duration":"2.933926071s","start":"2026-07-08T15:27:11.30332Z","end":"2026-07-08T15:27:14.237246Z","steps":["trace[1900429302] 'agreement among raft nodes before linearized reading' (duration: 2.933758683s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:27:14.237334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:27:11.303297Z","time spent":"2.934022905s","remote":"127.0.0.1:34996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"warn","ts":"2026-07-08T15:27:14.237315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.13106367s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:5513"} {"level":"info","ts":"2026-07-08T15:27:14.237452Z","caller":"traceutil/trace.go:171","msg":"trace[853519117] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:202; }","duration":"2.131268239s","start":"2026-07-08T15:27:12.106154Z","end":"2026-07-08T15:27:14.237422Z","steps":["trace[853519117] 'agreement among raft nodes before linearized reading' (duration: 2.130993836s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:27:14.237515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:27:12.106134Z","time spent":"2.131367093s","remote":"127.0.0.1:35144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":5536,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-07-08T15:27:14.238131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:27:12.939753Z","time spent":"1.298374724s","remote":"127.0.0.1:35058","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-08T15:27:14.594042Z","caller":"traceutil/trace.go:171","msg":"trace[931188380] transaction","detail":"{read_only:false; response_revision:221; number_of_response:1; }","duration":"157.518751ms","start":"2026-07-08T15:27:14.436492Z","end":"2026-07-08T15:27:14.594011Z","steps":["trace[931188380] 'process raft request' (duration: 153.257854ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:27:14.787587Z","caller":"traceutil/trace.go:171","msg":"trace[313512720] transaction","detail":"{read_only:false; response_revision:226; number_of_response:1; }","duration":"112.712525ms","start":"2026-07-08T15:27:14.674852Z","end":"2026-07-08T15:27:14.787564Z","steps":["trace[313512720] 'process raft request' (duration: 112.535748ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:27:15.260838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.907891ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T15:27:15.260933Z","caller":"traceutil/trace.go:171","msg":"trace[450800529] transaction","detail":"{read_only:false; response_revision:243; number_of_response:1; }","duration":"176.882839ms","start":"2026-07-08T15:27:15.084034Z","end":"2026-07-08T15:27:15.260917Z","steps":["trace[450800529] 'process raft request' (duration: 45.720276ms)","trace[450800529] 'compare' (duration: 130.811888ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:27:15.396863Z","caller":"traceutil/trace.go:171","msg":"trace[718788412] transaction","detail":"{read_only:false; response_revision:244; number_of_response:1; }","duration":"131.032937ms","start":"2026-07-08T15:27:15.265799Z","end":"2026-07-08T15:27:15.396832Z","steps":["trace[718788412] 'process raft request' (duration: 115.537281ms)","trace[718788412] 'compare' (duration: 15.37308ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:27:15.581219Z","caller":"traceutil/trace.go:171","msg":"trace[535595899] transaction","detail":"{read_only:false; response_revision:249; number_of_response:1; }","duration":"111.521073ms","start":"2026-07-08T15:27:15.469663Z","end":"2026-07-08T15:27:15.581184Z","steps":["trace[535595899] 'process raft request' (duration: 50.195875ms)","trace[535595899] 'compare' (duration: 61.189862ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:27:15.789468Z","caller":"traceutil/trace.go:171","msg":"trace[802186880] transaction","detail":"{read_only:false; response_revision:251; number_of_response:1; }","duration":"200.680734ms","start":"2026-07-08T15:27:15.588763Z","end":"2026-07-08T15:27:15.789444Z","steps":["trace[802186880] 'process raft request' (duration: 131.800941ms)","trace[802186880] 'compare' (duration: 68.499475ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:27:15.789707Z","caller":"traceutil/trace.go:171","msg":"trace[1385782203] transaction","detail":"{read_only:false; response_revision:252; number_of_response:1; }","duration":"168.352391ms","start":"2026-07-08T15:27:15.621319Z","end":"2026-07-08T15:27:15.789672Z","steps":["trace[1385782203] 'process raft request' (duration: 168.072919ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:27:38.673019Z","caller":"traceutil/trace.go:171","msg":"trace[816813826] linearizableReadLoop","detail":"{readStateIndex:498; appliedIndex:497; }","duration":"153.847912ms","start":"2026-07-08T15:27:38.519136Z","end":"2026-07-08T15:27:38.672984Z","steps":["trace[816813826] 'read index received' (duration: 153.601459ms)","trace[816813826] 'applied index is now lower than readState.Index' (duration: 244.923µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:27:38.673115Z","caller":"traceutil/trace.go:171","msg":"trace[502405828] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"154.927009ms","start":"2026-07-08T15:27:38.518151Z","end":"2026-07-08T15:27:38.673078Z","steps":["trace[502405828] 'process raft request' (duration: 154.622453ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:27:38.67329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.121637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-08T15:27:38.673374Z","caller":"traceutil/trace.go:171","msg":"trace[1584190641] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:477; }","duration":"154.281695ms","start":"2026-07-08T15:27:38.519072Z","end":"2026-07-08T15:27:38.673354Z","steps":["trace[1584190641] 'agreement among raft nodes before linearized reading' (duration: 154.065454ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:27:38.677956Z","caller":"traceutil/trace.go:171","msg":"trace[1860318108] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"156.882202ms","start":"2026-07-08T15:27:38.521057Z","end":"2026-07-08T15:27:38.67794Z","steps":["trace[1860318108] 'process raft request' (duration: 156.757716ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:27:46.961053Z","caller":"traceutil/trace.go:171","msg":"trace[1530630539] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"630.082241ms","start":"2026-07-08T15:27:46.330939Z","end":"2026-07-08T15:27:46.961021Z","steps":["trace[1530630539] 'process raft request' (duration: 629.886439ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:27:46.961259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:27:46.330891Z","time spent":"630.26387ms","remote":"127.0.0.1:35250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T15:27:46.978104Z","caller":"traceutil/trace.go:171","msg":"trace[1096557741] linearizableReadLoop","detail":"{readStateIndex:515; appliedIndex:513; }","duration":"264.577094ms","start":"2026-07-08T15:27:46.713479Z","end":"2026-07-08T15:27:46.978056Z","steps":["trace[1096557741] 'read index received' (duration: 247.443122ms)","trace[1096557741] 'applied index is now lower than readState.Index' (duration: 17.132242ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:27:46.978135Z","caller":"traceutil/trace.go:171","msg":"trace[2032034686] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"595.242675ms","start":"2026-07-08T15:27:46.382838Z","end":"2026-07-08T15:27:46.978081Z","steps":["trace[2032034686] 'process raft request' (duration: 581.536281ms)","trace[2032034686] 'compare' (duration: 13.410708ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T15:27:46.978343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.009773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-08T15:27:46.978326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.804657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-08T15:27:46.978407Z","caller":"traceutil/trace.go:171","msg":"trace[726481815] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:493; }","duration":"264.959226ms","start":"2026-07-08T15:27:46.713428Z","end":"2026-07-08T15:27:46.978388Z","steps":["trace[726481815] 'agreement among raft nodes before linearized reading' (duration: 264.773835ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:27:46.978418Z","caller":"traceutil/trace.go:171","msg":"trace[1135404548] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:493; }","duration":"260.113849ms","start":"2026-07-08T15:27:46.718263Z","end":"2026-07-08T15:27:46.978377Z","steps":["trace[1135404548] 'agreement among raft nodes before linearized reading' (duration: 260.020054ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:27:46.978397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.406805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-08T15:27:46.978487Z","caller":"traceutil/trace.go:171","msg":"trace[415445124] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:493; }","duration":"258.556104ms","start":"2026-07-08T15:27:46.719905Z","end":"2026-07-08T15:27:46.978461Z","steps":["trace[415445124] 'agreement among raft nodes before linearized reading' (duration: 258.402385ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:27:46.978511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:27:46.382816Z","time spent":"595.451726ms","remote":"127.0.0.1:35250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T15:27:50.38608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.179688154s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T15:27:50.386195Z","caller":"traceutil/trace.go:171","msg":"trace[858742298] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:536; }","duration":"1.179825592s","start":"2026-07-08T15:27:49.206354Z","end":"2026-07-08T15:27:50.38618Z","steps":["trace[858742298] 'range keys from in-memory index tree' (duration: 1.179666453s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:27:50.392367Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.070547016s","expected-duration":"1s"} {"level":"info","ts":"2026-07-08T15:27:50.392745Z","caller":"traceutil/trace.go:171","msg":"trace[1544774637] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"1.071106288s","start":"2026-07-08T15:27:49.321616Z","end":"2026-07-08T15:27:50.392722Z","steps":["trace[1544774637] 'process raft request' (duration: 1.070961719s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:27:50.392906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:27:49.321583Z","time spent":"1.071246556s","remote":"127.0.0.1:35016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T15:27:50.400208Z","caller":"traceutil/trace.go:171","msg":"trace[2055041805] linearizableReadLoop","detail":"{readStateIndex:571; appliedIndex:560; }","duration":"277.503633ms","start":"2026-07-08T15:27:50.122678Z","end":"2026-07-08T15:27:50.400182Z","steps":["trace[2055041805] 'read index received' (duration: 270.209425ms)","trace[2055041805] 'applied index is now lower than readState.Index' (duration: 7.293318ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T15:27:50.400342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.64449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T15:27:50.400367Z","caller":"traceutil/trace.go:171","msg":"trace[989033395] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:548; }","duration":"277.752937ms","start":"2026-07-08T15:27:50.122608Z","end":"2026-07-08T15:27:50.400361Z","steps":["trace[989033395] 'agreement among raft nodes before linearized reading' (duration: 277.668622ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:27:50.400521Z","caller":"traceutil/trace.go:171","msg":"trace[1459897368] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"1.054342614s","start":"2026-07-08T15:27:49.346132Z","end":"2026-07-08T15:27:50.400474Z","steps":["trace[1459897368] 'process raft request' (duration: 1.053628124s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:27:50.400696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:27:49.346114Z","time spent":"1.054496562s","remote":"127.0.0.1:35016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T15:27:50.400723Z","caller":"traceutil/trace.go:171","msg":"trace[1579178204] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"1.053138697s","start":"2026-07-08T15:27:49.347573Z","end":"2026-07-08T15:27:50.400712Z","steps":["trace[1579178204] 'process raft request' (duration: 1.052319862s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:27:50.400805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:27:49.347567Z","time spent":"1.053182389s","remote":"127.0.0.1:35016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T15:27:50.400889Z","caller":"traceutil/trace.go:171","msg":"trace[1439414219] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"1.071493401s","start":"2026-07-08T15:27:49.329381Z","end":"2026-07-08T15:27:50.400874Z","steps":["trace[1439414219] 'process raft request' (duration: 1.070065982s)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:27:50.400942Z","caller":"traceutil/trace.go:171","msg":"trace[325956600] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"1.041491147s","start":"2026-07-08T15:27:49.359444Z","end":"2026-07-08T15:27:50.400935Z","steps":["trace[325956600] 'process raft request' (duration: 1.040498061s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:27:50.400942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:27:49.329358Z","time spent":"1.071562215s","remote":"127.0.0.1:35016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-08T15:27:50.400976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:27:49.359426Z","time spent":"1.041536679s","remote":"127.0.0.1:35016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T15:27:50.401045Z","caller":"traceutil/trace.go:171","msg":"trace[1081214119] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"1.041182249s","start":"2026-07-08T15:27:49.359857Z","end":"2026-07-08T15:27:50.40104Z","steps":["trace[1081214119] 'process raft request' (duration: 1.040161043s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:27:50.401073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:27:49.359805Z","time spent":"1.041257354s","remote":"127.0.0.1:35016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T15:27:50.401126Z","caller":"traceutil/trace.go:171","msg":"trace[1427808998] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"1.019251207s","start":"2026-07-08T15:27:49.381867Z","end":"2026-07-08T15:27:50.401119Z","steps":["trace[1427808998] 'process raft request' (duration: 1.018198557s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:27:50.401156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:27:49.381848Z","time spent":"1.019295559s","remote":"127.0.0.1:35016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T15:27:50.401171Z","caller":"traceutil/trace.go:171","msg":"trace[1886388560] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"1.065756002s","start":"2026-07-08T15:27:49.335401Z","end":"2026-07-08T15:27:50.401157Z","steps":["trace[1886388560] 'process raft request' (duration: 1.064215735s)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:27:50.401253Z","caller":"traceutil/trace.go:171","msg":"trace[1441487628] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"892.962491ms","start":"2026-07-08T15:27:49.508283Z","end":"2026-07-08T15:27:50.401245Z","steps":["trace[1441487628] 'process raft request' (duration: 891.846728ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:27:50.40125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:27:49.335371Z","time spent":"1.065832827s","remote":"127.0.0.1:35016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-08T15:27:50.401288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:27:49.508241Z","time spent":"893.033884ms","remote":"127.0.0.1:35016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T15:27:50.401357Z","caller":"traceutil/trace.go:171","msg":"trace[2034629850] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"1.060980745s","start":"2026-07-08T15:27:49.340362Z","end":"2026-07-08T15:27:50.401343Z","steps":["trace[2034629850] 'process raft request' (duration: 1.059299301s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:27:50.401409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:27:49.340342Z","time spent":"1.061044199s","remote":"127.0.0.1:35016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T15:27:50.401695Z","caller":"traceutil/trace.go:171","msg":"trace[305764607] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"1.059049537s","start":"2026-07-08T15:27:49.342637Z","end":"2026-07-08T15:27:50.401687Z","steps":["trace[305764607] 'process raft request' (duration: 1.057056905s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:27:50.401734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:27:49.342621Z","time spent":"1.05909762s","remote":"127.0.0.1:35016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T15:27:50.401801Z","caller":"traceutil/trace.go:171","msg":"trace[148850638] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"1.057246777s","start":"2026-07-08T15:27:49.344531Z","end":"2026-07-08T15:27:50.401778Z","steps":["trace[148850638] 'process raft request' (duration: 1.055193572s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:27:50.401959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:27:49.344522Z","time spent":"1.057353933s","remote":"127.0.0.1:35016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T15:27:51.24977Z","caller":"traceutil/trace.go:171","msg":"trace[984960042] linearizableReadLoop","detail":"{readStateIndex:630; appliedIndex:629; }","duration":"124.156939ms","start":"2026-07-08T15:27:51.125586Z","end":"2026-07-08T15:27:51.249743Z","steps":["trace[984960042] 'read index received' (duration: 121.829999ms)","trace[984960042] 'applied index is now lower than readState.Index' (duration: 2.32524ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:27:51.25013Z","caller":"traceutil/trace.go:171","msg":"trace[1697828494] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"126.645661ms","start":"2026-07-08T15:27:51.123471Z","end":"2026-07-08T15:27:51.250117Z","steps":["trace[1697828494] 'process raft request' (duration: 124.010022ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:27:51.250409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.792715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T15:27:51.250456Z","caller":"traceutil/trace.go:171","msg":"trace[939573468] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:601; }","duration":"124.885831ms","start":"2026-07-08T15:27:51.125559Z","end":"2026-07-08T15:27:51.250444Z","steps":["trace[939573468] 'agreement among raft nodes before linearized reading' (duration: 124.786816ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:27:51.250651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.831688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T15:27:51.250687Z","caller":"traceutil/trace.go:171","msg":"trace[1114254627] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:601; }","duration":"124.915112ms","start":"2026-07-08T15:27:51.125763Z","end":"2026-07-08T15:27:51.250678Z","steps":["trace[1114254627] 'agreement among raft nodes before linearized reading' (duration: 124.859499ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:27:51.251895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.494673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T15:27:51.251952Z","caller":"traceutil/trace.go:171","msg":"trace[378700713] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:601; }","duration":"104.565066ms","start":"2026-07-08T15:27:51.147374Z","end":"2026-07-08T15:27:51.251939Z","steps":["trace[378700713] 'agreement among raft nodes before linearized reading' (duration: 104.484241ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:27:51.252168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.534223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T15:27:51.252209Z","caller":"traceutil/trace.go:171","msg":"trace[925871993] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:601; }","duration":"107.588037ms","start":"2026-07-08T15:27:51.144612Z","end":"2026-07-08T15:27:51.2522Z","steps":["trace[925871993] 'agreement among raft nodes before linearized reading' (duration: 107.528713ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:27:51.25238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.110655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T15:27:51.252418Z","caller":"traceutil/trace.go:171","msg":"trace[2083099619] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:601; }","duration":"108.167768ms","start":"2026-07-08T15:27:51.144241Z","end":"2026-07-08T15:27:51.252409Z","steps":["trace[2083099619] 'agreement among raft nodes before linearized reading' (duration: 108.101985ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:27:57.514827Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3033912406263064693,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-08T15:27:57.990197Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.0120584s","expected-duration":"1s"} {"level":"info","ts":"2026-07-08T15:27:57.999398Z","caller":"traceutil/trace.go:171","msg":"trace[75976558] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"1.022811391s","start":"2026-07-08T15:27:56.976549Z","end":"2026-07-08T15:27:57.99936Z","steps":["trace[75976558] 'process raft request' (duration: 1.014278948s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:27:57.999596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:27:56.976509Z","time spent":"1.02297915s","remote":"127.0.0.1:35082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T15:27:58.281083Z","caller":"traceutil/trace.go:171","msg":"trace[481724324] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"1.265160364s","start":"2026-07-08T15:27:57.015878Z","end":"2026-07-08T15:27:58.281038Z","steps":["trace[481724324] 'process raft request' (duration: 1.24972166s)","trace[481724324] 'compare' (duration: 14.950266ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:27:58.28118Z","caller":"traceutil/trace.go:171","msg":"trace[770784904] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"1.102078013s","start":"2026-07-08T15:27:57.179075Z","end":"2026-07-08T15:27:58.281153Z","steps":["trace[770784904] 'process raft request' (duration: 1.101802447s)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:27:58.281174Z","caller":"traceutil/trace.go:171","msg":"trace[91535742] linearizableReadLoop","detail":"{readStateIndex:673; appliedIndex:670; }","duration":"1.266577957s","start":"2026-07-08T15:27:57.014489Z","end":"2026-07-08T15:27:58.281067Z","steps":["trace[91535742] 'read index received' (duration: 975.948563ms)","trace[91535742] 'applied index is now lower than readState.Index' (duration: 290.627744ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T15:27:58.281275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:27:57.015846Z","time spent":"1.265326664s","remote":"127.0.0.1:35250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T15:27:58.281303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:27:57.179056Z","time spent":"1.102180998s","remote":"127.0.0.1:35250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T15:27:58.281574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.267085015s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-08T15:27:58.281695Z","caller":"traceutil/trace.go:171","msg":"trace[327186663] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:643; }","duration":"1.267251677s","start":"2026-07-08T15:27:57.014421Z","end":"2026-07-08T15:27:58.281673Z","steps":["trace[327186663] 'agreement among raft nodes before linearized reading' (duration: 1.266845612s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:27:58.281852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:27:57.014394Z","time spent":"1.267333961s","remote":"127.0.0.1:35250","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-07-08T15:27:59.07334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.905958ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-08T15:27:59.073517Z","caller":"traceutil/trace.go:171","msg":"trace[265046925] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"786.686641ms","start":"2026-07-08T15:27:58.286811Z","end":"2026-07-08T15:27:59.073497Z","steps":["trace[265046925] 'process raft request' (duration: 786.620997ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:27:59.073553Z","caller":"traceutil/trace.go:171","msg":"trace[864517205] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"1.013605846s","start":"2026-07-08T15:27:58.059916Z","end":"2026-07-08T15:27:59.073522Z","steps":["trace[864517205] 'process raft request' (duration: 335.446403ms)","trace[864517205] 'compare' (duration: 677.770021ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:27:59.073565Z","caller":"traceutil/trace.go:171","msg":"trace[410076604] linearizableReadLoop","detail":"{readStateIndex:674; appliedIndex:673; }","duration":"792.214891ms","start":"2026-07-08T15:27:58.281303Z","end":"2026-07-08T15:27:59.073518Z","steps":["trace[410076604] 'read index received' (duration: 114.065549ms)","trace[410076604] 'applied index is now lower than readState.Index' (duration: 678.145212ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T15:27:59.073603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:27:58.286791Z","time spent":"786.767756ms","remote":"127.0.0.1:35250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T15:27:59.073853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.913152163s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-08T15:27:59.073884Z","caller":"traceutil/trace.go:171","msg":"trace[824564174] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:645; }","duration":"1.913200546s","start":"2026-07-08T15:27:57.160675Z","end":"2026-07-08T15:27:59.073875Z","steps":["trace[824564174] 'agreement among raft nodes before linearized reading' (duration: 1.913087339s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:27:59.073925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:27:57.160667Z","time spent":"1.913247538s","remote":"127.0.0.1:35250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-08T15:27:59.074004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.914729393s","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-07-08T15:27:59.074008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.898244122s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.100\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-08T15:27:59.074055Z","caller":"traceutil/trace.go:171","msg":"trace[388735280] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:645; }","duration":"1.91485511s","start":"2026-07-08T15:27:57.159185Z","end":"2026-07-08T15:27:59.07404Z","steps":["trace[388735280] 'agreement among raft nodes before linearized reading' (duration: 1.914627507s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:27:59.074079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.913348864s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-08T15:27:59.074089Z","caller":"traceutil/trace.go:171","msg":"trace[208450069] range","detail":"{range_begin:/registry/masterleases/199.19.213.100; range_end:; response_count:1; response_revision:645; }","duration":"1.898363489s","start":"2026-07-08T15:27:57.175705Z","end":"2026-07-08T15:27:59.074069Z","steps":["trace[208450069] 'agreement among raft nodes before linearized reading' (duration: 1.898188419s)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:27:59.074111Z","caller":"traceutil/trace.go:171","msg":"trace[1633554784] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:645; }","duration":"1.913389506s","start":"2026-07-08T15:27:57.160713Z","end":"2026-07-08T15:27:59.074102Z","steps":["trace[1633554784] 'agreement among raft nodes before linearized reading' (duration: 1.913316462s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:27:59.074148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:27:57.160708Z","time spent":"1.913433439s","remote":"127.0.0.1:35250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-08T15:27:59.074153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:27:57.175693Z","time spent":"1.898451123s","remote":"127.0.0.1:34996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.100\" "} {"level":"warn","ts":"2026-07-08T15:27:59.074219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.258561036s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-pjwgk\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-07-08T15:27:59.074099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:27:57.159159Z","time spent":"1.914931084s","remote":"127.0.0.1:35250","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-07-08T15:27:59.074257Z","caller":"traceutil/trace.go:171","msg":"trace[904411580] range","detail":"{range_begin:/registry/pods/kube-system/cilium-pjwgk; range_end:; response_count:1; response_revision:645; }","duration":"1.25863204s","start":"2026-07-08T15:27:57.815616Z","end":"2026-07-08T15:27:59.074248Z","steps":["trace[904411580] 'agreement among raft nodes before linearized reading' (duration: 1.258512174s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:27:59.074285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:27:57.815558Z","time spent":"1.258721385s","remote":"127.0.0.1:35144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-pjwgk\" "} {"level":"warn","ts":"2026-07-08T15:27:59.074109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.954988006s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T15:27:59.074423Z","caller":"traceutil/trace.go:171","msg":"trace[1989518722] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:645; }","duration":"1.955391831s","start":"2026-07-08T15:27:57.119003Z","end":"2026-07-08T15:27:59.074395Z","steps":["trace[1989518722] 'agreement among raft nodes before linearized reading' (duration: 1.954919384s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:27:59.074483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:27:57.118976Z","time spent":"1.955492067s","remote":"127.0.0.1:34974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-08T15:27:59.07362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:27:58.059896Z","time spent":"1.013689252s","remote":"127.0.0.1:35508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-08T15:28:00.935202Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.761124657s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-08T15:28:00.935528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.732144291s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T15:28:00.93565Z","caller":"traceutil/trace.go:171","msg":"trace[191927920] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:645; }","duration":"1.732286429s","start":"2026-07-08T15:27:59.20334Z","end":"2026-07-08T15:28:00.935627Z","steps":["trace[191927920] 'range keys from in-memory index tree' (duration: 1.73212201s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:28:00.935896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.761676539s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T15:28:00.936085Z","caller":"traceutil/trace.go:171","msg":"trace[1792890054] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"1.856526385s","start":"2026-07-08T15:27:59.079536Z","end":"2026-07-08T15:28:00.936062Z","steps":["trace[1792890054] 'process raft request' (duration: 94.57302ms)","trace[1792890054] 'compare' (duration: 1.761333499s)"],"step_count":2} {"level":"warn","ts":"2026-07-08T15:28:00.936193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:27:59.079521Z","time spent":"1.856621731s","remote":"127.0.0.1:35250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T15:28:00.936728Z","caller":"traceutil/trace.go:171","msg":"trace[1942113724] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"1.817766131s","start":"2026-07-08T15:27:59.118935Z","end":"2026-07-08T15:28:00.936702Z","steps":["trace[1942113724] 'process raft request' (duration: 1.817715448s)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:28:00.93678Z","caller":"traceutil/trace.go:171","msg":"trace[2011615705] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"1.851756648s","start":"2026-07-08T15:27:59.084972Z","end":"2026-07-08T15:28:00.936728Z","steps":["trace[2011615705] 'process raft request' (duration: 1.851488902s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:28:00.936913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:27:59.118915Z","time spent":"1.817898639s","remote":"127.0.0.1:34996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T15:28:00.93698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:27:59.084965Z","time spent":"1.851904756s","remote":"127.0.0.1:35250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T15:28:00.937302Z","caller":"traceutil/trace.go:171","msg":"trace[641469289] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"1.847187061s","start":"2026-07-08T15:27:59.090102Z","end":"2026-07-08T15:28:00.937289Z","steps":["trace[641469289] 'process raft request' (duration: 1.846490371s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:28:00.937362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:27:59.090078Z","time spent":"1.847252255s","remote":"127.0.0.1:35144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T15:28:00.937435Z","caller":"traceutil/trace.go:171","msg":"trace[1265605194] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"1.857211924s","start":"2026-07-08T15:27:59.0802Z","end":"2026-07-08T15:28:00.937411Z","steps":["trace[1265605194] 'process raft request' (duration: 1.855796241s)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:28:00.937483Z","caller":"traceutil/trace.go:171","msg":"trace[1585138476] linearizableReadLoop","detail":"{readStateIndex:678; appliedIndex:675; }","duration":"1.855142215s","start":"2026-07-08T15:27:59.082328Z","end":"2026-07-08T15:28:00.93747Z","steps":["trace[1585138476] 'read index received' (duration: 35.524546ms)","trace[1585138476] 'applied index is now lower than readState.Index' (duration: 1.819616489s)"],"step_count":2} {"level":"warn","ts":"2026-07-08T15:28:00.937525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:27:59.08019Z","time spent":"1.857288059s","remote":"127.0.0.1:35250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T15:28:00.9378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.850320195s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T15:28:00.937836Z","caller":"traceutil/trace.go:171","msg":"trace[17286213] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:650; }","duration":"1.850350136s","start":"2026-07-08T15:27:59.08747Z","end":"2026-07-08T15:28:00.93782Z","steps":["trace[17286213] 'agreement among raft nodes before linearized reading' (duration: 1.850289393s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:28:00.937865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:27:59.087453Z","time spent":"1.85040486s","remote":"127.0.0.1:35220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-07-08T15:28:00.937929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.855597432s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T15:28:00.937965Z","caller":"traceutil/trace.go:171","msg":"trace[864951709] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:650; }","duration":"1.855652335s","start":"2026-07-08T15:27:59.082301Z","end":"2026-07-08T15:28:00.937953Z","steps":["trace[864951709] 'agreement among raft nodes before linearized reading' (duration: 1.855594692s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:28:00.937993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:27:59.082291Z","time spent":"1.855695058s","remote":"127.0.0.1:34968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-08T15:28:01.999778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"917.475598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-08T15:28:02.000093Z","caller":"traceutil/trace.go:171","msg":"trace[214114384] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:650; }","duration":"917.753614ms","start":"2026-07-08T15:28:01.082238Z","end":"2026-07-08T15:28:01.999991Z","steps":["trace[214114384] 'range keys from in-memory index tree' (duration: 916.32242ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:28:02.000191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:28:01.082222Z","time spent":"917.953516ms","remote":"127.0.0.1:35250","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":"info","ts":"2026-07-08T15:28:02.00992Z","caller":"traceutil/trace.go:171","msg":"trace[797099095] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"677.757416ms","start":"2026-07-08T15:28:01.33214Z","end":"2026-07-08T15:28:02.009897Z","steps":["trace[797099095] 'process raft request' (duration: 677.225635ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:28:02.010268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:28:01.332102Z","time spent":"678.067894ms","remote":"127.0.0.1:35082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T15:28:04.410241Z","caller":"traceutil/trace.go:171","msg":"trace[1951076764] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"388.392704ms","start":"2026-07-08T15:28:04.021812Z","end":"2026-07-08T15:28:04.410205Z","steps":["trace[1951076764] 'process raft request' (duration: 384.459711ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:28:04.410463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:28:04.021791Z","time spent":"388.543282ms","remote":"127.0.0.1:35250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T15:28:05.231742Z","caller":"traceutil/trace.go:171","msg":"trace[892984278] linearizableReadLoop","detail":"{readStateIndex:712; appliedIndex:711; }","duration":"112.684724ms","start":"2026-07-08T15:28:05.119026Z","end":"2026-07-08T15:28:05.231711Z","steps":["trace[892984278] 'read index received' (duration: 93.26317ms)","trace[892984278] 'applied index is now lower than readState.Index' (duration: 19.419984ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T15:28:05.23186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.828672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T15:28:05.231901Z","caller":"traceutil/trace.go:171","msg":"trace[1990745354] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:680; }","duration":"112.931368ms","start":"2026-07-08T15:28:05.118955Z","end":"2026-07-08T15:28:05.231887Z","steps":["trace[1990745354] 'agreement among raft nodes before linearized reading' (duration: 112.841253ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:28:05.231836Z","caller":"traceutil/trace.go:171","msg":"trace[1407385261] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"202.102555ms","start":"2026-07-08T15:28:05.029661Z","end":"2026-07-08T15:28:05.231764Z","steps":["trace[1407385261] 'process raft request' (duration: 182.574335ms)","trace[1407385261] 'compare' (duration: 19.35273ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:28:06.744905Z","caller":"traceutil/trace.go:171","msg":"trace[853384708] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"218.014733ms","start":"2026-07-08T15:28:06.52687Z","end":"2026-07-08T15:28:06.744885Z","steps":["trace[853384708] 'process raft request' (duration: 217.699414ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:28:06.989629Z","caller":"traceutil/trace.go:171","msg":"trace[735849149] linearizableReadLoop","detail":"{readStateIndex:745; appliedIndex:744; }","duration":"112.41779ms","start":"2026-07-08T15:28:06.877196Z","end":"2026-07-08T15:28:06.989614Z","steps":["trace[735849149] 'read index received' (duration: 102.02549ms)","trace[735849149] 'applied index is now lower than readState.Index' (duration: 10.39173ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:28:06.989705Z","caller":"traceutil/trace.go:171","msg":"trace[578352904] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"238.884597ms","start":"2026-07-08T15:28:06.750811Z","end":"2026-07-08T15:28:06.989696Z","steps":["trace[578352904] 'process raft request' (duration: 228.395041ms)","trace[578352904] 'compare' (duration: 10.190748ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T15:28:06.98976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.554418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T15:28:06.989793Z","caller":"traceutil/trace.go:171","msg":"trace[1993553671] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:713; }","duration":"112.623732ms","start":"2026-07-08T15:28:06.87716Z","end":"2026-07-08T15:28:06.989784Z","steps":["trace[1993553671] 'agreement among raft nodes before linearized reading' (duration: 112.562818ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:28:07.183026Z","caller":"traceutil/trace.go:171","msg":"trace[431806862] linearizableReadLoop","detail":"{readStateIndex:746; appliedIndex:745; }","duration":"193.323637ms","start":"2026-07-08T15:28:06.989675Z","end":"2026-07-08T15:28:07.182998Z","steps":["trace[431806862] 'read index received' (duration: 185.577883ms)","trace[431806862] 'applied index is now lower than readState.Index' (duration: 7.744204ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:28:07.183073Z","caller":"traceutil/trace.go:171","msg":"trace[1432304574] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"300.121872ms","start":"2026-07-08T15:28:06.882929Z","end":"2026-07-08T15:28:07.183051Z","steps":["trace[1432304574] 'process raft request' (duration: 292.487865ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:28:07.183184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.309703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-08T15:28:07.18322Z","caller":"traceutil/trace.go:171","msg":"trace[1364193749] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:714; }","duration":"201.412368ms","start":"2026-07-08T15:28:06.981798Z","end":"2026-07-08T15:28:07.183211Z","steps":["trace[1364193749] 'agreement among raft nodes before linearized reading' (duration: 201.226597ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:28:07.183261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.405437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-08T15:28:07.183292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:28:06.882908Z","time spent":"300.2569ms","remote":"127.0.0.1:35058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":773,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T15:28:07.183376Z","caller":"traceutil/trace.go:171","msg":"trace[607010655] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:714; }","duration":"202.555016ms","start":"2026-07-08T15:28:06.980806Z","end":"2026-07-08T15:28:07.183361Z","steps":["trace[607010655] 'agreement among raft nodes before linearized reading' (duration: 202.327713ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:28:07.183317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.402949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-08T15:28:07.183471Z","caller":"traceutil/trace.go:171","msg":"trace[375210408] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:714; }","duration":"222.608201ms","start":"2026-07-08T15:28:06.960856Z","end":"2026-07-08T15:28:07.183465Z","steps":["trace[375210408] 'agreement among raft nodes before linearized reading' (duration: 222.341766ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:28:07.183513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.571479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-pjwgk\" ","response":"range_response_count:1 size:18067"} {"level":"warn","ts":"2026-07-08T15:28:07.183575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.014629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-07-08T15:28:07.183588Z","caller":"traceutil/trace.go:171","msg":"trace[187178707] range","detail":"{range_begin:/registry/pods/kube-system/cilium-pjwgk; range_end:; response_count:1; response_revision:714; }","duration":"301.653443ms","start":"2026-07-08T15:28:06.881907Z","end":"2026-07-08T15:28:07.18356Z","steps":["trace[187178707] 'agreement among raft nodes before linearized reading' (duration: 301.43887ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:28:07.183603Z","caller":"traceutil/trace.go:171","msg":"trace[371422805] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:714; }","duration":"107.073802ms","start":"2026-07-08T15:28:07.076519Z","end":"2026-07-08T15:28:07.183593Z","steps":["trace[371422805] 'agreement among raft nodes before linearized reading' (duration: 107.003618ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:28:07.183886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:28:06.881897Z","time spent":"301.971312ms","remote":"127.0.0.1:35144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18090,"request content":"key:\"/registry/pods/kube-system/cilium-pjwgk\" "} {"level":"warn","ts":"2026-07-08T15:28:07.183381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.195695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-08T15:28:07.184544Z","caller":"traceutil/trace.go:171","msg":"trace[1835573382] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:714; }","duration":"201.319122ms","start":"2026-07-08T15:28:06.983165Z","end":"2026-07-08T15:28:07.184484Z","steps":["trace[1835573382] 'agreement among raft nodes before linearized reading' (duration: 200.184295ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:28:07.374803Z","caller":"traceutil/trace.go:171","msg":"trace[517683213] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"157.55161ms","start":"2026-07-08T15:28:07.217213Z","end":"2026-07-08T15:28:07.374765Z","steps":["trace[517683213] 'process raft request' (duration: 85.232987ms)","trace[517683213] 'compare' (duration: 71.991854ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:28:15.660009Z","caller":"traceutil/trace.go:171","msg":"trace[1658882309] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"346.783216ms","start":"2026-07-08T15:28:15.313191Z","end":"2026-07-08T15:28:15.659974Z","steps":["trace[1658882309] 'process raft request' (duration: 346.541451ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:28:15.660233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:28:15.313171Z","time spent":"346.942865ms","remote":"127.0.0.1:35250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T15:28:15.664364Z","caller":"traceutil/trace.go:171","msg":"trace[1239231643] linearizableReadLoop","detail":"{readStateIndex:834; appliedIndex:831; }","duration":"284.844571ms","start":"2026-07-08T15:28:15.379493Z","end":"2026-07-08T15:28:15.664338Z","steps":["trace[1239231643] 'read index received' (duration: 280.249291ms)","trace[1239231643] 'applied index is now lower than readState.Index' (duration: 4.59433ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:28:15.664426Z","caller":"traceutil/trace.go:171","msg":"trace[1595161973] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"350.311541ms","start":"2026-07-08T15:28:15.314079Z","end":"2026-07-08T15:28:15.664391Z","steps":["trace[1595161973] 'process raft request' (duration: 350.102599ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:28:15.664482Z","caller":"traceutil/trace.go:171","msg":"trace[885517112] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"348.963838ms","start":"2026-07-08T15:28:15.315506Z","end":"2026-07-08T15:28:15.66447Z","steps":["trace[885517112] 'process raft request' (duration: 348.794558ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:28:15.664584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:28:15.315499Z","time spent":"349.012492ms","remote":"127.0.0.1:35250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T15:28:15.664841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:28:15.314058Z","time spent":"350.46042ms","remote":"127.0.0.1:35250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T15:28:15.66501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.471211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T15:28:15.665152Z","caller":"traceutil/trace.go:171","msg":"trace[73569003] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:798; }","duration":"285.703745ms","start":"2026-07-08T15:28:15.379417Z","end":"2026-07-08T15:28:15.66512Z","steps":["trace[73569003] 'agreement among raft nodes before linearized reading' (duration: 285.437409ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:28:15.66503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.982474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-08T15:28:15.665314Z","caller":"traceutil/trace.go:171","msg":"trace[2098009446] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:798; }","duration":"223.316884ms","start":"2026-07-08T15:28:15.441975Z","end":"2026-07-08T15:28:15.665292Z","steps":["trace[2098009446] 'agreement among raft nodes before linearized reading' (duration: 222.855656ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:28:19.620697Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3033912406263065048,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-08T15:28:20.121252Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3033912406263065048,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-08T15:28:20.139984Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.61738015s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-08T15:28:20.255129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.051428636s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T15:28:20.255264Z","caller":"traceutil/trace.go:171","msg":"trace[1600012387] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:817; }","duration":"1.051603767s","start":"2026-07-08T15:28:19.203642Z","end":"2026-07-08T15:28:20.255246Z","steps":["trace[1600012387] 'range keys from in-memory index tree' (duration: 1.051409995s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:28:20.255419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.161086ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T15:28:20.255516Z","caller":"traceutil/trace.go:171","msg":"trace[823102458] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"1.732966972s","start":"2026-07-08T15:28:18.522528Z","end":"2026-07-08T15:28:20.255495Z","steps":["trace[823102458] 'process raft request' (duration: 1.617673998s)","trace[823102458] 'compare' (duration: 114.990675ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T15:28:20.255572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:28:18.522518Z","time spent":"1.733028876s","remote":"127.0.0.1:35250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T15:28:20.260262Z","caller":"traceutil/trace.go:171","msg":"trace[750850405] linearizableReadLoop","detail":"{readStateIndex:856; appliedIndex:854; }","duration":"1.139794647s","start":"2026-07-08T15:28:19.12045Z","end":"2026-07-08T15:28:20.260245Z","steps":["trace[750850405] 'read index received' (duration: 1.019760119s)","trace[750850405] 'applied index is now lower than readState.Index' (duration: 120.033638ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T15:28:20.2604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.139951176s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T15:28:20.260463Z","caller":"traceutil/trace.go:171","msg":"trace[824743559] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:819; }","duration":"1.14002379s","start":"2026-07-08T15:28:19.120422Z","end":"2026-07-08T15:28:20.260446Z","steps":["trace[824743559] 'agreement among raft nodes before linearized reading' (duration: 1.139923444s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:28:20.260521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:28:19.120339Z","time spent":"1.140167769s","remote":"127.0.0.1:34968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-08T15:28:20.260541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.431941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-08T15:28:20.260565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.414848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-08T15:28:20.260603Z","caller":"traceutil/trace.go:171","msg":"trace[806060393] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:819; }","duration":"578.543017ms","start":"2026-07-08T15:28:19.682049Z","end":"2026-07-08T15:28:20.260592Z","steps":["trace[806060393] 'agreement among raft nodes before linearized reading' (duration: 578.388298ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:28:20.260621Z","caller":"traceutil/trace.go:171","msg":"trace[724925832] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:819; }","duration":"572.492672ms","start":"2026-07-08T15:28:19.688116Z","end":"2026-07-08T15:28:20.260608Z","steps":["trace[724925832] 'agreement among raft nodes before linearized reading' (duration: 572.320132ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:28:20.260632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:28:19.682022Z","time spent":"578.603671ms","remote":"127.0.0.1:35250","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-07-08T15:28:20.260384Z","caller":"traceutil/trace.go:171","msg":"trace[390970213] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"1.288778063s","start":"2026-07-08T15:28:18.971582Z","end":"2026-07-08T15:28:20.26036Z","steps":["trace[390970213] 'process raft request' (duration: 1.288551599s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:28:20.260679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:28:19.688105Z","time spent":"572.563327ms","remote":"127.0.0.1:35250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-08T15:28:20.260542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.920857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-08T15:28:20.260779Z","caller":"traceutil/trace.go:171","msg":"trace[1082290210] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:819; }","duration":"577.205164ms","start":"2026-07-08T15:28:19.68356Z","end":"2026-07-08T15:28:20.260765Z","steps":["trace[1082290210] 'agreement among raft nodes before linearized reading' (duration: 576.869553ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:28:20.26082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:28:19.683539Z","time spent":"577.270139ms","remote":"127.0.0.1:35250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-08T15:28:20.260886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.869139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-08T15:28:20.260908Z","caller":"traceutil/trace.go:171","msg":"trace[437823892] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:819; }","duration":"584.920222ms","start":"2026-07-08T15:28:19.675981Z","end":"2026-07-08T15:28:20.260902Z","steps":["trace[437823892] 'agreement among raft nodes before linearized reading' (duration: 584.419521ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:28:20.260922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:28:19.675928Z","time spent":"584.991296ms","remote":"127.0.0.1:35250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-08T15:28:20.260866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:28:18.971536Z","time spent":"1.289153926s","remote":"127.0.0.1:35220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T15:28:40.6743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.54899ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T15:28:40.67477Z","caller":"traceutil/trace.go:171","msg":"trace[633503326] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"205.372285ms","start":"2026-07-08T15:28:40.469357Z","end":"2026-07-08T15:28:40.674729Z","steps":["trace[633503326] 'process raft request' (duration: 68.26974ms)","trace[633503326] 'compare' (duration: 136.323615ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:28:40.729238Z","caller":"traceutil/trace.go:171","msg":"trace[51799929] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"259.108134ms","start":"2026-07-08T15:28:40.470087Z","end":"2026-07-08T15:28:40.729195Z","steps":["trace[51799929] 'process raft request' (duration: 258.817125ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:28:40.729664Z","caller":"traceutil/trace.go:171","msg":"trace[950337691] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"259.148326ms","start":"2026-07-08T15:28:40.470479Z","end":"2026-07-08T15:28:40.729627Z","steps":["trace[950337691] 'process raft request' (duration: 258.653254ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:28:40.729788Z","caller":"traceutil/trace.go:171","msg":"trace[214868992] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"258.119791ms","start":"2026-07-08T15:28:40.471638Z","end":"2026-07-08T15:28:40.729757Z","steps":["trace[214868992] 'process raft request' (duration: 257.841892ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:30:11.411515Z","caller":"traceutil/trace.go:171","msg":"trace[195370457] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"448.916861ms","start":"2026-07-08T15:30:10.96255Z","end":"2026-07-08T15:30:11.411467Z","steps":["trace[195370457] 'process raft request' (duration: 448.643118ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:30:11.411756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:30:10.962524Z","time spent":"449.105076ms","remote":"127.0.0.1:35250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T15:30:11.414377Z","caller":"traceutil/trace.go:171","msg":"trace[203364884] linearizableReadLoop","detail":"{readStateIndex:1408; appliedIndex:1406; }","duration":"387.739398ms","start":"2026-07-08T15:30:11.026608Z","end":"2026-07-08T15:30:11.414348Z","steps":["trace[203364884] 'read index received' (duration: 384.622886ms)","trace[203364884] 'applied index is now lower than readState.Index' (duration: 3.115312ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T15:30:11.415251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.01552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-08T15:30:11.415356Z","caller":"traceutil/trace.go:171","msg":"trace[2039464910] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1346; }","duration":"386.165267ms","start":"2026-07-08T15:30:11.029173Z","end":"2026-07-08T15:30:11.415338Z","steps":["trace[2039464910] 'agreement among raft nodes before linearized reading' (duration: 385.949101ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:30:11.415506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:30:11.029147Z","time spent":"386.343642ms","remote":"127.0.0.1:35250","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-07-08T15:30:11.415751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.317589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-08T15:30:11.415536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.391916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-08T15:30:11.415777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.863389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-08T15:30:11.415828Z","caller":"traceutil/trace.go:171","msg":"trace[771213340] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1346; }","duration":"104.502565ms","start":"2026-07-08T15:30:11.311301Z","end":"2026-07-08T15:30:11.415804Z","steps":["trace[771213340] 'agreement among raft nodes before linearized reading' (duration: 104.29248ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:30:11.415935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.768383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T15:30:11.415977Z","caller":"traceutil/trace.go:171","msg":"trace[1963723337] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1346; }","duration":"295.843371ms","start":"2026-07-08T15:30:11.120119Z","end":"2026-07-08T15:30:11.415963Z","steps":["trace[1963723337] 'agreement among raft nodes before linearized reading' (duration: 295.780103ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:30:11.415848Z","caller":"traceutil/trace.go:171","msg":"trace[868297128] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1346; }","duration":"194.962466ms","start":"2026-07-08T15:30:11.220866Z","end":"2026-07-08T15:30:11.415829Z","steps":["trace[868297128] 'agreement among raft nodes before linearized reading' (duration: 194.783511ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:30:11.416061Z","caller":"traceutil/trace.go:171","msg":"trace[1038255542] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1346; }","duration":"185.99007ms","start":"2026-07-08T15:30:11.229878Z","end":"2026-07-08T15:30:11.415868Z","steps":["trace[1038255542] 'agreement among raft nodes before linearized reading' (duration: 185.248998ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:30:11.414457Z","caller":"traceutil/trace.go:171","msg":"trace[1712132123] transaction","detail":"{read_only:false; response_revision:1346; number_of_response:1; }","duration":"451.380639ms","start":"2026-07-08T15:30:10.963039Z","end":"2026-07-08T15:30:11.41442Z","steps":["trace[1712132123] 'process raft request' (duration: 451.136895ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:30:11.416298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:30:10.963011Z","time spent":"453.211115ms","remote":"127.0.0.1:35250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T15:30:11.414603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.965802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T15:30:11.416408Z","caller":"traceutil/trace.go:171","msg":"trace[936977806] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1346; }","duration":"389.799806ms","start":"2026-07-08T15:30:11.026588Z","end":"2026-07-08T15:30:11.416388Z","steps":["trace[936977806] 'agreement among raft nodes before linearized reading' (duration: 387.926823ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:30:11.416454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:30:11.026489Z","time spent":"389.949513ms","remote":"127.0.0.1:40954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-07-08T15:30:11.415878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.964699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-07-08T15:30:11.416569Z","caller":"traceutil/trace.go:171","msg":"trace[665014755] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1346; }","duration":"172.665551ms","start":"2026-07-08T15:30:11.243885Z","end":"2026-07-08T15:30:11.41655Z","steps":["trace[665014755] 'agreement among raft nodes before linearized reading' (duration: 171.877251ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:30:11.415718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.817465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-08T15:30:11.417092Z","caller":"traceutil/trace.go:171","msg":"trace[919814315] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1346; }","duration":"255.301057ms","start":"2026-07-08T15:30:11.161773Z","end":"2026-07-08T15:30:11.417074Z","steps":["trace[919814315] 'agreement among raft nodes before linearized reading' (duration: 253.414824ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:30:19.860636Z","caller":"traceutil/trace.go:171","msg":"trace[861435149] linearizableReadLoop","detail":"{readStateIndex:1442; appliedIndex:1441; }","duration":"397.107159ms","start":"2026-07-08T15:30:19.463495Z","end":"2026-07-08T15:30:19.860602Z","steps":["trace[861435149] 'read index received' (duration: 396.980852ms)","trace[861435149] 'applied index is now lower than readState.Index' (duration: 124.657µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:30:19.860796Z","caller":"traceutil/trace.go:171","msg":"trace[28677717] transaction","detail":"{read_only:false; response_revision:1378; number_of_response:1; }","duration":"398.63276ms","start":"2026-07-08T15:30:19.462149Z","end":"2026-07-08T15:30:19.860782Z","steps":["trace[28677717] 'process raft request' (duration: 398.229348ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:30:19.861069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:30:19.462129Z","time spent":"398.703008ms","remote":"127.0.0.1:35250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T15:30:19.861287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.757147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-08T15:30:19.861406Z","caller":"traceutil/trace.go:171","msg":"trace[1537257982] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1378; }","duration":"397.926644ms","start":"2026-07-08T15:30:19.463454Z","end":"2026-07-08T15:30:19.86138Z","steps":["trace[1537257982] 'agreement among raft nodes before linearized reading' (duration: 397.572101ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:30:19.861507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:30:19.463441Z","time spent":"398.053511ms","remote":"127.0.0.1:35250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-08T15:30:19.910115Z","caller":"traceutil/trace.go:171","msg":"trace[1243707978] transaction","detail":"{read_only:false; response_revision:1379; number_of_response:1; }","duration":"208.870017ms","start":"2026-07-08T15:30:19.70119Z","end":"2026-07-08T15:30:19.91006Z","steps":["trace[1243707978] 'process raft request' (duration: 181.539156ms)","trace[1243707978] 'compare' (duration: 27.141855ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T15:30:19.910354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.439339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-08T15:30:19.910424Z","caller":"traceutil/trace.go:171","msg":"trace[1161946538] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1380; }","duration":"415.573676ms","start":"2026-07-08T15:30:19.494833Z","end":"2026-07-08T15:30:19.910407Z","steps":["trace[1161946538] 'agreement among raft nodes before linearized reading' (duration: 415.364621ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:30:19.910468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:30:19.494805Z","time spent":"415.654374ms","remote":"127.0.0.1:35250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-08T15:30:19.910463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.403679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-08T15:30:19.910507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.501766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-08T15:30:19.9106Z","caller":"traceutil/trace.go:171","msg":"trace[793941346] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1380; }","duration":"444.610094ms","start":"2026-07-08T15:30:19.465943Z","end":"2026-07-08T15:30:19.910553Z","steps":["trace[793941346] 'agreement among raft nodes before linearized reading' (duration: 444.278841ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:30:19.910622Z","caller":"traceutil/trace.go:171","msg":"trace[1278975886] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1380; }","duration":"444.669953ms","start":"2026-07-08T15:30:19.465929Z","end":"2026-07-08T15:30:19.910599Z","steps":["trace[1278975886] 'agreement among raft nodes before linearized reading' (duration: 444.32995ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:30:19.910641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:30:19.465931Z","time spent":"444.702563ms","remote":"127.0.0.1:35250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-08T15:30:19.910686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:30:19.465905Z","time spent":"444.770791ms","remote":"127.0.0.1:35250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}