{"level":"warn","ts":"2026-05-27T19:39:20.979835Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-27T19:39:20.980002Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.65: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.65:2380","--initial-cluster=instance=https://199.19.213.65:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.65:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.65:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-27T19:39:20.980123Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-27T19:39:20.980148Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.65:2380"]} {"level":"info","ts":"2026-05-27T19:39:20.98018Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-27T19:39:20.98132Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.65:2379"]} {"level":"info","ts":"2026-05-27T19:39:20.981533Z","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.65:2380"],"listen-peer-urls":["https://199.19.213.65:2380"],"advertise-client-urls":["https://199.19.213.65:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.65:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.65:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-27T19:39:20.986609Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.632467ms"} {"level":"info","ts":"2026-05-27T19:39:20.992663Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a071a454a8148542","cluster-id":"aa233e43aeaf7fe5"} {"level":"info","ts":"2026-05-27T19:39:20.992765Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a071a454a8148542 switched to configuration voters=()"} {"level":"info","ts":"2026-05-27T19:39:20.992799Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a071a454a8148542 became follower at term 0"} {"level":"info","ts":"2026-05-27T19:39:20.992811Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a071a454a8148542 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-27T19:39:20.992827Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a071a454a8148542 became follower at term 1"} {"level":"info","ts":"2026-05-27T19:39:20.992886Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a071a454a8148542 switched to configuration voters=(11561202401940899138)"} {"level":"warn","ts":"2026-05-27T19:39:21.003552Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-27T19:39:21.005105Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-27T19:39:21.006445Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-27T19:39:21.008913Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a071a454a8148542","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-27T19:39:21.00911Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a071a454a8148542","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-27T19:39:21.009359Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-27T19:39:21.009471Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-27T19:39:21.009488Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-27T19:39:21.011615Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a071a454a8148542 switched to configuration voters=(11561202401940899138)"} {"level":"info","ts":"2026-05-27T19:39:21.01206Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"aa233e43aeaf7fe5","local-member-id":"a071a454a8148542","added-peer-id":"a071a454a8148542","added-peer-peer-urls":["https://199.19.213.65:2380"]} {"level":"info","ts":"2026-05-27T19:39:21.015315Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-27T19:39:21.015384Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.65:2380"} {"level":"info","ts":"2026-05-27T19:39:21.0155Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.65:2380"} {"level":"info","ts":"2026-05-27T19:39:21.016429Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a071a454a8148542","initial-advertise-peer-urls":["https://199.19.213.65:2380"],"listen-peer-urls":["https://199.19.213.65:2380"],"advertise-client-urls":["https://199.19.213.65:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.65:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-27T19:39:21.016479Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-27T19:39:21.593478Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a071a454a8148542 is starting a new election at term 1"} {"level":"info","ts":"2026-05-27T19:39:21.593562Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a071a454a8148542 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-27T19:39:21.593615Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a071a454a8148542 received MsgPreVoteResp from a071a454a8148542 at term 1"} {"level":"info","ts":"2026-05-27T19:39:21.593648Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a071a454a8148542 became candidate at term 2"} {"level":"info","ts":"2026-05-27T19:39:21.593668Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a071a454a8148542 received MsgVoteResp from a071a454a8148542 at term 2"} {"level":"info","ts":"2026-05-27T19:39:21.593724Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a071a454a8148542 became leader at term 2"} {"level":"info","ts":"2026-05-27T19:39:21.593747Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a071a454a8148542 elected leader a071a454a8148542 at term 2"} {"level":"info","ts":"2026-05-27T19:39:21.595338Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T19:39:21.596518Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a071a454a8148542","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.65:2379]}","request-path":"/0/members/a071a454a8148542/attributes","cluster-id":"aa233e43aeaf7fe5","publish-timeout":"7s"} {"level":"info","ts":"2026-05-27T19:39:21.596572Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-27T19:39:21.596706Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-27T19:39:21.596918Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-27T19:39:21.596964Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-27T19:39:21.600153Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.65:2379"} {"level":"info","ts":"2026-05-27T19:39:21.60029Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-27T19:39:21.600427Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"aa233e43aeaf7fe5","local-member-id":"a071a454a8148542","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T19:39:21.600496Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T19:39:21.600521Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-05-27T19:39:25.951853Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9602411537685422835,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T19:39:26.452561Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9602411537685422835,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T19:39:26.953137Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9602411537685422835,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T19:39:27.453756Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9602411537685422835,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T19:39:27.9543Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9602411537685422835,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T19:39:28.242797Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.937843257s","expected-duration":"1s"} {"level":"info","ts":"2026-05-27T19:39:28.243264Z","caller":"traceutil/trace.go:171","msg":"trace[573570141] transaction","detail":"{read_only:false; response_revision:205; number_of_response:1; }","duration":"2.93836091s","start":"2026-05-27T19:39:25.304866Z","end":"2026-05-27T19:39:28.243227Z","steps":["trace[573570141] 'process raft request' (duration: 2.938154395s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:39:28.243993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:39:25.304854Z","time spent":"2.938518804s","remote":"127.0.0.1:59562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3374,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:39:30.437589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.223774502s","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:42"} {"level":"info","ts":"2026-05-27T19:39:30.437691Z","caller":"traceutil/trace.go:171","msg":"trace[174528904] linearizableReadLoop","detail":"{readStateIndex:211; appliedIndex:209; }","duration":"4.986846249s","start":"2026-05-27T19:39:25.450829Z","end":"2026-05-27T19:39:30.437675Z","steps":["trace[174528904] 'read index received' (duration: 2.792220166s)","trace[174528904] 'applied index is now lower than readState.Index' (duration: 2.194624243s)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:39:30.437745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:39:25.485343Z","time spent":"4.952394929s","remote":"127.0.0.1:59460","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-27T19:39:30.437848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.987015082s","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-05-27T19:39:30.43789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.487987304s","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-05-27T19:39:30.437901Z","caller":"traceutil/trace.go:171","msg":"trace[246661876] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:205; }","duration":"4.987106565s","start":"2026-05-27T19:39:25.450784Z","end":"2026-05-27T19:39:30.437891Z","steps":["trace[246661876] 'agreement among raft nodes before linearized reading' (duration: 4.986972872s)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:39:30.438112Z","caller":"traceutil/trace.go:171","msg":"trace[831918896] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:205; }","duration":"4.488244401s","start":"2026-05-27T19:39:25.949854Z","end":"2026-05-27T19:39:30.438098Z","steps":["trace[831918896] 'agreement among raft nodes before linearized reading' (duration: 4.487985144s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:39:30.437976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.123537934s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-27T19:39:30.438147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:39:25.450768Z","time spent":"4.98735306s","remote":"127.0.0.1:59664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-27T19:39:30.438156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:39:25.949838Z","time spent":"4.488307292s","remote":"127.0.0.1:59664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-27T19:39:30.438193Z","caller":"traceutil/trace.go:171","msg":"trace[1636268110] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:205; }","duration":"3.12376569s","start":"2026-05-27T19:39:27.314383Z","end":"2026-05-27T19:39:30.438149Z","steps":["trace[1636268110] 'agreement among raft nodes before linearized reading' (duration: 3.123553435s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:39:30.43826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:39:27.31436Z","time spent":"3.123877693s","remote":"127.0.0.1:59664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-27T19:39:30.438016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.444744127s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:39:30.438386Z","caller":"traceutil/trace.go:171","msg":"trace[1907903690] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:205; }","duration":"4.445149986s","start":"2026-05-27T19:39:25.993226Z","end":"2026-05-27T19:39:30.438376Z","steps":["trace[1907903690] 'agreement among raft nodes before linearized reading' (duration: 4.444750437s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:39:30.438416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:39:25.993208Z","time spent":"4.445199848s","remote":"127.0.0.1:59664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-27T19:39:30.438057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"917.869699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:3974"} {"level":"info","ts":"2026-05-27T19:39:30.438543Z","caller":"traceutil/trace.go:171","msg":"trace[2128244082] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:205; }","duration":"918.376362ms","start":"2026-05-27T19:39:29.520148Z","end":"2026-05-27T19:39:30.438524Z","steps":["trace[2128244082] 'agreement among raft nodes before linearized reading' (duration: 917.860999ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:39:30.438594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:39:29.520129Z","time spent":"918.449904ms","remote":"127.0.0.1:59564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":3998,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"warn","ts":"2026-05-27T19:39:30.438021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.19036152s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-dx5vga\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:39:30.438708Z","caller":"traceutil/trace.go:171","msg":"trace[1015267583] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-dx5vga; range_end:; response_count:0; response_revision:205; }","duration":"2.191047647s","start":"2026-05-27T19:39:28.247587Z","end":"2026-05-27T19:39:30.438635Z","steps":["trace[1015267583] 'agreement among raft nodes before linearized reading' (duration: 2.190331889s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:39:30.438746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:39:28.247568Z","time spent":"2.1911659s","remote":"127.0.0.1:59486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":29,"request content":"key:\"/registry/secrets/kube-system/bootstrap-token-dx5vga\" "} {"level":"warn","ts":"2026-05-27T19:40:05.108926Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.490191274s","expected-duration":"1s"} {"level":"info","ts":"2026-05-27T19:40:05.109335Z","caller":"traceutil/trace.go:171","msg":"trace[1984544195] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"1.490698643s","start":"2026-05-27T19:40:03.618616Z","end":"2026-05-27T19:40:05.109314Z","steps":["trace[1984544195] 'process raft request' (duration: 1.490598171s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:05.109571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:03.618591Z","time spent":"1.490798636s","remote":"127.0.0.1:59432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T19:40:05.121923Z","caller":"traceutil/trace.go:171","msg":"trace[593655842] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"1.486046025s","start":"2026-05-27T19:40:03.63585Z","end":"2026-05-27T19:40:05.121896Z","steps":["trace[593655842] 'process raft request' (duration: 1.485827281s)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:40:05.12204Z","caller":"traceutil/trace.go:171","msg":"trace[699977420] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"1.485890572s","start":"2026-05-27T19:40:03.636088Z","end":"2026-05-27T19:40:05.121979Z","steps":["trace[699977420] 'process raft request' (duration: 1.48580096s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:05.122085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:03.635824Z","time spent":"1.486168568s","remote":"127.0.0.1:59432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T19:40:05.12209Z","caller":"traceutil/trace.go:171","msg":"trace[594854507] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"1.485903562s","start":"2026-05-27T19:40:03.636151Z","end":"2026-05-27T19:40:05.122055Z","steps":["trace[594854507] 'process raft request' (duration: 1.48576817s)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:40:05.122234Z","caller":"traceutil/trace.go:171","msg":"trace[987452419] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"1.44087586s","start":"2026-05-27T19:40:03.681344Z","end":"2026-05-27T19:40:05.12222Z","steps":["trace[987452419] 'process raft request' (duration: 1.440756867s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:05.122248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:03.636144Z","time spent":"1.486077656s","remote":"127.0.0.1:59432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T19:40:05.122287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:03.681318Z","time spent":"1.440940102s","remote":"127.0.0.1:59432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T19:40:05.12195Z","caller":"traceutil/trace.go:171","msg":"trace[1455878440] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"1.485913133s","start":"2026-05-27T19:40:03.636021Z","end":"2026-05-27T19:40:05.121934Z","steps":["trace[1455878440] 'process raft request' (duration: 1.485771s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:05.122307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:03.636079Z","time spent":"1.486134868s","remote":"127.0.0.1:59432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T19:40:05.12234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:03.636012Z","time spent":"1.486307521s","remote":"127.0.0.1:59432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T19:40:05.122388Z","caller":"traceutil/trace.go:171","msg":"trace[1623159610] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"1.463109066s","start":"2026-05-27T19:40:03.659026Z","end":"2026-05-27T19:40:05.122135Z","steps":["trace[1623159610] 'process raft request' (duration: 1.462927282s)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:40:05.1224Z","caller":"traceutil/trace.go:171","msg":"trace[292973822] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"1.438976032s","start":"2026-05-27T19:40:03.683416Z","end":"2026-05-27T19:40:05.122392Z","steps":["trace[292973822] 'process raft request' (duration: 1.438783897s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:05.12244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:03.683398Z","time spent":"1.439024193s","remote":"127.0.0.1:59432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T19:40:05.122483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:03.658998Z","time spent":"1.463448943s","remote":"127.0.0.1:59432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T19:40:05.122493Z","caller":"traceutil/trace.go:171","msg":"trace[459846209] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"1.429589021s","start":"2026-05-27T19:40:03.692874Z","end":"2026-05-27T19:40:05.122463Z","steps":["trace[459846209] 'process raft request' (duration: 1.429411896s)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:40:05.122589Z","caller":"traceutil/trace.go:171","msg":"trace[2066813556] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"1.426566239s","start":"2026-05-27T19:40:03.696007Z","end":"2026-05-27T19:40:05.122573Z","steps":["trace[2066813556] 'process raft request' (duration: 1.426360125s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:05.122629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:03.692853Z","time spent":"1.429712003s","remote":"127.0.0.1:59432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T19:40:05.122651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:03.695988Z","time spent":"1.42663763s","remote":"127.0.0.1:59432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T19:40:05.123538Z","caller":"traceutil/trace.go:171","msg":"trace[900988533] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"1.374806968s","start":"2026-05-27T19:40:03.748716Z","end":"2026-05-27T19:40:05.123523Z","steps":["trace[900988533] 'process raft request' (duration: 1.373723976s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:05.123611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:03.748682Z","time spent":"1.37489686s","remote":"127.0.0.1:59432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T19:40:05.123921Z","caller":"traceutil/trace.go:171","msg":"trace[268910884] linearizableReadLoop","detail":"{readStateIndex:564; appliedIndex:551; }","duration":"457.319113ms","start":"2026-05-27T19:40:04.666589Z","end":"2026-05-27T19:40:05.123908Z","steps":["trace[268910884] 'read index received' (duration: 442.687187ms)","trace[268910884] 'applied index is now lower than readState.Index' (duration: 14.631016ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:40:05.124002Z","caller":"traceutil/trace.go:171","msg":"trace[1617825967] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"1.274946072s","start":"2026-05-27T19:40:03.849045Z","end":"2026-05-27T19:40:05.123991Z","steps":["trace[1617825967] 'process raft request' (duration: 1.274694907s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:05.124061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:03.849022Z","time spent":"1.275008193s","remote":"127.0.0.1:59432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T19:40:05.124335Z","caller":"traceutil/trace.go:171","msg":"trace[2061242098] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"1.235333721s","start":"2026-05-27T19:40:03.888991Z","end":"2026-05-27T19:40:05.124324Z","steps":["trace[2061242098] 'process raft request' (duration: 1.234848831s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:05.124413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:03.888964Z","time spent":"1.235407862s","remote":"127.0.0.1:59664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:40:05.124825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.201782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-27T19:40:05.124925Z","caller":"traceutil/trace.go:171","msg":"trace[1704769625] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:543; }","duration":"458.313234ms","start":"2026-05-27T19:40:04.666554Z","end":"2026-05-27T19:40:05.124867Z","steps":["trace[1704769625] 'agreement among raft nodes before linearized reading' (duration: 458.11317ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:05.125015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:04.666537Z","time spent":"458.431807ms","remote":"127.0.0.1:59664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-27T19:40:05.127401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.790529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-27T19:40:05.127484Z","caller":"traceutil/trace.go:171","msg":"trace[1058920967] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:545; }","duration":"449.895891ms","start":"2026-05-27T19:40:04.677566Z","end":"2026-05-27T19:40:05.127461Z","steps":["trace[1058920967] 'agreement among raft nodes before linearized reading' (duration: 449.689117ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:05.127539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:04.677558Z","time spent":"449.966733ms","remote":"127.0.0.1:59664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-27T19:40:05.128328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.173456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.65\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-27T19:40:05.128383Z","caller":"traceutil/trace.go:171","msg":"trace[1738310473] range","detail":"{range_begin:/registry/masterleases/199.19.213.65; range_end:; response_count:1; response_revision:545; }","duration":"447.238947ms","start":"2026-05-27T19:40:04.68113Z","end":"2026-05-27T19:40:05.128369Z","steps":["trace[1738310473] 'agreement among raft nodes before linearized reading' (duration: 447.154446ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:05.128422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:04.681124Z","time spent":"447.287798ms","remote":"127.0.0.1:59408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.65\" "} {"level":"warn","ts":"2026-05-27T19:40:05.128833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.479483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-27T19:40:05.12889Z","caller":"traceutil/trace.go:171","msg":"trace[1990409701] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:545; }","duration":"450.573146ms","start":"2026-05-27T19:40:04.678303Z","end":"2026-05-27T19:40:05.128876Z","steps":["trace[1990409701] 'agreement among raft nodes before linearized reading' (duration: 450.478534ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:05.128932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:04.67828Z","time spent":"450.640587ms","remote":"127.0.0.1:59664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-27T19:40:05.132422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.74328ms","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-05-27T19:40:05.132459Z","caller":"traceutil/trace.go:171","msg":"trace[1719081081] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:545; }","duration":"454.815432ms","start":"2026-05-27T19:40:04.677635Z","end":"2026-05-27T19:40:05.13245Z","steps":["trace[1719081081] 'agreement among raft nodes before linearized reading' (duration: 454.645389ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:05.133098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:04.677617Z","time spent":"455.470235ms","remote":"127.0.0.1:59664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-27T19:40:05.467623Z","caller":"traceutil/trace.go:171","msg":"trace[135091384] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"222.195937ms","start":"2026-05-27T19:40:05.245402Z","end":"2026-05-27T19:40:05.467598Z","steps":["trace[135091384] 'process raft request' (duration: 173.453056ms)","trace[135091384] 'compare' (duration: 48.608059ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:40:05.527661Z","caller":"traceutil/trace.go:171","msg":"trace[371991881] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"276.269225ms","start":"2026-05-27T19:40:05.251364Z","end":"2026-05-27T19:40:05.527634Z","steps":["trace[371991881] 'process raft request' (duration: 276.01956ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:40:05.528339Z","caller":"traceutil/trace.go:171","msg":"trace[2021156352] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"276.149643ms","start":"2026-05-27T19:40:05.252164Z","end":"2026-05-27T19:40:05.528314Z","steps":["trace[2021156352] 'process raft request' (duration: 275.50432ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:40:05.52884Z","caller":"traceutil/trace.go:171","msg":"trace[264314597] linearizableReadLoop","detail":"{readStateIndex:595; appliedIndex:591; }","duration":"231.546647ms","start":"2026-05-27T19:40:05.297274Z","end":"2026-05-27T19:40:05.528821Z","steps":["trace[264314597] 'read index received' (duration: 121.660432ms)","trace[264314597] 'applied index is now lower than readState.Index' (duration: 109.885085ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:40:05.528927Z","caller":"traceutil/trace.go:171","msg":"trace[152466145] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"217.988451ms","start":"2026-05-27T19:40:05.310932Z","end":"2026-05-27T19:40:05.528921Z","steps":["trace[152466145] 'process raft request' (duration: 217.744316ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:40:05.528949Z","caller":"traceutil/trace.go:171","msg":"trace[1866396740] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"276.118783ms","start":"2026-05-27T19:40:05.252801Z","end":"2026-05-27T19:40:05.52892Z","steps":["trace[1866396740] 'process raft request' (duration: 275.441079ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:05.52914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.850073ms","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-05-27T19:40:05.52917Z","caller":"traceutil/trace.go:171","msg":"trace[1635290553] 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:570; }","duration":"231.918715ms","start":"2026-05-27T19:40:05.297243Z","end":"2026-05-27T19:40:05.529162Z","steps":["trace[1635290553] 'agreement among raft nodes before linearized reading' (duration: 231.799002ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:05.726994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.268466ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T19:40:05.727172Z","caller":"traceutil/trace.go:171","msg":"trace[1919366664] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"187.731956ms","start":"2026-05-27T19:40:05.539427Z","end":"2026-05-27T19:40:05.727159Z","steps":["trace[1919366664] 'process raft request' (duration: 187.667425ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:40:05.727259Z","caller":"traceutil/trace.go:171","msg":"trace[1191995498] linearizableReadLoop","detail":"{readStateIndex:597; appliedIndex:596; }","duration":"198.30517ms","start":"2026-05-27T19:40:05.528893Z","end":"2026-05-27T19:40:05.727198Z","steps":["trace[1191995498] 'read index received' (duration: 43.769639ms)","trace[1191995498] 'applied index is now lower than readState.Index' (duration: 154.534411ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:40:05.727328Z","caller":"traceutil/trace.go:171","msg":"trace[1952512196] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"248.772986ms","start":"2026-05-27T19:40:05.478534Z","end":"2026-05-27T19:40:05.727307Z","steps":["trace[1952512196] 'process raft request' (duration: 94.118223ms)","trace[1952512196] 'compare' (duration: 154.139903ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:40:05.727395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.049241ms","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-05-27T19:40:05.727423Z","caller":"traceutil/trace.go:171","msg":"trace[1869230994] 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:572; }","duration":"430.089452ms","start":"2026-05-27T19:40:05.297322Z","end":"2026-05-27T19:40:05.727411Z","steps":["trace[1869230994] 'agreement among raft nodes before linearized reading' (duration: 430.01166ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:05.727443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:05.29727Z","time spent":"430.167304ms","remote":"127.0.0.1:35332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 "} {"level":"warn","ts":"2026-05-27T19:40:05.727551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.790137ms","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-05-27T19:40:05.727619Z","caller":"traceutil/trace.go:171","msg":"trace[1640580784] 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:572; }","duration":"429.869489ms","start":"2026-05-27T19:40:05.297739Z","end":"2026-05-27T19:40:05.727608Z","steps":["trace[1640580784] 'agreement among raft nodes before linearized reading' (duration: 429.776737ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:05.727645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:05.297732Z","time spent":"429.906009ms","remote":"127.0.0.1:35332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 "} {"level":"warn","ts":"2026-05-27T19:40:07.455876Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.160352035s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-27T19:40:07.651273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.355761738s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-27T19:40:07.651507Z","caller":"traceutil/trace.go:171","msg":"trace[493350479] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"2.062149196s","start":"2026-05-27T19:40:05.589322Z","end":"2026-05-27T19:40:07.651471Z","steps":["trace[493350479] 'process raft request' (duration: 706.114912ms)","trace[493350479] 'compare' (duration: 1.355605135s)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:40:07.651588Z","caller":"traceutil/trace.go:171","msg":"trace[1626899531] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"1.913879682s","start":"2026-05-27T19:40:05.737685Z","end":"2026-05-27T19:40:07.651565Z","steps":["trace[1626899531] 'process raft request' (duration: 1.913729799s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:07.651621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:05.589294Z","time spent":"2.062267978s","remote":"127.0.0.1:59432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64392,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T19:40:07.651695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:05.737663Z","time spent":"1.913975234s","remote":"127.0.0.1:59432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31111,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:40:07.651722Z","caller":"traceutil/trace.go:171","msg":"trace[1344693013] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:598; }","duration":"1.61017008s","start":"2026-05-27T19:40:06.041541Z","end":"2026-05-27T19:40:07.651711Z","steps":["trace[1344693013] 'read index received' (duration: 253.904981ms)","trace[1344693013] 'applied index is now lower than readState.Index' (duration: 1.356263978s)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:40:07.651825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.610286833s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:40:07.651851Z","caller":"traceutil/trace.go:171","msg":"trace[1805985994] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:575; }","duration":"1.610349193s","start":"2026-05-27T19:40:06.041493Z","end":"2026-05-27T19:40:07.651843Z","steps":["trace[1805985994] 'agreement among raft nodes before linearized reading' (duration: 1.610299252s)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:40:07.651841Z","caller":"traceutil/trace.go:171","msg":"trace[685935174] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"1.912400311s","start":"2026-05-27T19:40:05.739427Z","end":"2026-05-27T19:40:07.651827Z","steps":["trace[685935174] 'process raft request' (duration: 1.912077754s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:07.651871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:06.041475Z","time spent":"1.610392495s","remote":"127.0.0.1:59390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-27T19:40:07.651893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.223732ms","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-05-27T19:40:07.651908Z","caller":"traceutil/trace.go:171","msg":"trace[825173098] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:575; }","duration":"494.280972ms","start":"2026-05-27T19:40:07.157623Z","end":"2026-05-27T19:40:07.651904Z","steps":["trace[825173098] 'agreement among raft nodes before linearized reading' (duration: 494.208861ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:07.651923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:07.157602Z","time spent":"494.317464ms","remote":"127.0.0.1:59664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-27T19:40:07.651924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:05.739398Z","time spent":"1.912482193s","remote":"127.0.0.1:59432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29452,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:40:07.652045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.331265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-27T19:40:07.652062Z","caller":"traceutil/trace.go:171","msg":"trace[1391858868] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:575; }","duration":"493.367705ms","start":"2026-05-27T19:40:07.158689Z","end":"2026-05-27T19:40:07.652057Z","steps":["trace[1391858868] 'agreement among raft nodes before linearized reading' (duration: 493.334405ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:07.652075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:07.158682Z","time spent":"493.390906ms","remote":"127.0.0.1:59664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-27T19:40:07.652089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.318945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-27T19:40:07.652113Z","caller":"traceutil/trace.go:171","msg":"trace[131490229] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:575; }","duration":"494.375005ms","start":"2026-05-27T19:40:07.157731Z","end":"2026-05-27T19:40:07.652106Z","steps":["trace[131490229] 'agreement among raft nodes before linearized reading' (duration: 494.308834ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:07.652131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:07.157723Z","time spent":"494.403376ms","remote":"127.0.0.1:59664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-27T19:40:07.652305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.499788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-27T19:40:07.652361Z","caller":"traceutil/trace.go:171","msg":"trace[1746702453] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:575; }","duration":"494.638501ms","start":"2026-05-27T19:40:07.157706Z","end":"2026-05-27T19:40:07.652345Z","steps":["trace[1746702453] 'agreement among raft nodes before linearized reading' (duration: 494.363176ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:07.652407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:07.157683Z","time spent":"494.711512ms","remote":"127.0.0.1:59664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-27T19:40:12.177776Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9602411537685423674,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-27T19:40:12.201111Z","caller":"traceutil/trace.go:171","msg":"trace[238723872] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"610.525496ms","start":"2026-05-27T19:40:11.590556Z","end":"2026-05-27T19:40:12.201081Z","steps":["trace[238723872] 'process raft request' (duration: 610.357403ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:12.201274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:11.590528Z","time spent":"610.670529ms","remote":"127.0.0.1:59564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:40:13.291522Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.088555448s","expected-duration":"1s"} {"level":"info","ts":"2026-05-27T19:40:13.293515Z","caller":"traceutil/trace.go:171","msg":"trace[1386814016] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:657; }","duration":"1.61638117s","start":"2026-05-27T19:40:11.677107Z","end":"2026-05-27T19:40:13.293488Z","steps":["trace[1386814016] 'read index received' (duration: 523.931594ms)","trace[1386814016] 'applied index is now lower than readState.Index' (duration: 1.092448266s)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:40:13.293638Z","caller":"traceutil/trace.go:171","msg":"trace[638232220] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"1.466913418s","start":"2026-05-27T19:40:11.826674Z","end":"2026-05-27T19:40:13.293587Z","steps":["trace[638232220] 'process raft request' (duration: 1.465740955s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:13.293825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:11.82664Z","time spent":"1.467091161s","remote":"127.0.0.1:59486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036193,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:40:13.294286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.617175557s","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-05-27T19:40:13.294354Z","caller":"traceutil/trace.go:171","msg":"trace[301784762] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:632; }","duration":"1.617295879s","start":"2026-05-27T19:40:11.67704Z","end":"2026-05-27T19:40:13.294336Z","steps":["trace[301784762] 'agreement among raft nodes before linearized reading' (duration: 1.616553494s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:13.29439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:11.677011Z","time spent":"1.61737051s","remote":"127.0.0.1:59664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-27T19:40:13.31353Z","caller":"traceutil/trace.go:171","msg":"trace[797000090] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"815.632399ms","start":"2026-05-27T19:40:12.497875Z","end":"2026-05-27T19:40:13.313507Z","steps":["trace[797000090] 'process raft request' (duration: 815.516566ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:13.313671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:12.49785Z","time spent":"815.749233ms","remote":"127.0.0.1:59664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:40:13.31722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.636532747s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-27T19:40:13.317292Z","caller":"traceutil/trace.go:171","msg":"trace[1808335852] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:633; }","duration":"1.63667354s","start":"2026-05-27T19:40:11.680599Z","end":"2026-05-27T19:40:13.317273Z","steps":["trace[1808335852] 'agreement among raft nodes before linearized reading' (duration: 1.636457226s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:13.317333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:11.68057Z","time spent":"1.636756052s","remote":"127.0.0.1:59664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-27T19:40:13.317517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.275291888s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:40:13.317545Z","caller":"traceutil/trace.go:171","msg":"trace[37766238] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:633; }","duration":"1.275354868s","start":"2026-05-27T19:40:12.042183Z","end":"2026-05-27T19:40:13.317538Z","steps":["trace[37766238] 'agreement among raft nodes before linearized reading' (duration: 1.275312347s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:13.31757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:12.04216Z","time spent":"1.27540423s","remote":"127.0.0.1:59378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-27T19:40:13.317728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.636205211s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-27T19:40:13.317757Z","caller":"traceutil/trace.go:171","msg":"trace[34040620] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:633; }","duration":"1.636255731s","start":"2026-05-27T19:40:11.681494Z","end":"2026-05-27T19:40:13.31775Z","steps":["trace[34040620] 'agreement among raft nodes before linearized reading' (duration: 1.63618011s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:13.317782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:11.681478Z","time spent":"1.636298313s","remote":"127.0.0.1:59664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-27T19:40:13.317913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.637250161s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-27T19:40:13.31794Z","caller":"traceutil/trace.go:171","msg":"trace[545176269] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:633; }","duration":"1.637332923s","start":"2026-05-27T19:40:11.680599Z","end":"2026-05-27T19:40:13.317932Z","steps":["trace[545176269] 'agreement among raft nodes before linearized reading' (duration: 1.637264461s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:13.317966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:11.680582Z","time spent":"1.637376953s","remote":"127.0.0.1:59664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-27T19:40:15.32822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.0002707s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-27T19:40:15.328345Z","caller":"traceutil/trace.go:171","msg":"trace[1994418107] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000447734s","start":"2026-05-27T19:40:13.32787Z","end":"2026-05-27T19:40:15.328318Z","steps":["trace[1994418107] 'agreement among raft nodes before linearized reading' (duration: 2.000287381s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:15.328411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:13.327863Z","time spent":"2.000533585s","remote":"127.0.0.1:59390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/27 19:40:15 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-27T19:40:15.492838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.466045394s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T19:40:15.49306Z","caller":"traceutil/trace.go:171","msg":"trace[1233775401] linearizableReadLoop","detail":"{readStateIndex:664; appliedIndex:660; }","duration":"2.165147404s","start":"2026-05-27T19:40:13.327896Z","end":"2026-05-27T19:40:15.493043Z","steps":["trace[1233775401] 'read index received' (duration: 8.360355ms)","trace[1233775401] 'applied index is now lower than readState.Index' (duration: 2.156785599s)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:40:15.493134Z","caller":"traceutil/trace.go:171","msg":"trace[200557832] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"2.170274246s","start":"2026-05-27T19:40:13.322849Z","end":"2026-05-27T19:40:15.493123Z","steps":["trace[200557832] 'process raft request' (duration: 703.866494ms)","trace[200557832] 'compare' (duration: 1.46583919s)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:40:15.49319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:13.322833Z","time spent":"2.170324796s","remote":"127.0.0.1:59664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:40:15.4933Z","caller":"traceutil/trace.go:171","msg":"trace[1443555320] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"2.167881649s","start":"2026-05-27T19:40:13.32539Z","end":"2026-05-27T19:40:15.493272Z","steps":["trace[1443555320] 'process raft request' (duration: 2.167617793s)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:40:15.493328Z","caller":"traceutil/trace.go:171","msg":"trace[1781648635] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"2.170349738s","start":"2026-05-27T19:40:13.322927Z","end":"2026-05-27T19:40:15.493277Z","steps":["trace[1781648635] 'process raft request' (duration: 2.17000365s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:15.49341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:13.325372Z","time spent":"2.16797318s","remote":"127.0.0.1:59664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:40:15.4935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:13.322923Z","time spent":"2.17048355s","remote":"127.0.0.1:59664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:40:15.993804Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9602411537685423685,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T19:40:16.4941Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9602411537685423685,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T19:40:16.541064Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.514258973s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-27T19:40:16.835019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.72125ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-27T19:40:16.835182Z","caller":"traceutil/trace.go:171","msg":"trace[994751261] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:664; }","duration":"1.34207861s","start":"2026-05-27T19:40:15.493089Z","end":"2026-05-27T19:40:16.835168Z","steps":["trace[994751261] 'read index received' (duration: 1.048168687s)","trace[994751261] 'applied index is now lower than readState.Index' (duration: 293.908643ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:40:16.835323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.152596946s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.65\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-27T19:40:16.835351Z","caller":"traceutil/trace.go:171","msg":"trace[889856253] range","detail":"{range_begin:/registry/masterleases/199.19.213.65; range_end:; response_count:1; response_revision:639; }","duration":"2.152683907s","start":"2026-05-27T19:40:14.68266Z","end":"2026-05-27T19:40:16.835344Z","steps":["trace[889856253] 'agreement among raft nodes before linearized reading' (duration: 2.152546595s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:16.835375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:14.682625Z","time spent":"2.152744259s","remote":"127.0.0.1:59408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.65\" "} {"level":"warn","ts":"2026-05-27T19:40:16.835901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.506997525s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:40:16.835934Z","caller":"traceutil/trace.go:171","msg":"trace[1472106947] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:639; }","duration":"1.507079826s","start":"2026-05-27T19:40:15.328847Z","end":"2026-05-27T19:40:16.835927Z","steps":["trace[1472106947] 'agreement among raft nodes before linearized reading' (duration: 1.507028705s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:16.835954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:15.328825Z","time spent":"1.507125137s","remote":"127.0.0.1:59378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-27T19:40:16.836003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.495821046s","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-05-27T19:40:16.836078Z","caller":"traceutil/trace.go:171","msg":"trace[864156817] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:639; }","duration":"1.495933788s","start":"2026-05-27T19:40:15.340123Z","end":"2026-05-27T19:40:16.836057Z","steps":["trace[864156817] 'agreement among raft nodes before linearized reading' (duration: 1.495708223s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:16.836121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:15.340111Z","time spent":"1.496001849s","remote":"127.0.0.1:59664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-27T19:40:16.8361Z","caller":"traceutil/trace.go:171","msg":"trace[1786248835] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"3.486463454s","start":"2026-05-27T19:40:13.349603Z","end":"2026-05-27T19:40:16.836066Z","steps":["trace[1786248835] 'process raft request' (duration: 3.191642053s)","trace[1786248835] 'compare' (duration: 293.624478ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:40:16.836222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:13.349581Z","time spent":"3.486580117s","remote":"127.0.0.1:59730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1044,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T19:40:16.836347Z","caller":"traceutil/trace.go:171","msg":"trace[1458282662] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"1.318494988s","start":"2026-05-27T19:40:15.51772Z","end":"2026-05-27T19:40:16.836215Z","steps":["trace[1458282662] 'process raft request' (duration: 1.317387307s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:16.836671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:15.517656Z","time spent":"1.318856995s","remote":"127.0.0.1:59664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:40:18.082426Z","caller":"traceutil/trace.go:171","msg":"trace[1363085356] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"258.080542ms","start":"2026-05-27T19:40:17.82432Z","end":"2026-05-27T19:40:18.0824Z","steps":["trace[1363085356] 'process raft request' (duration: 257.585002ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:40:18.986791Z","caller":"traceutil/trace.go:171","msg":"trace[428543125] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"243.798032ms","start":"2026-05-27T19:40:18.742962Z","end":"2026-05-27T19:40:18.98676Z","steps":["trace[428543125] 'process raft request' (duration: 227.16752ms)","trace[428543125] 'compare' (duration: 16.164694ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:40:18.986945Z","caller":"traceutil/trace.go:171","msg":"trace[1104374948] linearizableReadLoop","detail":"{readStateIndex:701; appliedIndex:700; }","duration":"117.151225ms","start":"2026-05-27T19:40:18.86977Z","end":"2026-05-27T19:40:18.986922Z","steps":["trace[1104374948] 'read index received' (duration: 100.372889ms)","trace[1104374948] 'applied index is now lower than readState.Index' (duration: 16.777166ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:40:18.987146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.367108ms","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-05-27T19:40:18.987191Z","caller":"traceutil/trace.go:171","msg":"trace[1685184485] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:674; }","duration":"117.46156ms","start":"2026-05-27T19:40:18.869718Z","end":"2026-05-27T19:40:18.98718Z","steps":["trace[1685184485] 'agreement among raft nodes before linearized reading' (duration: 117.284187ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:40:29.841779Z","caller":"traceutil/trace.go:171","msg":"trace[1232834871] linearizableReadLoop","detail":"{readStateIndex:825; appliedIndex:824; }","duration":"275.737967ms","start":"2026-05-27T19:40:29.566017Z","end":"2026-05-27T19:40:29.841755Z","steps":["trace[1232834871] 'read index received' (duration: 275.508191ms)","trace[1232834871] 'applied index is now lower than readState.Index' (duration: 228.736µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:40:29.841892Z","caller":"traceutil/trace.go:171","msg":"trace[1171142397] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"281.142688ms","start":"2026-05-27T19:40:29.56073Z","end":"2026-05-27T19:40:29.841872Z","steps":["trace[1171142397] 'process raft request' (duration: 280.892063ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:29.842021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.902069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-27T19:40:29.84209Z","caller":"traceutil/trace.go:171","msg":"trace[550242207] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:793; }","duration":"276.004671ms","start":"2026-05-27T19:40:29.566072Z","end":"2026-05-27T19:40:29.842076Z","steps":["trace[550242207] 'agreement among raft nodes before linearized reading' (duration: 275.830448ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:29.842016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.981101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-27T19:40:29.842244Z","caller":"traceutil/trace.go:171","msg":"trace[1161531395] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:793; }","duration":"276.265146ms","start":"2026-05-27T19:40:29.565967Z","end":"2026-05-27T19:40:29.842232Z","steps":["trace[1161531395] 'agreement among raft nodes before linearized reading' (duration: 275.92091ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:40:30.005428Z","caller":"traceutil/trace.go:171","msg":"trace[1780227821] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"159.601407ms","start":"2026-05-27T19:40:29.845801Z","end":"2026-05-27T19:40:30.005403Z","steps":["trace[1780227821] 'process raft request' (duration: 155.676024ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:40:30.005663Z","caller":"traceutil/trace.go:171","msg":"trace[1400544870] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"159.632158ms","start":"2026-05-27T19:40:29.846021Z","end":"2026-05-27T19:40:30.005653Z","steps":["trace[1400544870] 'process raft request' (duration: 159.346533ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:33.564519Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9602411537685424025,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T19:40:33.864204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.169799642s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-wj85w\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-05-27T19:40:33.864268Z","caller":"traceutil/trace.go:171","msg":"trace[1510742064] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-wj85w; range_end:; response_count:1; response_revision:808; }","duration":"1.169906194s","start":"2026-05-27T19:40:32.69435Z","end":"2026-05-27T19:40:33.864256Z","steps":["trace[1510742064] 'range keys from in-memory index tree' (duration: 1.16968726s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:33.86429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:32.694334Z","time spent":"1.169950075s","remote":"127.0.0.1:59564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-wj85w\" "} {"level":"warn","ts":"2026-05-27T19:40:33.864519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.410543436s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-27T19:40:33.86456Z","caller":"traceutil/trace.go:171","msg":"trace[1761435822] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:808; }","duration":"1.410646697s","start":"2026-05-27T19:40:32.453905Z","end":"2026-05-27T19:40:33.864552Z","steps":["trace[1761435822] 'count revisions from in-memory index tree' (duration: 1.410426303s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:33.864591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:32.453881Z","time spent":"1.410699318s","remote":"127.0.0.1:59584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":42,"response size":31,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-05-27T19:40:33.864562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.251205919s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-27T19:40:33.864964Z","caller":"traceutil/trace.go:171","msg":"trace[1361370235] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:808; }","duration":"1.251642677s","start":"2026-05-27T19:40:32.613314Z","end":"2026-05-27T19:40:33.864957Z","steps":["trace[1361370235] 'range keys from in-memory index tree' (duration: 1.251073037s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:33.864987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:32.613295Z","time spent":"1.251684628s","remote":"127.0.0.1:59664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-27T19:40:34.064755Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9602411537685424025,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T19:40:34.430695Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.592592356s","expected-duration":"1s"} {"level":"info","ts":"2026-05-27T19:40:34.431397Z","caller":"traceutil/trace.go:171","msg":"trace[1195717377] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"1.593387741s","start":"2026-05-27T19:40:32.837986Z","end":"2026-05-27T19:40:34.431374Z","steps":["trace[1195717377] 'process raft request' (duration: 1.593141546s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:34.431532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:32.837967Z","time spent":"1.593489612s","remote":"127.0.0.1:59562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4904,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:40:34.444974Z","caller":"traceutil/trace.go:171","msg":"trace[573504736] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"608.461717ms","start":"2026-05-27T19:40:33.836483Z","end":"2026-05-27T19:40:34.444945Z","steps":["trace[573504736] 'process raft request' (duration: 608.267734ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:34.445136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:33.836455Z","time spent":"608.597989ms","remote":"127.0.0.1:59664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:40:34.445323Z","caller":"traceutil/trace.go:171","msg":"trace[1859362100] linearizableReadLoop","detail":"{readStateIndex:845; appliedIndex:841; }","duration":"1.381646001s","start":"2026-05-27T19:40:33.063656Z","end":"2026-05-27T19:40:34.445302Z","steps":["trace[1859362100] 'read index received' (duration: 1.367378467s)","trace[1859362100] 'applied index is now lower than readState.Index' (duration: 14.266454ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:40:34.445339Z","caller":"traceutil/trace.go:171","msg":"trace[1385382049] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"574.416535ms","start":"2026-05-27T19:40:33.870891Z","end":"2026-05-27T19:40:34.445307Z","steps":["trace[1385382049] 'process raft request' (duration: 574.014697ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:40:34.445452Z","caller":"traceutil/trace.go:171","msg":"trace[837307802] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"572.02789ms","start":"2026-05-27T19:40:33.873415Z","end":"2026-05-27T19:40:34.445443Z","steps":["trace[837307802] 'process raft request' (duration: 571.715415ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:34.445573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:33.873387Z","time spent":"572.088892ms","remote":"127.0.0.1:59564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4024,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:40:34.445577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:33.870838Z","time spent":"574.555647ms","remote":"127.0.0.1:59664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:40:34.445705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.439913ms","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-05-27T19:40:34.445967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.842859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-27T19:40:34.445968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.382329023s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-27T19:40:34.445968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.959931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-27T19:40:34.446009Z","caller":"traceutil/trace.go:171","msg":"trace[1526258576] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:812; }","duration":"1.382404914s","start":"2026-05-27T19:40:33.063594Z","end":"2026-05-27T19:40:34.445999Z","steps":["trace[1526258576] 'agreement among raft nodes before linearized reading' (duration: 1.382311273s)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:40:34.446007Z","caller":"traceutil/trace.go:171","msg":"trace[1790719459] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:812; }","duration":"403.901599ms","start":"2026-05-27T19:40:34.042093Z","end":"2026-05-27T19:40:34.445994Z","steps":["trace[1790719459] 'agreement among raft nodes before linearized reading' (duration: 403.852758ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:40:34.446015Z","caller":"traceutil/trace.go:171","msg":"trace[688036274] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:812; }","duration":"570.040293ms","start":"2026-05-27T19:40:33.875965Z","end":"2026-05-27T19:40:34.446005Z","steps":["trace[688036274] 'agreement among raft nodes before linearized reading' (duration: 569.956162ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:34.446041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:34.042079Z","time spent":"403.956341ms","remote":"127.0.0.1:59390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-27T19:40:34.446097Z","caller":"traceutil/trace.go:171","msg":"trace[1171966261] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:812; }","duration":"422.747988ms","start":"2026-05-27T19:40:34.023235Z","end":"2026-05-27T19:40:34.445983Z","steps":["trace[1171966261] 'agreement among raft nodes before linearized reading' (duration: 422.342671ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:34.446199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:34.023223Z","time spent":"422.966183ms","remote":"127.0.0.1:59664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-27T19:40:34.446047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:33.875955Z","time spent":"570.082954ms","remote":"127.0.0.1:59726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":14,"response size":31,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-05-27T19:40:34.445741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.363523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-27T19:40:34.446393Z","caller":"traceutil/trace.go:171","msg":"trace[273893828] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:812; }","duration":"589.050406ms","start":"2026-05-27T19:40:33.857332Z","end":"2026-05-27T19:40:34.446382Z","steps":["trace[273893828] 'agreement among raft nodes before linearized reading' (duration: 588.211991ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:34.446459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:33.857313Z","time spent":"589.134057ms","remote":"127.0.0.1:59664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-27T19:40:34.445911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.896702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-27T19:40:34.446687Z","caller":"traceutil/trace.go:171","msg":"trace[890218964] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:812; }","duration":"423.707466ms","start":"2026-05-27T19:40:34.02297Z","end":"2026-05-27T19:40:34.446677Z","steps":["trace[890218964] 'agreement among raft nodes before linearized reading' (duration: 422.87117ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:34.446719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:34.022945Z","time spent":"423.767147ms","remote":"127.0.0.1:59664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-27T19:40:34.446148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:33.063573Z","time spent":"1.382565497s","remote":"127.0.0.1:59664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-27T19:42:19.961914Z","caller":"traceutil/trace.go:171","msg":"trace[372645269] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"200.542345ms","start":"2026-05-27T19:42:19.761282Z","end":"2026-05-27T19:42:19.961825Z","steps":["trace[372645269] 'process raft request' (duration: 200.234381ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:42:27.612909Z","caller":"traceutil/trace.go:171","msg":"trace[940379461] transaction","detail":"{read_only:false; response_revision:1352; number_of_response:1; }","duration":"499.976956ms","start":"2026-05-27T19:42:27.112881Z","end":"2026-05-27T19:42:27.612858Z","steps":["trace[940379461] 'process raft request' (duration: 478.071838ms)","trace[940379461] 'compare' (duration: 21.642664ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:42:27.613239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:42:27.112863Z","time spent":"500.248141ms","remote":"127.0.0.1:59664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:42:27.636268Z","caller":"traceutil/trace.go:171","msg":"trace[264570562] transaction","detail":"{read_only:false; response_revision:1353; number_of_response:1; }","duration":"517.443076ms","start":"2026-05-27T19:42:27.118777Z","end":"2026-05-27T19:42:27.63622Z","steps":["trace[264570562] 'process raft request' (duration: 517.154391ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:42:27.636331Z","caller":"traceutil/trace.go:171","msg":"trace[819262065] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"423.805546ms","start":"2026-05-27T19:42:27.212475Z","end":"2026-05-27T19:42:27.636281Z","steps":["trace[819262065] 'process raft request' (duration: 423.644283ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:42:27.636583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:42:27.118751Z","time spent":"517.674229ms","remote":"127.0.0.1:59664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:42:27.63626Z","caller":"traceutil/trace.go:171","msg":"trace[2022467288] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"516.230416ms","start":"2026-05-27T19:42:27.119979Z","end":"2026-05-27T19:42:27.636209Z","steps":["trace[2022467288] 'process raft request' (duration: 516.098664ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:42:27.636661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:42:27.212439Z","time spent":"424.09396ms","remote":"127.0.0.1:59664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:42:27.637153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:42:27.119971Z","time spent":"516.915497ms","remote":"127.0.0.1:59664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"}