{"level":"warn","ts":"2026-02-04T01:48:37.171949Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-04T01:48:37.172054Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.57: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.57:2380","--initial-cluster=instance=https://199.204.45.57:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.57:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.57: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-02-04T01:48:37.172344Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-04T01:48:37.172364Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.57:2380"]} {"level":"info","ts":"2026-02-04T01:48:37.172412Z","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-02-04T01:48:37.173136Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"]} {"level":"info","ts":"2026-02-04T01:48:37.173274Z","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":2,"max-cpu-available":2,"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.57:2380"],"listen-peer-urls":["https://199.204.45.57:2380"],"advertise-client-urls":["https://199.204.45.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.57: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-02-04T01:48:37.379619Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"202.447284ms"} {"level":"info","ts":"2026-02-04T01:48:37.389967Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b6478f0ac5aeb8e1","cluster-id":"6c58cff80d4a5b62"} {"level":"info","ts":"2026-02-04T01:48:37.39004Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=()"} {"level":"info","ts":"2026-02-04T01:48:37.39006Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became follower at term 0"} {"level":"info","ts":"2026-02-04T01:48:37.390071Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b6478f0ac5aeb8e1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-04T01:48:37.390078Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became follower at term 1"} {"level":"info","ts":"2026-02-04T01:48:37.390105Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=(13134624114678348001)"} {"level":"warn","ts":"2026-02-04T01:48:37.409024Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-04T01:48:37.426888Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-04T01:48:37.45705Z","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-02-04T01:48:37.518688Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b6478f0ac5aeb8e1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-04T01:48:37.518814Z","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-02-04T01:48:37.525017Z","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-02-04T01:48:37.525027Z","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-02-04T01:48:37.527221Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b6478f0ac5aeb8e1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-04T01:48:37.52751Z","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-02-04T01:48:37.527596Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.57:2380"} {"level":"info","ts":"2026-02-04T01:48:37.527606Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.57:2380"} {"level":"info","ts":"2026-02-04T01:48:37.528754Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-04T01:48:37.528822Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b6478f0ac5aeb8e1","initial-advertise-peer-urls":["https://199.204.45.57:2380"],"listen-peer-urls":["https://199.204.45.57:2380"],"advertise-client-urls":["https://199.204.45.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-04T01:48:37.533136Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=(13134624114678348001)"} {"level":"info","ts":"2026-02-04T01:48:37.533221Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6c58cff80d4a5b62","local-member-id":"b6478f0ac5aeb8e1","added-peer-id":"b6478f0ac5aeb8e1","added-peer-peer-urls":["https://199.204.45.57:2380"]} {"level":"info","ts":"2026-02-04T01:48:37.99194Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 is starting a new election at term 1"} {"level":"info","ts":"2026-02-04T01:48:37.991976Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-04T01:48:37.991996Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 received MsgPreVoteResp from b6478f0ac5aeb8e1 at term 1"} {"level":"info","ts":"2026-02-04T01:48:37.992007Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became candidate at term 2"} {"level":"info","ts":"2026-02-04T01:48:37.992012Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 received MsgVoteResp from b6478f0ac5aeb8e1 at term 2"} {"level":"info","ts":"2026-02-04T01:48:37.992025Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became leader at term 2"} {"level":"info","ts":"2026-02-04T01:48:37.992032Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b6478f0ac5aeb8e1 elected leader b6478f0ac5aeb8e1 at term 2"} {"level":"info","ts":"2026-02-04T01:48:37.992914Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-04T01:48:37.993058Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b6478f0ac5aeb8e1","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.57:2379]}","request-path":"/0/members/b6478f0ac5aeb8e1/attributes","cluster-id":"6c58cff80d4a5b62","publish-timeout":"7s"} {"level":"info","ts":"2026-02-04T01:48:37.993092Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-04T01:48:37.99351Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-04T01:48:38.00108Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-04T01:48:38.001242Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-04T01:48:38.001255Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-04T01:48:38.020902Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.57:2379"} {"level":"info","ts":"2026-02-04T01:48:38.04591Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6c58cff80d4a5b62","local-member-id":"b6478f0ac5aeb8e1","cluster-version":"3.5"} {"level":"info","ts":"2026-02-04T01:48:38.045976Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-04T01:48:38.045996Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-02-04T01:48:44.426943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.082723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-04T01:48:44.427007Z","caller":"traceutil/trace.go:171","msg":"trace[1282152824] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:316; }","duration":"108.187863ms","start":"2026-02-04T01:48:44.318805Z","end":"2026-02-04T01:48:44.426993Z","steps":["trace[1282152824] 'range keys from in-memory index tree' (duration: 107.980383ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:45.131068Z","caller":"traceutil/trace.go:171","msg":"trace[139138512] transaction","detail":"{read_only:false; response_revision:317; number_of_response:1; }","duration":"701.51589ms","start":"2026-02-04T01:48:44.429537Z","end":"2026-02-04T01:48:45.131053Z","steps":["trace[139138512] 'process raft request' (duration: 701.42234ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:45.13138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:48:44.429521Z","time spent":"701.60602ms","remote":"127.0.0.1:55062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":194,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-04T01:48:45.524102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.438818ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:48:45.524195Z","caller":"traceutil/trace.go:171","msg":"trace[1104399634] linearizableReadLoop","detail":"{readStateIndex:334; appliedIndex:333; }","duration":"237.065653ms","start":"2026-02-04T01:48:45.287113Z","end":"2026-02-04T01:48:45.524179Z","steps":["trace[1104399634] 'read index received' (duration: 29.09µs)","trace[1104399634] 'applied index is now lower than readState.Index' (duration: 237.035483ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:48:45.525184Z","caller":"traceutil/trace.go:171","msg":"trace[335966595] transaction","detail":"{read_only:false; response_revision:318; number_of_response:1; }","duration":"385.602737ms","start":"2026-02-04T01:48:45.139562Z","end":"2026-02-04T01:48:45.525165Z","steps":["trace[335966595] 'process raft request' (duration: 47.930816ms)","trace[335966595] 'compare' (duration: 336.316507ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:48:45.525256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:48:45.139546Z","time spent":"385.681327ms","remote":"127.0.0.1:55062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":186,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-04T01:48:45.525437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.313847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-04T01:48:45.525465Z","caller":"traceutil/trace.go:171","msg":"trace[23710057] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:318; }","duration":"238.374138ms","start":"2026-02-04T01:48:45.287082Z","end":"2026-02-04T01:48:45.525456Z","steps":["trace[23710057] 'agreement among raft nodes before linearized reading' (duration: 238.271577ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:45.525656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.685497ms","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-02-04T01:48:45.525719Z","caller":"traceutil/trace.go:171","msg":"trace[1089361025] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:318; }","duration":"110.785517ms","start":"2026-02-04T01:48:45.414917Z","end":"2026-02-04T01:48:45.525702Z","steps":["trace[1089361025] 'agreement among raft nodes before linearized reading' (duration: 110.664647ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:48.89522Z","caller":"traceutil/trace.go:171","msg":"trace[610328688] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"262.239266ms","start":"2026-02-04T01:48:48.632966Z","end":"2026-02-04T01:48:48.895206Z","steps":["trace[610328688] 'process raft request' (duration: 262.219576ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:48.895627Z","caller":"traceutil/trace.go:171","msg":"trace[1435288615] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"267.944042ms","start":"2026-02-04T01:48:48.627677Z","end":"2026-02-04T01:48:48.895621Z","steps":["trace[1435288615] 'process raft request' (duration: 216.817369ms)","trace[1435288615] 'compare' (duration: 50.570251ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:48:48.895692Z","caller":"traceutil/trace.go:171","msg":"trace[162033253] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"267.653941ms","start":"2026-02-04T01:48:48.628034Z","end":"2026-02-04T01:48:48.895688Z","steps":["trace[162033253] 'process raft request' (duration: 267.10169ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:48.895784Z","caller":"traceutil/trace.go:171","msg":"trace[1641704297] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"262.865827ms","start":"2026-02-04T01:48:48.632914Z","end":"2026-02-04T01:48:48.89578Z","steps":["trace[1641704297] 'process raft request' (duration: 262.250586ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:49.083685Z","caller":"traceutil/trace.go:171","msg":"trace[1779840844] linearizableReadLoop","detail":"{readStateIndex:404; appliedIndex:403; }","duration":"133.301617ms","start":"2026-02-04T01:48:48.950356Z","end":"2026-02-04T01:48:49.083658Z","steps":["trace[1779840844] 'read index received' (duration: 127.993872ms)","trace[1779840844] 'applied index is now lower than readState.Index' (duration: 5.307155ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:48:49.083782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.411768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-5dd5756b68\" ","response":"range_response_count:1 size:3797"} {"level":"info","ts":"2026-02-04T01:48:49.083805Z","caller":"traceutil/trace.go:171","msg":"trace[2020300720] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-5dd5756b68; range_end:; response_count:1; response_revision:388; }","duration":"133.464338ms","start":"2026-02-04T01:48:48.950333Z","end":"2026-02-04T01:48:49.083798Z","steps":["trace[2020300720] 'agreement among raft nodes before linearized reading' (duration: 133.391848ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:49.084018Z","caller":"traceutil/trace.go:171","msg":"trace[723831643] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"134.845332ms","start":"2026-02-04T01:48:48.949165Z","end":"2026-02-04T01:48:49.08401Z","steps":["trace[723831643] 'process raft request' (duration: 129.225426ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:49.84779Z","caller":"traceutil/trace.go:171","msg":"trace[1460597755] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"203.765867ms","start":"2026-02-04T01:48:49.644009Z","end":"2026-02-04T01:48:49.847775Z","steps":["trace[1460597755] 'process raft request' (duration: 203.204495ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:49.848645Z","caller":"traceutil/trace.go:171","msg":"trace[1382387633] linearizableReadLoop","detail":"{readStateIndex:408; appliedIndex:407; }","duration":"192.914534ms","start":"2026-02-04T01:48:49.654681Z","end":"2026-02-04T01:48:49.847596Z","steps":["trace[1382387633] 'read index received' (duration: 192.398693ms)","trace[1382387633] 'applied index is now lower than readState.Index' (duration: 514.521µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:48:49.848773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.067957ms","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-02-04T01:48:49.848794Z","caller":"traceutil/trace.go:171","msg":"trace[240589425] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:392; }","duration":"194.135317ms","start":"2026-02-04T01:48:49.654651Z","end":"2026-02-04T01:48:49.848786Z","steps":["trace[240589425] 'agreement among raft nodes before linearized reading' (duration: 194.050947ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:50.271273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.882177ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-02-04T01:48:50.939483Z","caller":"traceutil/trace.go:171","msg":"trace[1228342234] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"201.921366ms","start":"2026-02-04T01:48:50.737535Z","end":"2026-02-04T01:48:50.939457Z","steps":["trace[1228342234] 'process raft request' (duration: 201.729805ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:51.996094Z","caller":"traceutil/trace.go:171","msg":"trace[1407640029] linearizableReadLoop","detail":"{readStateIndex:421; appliedIndex:420; }","duration":"128.224998ms","start":"2026-02-04T01:48:51.867849Z","end":"2026-02-04T01:48:51.996074Z","steps":["trace[1407640029] 'read index received' (duration: 128.039707ms)","trace[1407640029] 'applied index is now lower than readState.Index' (duration: 184.411µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:48:51.996248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.375988ms","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-02-04T01:48:51.996289Z","caller":"traceutil/trace.go:171","msg":"trace[2115125450] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:403; }","duration":"128.444628ms","start":"2026-02-04T01:48:51.867836Z","end":"2026-02-04T01:48:51.99628Z","steps":["trace[2115125450] 'agreement among raft nodes before linearized reading' (duration: 128.322898ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:51.996428Z","caller":"traceutil/trace.go:171","msg":"trace[894500957] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"141.331995ms","start":"2026-02-04T01:48:51.855088Z","end":"2026-02-04T01:48:51.99642Z","steps":["trace[894500957] 'process raft request' (duration: 140.832534ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:52.209194Z","caller":"traceutil/trace.go:171","msg":"trace[24912093] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"207.367846ms","start":"2026-02-04T01:48:52.001807Z","end":"2026-02-04T01:48:52.209175Z","steps":["trace[24912093] 'process raft request' (duration: 207.264796ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:53.343369Z","caller":"traceutil/trace.go:171","msg":"trace[679274153] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"659.015955ms","start":"2026-02-04T01:48:52.684338Z","end":"2026-02-04T01:48:53.343354Z","steps":["trace[679274153] 'process raft request' (duration: 658.935944ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:53.34373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:48:52.684321Z","time spent":"659.091325ms","remote":"127.0.0.1:55056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4579,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:48:53.54073Z","caller":"traceutil/trace.go:171","msg":"trace[1526090101] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"580.471572ms","start":"2026-02-04T01:48:52.959676Z","end":"2026-02-04T01:48:53.540147Z","steps":["trace[1526090101] 'process raft request' (duration: 558.334767ms)","trace[1526090101] 'compare' (duration: 21.241792ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:48:53.541321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:48:52.959653Z","time spent":"581.244663ms","remote":"127.0.0.1:55122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:48:55.813501Z","caller":"traceutil/trace.go:171","msg":"trace[1902483104] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"131.017668ms","start":"2026-02-04T01:48:55.682272Z","end":"2026-02-04T01:48:55.81329Z","steps":["trace[1902483104] 'process raft request' (duration: 110.306698ms)","trace[1902483104] 'compare' (duration: 20.5699ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:48:56.187608Z","caller":"traceutil/trace.go:171","msg":"trace[1381913124] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"176.797297ms","start":"2026-02-04T01:48:56.010803Z","end":"2026-02-04T01:48:56.1876Z","steps":["trace[1381913124] 'process raft request' (duration: 176.471136ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:56.187378Z","caller":"traceutil/trace.go:171","msg":"trace[1436370021] linearizableReadLoop","detail":"{readStateIndex:436; appliedIndex:435; }","duration":"173.116228ms","start":"2026-02-04T01:48:56.014245Z","end":"2026-02-04T01:48:56.187362Z","steps":["trace[1436370021] 'read index received' (duration: 172.999757ms)","trace[1436370021] 'applied index is now lower than readState.Index' (duration: 115.901µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:48:56.187847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.576939ms","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-02-04T01:48:56.18787Z","caller":"traceutil/trace.go:171","msg":"trace[503551253] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:418; }","duration":"173.649709ms","start":"2026-02-04T01:48:56.014214Z","end":"2026-02-04T01:48:56.187864Z","steps":["trace[503551253] 'agreement among raft nodes before linearized reading' (duration: 173.587899ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:49:06.437145Z","caller":"traceutil/trace.go:171","msg":"trace[822918303] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"133.970019ms","start":"2026-02-04T01:49:06.30315Z","end":"2026-02-04T01:49:06.43712Z","steps":["trace[822918303] 'process raft request' (duration: 133.747918ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:49:07.416717Z","caller":"traceutil/trace.go:171","msg":"trace[1969299991] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"187.540554ms","start":"2026-02-04T01:49:07.229154Z","end":"2026-02-04T01:49:07.416695Z","steps":["trace[1969299991] 'process raft request' (duration: 187.201263ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:49:07.772251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.057926ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:49:07.772351Z","caller":"traceutil/trace.go:171","msg":"trace[1264950568] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"215.597932ms","start":"2026-02-04T01:49:07.556735Z","end":"2026-02-04T01:49:07.772333Z","steps":["trace[1264950568] 'process raft request' (duration: 53.402177ms)","trace[1264950568] 'compare' (duration: 161.933114ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:49:08.080821Z","caller":"traceutil/trace.go:171","msg":"trace[715666570] linearizableReadLoop","detail":"{readStateIndex:509; appliedIndex:507; }","duration":"115.652607ms","start":"2026-02-04T01:49:07.965013Z","end":"2026-02-04T01:49:08.080666Z","steps":["trace[715666570] 'read index received' (duration: 21.889641ms)","trace[715666570] 'applied index is now lower than readState.Index' (duration: 93.761796ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:49:08.081404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.387168ms","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-02-04T01:49:08.081479Z","caller":"traceutil/trace.go:171","msg":"trace[1101053608] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:488; }","duration":"116.442979ms","start":"2026-02-04T01:49:07.964991Z","end":"2026-02-04T01:49:08.081434Z","steps":["trace[1101053608] 'agreement among raft nodes before linearized reading' (duration: 115.910077ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:49:08.086484Z","caller":"traceutil/trace.go:171","msg":"trace[158335010] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"118.684335ms","start":"2026-02-04T01:49:07.964441Z","end":"2026-02-04T01:49:08.083125Z","steps":["trace[158335010] 'process raft request' (duration: 108.831037ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:49:08.112324Z","caller":"traceutil/trace.go:171","msg":"trace[1640306942] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"113.809511ms","start":"2026-02-04T01:49:07.998491Z","end":"2026-02-04T01:49:08.1123Z","steps":["trace[1640306942] 'process raft request' (duration: 113.35354ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:49:08.276332Z","caller":"traceutil/trace.go:171","msg":"trace[1948161878] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"146.971533ms","start":"2026-02-04T01:49:08.129343Z","end":"2026-02-04T01:49:08.276315Z","steps":["trace[1948161878] 'process raft request' (duration: 85.604535ms)","trace[1948161878] 'compare' (duration: 61.243298ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:49:08.276844Z","caller":"traceutil/trace.go:171","msg":"trace[482263013] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"147.432993ms","start":"2026-02-04T01:49:08.129403Z","end":"2026-02-04T01:49:08.276836Z","steps":["trace[482263013] 'process raft request' (duration: 147.249503ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:49:08.277964Z","caller":"traceutil/trace.go:171","msg":"trace[484667702] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"148.441406ms","start":"2026-02-04T01:49:08.129514Z","end":"2026-02-04T01:49:08.277955Z","steps":["trace[484667702] 'process raft request' (duration: 147.203803ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:49:08.278195Z","caller":"traceutil/trace.go:171","msg":"trace[472524672] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"125.788014ms","start":"2026-02-04T01:49:08.1524Z","end":"2026-02-04T01:49:08.278188Z","steps":["trace[472524672] 'process raft request' (duration: 125.707634ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:49:08.281584Z","caller":"traceutil/trace.go:171","msg":"trace[1314488981] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"123.194317ms","start":"2026-02-04T01:49:08.158324Z","end":"2026-02-04T01:49:08.281519Z","steps":["trace[1314488981] 'process raft request' (duration: 119.822868ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:49:08.289323Z","caller":"traceutil/trace.go:171","msg":"trace[960192255] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"130.630186ms","start":"2026-02-04T01:49:08.158622Z","end":"2026-02-04T01:49:08.289252Z","steps":["trace[960192255] 'process raft request' (duration: 121.02687ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:49:08.444138Z","caller":"traceutil/trace.go:171","msg":"trace[1726830149] linearizableReadLoop","detail":"{readStateIndex:522; appliedIndex:520; }","duration":"150.828041ms","start":"2026-02-04T01:49:08.293259Z","end":"2026-02-04T01:49:08.444087Z","steps":["trace[1726830149] 'read index received' (duration: 21.169897ms)","trace[1726830149] 'applied index is now lower than readState.Index' (duration: 129.656984ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:49:08.444339Z","caller":"traceutil/trace.go:171","msg":"trace[1114582195] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"151.536945ms","start":"2026-02-04T01:49:08.292788Z","end":"2026-02-04T01:49:08.444325Z","steps":["trace[1114582195] 'process raft request' (duration: 151.104533ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:49:08.444464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.193773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/cilium-operator\" ","response":"range_response_count:1 size:4313"} {"level":"info","ts":"2026-02-04T01:49:08.444504Z","caller":"traceutil/trace.go:171","msg":"trace[712960722] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:502; }","duration":"153.84069ms","start":"2026-02-04T01:49:08.290651Z","end":"2026-02-04T01:49:08.444492Z","steps":["trace[712960722] 'agreement among raft nodes before linearized reading' (duration: 153.7651ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:49:08.444649Z","caller":"traceutil/trace.go:171","msg":"trace[1367197368] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"148.460586ms","start":"2026-02-04T01:49:08.29618Z","end":"2026-02-04T01:49:08.44464Z","steps":["trace[1367197368] 'process raft request' (duration: 147.816403ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:49:08.444792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.704717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-kl6qg\" ","response":"range_response_count:1 size:14921"} {"level":"info","ts":"2026-02-04T01:49:08.444868Z","caller":"traceutil/trace.go:171","msg":"trace[404675363] range","detail":"{range_begin:/registry/pods/kube-system/cilium-kl6qg; range_end:; response_count:1; response_revision:502; }","duration":"148.844987ms","start":"2026-02-04T01:49:08.296008Z","end":"2026-02-04T01:49:08.444853Z","steps":["trace[404675363] 'agreement among raft nodes before linearized reading' (duration: 148.676777ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:49:08.444343Z","caller":"traceutil/trace.go:171","msg":"trace[1751628794] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"151.608156ms","start":"2026-02-04T01:49:08.292712Z","end":"2026-02-04T01:49:08.44432Z","steps":["trace[1751628794] 'process raft request' (duration: 102.19175ms)","trace[1751628794] 'compare' (duration: 48.755533ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:49:08.63232Z","caller":"traceutil/trace.go:171","msg":"trace[296517965] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"155.920864ms","start":"2026-02-04T01:49:08.476376Z","end":"2026-02-04T01:49:08.632297Z","steps":["trace[296517965] 'process raft request' (duration: 89.316355ms)","trace[296517965] 'compare' (duration: 66.432327ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:49:08.632377Z","caller":"traceutil/trace.go:171","msg":"trace[1571008883] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"135.620139ms","start":"2026-02-04T01:49:08.49673Z","end":"2026-02-04T01:49:08.63235Z","steps":["trace[1571008883] 'process raft request' (duration: 135.582929ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:49:08.632554Z","caller":"traceutil/trace.go:171","msg":"trace[1143977194] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"146.215818ms","start":"2026-02-04T01:49:08.486332Z","end":"2026-02-04T01:49:08.632548Z","steps":["trace[1143977194] 'process raft request' (duration: 145.941705ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:49:08.632576Z","caller":"traceutil/trace.go:171","msg":"trace[403159921] linearizableReadLoop","detail":"{readStateIndex:526; appliedIndex:523; }","duration":"156.070796ms","start":"2026-02-04T01:49:08.476497Z","end":"2026-02-04T01:49:08.632568Z","steps":["trace[403159921] 'read index received' (duration: 19.736524ms)","trace[403159921] 'applied index is now lower than readState.Index' (duration: 136.333502ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:49:08.632761Z","caller":"traceutil/trace.go:171","msg":"trace[57733726] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"156.336808ms","start":"2026-02-04T01:49:08.476419Z","end":"2026-02-04T01:49:08.632756Z","steps":["trace[57733726] 'process raft request' (duration: 155.813273ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:49:08.632761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.251178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:520"} {"level":"info","ts":"2026-02-04T01:49:08.632785Z","caller":"traceutil/trace.go:171","msg":"trace[693842922] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:507; }","duration":"156.306068ms","start":"2026-02-04T01:49:08.476472Z","end":"2026-02-04T01:49:08.632778Z","steps":["trace[693842922] 'agreement among raft nodes before linearized reading' (duration: 156.253857ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:49:08.632904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.235234ms","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-02-04T01:49:08.632928Z","caller":"traceutil/trace.go:171","msg":"trace[672265791] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:507; }","duration":"136.280624ms","start":"2026-02-04T01:49:08.49664Z","end":"2026-02-04T01:49:08.63292Z","steps":["trace[672265791] 'agreement among raft nodes before linearized reading' (duration: 136.239534ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:49:08.809516Z","caller":"traceutil/trace.go:171","msg":"trace[1122370015] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"144.239371ms","start":"2026-02-04T01:49:08.66526Z","end":"2026-02-04T01:49:08.8095Z","steps":["trace[1122370015] 'process raft request' (duration: 125.9597ms)","trace[1122370015] 'compare' (duration: 17.317142ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:49:08.810344Z","caller":"traceutil/trace.go:171","msg":"trace[1143630788] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"141.11487ms","start":"2026-02-04T01:49:08.669219Z","end":"2026-02-04T01:49:08.810334Z","steps":["trace[1143630788] 'process raft request' (duration: 139.887159ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:49:12.059646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.938114ms","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-02-04T01:49:12.059695Z","caller":"traceutil/trace.go:171","msg":"trace[1893241316] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:518; }","duration":"102.029289ms","start":"2026-02-04T01:49:11.957654Z","end":"2026-02-04T01:49:12.059683Z","steps":["trace[1893241316] 'range keys from in-memory index tree' (duration: 101.819097ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:49:12.059947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.882301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:49:12.059961Z","caller":"traceutil/trace.go:171","msg":"trace[1717351747] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:518; }","duration":"524.922714ms","start":"2026-02-04T01:49:11.535034Z","end":"2026-02-04T01:49:12.059956Z","steps":["trace[1717351747] 'range keys from in-memory index tree' (duration: 524.841969ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:49:12.059974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:49:11.535019Z","time spent":"524.950646ms","remote":"127.0.0.1:54856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T01:49:12.063674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"961.162106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.57\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-04T01:49:12.063747Z","caller":"traceutil/trace.go:171","msg":"trace[3217021] range","detail":"{range_begin:/registry/masterleases/199.204.45.57; range_end:; response_count:1; response_revision:518; }","duration":"961.259762ms","start":"2026-02-04T01:49:11.102469Z","end":"2026-02-04T01:49:12.063729Z","steps":["trace[3217021] 'range keys from in-memory index tree' (duration: 957.398003ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:49:12.067975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:49:11.102457Z","time spent":"965.441829ms","remote":"127.0.0.1:54908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.57\" "} {"level":"info","ts":"2026-02-04T01:49:13.257579Z","caller":"traceutil/trace.go:171","msg":"trace[638966890] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"107.001103ms","start":"2026-02-04T01:49:13.150555Z","end":"2026-02-04T01:49:13.257556Z","steps":["trace[638966890] 'process raft request' (duration: 106.94464ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:49:13.257697Z","caller":"traceutil/trace.go:171","msg":"trace[1671917888] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"108.718322ms","start":"2026-02-04T01:49:13.148949Z","end":"2026-02-04T01:49:13.257667Z","steps":["trace[1671917888] 'process raft request' (duration: 107.456448ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:49:13.602629Z","caller":"traceutil/trace.go:171","msg":"trace[207664409] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"119.228874ms","start":"2026-02-04T01:49:13.483388Z","end":"2026-02-04T01:49:13.602617Z","steps":["trace[207664409] 'process raft request' (duration: 119.044814ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:49:13.872326Z","caller":"traceutil/trace.go:171","msg":"trace[1766529367] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"152.728544ms","start":"2026-02-04T01:49:13.719581Z","end":"2026-02-04T01:49:13.872309Z","steps":["trace[1766529367] 'process raft request' (duration: 152.054375ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:49:13.872525Z","caller":"traceutil/trace.go:171","msg":"trace[2086750303] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"149.549849ms","start":"2026-02-04T01:49:13.722969Z","end":"2026-02-04T01:49:13.872519Z","steps":["trace[2086750303] 'process raft request' (duration: 149.38825ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:49:13.872496Z","caller":"traceutil/trace.go:171","msg":"trace[2008142853] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"150.455983ms","start":"2026-02-04T01:49:13.722019Z","end":"2026-02-04T01:49:13.872475Z","steps":["trace[2008142853] 'process raft request' (duration: 150.24237ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:49:13.872683Z","caller":"traceutil/trace.go:171","msg":"trace[475493691] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"150.616952ms","start":"2026-02-04T01:49:13.722059Z","end":"2026-02-04T01:49:13.872676Z","steps":["trace[475493691] 'process raft request' (duration: 150.242171ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:49:15.315572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.165161ms","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-02-04T01:49:15.315617Z","caller":"traceutil/trace.go:171","msg":"trace[337152970] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:548; }","duration":"543.251346ms","start":"2026-02-04T01:49:14.772354Z","end":"2026-02-04T01:49:15.315605Z","steps":["trace[337152970] 'range keys from in-memory index tree' (duration: 543.043144ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:49:15.315637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:49:14.772335Z","time spent":"543.298339ms","remote":"127.0.0.1:55122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-04T01:49:15.315829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.177517ms","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-02-04T01:49:15.315842Z","caller":"traceutil/trace.go:171","msg":"trace[2112192270] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:548; }","duration":"430.213449ms","start":"2026-02-04T01:49:14.885624Z","end":"2026-02-04T01:49:15.315838Z","steps":["trace[2112192270] 'range keys from in-memory index tree' (duration: 430.120094ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:49:15.315854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:49:14.885611Z","time spent":"430.2394ms","remote":"127.0.0.1:55122","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-02-04T01:49:20.033756Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322100861209580222,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T01:49:20.533986Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322100861209580222,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T01:49:21.034337Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322100861209580222,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T01:49:21.533945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000591528s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-04T01:49:21.534049Z","caller":"traceutil/trace.go:171","msg":"trace[963267891] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000712914s","start":"2026-02-04T01:49:19.533302Z","end":"2026-02-04T01:49:21.534015Z","steps":["trace[963267891] 'agreement among raft nodes before linearized reading' (duration: 2.000610269s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:49:21.534111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:49:19.533288Z","time spent":"2.00080972s","remote":"127.0.0.1:54870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T01:49:21.534444Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322100861209580222,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T01:49:22.034855Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322100861209580222,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T01:49:22.535731Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322100861209580222,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T01:49:22.754843Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.408268818s","expected-duration":"1s"} {"level":"info","ts":"2026-02-04T01:49:22.755122Z","caller":"traceutil/trace.go:171","msg":"trace[2105022500] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"3.408603705s","start":"2026-02-04T01:49:19.346504Z","end":"2026-02-04T01:49:22.755108Z","steps":["trace[2105022500] 'process raft request' (duration: 3.40850664s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:49:22.755219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:49:19.346496Z","time spent":"3.408661497s","remote":"127.0.0.1:55122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:49:23.541077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000136591s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-04T01:49:23.541128Z","caller":"traceutil/trace.go:171","msg":"trace[1761706717] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000230795s","start":"2026-02-04T01:49:21.540885Z","end":"2026-02-04T01:49:23.541116Z","steps":["trace[1761706717] 'agreement among raft nodes before linearized reading' (duration: 2.000162801s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:49:23.541158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:49:21.540871Z","time spent":"2.000281658s","remote":"127.0.0.1:54856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/04 01:49:23 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-04T01:49:24.683832Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.928885389s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-04T01:49:25.075175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.15124941s","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-02-04T01:49:25.075265Z","caller":"traceutil/trace.go:171","msg":"trace[1215214471] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:560; }","duration":"1.151352085s","start":"2026-02-04T01:49:23.923899Z","end":"2026-02-04T01:49:25.075252Z","steps":["trace[1215214471] 'range keys from in-memory index tree' (duration: 1.151236849s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:49:25.075966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.882098ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:49:25.076075Z","caller":"traceutil/trace.go:171","msg":"trace[2035446255] linearizableReadLoop","detail":"{readStateIndex:586; appliedIndex:583; }","duration":"5.54273922s","start":"2026-02-04T01:49:19.533327Z","end":"2026-02-04T01:49:25.076066Z","steps":["trace[2035446255] 'read index received' (duration: 3.222175454s)","trace[2035446255] 'applied index is now lower than readState.Index' (duration: 2.320563006s)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:49:25.07622Z","caller":"traceutil/trace.go:171","msg":"trace[156790867] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"5.727657947s","start":"2026-02-04T01:49:19.348552Z","end":"2026-02-04T01:49:25.07621Z","steps":["trace[156790867] 'process raft request' (duration: 5.335483144s)","trace[156790867] 'compare' (duration: 391.679199ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:49:25.076261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:49:19.348546Z","time spent":"5.727693829s","remote":"127.0.0.1:55122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:49:25.076366Z","caller":"traceutil/trace.go:171","msg":"trace[283879346] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"4.336134426s","start":"2026-02-04T01:49:20.740225Z","end":"2026-02-04T01:49:25.07636Z","steps":["trace[283879346] 'process raft request' (duration: 4.335777299s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:49:25.0764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:49:20.74021Z","time spent":"4.336171108s","remote":"127.0.0.1:55122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:49:25.076476Z","caller":"traceutil/trace.go:171","msg":"trace[1336142170] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"3.537884818s","start":"2026-02-04T01:49:21.538585Z","end":"2026-02-04T01:49:25.07647Z","steps":["trace[1336142170] 'process raft request' (duration: 3.537451315s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:49:25.076517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:49:21.538562Z","time spent":"3.53794079s","remote":"127.0.0.1:54956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-04T01:49:25.118209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999434009s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-04T01:49:25.118261Z","caller":"traceutil/trace.go:171","msg":"trace[1998146063] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"4.999531044s","start":"2026-02-04T01:49:20.118716Z","end":"2026-02-04T01:49:25.118247Z","steps":["trace[1998146063] 'agreement among raft nodes before linearized reading' (duration: 4.99946324s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:49:25.118295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:49:20.118697Z","time spent":"4.999586807s","remote":"127.0.0.1:55122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} 2026/02/04 01:49:25 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-02-04T01:49:25.131779Z","caller":"traceutil/trace.go:171","msg":"trace[451357860] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"771.538019ms","start":"2026-02-04T01:49:24.360222Z","end":"2026-02-04T01:49:25.13176Z","steps":["trace[451357860] 'process raft request' (duration: 771.376791ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:49:25.131897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:49:24.360205Z","time spent":"771.623894ms","remote":"127.0.0.1:55122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:49:25.132149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.324792007s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-kl6qg\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-04T01:49:25.132186Z","caller":"traceutil/trace.go:171","msg":"trace[2016336351] range","detail":"{range_begin:/registry/pods/kube-system/cilium-kl6qg; range_end:; response_count:1; response_revision:564; }","duration":"5.32485414s","start":"2026-02-04T01:49:19.807323Z","end":"2026-02-04T01:49:25.132177Z","steps":["trace[2016336351] 'agreement among raft nodes before linearized reading' (duration: 5.324759905s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:49:25.132218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:49:19.807309Z","time spent":"5.324902723s","remote":"127.0.0.1:55056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16675,"request content":"key:\"/registry/pods/kube-system/cilium-kl6qg\" "} {"level":"warn","ts":"2026-02-04T01:49:25.132509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.065314ms","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-02-04T01:49:25.132543Z","caller":"traceutil/trace.go:171","msg":"trace[533439518] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:564; }","duration":"373.125557ms","start":"2026-02-04T01:49:24.759409Z","end":"2026-02-04T01:49:25.132534Z","steps":["trace[533439518] 'agreement among raft nodes before linearized reading' (duration: 373.027891ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:49:25.132569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:49:24.759395Z","time spent":"373.167819ms","remote":"127.0.0.1:55122","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-02-04T01:49:25.132674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.591304096s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:49:25.132697Z","caller":"traceutil/trace.go:171","msg":"trace[873617058] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:564; }","duration":"1.591345948s","start":"2026-02-04T01:49:23.541344Z","end":"2026-02-04T01:49:25.13269Z","steps":["trace[873617058] 'agreement among raft nodes before linearized reading' (duration: 1.591312617s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:49:25.132729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:49:23.541334Z","time spent":"1.59138732s","remote":"127.0.0.1:54870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T01:49:25.132859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.030037863s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.57\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-04T01:49:25.132889Z","caller":"traceutil/trace.go:171","msg":"trace[781030280] range","detail":"{range_begin:/registry/masterleases/199.204.45.57; range_end:; response_count:1; response_revision:564; }","duration":"4.030092216s","start":"2026-02-04T01:49:21.102789Z","end":"2026-02-04T01:49:25.132882Z","steps":["trace[781030280] 'agreement among raft nodes before linearized reading' (duration: 4.030044643s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:49:25.132919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:49:21.102776Z","time spent":"4.030137338s","remote":"127.0.0.1:54908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.57\" "} {"level":"warn","ts":"2026-02-04T01:49:25.133139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.788485949s","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-02-04T01:49:25.13316Z","caller":"traceutil/trace.go:171","msg":"trace[1621332616] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:564; }","duration":"4.788559812s","start":"2026-02-04T01:49:20.344595Z","end":"2026-02-04T01:49:25.133155Z","steps":["trace[1621332616] 'agreement among raft nodes before linearized reading' (duration: 4.78851915s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:49:25.133174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:49:20.344581Z","time spent":"4.788590594s","remote":"127.0.0.1:55122","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":"warn","ts":"2026-02-04T01:49:25.472318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.369363ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-02-04T01:49:25.47314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:49:25.141239Z","time spent":"331.897165ms","remote":"127.0.0.1:54908","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-04T01:49:27.052112Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.772134806s","expected-duration":"1s"} {"level":"info","ts":"2026-02-04T01:49:27.10828Z","caller":"traceutil/trace.go:171","msg":"trace[1376002032] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"1.962895258s","start":"2026-02-04T01:49:25.145367Z","end":"2026-02-04T01:49:27.108262Z","steps":["trace[1376002032] 'process raft request' (duration: 1.907167285s)","trace[1376002032] 'compare' (duration: 55.011769ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:49:27.108425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:49:25.145356Z","time spent":"1.962979152s","remote":"127.0.0.1:55122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:49:27.108576Z","caller":"traceutil/trace.go:171","msg":"trace[168958003] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"1.963161931s","start":"2026-02-04T01:49:25.145408Z","end":"2026-02-04T01:49:27.10857Z","steps":["trace[168958003] 'process raft request' (duration: 1.96272018s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:49:27.108618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:49:25.145405Z","time spent":"1.963193482s","remote":"127.0.0.1:55122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:49:27.110748Z","caller":"traceutil/trace.go:171","msg":"trace[1620396895] linearizableReadLoop","detail":"{readStateIndex:590; appliedIndex:587; }","duration":"1.965178929s","start":"2026-02-04T01:49:25.145503Z","end":"2026-02-04T01:49:27.110682Z","steps":["trace[1620396895] 'read index received' (duration: 134.508484ms)","trace[1620396895] 'applied index is now lower than readState.Index' (duration: 1.830668474s)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:49:27.111169Z","caller":"traceutil/trace.go:171","msg":"trace[1264365966] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"1.961685373s","start":"2026-02-04T01:49:25.149473Z","end":"2026-02-04T01:49:27.111158Z","steps":["trace[1264365966] 'process raft request' (duration: 1.958746871s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:49:27.111574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:49:25.149465Z","time spent":"1.961971087s","remote":"127.0.0.1:55056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:49:27.111803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.966292172s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.1890e804f4661145\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-02-04T01:49:27.111846Z","caller":"traceutil/trace.go:171","msg":"trace[432270605] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.1890e804f4661145; range_end:; response_count:1; response_revision:567; }","duration":"1.966347525s","start":"2026-02-04T01:49:25.145488Z","end":"2026-02-04T01:49:27.111835Z","steps":["trace[432270605] 'agreement among raft nodes before linearized reading' (duration: 1.966208398s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:49:27.11195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:49:25.145483Z","time spent":"1.9664579s","remote":"127.0.0.1:54956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":797,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.1890e804f4661145\" "} {"level":"info","ts":"2026-02-04T01:49:27.120843Z","caller":"traceutil/trace.go:171","msg":"trace[139350919] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"1.646756521s","start":"2026-02-04T01:49:25.474062Z","end":"2026-02-04T01:49:27.120818Z","steps":["trace[139350919] 'process raft request' (duration: 1.646552651s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:49:27.120875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.971866638s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-04T01:49:27.120964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:49:25.474018Z","time spent":"1.646876786s","remote":"127.0.0.1:54908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:49:27.121079Z","caller":"traceutil/trace.go:171","msg":"trace[1043206736] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:568; }","duration":"1.972018846s","start":"2026-02-04T01:49:25.148961Z","end":"2026-02-04T01:49:27.12098Z","steps":["trace[1043206736] 'agreement among raft nodes before linearized reading' (duration: 1.971776184s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:49:27.121139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:49:25.148954Z","time spent":"1.972164502s","remote":"127.0.0.1:54856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-04T01:49:28.113255Z","caller":"traceutil/trace.go:171","msg":"trace[163978910] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"285.129251ms","start":"2026-02-04T01:49:27.828086Z","end":"2026-02-04T01:49:28.113215Z","steps":["trace[163978910] 'process raft request' (duration: 284.853158ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:49:28.15925Z","caller":"traceutil/trace.go:171","msg":"trace[957957417] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"321.845233ms","start":"2026-02-04T01:49:27.837381Z","end":"2026-02-04T01:49:28.159226Z","steps":["trace[957957417] 'process raft request' (duration: 303.894603ms)","trace[957957417] 'compare' (duration: 17.690527ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:49:28.159383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:49:27.837368Z","time spent":"321.939868ms","remote":"127.0.0.1:55056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16897,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:49:29.979781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.369244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:49:29.97988Z","caller":"traceutil/trace.go:171","msg":"trace[2078459124] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:581; }","duration":"434.515101ms","start":"2026-02-04T01:49:29.545341Z","end":"2026-02-04T01:49:29.979856Z","steps":["trace[2078459124] 'range keys from in-memory index tree' (duration: 434.262589ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:49:29.979947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:49:29.545322Z","time spent":"434.615566ms","remote":"127.0.0.1:54856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-04T01:49:31.993058Z","caller":"traceutil/trace.go:171","msg":"trace[1617910860] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"123.949302ms","start":"2026-02-04T01:49:31.869077Z","end":"2026-02-04T01:49:31.993027Z","steps":["trace[1617910860] 'process raft request' (duration: 98.440027ms)","trace[1617910860] 'compare' (duration: 25.088776ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:49:33.675786Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322100861209580297,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-04T01:49:33.761159Z","caller":"traceutil/trace.go:171","msg":"trace[64560015] linearizableReadLoop","detail":"{readStateIndex:621; appliedIndex:620; }","duration":"586.466764ms","start":"2026-02-04T01:49:33.174667Z","end":"2026-02-04T01:49:33.761134Z","steps":["trace[64560015] 'read index received' (duration: 586.141049ms)","trace[64560015] 'applied index is now lower than readState.Index' (duration: 324.765µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:49:33.761387Z","caller":"traceutil/trace.go:171","msg":"trace[1160970834] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"878.605591ms","start":"2026-02-04T01:49:32.882772Z","end":"2026-02-04T01:49:33.761378Z","steps":["trace[1160970834] 'process raft request' (duration: 878.117568ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:49:33.761495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:49:32.882746Z","time spent":"878.669594ms","remote":"127.0.0.1:55056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17558,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:49:33.761686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.020718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-04T01:49:33.761715Z","caller":"traceutil/trace.go:171","msg":"trace[549468825] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:595; }","duration":"587.109913ms","start":"2026-02-04T01:49:33.174596Z","end":"2026-02-04T01:49:33.761706Z","steps":["trace[549468825] 'agreement among raft nodes before linearized reading' (duration: 587.0435ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:49:33.761734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:49:33.174565Z","time spent":"587.165315ms","remote":"127.0.0.1:55122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-04T01:49:33.761856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.765036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-04T01:49:33.761882Z","caller":"traceutil/trace.go:171","msg":"trace[1230566115] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:595; }","duration":"521.845769ms","start":"2026-02-04T01:49:33.24003Z","end":"2026-02-04T01:49:33.761876Z","steps":["trace[1230566115] 'agreement among raft nodes before linearized reading' (duration: 521.798937ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:49:33.761899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:49:33.240004Z","time spent":"521.891581ms","remote":"127.0.0.1:55122","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-02-04T01:49:33.762093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.003565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:49:33.762117Z","caller":"traceutil/trace.go:171","msg":"trace[1150861971] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:595; }","duration":"226.031557ms","start":"2026-02-04T01:49:33.53608Z","end":"2026-02-04T01:49:33.762112Z","steps":["trace[1150861971] 'agreement among raft nodes before linearized reading' (duration: 225.992385ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:49:33.762261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.548385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-04T01:49:33.762279Z","caller":"traceutil/trace.go:171","msg":"trace[1519163256] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:595; }","duration":"466.625999ms","start":"2026-02-04T01:49:33.295646Z","end":"2026-02-04T01:49:33.762273Z","steps":["trace[1519163256] 'agreement among raft nodes before linearized reading' (duration: 466.541435ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:49:33.762294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:49:33.295622Z","time spent":"466.668571ms","remote":"127.0.0.1:55122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-04T01:49:33.762376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.51312ms","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-02-04T01:49:33.762393Z","caller":"traceutil/trace.go:171","msg":"trace[339857101] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:595; }","duration":"520.550471ms","start":"2026-02-04T01:49:33.241837Z","end":"2026-02-04T01:49:33.762388Z","steps":["trace[339857101] 'agreement among raft nodes before linearized reading' (duration: 520.51981ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:49:33.762408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:49:33.241827Z","time spent":"520.576753ms","remote":"127.0.0.1:55122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-04T01:49:33.990667Z","caller":"traceutil/trace.go:171","msg":"trace[768118070] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"100.82678ms","start":"2026-02-04T01:49:33.889816Z","end":"2026-02-04T01:49:33.990643Z","steps":["trace[768118070] 'process raft request' (duration: 99.291371ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:49:35.347247Z","caller":"traceutil/trace.go:171","msg":"trace[218654010] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"144.924121ms","start":"2026-02-04T01:49:35.202296Z","end":"2026-02-04T01:49:35.34722Z","steps":["trace[218654010] 'process raft request' (duration: 143.529729ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:49:48.485539Z","caller":"traceutil/trace.go:171","msg":"trace[1994714604] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"101.030106ms","start":"2026-02-04T01:49:48.384492Z","end":"2026-02-04T01:49:48.485522Z","steps":["trace[1994714604] 'process raft request' (duration: 100.932542ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:49:56.66323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.984461ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-04T01:49:56.663728Z","caller":"traceutil/trace.go:171","msg":"trace[637938069] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"105.660504ms","start":"2026-02-04T01:49:56.558047Z","end":"2026-02-04T01:49:56.663708Z","steps":["trace[637938069] 'process raft request' (duration: 105.330313ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:50:08.436146Z","caller":"traceutil/trace.go:171","msg":"trace[621294649] linearizableReadLoop","detail":"{readStateIndex:865; appliedIndex:864; }","duration":"242.354039ms","start":"2026-02-04T01:50:08.19377Z","end":"2026-02-04T01:50:08.436124Z","steps":["trace[621294649] 'read index received' (duration: 242.04433ms)","trace[621294649] 'applied index is now lower than readState.Index' (duration: 308.819µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:50:08.436242Z","caller":"traceutil/trace.go:171","msg":"trace[1387320016] transaction","detail":"{read_only:false; response_revision:829; number_of_response:1; }","duration":"263.458016ms","start":"2026-02-04T01:50:08.172777Z","end":"2026-02-04T01:50:08.436235Z","steps":["trace[1387320016] 'process raft request' (duration: 263.033514ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:50:08.436446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.557266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-04T01:50:08.436492Z","caller":"traceutil/trace.go:171","msg":"trace[1970425685] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:829; }","duration":"207.639888ms","start":"2026-02-04T01:50:08.22884Z","end":"2026-02-04T01:50:08.43648Z","steps":["trace[1970425685] 'agreement among raft nodes before linearized reading' (duration: 207.505604ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:50:08.436622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.835383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-04T01:50:08.436661Z","caller":"traceutil/trace.go:171","msg":"trace[1306138148] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:829; }","duration":"242.882664ms","start":"2026-02-04T01:50:08.193766Z","end":"2026-02-04T01:50:08.436649Z","steps":["trace[1306138148] 'agreement among raft nodes before linearized reading' (duration: 242.73868ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:50:08.436634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.334753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-04T01:50:08.436714Z","caller":"traceutil/trace.go:171","msg":"trace[181865312] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:829; }","duration":"158.437086ms","start":"2026-02-04T01:50:08.278272Z","end":"2026-02-04T01:50:08.436709Z","steps":["trace[181865312] 'agreement among raft nodes before linearized reading' (duration: 158.330413ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:50:08.553994Z","caller":"traceutil/trace.go:171","msg":"trace[498001135] transaction","detail":"{read_only:false; response_revision:830; number_of_response:1; }","duration":"112.731709ms","start":"2026-02-04T01:50:08.441246Z","end":"2026-02-04T01:50:08.553977Z","steps":["trace[498001135] 'process raft request' (duration: 80.060049ms)","trace[498001135] 'compare' (duration: 32.564306ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:50:08.554209Z","caller":"traceutil/trace.go:171","msg":"trace[785516490] transaction","detail":"{read_only:false; response_revision:831; number_of_response:1; }","duration":"110.44542ms","start":"2026-02-04T01:50:08.443747Z","end":"2026-02-04T01:50:08.554193Z","steps":["trace[785516490] 'process raft request' (duration: 110.207623ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:50:08.554473Z","caller":"traceutil/trace.go:171","msg":"trace[26031729] transaction","detail":"{read_only:false; response_revision:832; number_of_response:1; }","duration":"108.640567ms","start":"2026-02-04T01:50:08.445824Z","end":"2026-02-04T01:50:08.554465Z","steps":["trace[26031729] 'process raft request' (duration: 108.318656ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:50:10.566623Z","caller":"traceutil/trace.go:171","msg":"trace[1788713824] transaction","detail":"{read_only:false; response_revision:834; number_of_response:1; }","duration":"121.46568ms","start":"2026-02-04T01:50:10.445142Z","end":"2026-02-04T01:50:10.566608Z","steps":["trace[1788713824] 'process raft request' (duration: 121.346637ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:50:10.689572Z","caller":"traceutil/trace.go:171","msg":"trace[1567781406] transaction","detail":"{read_only:false; response_revision:835; number_of_response:1; }","duration":"119.00975ms","start":"2026-02-04T01:50:10.57054Z","end":"2026-02-04T01:50:10.68955Z","steps":["trace[1567781406] 'process raft request' (duration: 74.186277ms)","trace[1567781406] 'compare' (duration: 44.664068ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:50:10.690223Z","caller":"traceutil/trace.go:171","msg":"trace[82592386] transaction","detail":"{read_only:false; response_revision:837; number_of_response:1; }","duration":"114.195529ms","start":"2026-02-04T01:50:10.576012Z","end":"2026-02-04T01:50:10.690207Z","steps":["trace[82592386] 'process raft request' (duration: 114.172758ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:50:10.690413Z","caller":"traceutil/trace.go:171","msg":"trace[169999796] transaction","detail":"{read_only:false; response_revision:836; number_of_response:1; }","duration":"116.376273ms","start":"2026-02-04T01:50:10.574031Z","end":"2026-02-04T01:50:10.690407Z","steps":["trace[169999796] 'process raft request' (duration: 116.110455ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:50:43.759549Z","caller":"traceutil/trace.go:171","msg":"trace[749505109] linearizableReadLoop","detail":"{readStateIndex:1002; appliedIndex:1001; }","duration":"106.028109ms","start":"2026-02-04T01:50:43.653503Z","end":"2026-02-04T01:50:43.759531Z","steps":["trace[749505109] 'read index received' (duration: 93.973486ms)","trace[749505109] 'applied index is now lower than readState.Index' (duration: 12.053723ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:50:43.759719Z","caller":"traceutil/trace.go:171","msg":"trace[997056952] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"118.035211ms","start":"2026-02-04T01:50:43.641653Z","end":"2026-02-04T01:50:43.759688Z","steps":["trace[997056952] 'process raft request' (duration: 105.883506ms)","trace[997056952] 'compare' (duration: 11.831048ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:50:43.759759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.234083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1465"} {"level":"info","ts":"2026-02-04T01:50:43.75992Z","caller":"traceutil/trace.go:171","msg":"trace[1676626650] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:958; }","duration":"106.421097ms","start":"2026-02-04T01:50:43.653476Z","end":"2026-02-04T01:50:43.759897Z","steps":["trace[1676626650] 'agreement among raft nodes before linearized reading' (duration: 106.125621ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:50:55.11549Z","caller":"traceutil/trace.go:171","msg":"trace[1603477357] linearizableReadLoop","detail":"{readStateIndex:1043; appliedIndex:1042; }","duration":"109.404905ms","start":"2026-02-04T01:50:55.006064Z","end":"2026-02-04T01:50:55.115469Z","steps":["trace[1603477357] 'read index received' (duration: 109.208352ms)","trace[1603477357] 'applied index is now lower than readState.Index' (duration: 195.323µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:50:55.115774Z","caller":"traceutil/trace.go:171","msg":"trace[660210257] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"223.903284ms","start":"2026-02-04T01:50:54.891861Z","end":"2026-02-04T01:50:55.115765Z","steps":["trace[660210257] 'process raft request' (duration: 223.493966ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:50:55.115969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.889564ms","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-02-04T01:50:55.115995Z","caller":"traceutil/trace.go:171","msg":"trace[1726987862] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:997; }","duration":"109.954216ms","start":"2026-02-04T01:50:55.006034Z","end":"2026-02-04T01:50:55.115988Z","steps":["trace[1726987862] 'agreement among raft nodes before linearized reading' (duration: 109.856514ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:50:55.379903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.711568ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:50:55.380261Z","caller":"traceutil/trace.go:171","msg":"trace[2056804872] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"175.065597ms","start":"2026-02-04T01:50:55.205181Z","end":"2026-02-04T01:50:55.380247Z","steps":["trace[2056804872] 'process raft request' (duration: 42.944681ms)","trace[2056804872] 'compare' (duration: 131.624246ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:50:55.718086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.349471ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:50:55.718179Z","caller":"traceutil/trace.go:171","msg":"trace[580500594] linearizableReadLoop","detail":"{readStateIndex:1047; appliedIndex:1046; }","duration":"181.852623ms","start":"2026-02-04T01:50:55.536312Z","end":"2026-02-04T01:50:55.718164Z","steps":["trace[580500594] 'read index received' (duration: 55.712µs)","trace[580500594] 'applied index is now lower than readState.Index' (duration: 181.795761ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:50:55.71908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.763271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:50:55.719173Z","caller":"traceutil/trace.go:171","msg":"trace[281116574] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1001; }","duration":"182.906824ms","start":"2026-02-04T01:50:55.536254Z","end":"2026-02-04T01:50:55.719161Z","steps":["trace[281116574] 'agreement among raft nodes before linearized reading' (duration: 182.751241ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:50:55.7195Z","caller":"traceutil/trace.go:171","msg":"trace[996716030] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"335.032735ms","start":"2026-02-04T01:50:55.384431Z","end":"2026-02-04T01:50:55.719464Z","steps":["trace[996716030] 'process raft request' (duration: 140.209297ms)","trace[996716030] 'compare' (duration: 193.249778ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:50:55.720444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:50:55.384418Z","time spent":"335.925282ms","remote":"127.0.0.1:55122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:51:06.001907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.907866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:generic-garbage-collector\" ","response":"range_response_count:1 size:695"} {"level":"info","ts":"2026-02-04T01:51:06.001989Z","caller":"traceutil/trace.go:171","msg":"trace[993764132] range","detail":"{range_begin:/registry/clusterroles/system:controller:generic-garbage-collector; range_end:; response_count:1; response_revision:1033; }","duration":"149.020618ms","start":"2026-02-04T01:51:05.852946Z","end":"2026-02-04T01:51:06.001967Z","steps":["trace[993764132] 'range keys from in-memory index tree' (duration: 148.816954ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:51:06.002428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.160784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:775"} {"level":"info","ts":"2026-02-04T01:51:06.002475Z","caller":"traceutil/trace.go:171","msg":"trace[1837078071] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:1033; }","duration":"144.217526ms","start":"2026-02-04T01:51:05.858244Z","end":"2026-02-04T01:51:06.002462Z","steps":["trace[1837078071] 'range keys from in-memory index tree' (duration: 144.126254ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:51:28.61258Z","caller":"traceutil/trace.go:171","msg":"trace[2075143419] linearizableReadLoop","detail":"{readStateIndex:1150; appliedIndex:1149; }","duration":"289.582176ms","start":"2026-02-04T01:51:28.322976Z","end":"2026-02-04T01:51:28.612558Z","steps":["trace[2075143419] 'read index received' (duration: 289.399184ms)","trace[2075143419] 'applied index is now lower than readState.Index' (duration: 182.082µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:51:28.612818Z","caller":"traceutil/trace.go:171","msg":"trace[1745110991] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"307.920398ms","start":"2026-02-04T01:51:28.304885Z","end":"2026-02-04T01:51:28.612805Z","steps":["trace[1745110991] 'process raft request' (duration: 307.533532ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:51:28.612933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.949201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:51:28.612968Z","caller":"traceutil/trace.go:171","msg":"trace[296190798] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1097; }","duration":"290.016052ms","start":"2026-02-04T01:51:28.322944Z","end":"2026-02-04T01:51:28.61296Z","steps":["trace[296190798] 'agreement among raft nodes before linearized reading' (duration: 289.968281ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:51:28.612938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:51:28.304868Z","time spent":"308.00779ms","remote":"127.0.0.1:55122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}