{"level":"warn","ts":"2026-06-13T22:49:25.510063Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-13T22:49:25.510155Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.72: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.204.45.72:2380","--initial-cluster=instance=https://199.204.45.72:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.72:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.72: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-06-13T22:49:25.510243Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-13T22:49:25.510254Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-06-13T22:49:25.510285Z","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-06-13T22:49:25.511024Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"]} {"level":"info","ts":"2026-06-13T22:49:25.511108Z","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.204.45.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.72: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-06-13T22:49:25.513451Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.085737ms"} {"level":"info","ts":"2026-06-13T22:49:25.52084Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"50e03cebfd94cbba","cluster-id":"120c03c1d5b80f94"} {"level":"info","ts":"2026-06-13T22:49:25.520942Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=()"} {"level":"info","ts":"2026-06-13T22:49:25.520968Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 0"} {"level":"info","ts":"2026-06-13T22:49:25.520984Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 50e03cebfd94cbba [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-13T22:49:25.520995Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 1"} {"level":"info","ts":"2026-06-13T22:49:25.521038Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"warn","ts":"2026-06-13T22:49:25.526491Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-13T22:49:25.528261Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-13T22:49:25.529375Z","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-06-13T22:49:25.535022Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"50e03cebfd94cbba","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-13T22:49:25.538829Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"50e03cebfd94cbba","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-13T22:49:25.539034Z","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-06-13T22:49:25.539079Z","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-06-13T22:49:25.539086Z","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-06-13T22:49:25.539448Z","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-06-13T22:49:25.539697Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"info","ts":"2026-06-13T22:49:25.539868Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-06-13T22:49:25.539941Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-06-13T22:49:25.540004Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","added-peer-id":"50e03cebfd94cbba","added-peer-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-06-13T22:49:25.540215Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"50e03cebfd94cbba","initial-advertise-peer-urls":["https://199.204.45.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-13T22:49:25.540268Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-13T22:49:25.621883Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba is starting a new election at term 1"} {"level":"info","ts":"2026-06-13T22:49:25.62193Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became pre-candidate at term 1"} {"level":"info","ts":"2026-06-13T22:49:25.621957Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgPreVoteResp from 50e03cebfd94cbba at term 1"} {"level":"info","ts":"2026-06-13T22:49:25.621975Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became candidate at term 2"} {"level":"info","ts":"2026-06-13T22:49:25.621983Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgVoteResp from 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-06-13T22:49:25.621994Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became leader at term 2"} {"level":"info","ts":"2026-06-13T22:49:25.622003Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 50e03cebfd94cbba elected leader 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-06-13T22:49:25.622952Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-13T22:49:25.623713Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"50e03cebfd94cbba","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.72:2379]}","request-path":"/0/members/50e03cebfd94cbba/attributes","cluster-id":"120c03c1d5b80f94","publish-timeout":"7s"} {"level":"info","ts":"2026-06-13T22:49:25.623919Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-13T22:49:25.623976Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-13T22:49:25.624019Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-13T22:49:25.624086Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-13T22:49:25.624253Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","cluster-version":"3.5"} {"level":"info","ts":"2026-06-13T22:49:25.624405Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-13T22:49:25.624446Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-13T22:49:25.627088Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-13T22:49:25.635038Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.72:2379"} {"level":"warn","ts":"2026-06-13T22:49:32.392562Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680220496478016434,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-13T22:49:32.43227Z","caller":"traceutil/trace.go:171","msg":"trace[107738902] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"582.370132ms","start":"2026-06-13T22:49:31.849846Z","end":"2026-06-13T22:49:32.432216Z","steps":["trace[107738902] 'process raft request' (duration: 555.895332ms)","trace[107738902] 'compare' (duration: 26.064597ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:49:32.432878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:49:31.849832Z","time spent":"582.540739ms","remote":"127.0.0.1:46488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-13T22:49:32.949368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.638631ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:5"} {"level":"info","ts":"2026-06-13T22:49:32.949594Z","caller":"traceutil/trace.go:171","msg":"trace[752559928] transaction","detail":"{read_only:false; number_of_response:0; response_revision:307; }","duration":"1.057375514s","start":"2026-06-13T22:49:31.892197Z","end":"2026-06-13T22:49:32.949573Z","steps":["trace[752559928] 'process raft request' (duration: 1.057284191s)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:49:32.949689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:49:31.892166Z","time spent":"1.057481557s","remote":"127.0.0.1:46436","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-13T22:49:32.949703Z","caller":"traceutil/trace.go:171","msg":"trace[904214489] transaction","detail":"{read_only:false; number_of_response:0; response_revision:307; }","duration":"1.056885338s","start":"2026-06-13T22:49:31.892782Z","end":"2026-06-13T22:49:32.949667Z","steps":["trace[904214489] 'process raft request' (duration: 1.056734553s)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:49:32.949827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:49:31.892766Z","time spent":"1.056999991s","remote":"127.0.0.1:46436","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-13T22:49:32.94961Z","caller":"traceutil/trace.go:171","msg":"trace[1740164983] transaction","detail":"{read_only:false; number_of_response:0; response_revision:307; }","duration":"1.057466526s","start":"2026-06-13T22:49:31.892114Z","end":"2026-06-13T22:49:32.949581Z","steps":["trace[1740164983] 'process raft request' (duration: 930.401691ms)","trace[1740164983] 'compare' (duration: 126.570759ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:49:32.950915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:49:31.892105Z","time spent":"1.058767978s","remote":"127.0.0.1:46436","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-13T22:49:32.950291Z","caller":"traceutil/trace.go:171","msg":"trace[1098253897] transaction","detail":"{read_only:false; number_of_response:0; response_revision:307; }","duration":"1.057488527s","start":"2026-06-13T22:49:31.892782Z","end":"2026-06-13T22:49:32.95027Z","steps":["trace[1098253897] 'process raft request' (duration: 1.05725693s)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:49:32.951084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:49:31.892768Z","time spent":"1.058279402s","remote":"127.0.0.1:46436","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-13T22:49:32.950422Z","caller":"traceutil/trace.go:171","msg":"trace[2021228840] linearizableReadLoop","detail":"{readStateIndex:320; appliedIndex:315; }","duration":"1.058356924s","start":"2026-06-13T22:49:31.892053Z","end":"2026-06-13T22:49:32.95041Z","steps":["trace[2021228840] 'read index received' (duration: 513.767867ms)","trace[2021228840] 'applied index is now lower than readState.Index' (duration: 544.587097ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:49:32.950509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.058436687s","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-06-13T22:49:32.951242Z","caller":"traceutil/trace.go:171","msg":"trace[744512595] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:307; }","duration":"1.059206792s","start":"2026-06-13T22:49:31.892024Z","end":"2026-06-13T22:49:32.951231Z","steps":["trace[744512595] 'agreement among raft nodes before linearized reading' (duration: 1.058418396s)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:49:32.951284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:49:31.89201Z","time spent":"1.059265494s","remote":"127.0.0.1:46436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":3996,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"warn","ts":"2026-06-13T22:49:32.951432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.058906952s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-06-13T22:49:32.951466Z","caller":"traceutil/trace.go:171","msg":"trace[1055000879] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:308; }","duration":"1.058950464s","start":"2026-06-13T22:49:31.892507Z","end":"2026-06-13T22:49:32.951458Z","steps":["trace[1055000879] 'agreement among raft nodes before linearized reading' (duration: 1.058850751s)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:49:32.951494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:49:31.892501Z","time spent":"1.058987145s","remote":"127.0.0.1:46370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-06-13T22:49:32.950643Z","caller":"traceutil/trace.go:171","msg":"trace[295613743] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"513.019342ms","start":"2026-06-13T22:49:32.437606Z","end":"2026-06-13T22:49:32.950626Z","steps":["trace[295613743] 'process raft request' (duration: 512.609589ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:49:32.951687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:49:32.437588Z","time spent":"514.074437ms","remote":"127.0.0.1:46330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":705,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-13T22:49:32.951944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.752761ms","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-06-13T22:49:32.951981Z","caller":"traceutil/trace.go:171","msg":"trace[940385830] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:308; }","duration":"515.825183ms","start":"2026-06-13T22:49:32.436147Z","end":"2026-06-13T22:49:32.951972Z","steps":["trace[940385830] 'agreement among raft nodes before linearized reading' (duration: 515.771841ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:49:32.952007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:49:32.436128Z","time spent":"515.873015ms","remote":"127.0.0.1:46488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-13T22:49:32.952128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.761722ms","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-06-13T22:49:32.95216Z","caller":"traceutil/trace.go:171","msg":"trace[1644383710] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:308; }","duration":"548.828293ms","start":"2026-06-13T22:49:32.403324Z","end":"2026-06-13T22:49:32.952152Z","steps":["trace[1644383710] 'agreement among raft nodes before linearized reading' (duration: 548.777702ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:49:32.952185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:49:32.403305Z","time spent":"548.875005ms","remote":"127.0.0.1:46488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-13T22:49:33.138465Z","caller":"traceutil/trace.go:171","msg":"trace[960363477] linearizableReadLoop","detail":"{readStateIndex:322; appliedIndex:322; }","duration":"182.102305ms","start":"2026-06-13T22:49:32.956335Z","end":"2026-06-13T22:49:33.138437Z","steps":["trace[960363477] 'read index received' (duration: 182.093004ms)","trace[960363477] 'applied index is now lower than readState.Index' (duration: 7.64µs)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:49:33.138793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.401404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:5513"} {"level":"info","ts":"2026-06-13T22:49:33.138911Z","caller":"traceutil/trace.go:171","msg":"trace[12582903] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:309; }","duration":"182.578549ms","start":"2026-06-13T22:49:32.956319Z","end":"2026-06-13T22:49:33.138898Z","steps":["trace[12582903] 'agreement among raft nodes before linearized reading' (duration: 182.201797ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:49:33.138469Z","caller":"traceutil/trace.go:171","msg":"trace[613082050] transaction","detail":"{read_only:false; response_revision:309; number_of_response:1; }","duration":"182.419835ms","start":"2026-06-13T22:49:32.955991Z","end":"2026-06-13T22:49:33.138411Z","steps":["trace[613082050] 'process raft request' (duration: 182.26521ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:49:33.140931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.527033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5326"} {"level":"info","ts":"2026-06-13T22:49:33.140987Z","caller":"traceutil/trace.go:171","msg":"trace[791931101] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:313; }","duration":"184.613635ms","start":"2026-06-13T22:49:32.956361Z","end":"2026-06-13T22:49:33.140975Z","steps":["trace[791931101] 'agreement among raft nodes before linearized reading' (duration: 184.493471ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:49:33.14127Z","caller":"traceutil/trace.go:171","msg":"trace[544535307] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"184.895424ms","start":"2026-06-13T22:49:32.956364Z","end":"2026-06-13T22:49:33.141259Z","steps":["trace[544535307] 'process raft request' (duration: 184.259374ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:49:33.141391Z","caller":"traceutil/trace.go:171","msg":"trace[998396802] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"184.940565ms","start":"2026-06-13T22:49:32.956442Z","end":"2026-06-13T22:49:33.141383Z","steps":["trace[998396802] 'process raft request' (duration: 184.281304ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:49:33.141495Z","caller":"traceutil/trace.go:171","msg":"trace[1318249954] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"184.894925ms","start":"2026-06-13T22:49:32.956592Z","end":"2026-06-13T22:49:33.141487Z","steps":["trace[1318249954] 'process raft request' (duration: 184.164901ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:49:33.141682Z","caller":"traceutil/trace.go:171","msg":"trace[539874252] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"170.022646ms","start":"2026-06-13T22:49:32.971651Z","end":"2026-06-13T22:49:33.141673Z","steps":["trace[539874252] 'process raft request' (duration: 169.163328ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:50:01.368898Z","caller":"traceutil/trace.go:171","msg":"trace[590596289] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"100.19338ms","start":"2026-06-13T22:50:01.268666Z","end":"2026-06-13T22:50:01.36886Z","steps":["trace[590596289] 'process raft request' (duration: 47.563698ms)","trace[590596289] 'compare' (duration: 52.513208ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:50:02.771856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.171199ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T22:50:02.772005Z","caller":"traceutil/trace.go:171","msg":"trace[772048018] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:522; }","duration":"127.345155ms","start":"2026-06-13T22:50:02.644637Z","end":"2026-06-13T22:50:02.771982Z","steps":["trace[772048018] 'range keys from in-memory index tree' (duration: 127.157489ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:50:02.772112Z","caller":"traceutil/trace.go:171","msg":"trace[1208000493] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"450.117081ms","start":"2026-06-13T22:50:02.321883Z","end":"2026-06-13T22:50:02.772Z","steps":["trace[1208000493] 'process raft request' (duration: 390.105622ms)","trace[1208000493] 'compare' (duration: 59.663578ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:50:02.772268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:50:02.321867Z","time spent":"450.337929ms","remote":"127.0.0.1:46298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-13T22:50:02.77998Z","caller":"traceutil/trace.go:171","msg":"trace[571288610] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"456.964121ms","start":"2026-06-13T22:50:02.32297Z","end":"2026-06-13T22:50:02.779934Z","steps":["trace[571288610] 'process raft request' (duration: 456.812336ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:50:02.780168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:50:02.322966Z","time spent":"457.125216ms","remote":"127.0.0.1:46298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-13T22:50:02.780421Z","caller":"traceutil/trace.go:171","msg":"trace[154030566] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"457.516109ms","start":"2026-06-13T22:50:02.322878Z","end":"2026-06-13T22:50:02.780394Z","steps":["trace[154030566] 'process raft request' (duration: 456.859808ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:50:02.780474Z","caller":"traceutil/trace.go:171","msg":"trace[605072976] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"449.110069ms","start":"2026-06-13T22:50:02.331314Z","end":"2026-06-13T22:50:02.780424Z","steps":["trace[605072976] 'process raft request' (duration: 448.612133ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:50:02.780544Z","caller":"traceutil/trace.go:171","msg":"trace[57821426] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"458.396957ms","start":"2026-06-13T22:50:02.3221Z","end":"2026-06-13T22:50:02.780497Z","steps":["trace[57821426] 'process raft request' (duration: 457.505019ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:50:02.780605Z","caller":"traceutil/trace.go:171","msg":"trace[2123570167] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"447.937931ms","start":"2026-06-13T22:50:02.332658Z","end":"2026-06-13T22:50:02.780596Z","steps":["trace[2123570167] 'process raft request' (duration: 447.295661ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:50:02.780698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:50:02.322871Z","time spent":"457.611902ms","remote":"127.0.0.1:46298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7317,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-13T22:50:02.780727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:50:02.322073Z","time spent":"458.577243ms","remote":"127.0.0.1:46298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-13T22:50:02.780787Z","caller":"traceutil/trace.go:171","msg":"trace[744312876] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"453.019364ms","start":"2026-06-13T22:50:02.327754Z","end":"2026-06-13T22:50:02.780773Z","steps":["trace[744312876] 'process raft request' (duration: 452.080364ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:50:02.780701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:50:02.332641Z","time spent":"448.028164ms","remote":"127.0.0.1:46298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-13T22:50:02.780749Z","caller":"traceutil/trace.go:171","msg":"trace[1586387770] transaction","detail":"{read_only:false; number_of_response:1; response_revision:526; }","duration":"457.171218ms","start":"2026-06-13T22:50:02.323569Z","end":"2026-06-13T22:50:02.78074Z","steps":["trace[1586387770] 'process raft request' (duration: 456.241028ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:50:02.780708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:50:02.331309Z","time spent":"449.322966ms","remote":"127.0.0.1:46298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-13T22:50:02.780994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:50:02.323563Z","time spent":"457.404455ms","remote":"127.0.0.1:46298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7365,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T22:50:02.780853Z","caller":"traceutil/trace.go:171","msg":"trace[1393376624] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"449.595344ms","start":"2026-06-13T22:50:02.331249Z","end":"2026-06-13T22:50:02.780844Z","steps":["trace[1393376624] 'process raft request' (duration: 448.625473ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:50:02.781061Z","caller":"traceutil/trace.go:171","msg":"trace[549135464] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"441.519325ms","start":"2026-06-13T22:50:02.339533Z","end":"2026-06-13T22:50:02.781052Z","steps":["trace[549135464] 'process raft request' (duration: 440.527173ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:50:02.780538Z","caller":"traceutil/trace.go:171","msg":"trace[933769915] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"445.549304ms","start":"2026-06-13T22:50:02.334976Z","end":"2026-06-13T22:50:02.780525Z","steps":["trace[933769915] 'process raft request' (duration: 445.002047ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:50:02.781168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:50:02.334959Z","time spent":"446.188565ms","remote":"127.0.0.1:46298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-13T22:50:02.780892Z","caller":"traceutil/trace.go:171","msg":"trace[1275635622] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"429.914582ms","start":"2026-06-13T22:50:02.35097Z","end":"2026-06-13T22:50:02.780885Z","steps":["trace[1275635622] 'process raft request' (duration: 429.155577ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:50:02.781211Z","caller":"traceutil/trace.go:171","msg":"trace[2014513580] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"309.315145ms","start":"2026-06-13T22:50:02.471842Z","end":"2026-06-13T22:50:02.781158Z","steps":["trace[2014513580] 'process raft request' (duration: 308.811268ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:50:02.781273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:50:02.350946Z","time spent":"430.295034ms","remote":"127.0.0.1:46298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-13T22:50:02.781277Z","caller":"traceutil/trace.go:171","msg":"trace[958374497] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"416.447379ms","start":"2026-06-13T22:50:02.364568Z","end":"2026-06-13T22:50:02.781015Z","steps":["trace[958374497] 'process raft request' (duration: 415.599942ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:50:02.781295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:50:02.471811Z","time spent":"309.460149ms","remote":"127.0.0.1:46298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-13T22:50:02.781106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:50:02.331234Z","time spent":"449.820872ms","remote":"127.0.0.1:46298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-13T22:50:02.781117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:50:02.339515Z","time spent":"441.572876ms","remote":"127.0.0.1:46298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-13T22:50:02.780882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:50:02.327731Z","time spent":"453.123678ms","remote":"127.0.0.1:46726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-13T22:50:02.781409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:50:02.364552Z","time spent":"416.771139ms","remote":"127.0.0.1:46298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-13T22:50:09.197578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.233369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.72\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-06-13T22:50:09.197623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.828584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-f6w5c\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-06-13T22:50:09.197663Z","caller":"traceutil/trace.go:171","msg":"trace[1708045815] range","detail":"{range_begin:/registry/masterleases/199.204.45.72; range_end:; response_count:1; response_revision:628; }","duration":"182.390324ms","start":"2026-06-13T22:50:09.015253Z","end":"2026-06-13T22:50:09.197644Z","steps":["trace[1708045815] 'range keys from in-memory index tree' (duration: 182.125726ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:50:09.197686Z","caller":"traceutil/trace.go:171","msg":"trace[1342092834] range","detail":"{range_begin:/registry/pods/kube-system/cilium-f6w5c; range_end:; response_count:1; response_revision:628; }","duration":"208.913987ms","start":"2026-06-13T22:50:08.988754Z","end":"2026-06-13T22:50:09.197668Z","steps":["trace[1342092834] 'range keys from in-memory index tree' (duration: 208.604997ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:50:09.602737Z","caller":"traceutil/trace.go:171","msg":"trace[1060919507] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"435.12394ms","start":"2026-06-13T22:50:09.167589Z","end":"2026-06-13T22:50:09.602713Z","steps":["trace[1060919507] 'process raft request' (duration: 432.161904ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:50:09.602855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:50:09.167559Z","time spent":"435.238873ms","remote":"127.0.0.1:46356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T22:50:09.624842Z","caller":"traceutil/trace.go:171","msg":"trace[1186281922] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:656; }","duration":"204.062481ms","start":"2026-06-13T22:50:09.420733Z","end":"2026-06-13T22:50:09.624796Z","steps":["trace[1186281922] 'read index received' (duration: 176.9748ms)","trace[1186281922] 'applied index is now lower than readState.Index' (duration: 27.086831ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:50:09.624942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:50:09.198642Z","time spent":"426.297565ms","remote":"127.0.0.1:46280","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-06-13T22:50:09.625264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.510725ms","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-06-13T22:50:09.625324Z","caller":"traceutil/trace.go:171","msg":"trace[1525686573] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:630; }","duration":"204.609408ms","start":"2026-06-13T22:50:09.4207Z","end":"2026-06-13T22:50:09.62531Z","steps":["trace[1525686573] 'agreement among raft nodes before linearized reading' (duration: 204.35855ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:50:09.625438Z","caller":"traceutil/trace.go:171","msg":"trace[1080330702] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"415.55325ms","start":"2026-06-13T22:50:09.209867Z","end":"2026-06-13T22:50:09.62542Z","steps":["trace[1080330702] 'process raft request' (duration: 414.846337ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:50:09.625555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:50:09.209839Z","time spent":"415.635382ms","remote":"127.0.0.1:46436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-13T22:50:09.62559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.186063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-13T22:50:09.625653Z","caller":"traceutil/trace.go:171","msg":"trace[1186416338] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:630; }","duration":"199.290387ms","start":"2026-06-13T22:50:09.426348Z","end":"2026-06-13T22:50:09.625638Z","steps":["trace[1186416338] 'agreement among raft nodes before linearized reading' (duration: 199.0884ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:50:09.62571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.66621ms","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-06-13T22:50:09.625752Z","caller":"traceutil/trace.go:171","msg":"trace[1968002270] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:630; }","duration":"199.873406ms","start":"2026-06-13T22:50:09.425868Z","end":"2026-06-13T22:50:09.625741Z","steps":["trace[1968002270] 'agreement among raft nodes before linearized reading' (duration: 199.600977ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:50:09.625857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.424023ms","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-06-13T22:50:09.625916Z","caller":"traceutil/trace.go:171","msg":"trace[347425942] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:630; }","duration":"204.498985ms","start":"2026-06-13T22:50:09.421404Z","end":"2026-06-13T22:50:09.625903Z","steps":["trace[347425942] 'agreement among raft nodes before linearized reading' (duration: 203.843684ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:50:10.17025Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680220496478017144,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-13T22:50:10.736159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.557271ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-13T22:50:10.736257Z","caller":"traceutil/trace.go:171","msg":"trace[440224930] linearizableReadLoop","detail":"{readStateIndex:665; appliedIndex:664; }","duration":"1.066807018s","start":"2026-06-13T22:50:09.669434Z","end":"2026-06-13T22:50:10.736241Z","steps":["trace[440224930] 'read index received' (duration: 795.04484ms)","trace[440224930] 'applied index is now lower than readState.Index' (duration: 271.761088ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:50:10.736402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"995.844406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-13T22:50:10.736452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.067017205s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-13T22:50:10.736483Z","caller":"traceutil/trace.go:171","msg":"trace[944847364] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:636; }","duration":"1.067083987s","start":"2026-06-13T22:50:09.66939Z","end":"2026-06-13T22:50:10.736474Z","steps":["trace[944847364] 'agreement among raft nodes before linearized reading' (duration: 1.066925312s)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:50:10.736477Z","caller":"traceutil/trace.go:171","msg":"trace[1765869548] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:636; }","duration":"995.94994ms","start":"2026-06-13T22:50:09.740509Z","end":"2026-06-13T22:50:10.736459Z","steps":["trace[1765869548] 'agreement among raft nodes before linearized reading' (duration: 995.831516ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:50:10.736524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:50:09.669371Z","time spent":"1.067144808s","remote":"127.0.0.1:46280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"warn","ts":"2026-06-13T22:50:10.736528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:50:09.740497Z","time spent":"996.022893ms","remote":"127.0.0.1:46260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-13T22:50:10.736319Z","caller":"traceutil/trace.go:171","msg":"trace[947392304] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"1.072005575s","start":"2026-06-13T22:50:09.664288Z","end":"2026-06-13T22:50:10.736294Z","steps":["trace[947392304] 'process raft request' (duration: 800.250638ms)","trace[947392304] 'compare' (duration: 271.399556ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:50:10.737143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:50:09.664261Z","time spent":"1.072839722s","remote":"127.0.0.1:46476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-13T22:50:12.444271Z","caller":"traceutil/trace.go:171","msg":"trace[1176742679] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"185.797454ms","start":"2026-06-13T22:50:12.258454Z","end":"2026-06-13T22:50:12.444252Z","steps":["trace[1176742679] 'process raft request' (duration: 185.69547ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:50:15.291584Z","caller":"traceutil/trace.go:171","msg":"trace[374070340] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"119.476998ms","start":"2026-06-13T22:50:15.172072Z","end":"2026-06-13T22:50:15.291549Z","steps":["trace[374070340] 'process raft request' (duration: 119.132757ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:50:25.516364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.46493ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-06-13T22:50:25.516927Z","caller":"traceutil/trace.go:171","msg":"trace[1189701807] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"416.454386ms","start":"2026-06-13T22:50:25.10045Z","end":"2026-06-13T22:50:25.516904Z","steps":["trace[1189701807] 'process raft request' (duration: 416.312352ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:50:25.517063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:50:25.100433Z","time spent":"416.547929ms","remote":"127.0.0.1:46436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4027,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T22:50:59.858352Z","caller":"traceutil/trace.go:171","msg":"trace[25608532] linearizableReadLoop","detail":"{readStateIndex:1053; appliedIndex:1052; }","duration":"116.556403ms","start":"2026-06-13T22:50:59.741775Z","end":"2026-06-13T22:50:59.858332Z","steps":["trace[25608532] 'read index received' (duration: 116.519152ms)","trace[25608532] 'applied index is now lower than readState.Index' (duration: 36.131µs)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:50:59.858484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.677777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T22:50:59.858514Z","caller":"traceutil/trace.go:171","msg":"trace[1509218617] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1009; }","duration":"116.769771ms","start":"2026-06-13T22:50:59.741734Z","end":"2026-06-13T22:50:59.858504Z","steps":["trace[1509218617] 'agreement among raft nodes before linearized reading' (duration: 116.694628ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:50:59.858488Z","caller":"traceutil/trace.go:171","msg":"trace[708667183] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"258.911866ms","start":"2026-06-13T22:50:59.599513Z","end":"2026-06-13T22:50:59.858425Z","steps":["trace[708667183] 'process raft request' (duration: 258.705739ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:00.275Z","caller":"traceutil/trace.go:171","msg":"trace[1727133421] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"373.703534ms","start":"2026-06-13T22:50:59.901273Z","end":"2026-06-13T22:51:00.274977Z","steps":["trace[1727133421] 'process raft request' (duration: 373.58117ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:00.275144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:50:59.901257Z","time spent":"373.814857ms","remote":"127.0.0.1:46488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T22:51:00.780675Z","caller":"traceutil/trace.go:171","msg":"trace[1549635116] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"877.404061ms","start":"2026-06-13T22:50:59.903244Z","end":"2026-06-13T22:51:00.780648Z","steps":["trace[1549635116] 'process raft request' (duration: 877.321869ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:00.780863Z","caller":"traceutil/trace.go:171","msg":"trace[2018815979] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"877.179765ms","start":"2026-06-13T22:50:59.903674Z","end":"2026-06-13T22:51:00.780854Z","steps":["trace[2018815979] 'process raft request' (duration: 876.936327ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:00.780868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:50:59.903228Z","time spent":"877.523826ms","remote":"127.0.0.1:46488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T22:51:00.780682Z","caller":"traceutil/trace.go:171","msg":"trace[594684174] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"877.470983ms","start":"2026-06-13T22:50:59.903179Z","end":"2026-06-13T22:51:00.78065Z","steps":["trace[594684174] 'process raft request' (duration: 877.232676ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:00.780982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:50:59.90366Z","time spent":"877.241346ms","remote":"127.0.0.1:46488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-13T22:51:00.781025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:50:59.903159Z","time spent":"877.827465ms","remote":"127.0.0.1:46488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T22:51:00.780998Z","caller":"traceutil/trace.go:171","msg":"trace[1095163849] linearizableReadLoop","detail":"{readStateIndex:1057; appliedIndex:1053; }","duration":"697.903467ms","start":"2026-06-13T22:51:00.083064Z","end":"2026-06-13T22:51:00.780967Z","steps":["trace[1095163849] 'read index received' (duration: 191.7618ms)","trace[1095163849] 'applied index is now lower than readState.Index' (duration: 506.139657ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:51:00.78113Z","caller":"traceutil/trace.go:171","msg":"trace[519018693] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"560.395239ms","start":"2026-06-13T22:51:00.220724Z","end":"2026-06-13T22:51:00.781119Z","steps":["trace[519018693] 'process raft request' (duration: 560.058919ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:00.781204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:51:00.220702Z","time spent":"560.473372ms","remote":"127.0.0.1:46488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-13T22:51:00.781258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.169635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-13T22:51:00.781321Z","caller":"traceutil/trace.go:171","msg":"trace[622257502] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1014; }","duration":"698.287069ms","start":"2026-06-13T22:51:00.083023Z","end":"2026-06-13T22:51:00.78131Z","steps":["trace[622257502] 'agreement among raft nodes before linearized reading' (duration: 698.02931ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:00.781366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:51:00.083007Z","time spent":"698.34925ms","remote":"127.0.0.1:46488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-13T22:51:22.623306Z","caller":"traceutil/trace.go:171","msg":"trace[1314693713] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"240.620149ms","start":"2026-06-13T22:51:22.382661Z","end":"2026-06-13T22:51:22.623282Z","steps":["trace[1314693713] 'process raft request' (duration: 240.460813ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:24.208639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.956555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T22:51:24.208718Z","caller":"traceutil/trace.go:171","msg":"trace[289214881] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1088; }","duration":"104.090629ms","start":"2026-06-13T22:51:24.10461Z","end":"2026-06-13T22:51:24.208701Z","steps":["trace[289214881] 'range keys from in-memory index tree' (duration: 103.869582ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:24.208641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.998246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T22:51:24.208815Z","caller":"traceutil/trace.go:171","msg":"trace[2060327748] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1088; }","duration":"436.223533ms","start":"2026-06-13T22:51:23.772573Z","end":"2026-06-13T22:51:24.208797Z","steps":["trace[2060327748] 'count revisions from in-memory index tree' (duration: 435.879583ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:24.208868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:51:23.772556Z","time spent":"436.299696ms","remote":"127.0.0.1:46396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-06-13T22:51:24.691537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.263936ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-06-13T22:51:24.691636Z","caller":"traceutil/trace.go:171","msg":"trace[110149033] linearizableReadLoop","detail":"{readStateIndex:1137; appliedIndex:1136; }","duration":"500.140793ms","start":"2026-06-13T22:51:24.191478Z","end":"2026-06-13T22:51:24.691619Z","steps":["trace[110149033] 'read index received' (duration: 38.69005ms)","trace[110149033] 'applied index is now lower than readState.Index' (duration: 461.449073ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:51:24.691889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.845631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-13T22:51:24.691861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.293418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T22:51:24.691951Z","caller":"traceutil/trace.go:171","msg":"trace[827298736] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1088; }","duration":"156.951855ms","start":"2026-06-13T22:51:24.534974Z","end":"2026-06-13T22:51:24.691926Z","steps":["trace[827298736] 'agreement among raft nodes before linearized reading' (duration: 156.827331ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:24.691962Z","caller":"traceutil/trace.go:171","msg":"trace[1347839904] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1088; }","duration":"500.479414ms","start":"2026-06-13T22:51:24.191462Z","end":"2026-06-13T22:51:24.691941Z","steps":["trace[1347839904] 'agreement among raft nodes before linearized reading' (duration: 500.243397ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:24.692016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:51:24.191397Z","time spent":"500.604918ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-06-13T22:51:47.331567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.738079ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-13T22:51:47.331765Z","caller":"traceutil/trace.go:171","msg":"trace[18700639] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"178.582909ms","start":"2026-06-13T22:51:47.153165Z","end":"2026-06-13T22:51:47.331747Z","steps":["trace[18700639] 'process raft request' (duration: 178.504106ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:47.331998Z","caller":"traceutil/trace.go:171","msg":"trace[910962101] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"178.846077ms","start":"2026-06-13T22:51:47.15314Z","end":"2026-06-13T22:51:47.331986Z","steps":["trace[910962101] 'process raft request' (duration: 49.181548ms)","trace[910962101] 'compare' (duration: 128.556504ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:51:59.055968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.273114ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-13T22:51:59.056063Z","caller":"traceutil/trace.go:171","msg":"trace[1503621193] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"141.742417ms","start":"2026-06-13T22:51:58.914292Z","end":"2026-06-13T22:51:59.056035Z","steps":["trace[1503621193] 'process raft request' (duration: 23.346279ms)","trace[1503621193] 'compare' (duration: 118.169501ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:51:59.32949Z","caller":"traceutil/trace.go:171","msg":"trace[292699436] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"294.476436ms","start":"2026-06-13T22:51:59.034987Z","end":"2026-06-13T22:51:59.329463Z","steps":["trace[292699436] 'process raft request' (duration: 263.796371ms)","trace[292699436] 'compare' (duration: 30.576961ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:51:59.33016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.211844ms","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-06-13T22:51:59.330263Z","caller":"traceutil/trace.go:171","msg":"trace[1196749878] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1274; }","duration":"201.329148ms","start":"2026-06-13T22:51:59.128922Z","end":"2026-06-13T22:51:59.330251Z","steps":["trace[1196749878] 'agreement among raft nodes before linearized reading' (duration: 201.040789ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:59.329913Z","caller":"traceutil/trace.go:171","msg":"trace[1729484968] linearizableReadLoop","detail":"{readStateIndex:1332; appliedIndex:1330; }","duration":"200.929125ms","start":"2026-06-13T22:51:59.128933Z","end":"2026-06-13T22:51:59.329862Z","steps":["trace[1729484968] 'read index received' (duration: 169.861009ms)","trace[1729484968] 'applied index is now lower than readState.Index' (duration: 31.066736ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:51:59.331069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.122916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-13T22:51:59.33113Z","caller":"traceutil/trace.go:171","msg":"trace[428321414] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1274; }","duration":"198.200308ms","start":"2026-06-13T22:51:59.132912Z","end":"2026-06-13T22:51:59.331112Z","steps":["trace[428321414] 'agreement among raft nodes before linearized reading' (duration: 198.053213ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:52:00.504275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.208304ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-13T22:52:00.504413Z","caller":"traceutil/trace.go:171","msg":"trace[173804175] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"1.106870154s","start":"2026-06-13T22:51:59.397522Z","end":"2026-06-13T22:52:00.504392Z","steps":["trace[173804175] 'process raft request' (duration: 650.473414ms)","trace[173804175] 'compare' (duration: 455.800141ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:52:00.504543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:51:59.397507Z","time spent":"1.106993229s","remote":"127.0.0.1:46488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-13T22:52:00.988193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.406086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T22:52:00.988281Z","caller":"traceutil/trace.go:171","msg":"trace[85615907] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1279; }","duration":"247.523549ms","start":"2026-06-13T22:52:00.740739Z","end":"2026-06-13T22:52:00.988262Z","steps":["trace[85615907] 'range keys from in-memory index tree' (duration: 247.337034ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:52:02.622985Z","caller":"traceutil/trace.go:171","msg":"trace[1621090229] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"110.36335ms","start":"2026-06-13T22:52:02.512596Z","end":"2026-06-13T22:52:02.622959Z","steps":["trace[1621090229] 'process raft request' (duration: 110.206385ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:52:04.775846Z","caller":"traceutil/trace.go:171","msg":"trace[2028554531] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"144.704541ms","start":"2026-06-13T22:52:04.631122Z","end":"2026-06-13T22:52:04.775826Z","steps":["trace[2028554531] 'process raft request' (duration: 144.569077ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:52:11.058773Z","caller":"traceutil/trace.go:171","msg":"trace[671336734] linearizableReadLoop","detail":"{readStateIndex:1414; appliedIndex:1413; }","duration":"145.050573ms","start":"2026-06-13T22:52:10.913701Z","end":"2026-06-13T22:52:11.058751Z","steps":["trace[671336734] 'read index received' (duration: 144.818336ms)","trace[671336734] 'applied index is now lower than readState.Index' (duration: 231.247µs)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:52:11.058925Z","caller":"traceutil/trace.go:171","msg":"trace[1348787768] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"235.656949ms","start":"2026-06-13T22:52:10.823237Z","end":"2026-06-13T22:52:11.058894Z","steps":["trace[1348787768] 'process raft request' (duration: 235.37481ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:52:11.058968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.251689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-06-13T22:52:11.059003Z","caller":"traceutil/trace.go:171","msg":"trace[1874724101] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1354; }","duration":"145.338592ms","start":"2026-06-13T22:52:10.913654Z","end":"2026-06-13T22:52:11.058993Z","steps":["trace[1874724101] 'agreement among raft nodes before linearized reading' (duration: 145.183167ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:52:12.518164Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.047706427s","expected-duration":"1s"} {"level":"warn","ts":"2026-06-13T22:52:12.528761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.058310498s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-13T22:52:12.528932Z","caller":"traceutil/trace.go:171","msg":"trace[2009342889] linearizableReadLoop","detail":"{readStateIndex:1415; appliedIndex:1414; }","duration":"1.391667321s","start":"2026-06-13T22:52:11.137249Z","end":"2026-06-13T22:52:12.528916Z","steps":["trace[2009342889] 'read index received' (duration: 333.25849ms)","trace[2009342889] 'applied index is now lower than readState.Index' (duration: 1.058407731s)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:52:12.529002Z","caller":"traceutil/trace.go:171","msg":"trace[595168357] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"1.465888552s","start":"2026-06-13T22:52:11.063103Z","end":"2026-06-13T22:52:12.528992Z","steps":["trace[595168357] 'process raft request' (duration: 407.273284ms)","trace[595168357] 'compare' (duration: 1.058017108s)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:52:12.529054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:52:11.063084Z","time spent":"1.465940904s","remote":"127.0.0.1:46488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T22:52:12.529081Z","caller":"traceutil/trace.go:171","msg":"trace[2125722497] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"1.127973963s","start":"2026-06-13T22:52:11.401085Z","end":"2026-06-13T22:52:12.529059Z","steps":["trace[2125722497] 'process raft request' (duration: 1.127774306s)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:52:12.529207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:52:11.401059Z","time spent":"1.128072395s","remote":"127.0.0.1:46488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-13T22:52:12.529327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.392089254s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-13T22:52:12.529367Z","caller":"traceutil/trace.go:171","msg":"trace[1473052024] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1356; }","duration":"1.392161707s","start":"2026-06-13T22:52:11.137194Z","end":"2026-06-13T22:52:12.529355Z","steps":["trace[1473052024] 'agreement among raft nodes before linearized reading' (duration: 1.392019773s)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:52:12.5294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:52:11.137165Z","time spent":"1.392228489s","remote":"127.0.0.1:46488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-13T22:52:12.529793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.01660332s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-06-13T22:52:12.529779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.110772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-13T22:52:12.52983Z","caller":"traceutil/trace.go:171","msg":"trace[1149905255] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1357; }","duration":"1.016655601s","start":"2026-06-13T22:52:11.513166Z","end":"2026-06-13T22:52:12.529822Z","steps":["trace[1149905255] 'agreement among raft nodes before linearized reading' (duration: 1.016535037s)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:52:12.529858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:52:11.51316Z","time spent":"1.016693462s","remote":"127.0.0.1:46488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-13T22:52:12.529846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.217461143s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-06-13T22:52:12.529929Z","caller":"traceutil/trace.go:171","msg":"trace[1449118786] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1357; }","duration":"1.217579337s","start":"2026-06-13T22:52:11.312326Z","end":"2026-06-13T22:52:12.529905Z","steps":["trace[1449118786] 'agreement among raft nodes before linearized reading' (duration: 1.21735859s)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:52:12.529964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"788.780762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-13T22:52:12.529977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.021948902s","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-06-13T22:52:12.529987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:52:11.312308Z","time spent":"1.217666229s","remote":"127.0.0.1:46488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":495,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-06-13T22:52:12.530003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.072004217s","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-06-13T22:52:12.530049Z","caller":"traceutil/trace.go:171","msg":"trace[1248343674] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1357; }","duration":"1.07210509s","start":"2026-06-13T22:52:11.457933Z","end":"2026-06-13T22:52:12.530038Z","steps":["trace[1248343674] 'agreement among raft nodes before linearized reading' (duration: 1.071965626s)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:52:12.530076Z","caller":"traceutil/trace.go:171","msg":"trace[370331890] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"946.134739ms","start":"2026-06-13T22:52:11.583933Z","end":"2026-06-13T22:52:12.530067Z","steps":["trace[370331890] 'process raft request' (duration: 945.626993ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:52:12.530086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:52:11.457915Z","time spent":"1.072163932s","remote":"127.0.0.1:46488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-13T22:52:12.530124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.231438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-13T22:52:12.530129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:52:11.58391Z","time spent":"946.192181ms","remote":"127.0.0.1:46488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T22:52:12.530152Z","caller":"traceutil/trace.go:171","msg":"trace[893763995] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1357; }","duration":"544.29313ms","start":"2026-06-13T22:52:11.985852Z","end":"2026-06-13T22:52:12.530145Z","steps":["trace[893763995] 'agreement among raft nodes before linearized reading' (duration: 544.253578ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:52:12.529986Z","caller":"traceutil/trace.go:171","msg":"trace[1869444619] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1357; }","duration":"788.835743ms","start":"2026-06-13T22:52:11.741142Z","end":"2026-06-13T22:52:12.529978Z","steps":["trace[1869444619] 'agreement among raft nodes before linearized reading' (duration: 788.804642ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:52:12.530176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:52:11.985834Z","time spent":"544.33619ms","remote":"127.0.0.1:35064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-06-13T22:52:12.530186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:52:11.741121Z","time spent":"789.061521ms","remote":"127.0.0.1:46260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-13T22:52:12.530014Z","caller":"traceutil/trace.go:171","msg":"trace[1580416380] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1357; }","duration":"1.022020344s","start":"2026-06-13T22:52:11.507985Z","end":"2026-06-13T22:52:12.530005Z","steps":["trace[1580416380] 'agreement among raft nodes before linearized reading' (duration: 1.02189738s)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:52:12.530279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:52:11.507966Z","time spent":"1.022306583s","remote":"127.0.0.1:46488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-13T22:52:12.529847Z","caller":"traceutil/trace.go:171","msg":"trace[1598235577] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1357; }","duration":"620.201764ms","start":"2026-06-13T22:52:11.909627Z","end":"2026-06-13T22:52:12.529829Z","steps":["trace[1598235577] 'agreement among raft nodes before linearized reading' (duration: 620.06211ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:52:12.530338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:52:11.909605Z","time spent":"620.725871ms","remote":"127.0.0.1:46418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":5,"response size":29,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-06-13T22:52:12.892262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.354904ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T22:52:12.892387Z","caller":"traceutil/trace.go:171","msg":"trace[801390463] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1358; }","duration":"247.501079ms","start":"2026-06-13T22:52:12.644866Z","end":"2026-06-13T22:52:12.892367Z","steps":["trace[801390463] 'range keys from in-memory index tree' (duration: 247.079605ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:52:12.895075Z","caller":"traceutil/trace.go:171","msg":"trace[2040738060] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"360.585737ms","start":"2026-06-13T22:52:12.534456Z","end":"2026-06-13T22:52:12.895042Z","steps":["trace[2040738060] 'process raft request' (duration: 360.324148ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:52:12.895105Z","caller":"traceutil/trace.go:171","msg":"trace[756244523] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"360.569446ms","start":"2026-06-13T22:52:12.534512Z","end":"2026-06-13T22:52:12.895081Z","steps":["trace[756244523] 'process raft request' (duration: 360.401671ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:52:12.895098Z","caller":"traceutil/trace.go:171","msg":"trace[39852109] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"360.334269ms","start":"2026-06-13T22:52:12.534677Z","end":"2026-06-13T22:52:12.895011Z","steps":["trace[39852109] 'process raft request' (duration: 360.273387ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:52:12.895244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:52:12.534665Z","time spent":"360.505224ms","remote":"127.0.0.1:46488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-13T22:52:12.895257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:52:12.534505Z","time spent":"360.65451ms","remote":"127.0.0.1:46488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T22:52:12.895286Z","caller":"traceutil/trace.go:171","msg":"trace[244864257] transaction","detail":"{read_only:false; response_revision:1362; number_of_response:1; }","duration":"359.797312ms","start":"2026-06-13T22:52:12.535472Z","end":"2026-06-13T22:52:12.895269Z","steps":["trace[244864257] 'process raft request' (duration: 359.509343ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:52:12.895402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:52:12.535451Z","time spent":"359.881734ms","remote":"127.0.0.1:46488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T22:52:12.895563Z","caller":"traceutil/trace.go:171","msg":"trace[1277445028] transaction","detail":"{read_only:false; response_revision:1363; number_of_response:1; }","duration":"359.828793ms","start":"2026-06-13T22:52:12.535717Z","end":"2026-06-13T22:52:12.895546Z","steps":["trace[1277445028] 'process raft request' (duration: 359.480622ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:52:12.895674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:52:12.535709Z","time spent":"359.920666ms","remote":"127.0.0.1:46488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-13T22:52:12.896403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:52:12.534446Z","time spent":"360.71292ms","remote":"127.0.0.1:46488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T22:55:51.77494Z","caller":"traceutil/trace.go:171","msg":"trace[980270677] transaction","detail":"{read_only:false; response_revision:2385; number_of_response:1; }","duration":"130.259897ms","start":"2026-06-13T22:55:51.644648Z","end":"2026-06-13T22:55:51.774908Z","steps":["trace[980270677] 'process raft request' (duration: 127.843361ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:55:59.190358Z","caller":"traceutil/trace.go:171","msg":"trace[1942944578] transaction","detail":"{read_only:false; response_revision:2424; number_of_response:1; }","duration":"134.700635ms","start":"2026-06-13T22:55:59.055625Z","end":"2026-06-13T22:55:59.190326Z","steps":["trace[1942944578] 'process raft request' (duration: 78.588883ms)","trace[1942944578] 'compare' (duration: 55.983618ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:55:59.229683Z","caller":"traceutil/trace.go:171","msg":"trace[359014392] transaction","detail":"{read_only:false; response_revision:2425; number_of_response:1; }","duration":"117.011912ms","start":"2026-06-13T22:55:59.112653Z","end":"2026-06-13T22:55:59.229665Z","steps":["trace[359014392] 'process raft request' (duration: 116.909589ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:55:59.803354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.825181ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-13T22:55:59.803483Z","caller":"traceutil/trace.go:171","msg":"trace[1827613102] linearizableReadLoop","detail":"{readStateIndex:2533; appliedIndex:2532; }","duration":"441.330658ms","start":"2026-06-13T22:55:59.362131Z","end":"2026-06-13T22:55:59.803461Z","steps":["trace[1827613102] 'read index received' (duration: 28.481µs)","trace[1827613102] 'applied index is now lower than readState.Index' (duration: 441.296987ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:55:59.803599Z","caller":"traceutil/trace.go:171","msg":"trace[1009702846] transaction","detail":"{read_only:false; response_revision:2426; number_of_response:1; }","duration":"539.632098ms","start":"2026-06-13T22:55:59.263949Z","end":"2026-06-13T22:55:59.803581Z","steps":["trace[1009702846] 'process raft request' (duration: 91.453936ms)","trace[1009702846] 'compare' (duration: 447.544352ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:55:59.803681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:55:59.263892Z","time spent":"539.742551ms","remote":"127.0.0.1:46488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-13T22:55:59.803883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.323706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-06-13T22:55:59.803977Z","caller":"traceutil/trace.go:171","msg":"trace[231439066] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2426; }","duration":"144.460631ms","start":"2026-06-13T22:55:59.659494Z","end":"2026-06-13T22:55:59.803955Z","steps":["trace[231439066] 'agreement among raft nodes before linearized reading' (duration: 144.197582ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:55:59.803999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.838694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-06-13T22:55:59.804066Z","caller":"traceutil/trace.go:171","msg":"trace[1844756980] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2426; }","duration":"441.955218ms","start":"2026-06-13T22:55:59.362091Z","end":"2026-06-13T22:55:59.804046Z","steps":["trace[1844756980] 'agreement among raft nodes before linearized reading' (duration: 441.740681ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:55:59.804125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:55:59.362073Z","time spent":"442.039311ms","remote":"127.0.0.1:46488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":548,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-06-13T22:56:00.082703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.106104ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-13T22:56:00.082913Z","caller":"traceutil/trace.go:171","msg":"trace[276115389] transaction","detail":"{read_only:false; response_revision:2428; number_of_response:1; }","duration":"274.953045ms","start":"2026-06-13T22:55:59.807942Z","end":"2026-06-13T22:56:00.082895Z","steps":["trace[276115389] 'process raft request' (duration: 274.846671ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:56:00.08289Z","caller":"traceutil/trace.go:171","msg":"trace[1315921491] transaction","detail":"{read_only:false; response_revision:2427; number_of_response:1; }","duration":"275.082548ms","start":"2026-06-13T22:55:59.807774Z","end":"2026-06-13T22:56:00.082856Z","steps":["trace[1315921491] 'process raft request' (duration: 118.745817ms)","trace[1315921491] 'compare' (duration: 155.97551ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:56:00.083048Z","caller":"traceutil/trace.go:171","msg":"trace[141529108] transaction","detail":"{read_only:false; response_revision:2429; number_of_response:1; }","duration":"274.894212ms","start":"2026-06-13T22:55:59.808145Z","end":"2026-06-13T22:56:00.083039Z","steps":["trace[141529108] 'process raft request' (duration: 274.696476ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:56:00.083312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.040871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-06-13T22:56:00.083379Z","caller":"traceutil/trace.go:171","msg":"trace[856282118] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2429; }","duration":"163.163074ms","start":"2026-06-13T22:55:59.920204Z","end":"2026-06-13T22:56:00.083367Z","steps":["trace[856282118] 'agreement among raft nodes before linearized reading' (duration: 162.944898ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:56:00.083602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.33905ms","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-06-13T22:56:00.083709Z","caller":"traceutil/trace.go:171","msg":"trace[1057488232] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2429; }","duration":"163.448654ms","start":"2026-06-13T22:55:59.920222Z","end":"2026-06-13T22:56:00.083671Z","steps":["trace[1057488232] 'agreement among raft nodes before linearized reading' (duration: 162.854465ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:56:00.083111Z","caller":"traceutil/trace.go:171","msg":"trace[266689692] linearizableReadLoop","detail":"{readStateIndex:2536; appliedIndex:2533; }","duration":"162.810594ms","start":"2026-06-13T22:55:59.920248Z","end":"2026-06-13T22:56:00.083058Z","steps":["trace[266689692] 'read index received' (duration: 6.342758ms)","trace[266689692] 'applied index is now lower than readState.Index' (duration: 156.465106ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:56:00.084076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.786614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-06-13T22:56:00.084119Z","caller":"traceutil/trace.go:171","msg":"trace[1346899400] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2429; }","duration":"163.844406ms","start":"2026-06-13T22:55:59.920264Z","end":"2026-06-13T22:56:00.084109Z","steps":["trace[1346899400] 'agreement among raft nodes before linearized reading' (duration: 163.729212ms)"],"step_count":1}