{"level":"warn","ts":"2026-01-21T00:41:19.169813Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-21T00:41:19.169939Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.195:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.195:2380","--initial-cluster=instance=https://162.253.55.195:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.195:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.195: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-01-21T00:41:19.170313Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-21T00:41:19.170357Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.195:2380"]} {"level":"info","ts":"2026-01-21T00:41:19.1704Z","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-01-21T00:41:19.171293Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.195:2379"]} {"level":"info","ts":"2026-01-21T00:41:19.171462Z","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://162.253.55.195:2380"],"listen-peer-urls":["https://162.253.55.195:2380"],"advertise-client-urls":["https://162.253.55.195:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.195:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.195: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-01-21T00:41:19.314124Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"142.339509ms"} {"level":"info","ts":"2026-01-21T00:41:19.60464Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b66d12fd59101739","cluster-id":"a64a525934ce8379"} {"level":"info","ts":"2026-01-21T00:41:19.605262Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 switched to configuration voters=()"} {"level":"info","ts":"2026-01-21T00:41:19.605405Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became follower at term 0"} {"level":"info","ts":"2026-01-21T00:41:19.605577Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b66d12fd59101739 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-01-21T00:41:19.605683Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became follower at term 1"} {"level":"info","ts":"2026-01-21T00:41:19.605878Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 switched to configuration voters=(13145183766694598457)"} {"level":"warn","ts":"2026-01-21T00:41:19.669517Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-01-21T00:41:19.825517Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-01-21T00:41:19.914952Z","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-01-21T00:41:19.976078Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b66d12fd59101739","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-01-21T00:41:19.976313Z","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-01-21T00:41:19.976862Z","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-01-21T00:41:19.976892Z","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-01-21T00:41:19.980442Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b66d12fd59101739","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-01-21T00:41:19.993496Z","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-01-21T00:41:19.994184Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b66d12fd59101739","initial-advertise-peer-urls":["https://162.253.55.195:2380"],"listen-peer-urls":["https://162.253.55.195:2380"],"advertise-client-urls":["https://162.253.55.195:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.195:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-01-21T00:41:19.994235Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-01-21T00:41:19.994891Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.195:2380"} {"level":"info","ts":"2026-01-21T00:41:19.994955Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.195:2380"} {"level":"info","ts":"2026-01-21T00:41:20.009711Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 switched to configuration voters=(13145183766694598457)"} {"level":"info","ts":"2026-01-21T00:41:20.01025Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"a64a525934ce8379","local-member-id":"b66d12fd59101739","added-peer-id":"b66d12fd59101739","added-peer-peer-urls":["https://162.253.55.195:2380"]} {"level":"info","ts":"2026-01-21T00:41:20.316912Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 is starting a new election at term 1"} {"level":"info","ts":"2026-01-21T00:41:20.31697Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became pre-candidate at term 1"} {"level":"info","ts":"2026-01-21T00:41:20.317107Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 received MsgPreVoteResp from b66d12fd59101739 at term 1"} {"level":"info","ts":"2026-01-21T00:41:20.317129Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became candidate at term 2"} {"level":"info","ts":"2026-01-21T00:41:20.317135Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 received MsgVoteResp from b66d12fd59101739 at term 2"} {"level":"info","ts":"2026-01-21T00:41:20.317174Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became leader at term 2"} {"level":"info","ts":"2026-01-21T00:41:20.317184Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b66d12fd59101739 elected leader b66d12fd59101739 at term 2"} {"level":"info","ts":"2026-01-21T00:41:20.333055Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b66d12fd59101739","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.195:2379]}","request-path":"/0/members/b66d12fd59101739/attributes","cluster-id":"a64a525934ce8379","publish-timeout":"7s"} {"level":"info","ts":"2026-01-21T00:41:20.333279Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-01-21T00:41:20.33366Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-21T00:41:20.339128Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.195:2379"} {"level":"info","ts":"2026-01-21T00:41:20.339655Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-21T00:41:20.340105Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-01-21T00:41:20.34013Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-01-21T00:41:20.3555Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-01-21T00:41:20.400281Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"a64a525934ce8379","local-member-id":"b66d12fd59101739","cluster-version":"3.5"} {"level":"info","ts":"2026-01-21T00:41:20.402746Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-01-21T00:41:20.402845Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-01-21T00:41:22.870032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.707219ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-01-21T00:41:22.870176Z","caller":"traceutil/trace.go:171","msg":"trace[1351906553] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:5; }","duration":"443.37391ms","start":"2026-01-21T00:41:22.426784Z","end":"2026-01-21T00:41:22.870158Z","steps":["trace[1351906553] 'read index received' (duration: 16.920975ms)","trace[1351906553] 'applied index is now lower than readState.Index' (duration: 426.451905ms)"],"step_count":2} {"level":"warn","ts":"2026-01-21T00:41:22.870424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.641857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-21T00:41:22.870258Z","caller":"traceutil/trace.go:171","msg":"trace[2137541937] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"453.964955ms","start":"2026-01-21T00:41:22.416221Z","end":"2026-01-21T00:41:22.870186Z","steps":["trace[2137541937] 'process raft request' (duration: 171.425467ms)","trace[2137541937] 'compare' (duration: 281.507924ms)"],"step_count":2} {"level":"info","ts":"2026-01-21T00:41:22.870463Z","caller":"traceutil/trace.go:171","msg":"trace[289592790] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:3; }","duration":"443.724519ms","start":"2026-01-21T00:41:22.426728Z","end":"2026-01-21T00:41:22.870452Z","steps":["trace[289592790] 'agreement among raft nodes before linearized reading' (duration: 443.519384ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:41:22.870513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:41:22.426716Z","time spent":"443.788681ms","remote":"127.0.0.1:43712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":28,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-01-21T00:41:22.870692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:41:22.416213Z","time spent":"454.299653ms","remote":"127.0.0.1:43438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-21T00:41:23.370586Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673540114324520574,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-21T00:41:23.410627Z","caller":"traceutil/trace.go:171","msg":"trace[242260778] linearizableReadLoop","detail":"{readStateIndex:12; appliedIndex:6; }","duration":"540.377204ms","start":"2026-01-21T00:41:22.870216Z","end":"2026-01-21T00:41:23.410594Z","steps":["trace[242260778] 'read index received' (duration: 539.908933ms)","trace[242260778] 'applied index is now lower than readState.Index' (duration: 466.701µs)"],"step_count":2} {"level":"info","ts":"2026-01-21T00:41:23.410795Z","caller":"traceutil/trace.go:171","msg":"trace[681160979] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"924.487435ms","start":"2026-01-21T00:41:22.486297Z","end":"2026-01-21T00:41:23.410784Z","steps":["trace[681160979] 'process raft request' (duration: 924.229119ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:41:23.410827Z","caller":"traceutil/trace.go:171","msg":"trace[1191175788] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"966.327658ms","start":"2026-01-21T00:41:22.444466Z","end":"2026-01-21T00:41:23.410794Z","steps":["trace[1191175788] 'process raft request' (duration: 965.690052ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:41:23.410911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:41:22.486285Z","time spent":"924.543886ms","remote":"127.0.0.1:43770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-01-21T00:41:23.410976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:41:22.444439Z","time spent":"966.451791ms","remote":"127.0.0.1:43872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-21T00:41:23.411198Z","caller":"traceutil/trace.go:171","msg":"trace[1609861719] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"966.619815ms","start":"2026-01-21T00:41:22.444565Z","end":"2026-01-21T00:41:23.411185Z","steps":["trace[1609861719] 'process raft request' (duration: 965.765535ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:41:23.411252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:41:22.444562Z","time spent":"966.659236ms","remote":"127.0.0.1:43872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-01-21T00:41:23.411362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"873.363651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-21T00:41:23.411429Z","caller":"traceutil/trace.go:171","msg":"trace[1888832785] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:9; }","duration":"873.474093ms","start":"2026-01-21T00:41:22.537937Z","end":"2026-01-21T00:41:23.411411Z","steps":["trace[1888832785] 'agreement among raft nodes before linearized reading' (duration: 873.295829ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:41:23.411382Z","caller":"traceutil/trace.go:171","msg":"trace[202837408] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"966.725437ms","start":"2026-01-21T00:41:22.444645Z","end":"2026-01-21T00:41:23.41137Z","steps":["trace[202837408] 'process raft request' (duration: 965.784325ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:41:23.411467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:41:22.537911Z","time spent":"873.545565ms","remote":"127.0.0.1:43486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-01-21T00:41:23.411526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:41:22.444615Z","time spent":"966.85704ms","remote":"127.0.0.1:43872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-01-21T00:41:23.411652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"925.07023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-21T00:41:23.411688Z","caller":"traceutil/trace.go:171","msg":"trace[724971908] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"962.31136ms","start":"2026-01-21T00:41:22.449365Z","end":"2026-01-21T00:41:23.411677Z","steps":["trace[724971908] 'process raft request' (duration: 961.095082ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:41:23.411738Z","caller":"traceutil/trace.go:171","msg":"trace[1146934783] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:9; }","duration":"925.11549ms","start":"2026-01-21T00:41:22.486558Z","end":"2026-01-21T00:41:23.411673Z","steps":["trace[1146934783] 'agreement among raft nodes before linearized reading' (duration: 925.058209ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:41:23.411748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:41:22.449349Z","time spent":"962.365102ms","remote":"127.0.0.1:43872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-01-21T00:41:23.411769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:41:22.486549Z","time spent":"925.212212ms","remote":"127.0.0.1:43456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"info","ts":"2026-01-21T00:41:23.411874Z","caller":"traceutil/trace.go:171","msg":"trace[1152288974] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"962.401413ms","start":"2026-01-21T00:41:22.449462Z","end":"2026-01-21T00:41:23.411864Z","steps":["trace[1152288974] 'process raft request' (duration: 961.03174ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:41:23.411916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"926.595546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-01-21T00:41:23.41192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:41:22.449454Z","time spent":"962.443854ms","remote":"127.0.0.1:43872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-21T00:41:23.411954Z","caller":"traceutil/trace.go:171","msg":"trace[659924870] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:9; }","duration":"926.643448ms","start":"2026-01-21T00:41:22.485301Z","end":"2026-01-21T00:41:23.411945Z","steps":["trace[659924870] 'agreement among raft nodes before linearized reading' (duration: 926.588336ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:41:23.412007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:41:22.485299Z","time spent":"926.676278ms","remote":"127.0.0.1:43486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-01-21T00:41:23.412146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"926.851972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-21T00:41:23.41217Z","caller":"traceutil/trace.go:171","msg":"trace[2052284699] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:9; }","duration":"926.885323ms","start":"2026-01-21T00:41:22.485277Z","end":"2026-01-21T00:41:23.412163Z","steps":["trace[2052284699] 'agreement among raft nodes before linearized reading' (duration: 926.844502ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:41:23.412197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:41:22.485272Z","time spent":"926.920044ms","remote":"127.0.0.1:43486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/default\" "} {"level":"warn","ts":"2026-01-21T00:41:23.412359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"928.154373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.195\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-21T00:41:23.412385Z","caller":"traceutil/trace.go:171","msg":"trace[1401042099] range","detail":"{range_begin:/registry/masterleases/162.253.55.195; range_end:; response_count:0; response_revision:9; }","duration":"928.191034ms","start":"2026-01-21T00:41:22.484185Z","end":"2026-01-21T00:41:23.412376Z","steps":["trace[1401042099] 'agreement among raft nodes before linearized reading' (duration: 928.140543ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:41:23.412408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:41:22.484182Z","time spent":"928.220835ms","remote":"127.0.0.1:43410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":28,"request content":"key:\"/registry/masterleases/162.253.55.195\" "} {"level":"warn","ts":"2026-01-21T00:41:23.412977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"928.857061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-21T00:41:23.41303Z","caller":"traceutil/trace.go:171","msg":"trace[149958869] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:9; }","duration":"928.912912ms","start":"2026-01-21T00:41:22.484098Z","end":"2026-01-21T00:41:23.413011Z","steps":["trace[149958869] 'agreement among raft nodes before linearized reading' (duration: 928.44379ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:41:23.413061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:41:22.484038Z","time spent":"929.016444ms","remote":"127.0.0.1:43486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-01-21T00:41:23.413167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.125934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-21T00:41:23.413195Z","caller":"traceutil/trace.go:171","msg":"trace[1999774955] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:0; response_revision:9; }","duration":"539.194906ms","start":"2026-01-21T00:41:22.873992Z","end":"2026-01-21T00:41:23.413187Z","steps":["trace[1999774955] 'agreement among raft nodes before linearized reading' (duration: 539.156685ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:41:23.413218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:41:22.873971Z","time spent":"539.241568ms","remote":"127.0.0.1:43536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":0,"response size":28,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-01-21T00:41:23.413453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.749874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-21T00:41:23.413485Z","caller":"traceutil/trace.go:171","msg":"trace[288177565] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:9; }","duration":"138.835306ms","start":"2026-01-21T00:41:23.274638Z","end":"2026-01-21T00:41:23.413473Z","steps":["trace[288177565] 'agreement among raft nodes before linearized reading' (duration: 138.783035ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:41:23.413635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.165233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-21T00:41:23.41366Z","caller":"traceutil/trace.go:171","msg":"trace[1421763278] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:9; }","duration":"139.229525ms","start":"2026-01-21T00:41:23.274423Z","end":"2026-01-21T00:41:23.413652Z","steps":["trace[1421763278] 'agreement among raft nodes before linearized reading' (duration: 139.177304ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:41:23.923135Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673540114324520576,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-21T00:41:24.0356Z","caller":"traceutil/trace.go:171","msg":"trace[1865344975] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"606.643513ms","start":"2026-01-21T00:41:23.428923Z","end":"2026-01-21T00:41:24.035566Z","steps":["trace[1865344975] 'process raft request' (duration: 606.427778ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:41:24.035706Z","caller":"traceutil/trace.go:171","msg":"trace[2136973424] linearizableReadLoop","detail":"{readStateIndex:21; appliedIndex:12; }","duration":"613.175838ms","start":"2026-01-21T00:41:23.422502Z","end":"2026-01-21T00:41:24.035678Z","steps":["trace[2136973424] 'read index received' (duration: 518.88301ms)","trace[2136973424] 'applied index is now lower than readState.Index' (duration: 94.291588ms)"],"step_count":2} {"level":"warn","ts":"2026-01-21T00:41:24.035877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:41:23.428903Z","time spent":"606.869598ms","remote":"127.0.0.1:43680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-21T00:41:24.035946Z","caller":"traceutil/trace.go:171","msg":"trace[1800557641] transaction","detail":"{read_only:false; number_of_response:0; response_revision:11; }","duration":"606.903028ms","start":"2026-01-21T00:41:23.42902Z","end":"2026-01-21T00:41:24.035923Z","steps":["trace[1800557641] 'process raft request' (duration: 606.377286ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:41:24.03606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:41:23.429014Z","time spent":"606.97635ms","remote":"127.0.0.1:43486","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-21T00:41:24.036179Z","caller":"traceutil/trace.go:171","msg":"trace[1954175750] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"613.913695ms","start":"2026-01-21T00:41:23.422237Z","end":"2026-01-21T00:41:24.036151Z","steps":["trace[1954175750] 'process raft request' (duration: 519.136105ms)","trace[1954175750] 'compare' (duration: 93.723096ms)"],"step_count":2} {"level":"warn","ts":"2026-01-21T00:41:24.036271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:41:23.422218Z","time spent":"614.004778ms","remote":"127.0.0.1:43486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":338,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-21T00:41:24.036601Z","caller":"traceutil/trace.go:171","msg":"trace[681346549] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"607.283138ms","start":"2026-01-21T00:41:23.429306Z","end":"2026-01-21T00:41:24.03659Z","steps":["trace[681346549] 'process raft request' (duration: 606.293295ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:41:24.036653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:41:23.429278Z","time spent":"607.350629ms","remote":"127.0.0.1:43872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-21T00:41:24.040614Z","caller":"traceutil/trace.go:171","msg":"trace[1927388803] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"611.415027ms","start":"2026-01-21T00:41:23.429171Z","end":"2026-01-21T00:41:24.040586Z","steps":["trace[1927388803] 'process raft request' (duration: 606.249303ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:41:24.046451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:41:23.429166Z","time spent":"617.243757ms","remote":"127.0.0.1:43872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-21T00:41:24.041612Z","caller":"traceutil/trace.go:171","msg":"trace[1680428263] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"611.896597ms","start":"2026-01-21T00:41:23.429697Z","end":"2026-01-21T00:41:24.041593Z","steps":["trace[1680428263] 'process raft request' (duration: 605.946385ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:41:24.04851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:41:23.42969Z","time spent":"618.792391ms","remote":"127.0.0.1:43770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-01-21T00:41:24.042259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.738995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-21T00:41:24.049194Z","caller":"traceutil/trace.go:171","msg":"trace[1674377274] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17; }","duration":"626.533009ms","start":"2026-01-21T00:41:23.42246Z","end":"2026-01-21T00:41:24.048993Z","steps":["trace[1674377274] 'agreement among raft nodes before linearized reading' (duration: 619.185232ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:41:24.049232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:41:23.422453Z","time spent":"626.769204ms","remote":"127.0.0.1:43386","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-01-21T00:41:24.042668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.857204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-21T00:41:24.05048Z","caller":"traceutil/trace.go:171","msg":"trace[1085160760] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:17; }","duration":"621.664721ms","start":"2026-01-21T00:41:23.4288Z","end":"2026-01-21T00:41:24.050465Z","steps":["trace[1085160760] 'agreement among raft nodes before linearized reading' (duration: 613.842194ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:41:24.052712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:41:23.428767Z","time spent":"623.877225ms","remote":"127.0.0.1:43668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":28,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" "} {"level":"warn","ts":"2026-01-21T00:41:24.042824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.228822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-21T00:41:24.054143Z","caller":"traceutil/trace.go:171","msg":"trace[296329570] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:17; }","duration":"425.579435ms","start":"2026-01-21T00:41:23.628548Z","end":"2026-01-21T00:41:24.054128Z","steps":["trace[296329570] 'agreement among raft nodes before linearized reading' (duration: 414.242412ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:41:24.054218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:41:23.628529Z","time spent":"425.682048ms","remote":"127.0.0.1:43590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" "} {"level":"warn","ts":"2026-01-21T00:41:24.042967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.626919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-01-21T00:41:24.054484Z","caller":"traceutil/trace.go:171","msg":"trace[140310362] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:17; }","duration":"569.154585ms","start":"2026-01-21T00:41:23.485313Z","end":"2026-01-21T00:41:24.054467Z","steps":["trace[140310362] 'agreement among raft nodes before linearized reading' (duration: 557.538126ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:41:24.05451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:41:23.485299Z","time spent":"569.204256ms","remote":"127.0.0.1:43486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-01-21T00:41:24.043358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.094091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-21T00:41:24.054665Z","caller":"traceutil/trace.go:171","msg":"trace[38852540] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:17; }","duration":"577.438282ms","start":"2026-01-21T00:41:23.477211Z","end":"2026-01-21T00:41:24.054649Z","steps":["trace[38852540] 'agreement among raft nodes before linearized reading' (duration: 565.774143ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:41:24.054688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:41:23.477193Z","time spent":"577.489524ms","remote":"127.0.0.1:43486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/default\" "} {"level":"warn","ts":"2026-01-21T00:41:24.043673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.490368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-21T00:41:24.054882Z","caller":"traceutil/trace.go:171","msg":"trace[587528388] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:17; }","duration":"579.759568ms","start":"2026-01-21T00:41:23.475116Z","end":"2026-01-21T00:41:24.054875Z","steps":["trace[587528388] 'agreement among raft nodes before linearized reading' (duration: 568.498479ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:41:24.054909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:41:23.475098Z","time spent":"579.804919ms","remote":"127.0.0.1:43712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":28,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-01-21T00:41:24.045219Z","caller":"traceutil/trace.go:171","msg":"trace[233293964] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"615.137876ms","start":"2026-01-21T00:41:23.429218Z","end":"2026-01-21T00:41:24.044355Z","steps":["trace[233293964] 'process raft request' (duration: 606.273664ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:41:24.055118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:41:23.429213Z","time spent":"625.877293ms","remote":"127.0.0.1:43872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-21T00:41:24.045824Z","caller":"traceutil/trace.go:171","msg":"trace[252170815] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"616.377255ms","start":"2026-01-21T00:41:23.429293Z","end":"2026-01-21T00:41:24.04567Z","steps":["trace[252170815] 'process raft request' (duration: 606.238343ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:41:24.055303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:41:23.429283Z","time spent":"625.990516ms","remote":"127.0.0.1:43872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-21T00:41:24.046088Z","caller":"traceutil/trace.go:171","msg":"trace[1370563403] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"616.483108ms","start":"2026-01-21T00:41:23.429367Z","end":"2026-01-21T00:41:24.04585Z","steps":["trace[1370563403] 'process raft request' (duration: 606.199232ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:41:24.055445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:41:23.429361Z","time spent":"626.067327ms","remote":"127.0.0.1:43872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":923,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-21T00:41:24.365786Z","caller":"traceutil/trace.go:171","msg":"trace[1211104793] transaction","detail":"{read_only:false; response_revision:27; number_of_response:1; }","duration":"226.481505ms","start":"2026-01-21T00:41:24.139266Z","end":"2026-01-21T00:41:24.365747Z","steps":["trace[1211104793] 'process raft request' (duration: 190.289178ms)","trace[1211104793] 'compare' (duration: 36.029373ms)"],"step_count":2} {"level":"info","ts":"2026-01-21T00:41:24.609654Z","caller":"traceutil/trace.go:171","msg":"trace[144344636] transaction","detail":"{read_only:false; response_revision:28; number_of_response:1; }","duration":"470.089161ms","start":"2026-01-21T00:41:24.139533Z","end":"2026-01-21T00:41:24.609622Z","steps":["trace[144344636] 'process raft request' (duration: 404.760625ms)","trace[144344636] 'compare' (duration: 64.874305ms)"],"step_count":2} {"level":"warn","ts":"2026-01-21T00:41:24.609796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:41:24.139517Z","time spent":"470.214893ms","remote":"127.0.0.1:43872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":959,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-21T00:41:24.610856Z","caller":"traceutil/trace.go:171","msg":"trace[1073765045] transaction","detail":"{read_only:false; response_revision:35; number_of_response:1; }","duration":"241.054824ms","start":"2026-01-21T00:41:24.369783Z","end":"2026-01-21T00:41:24.610838Z","steps":["trace[1073765045] 'process raft request' (duration: 241.026454ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:41:24.611005Z","caller":"traceutil/trace.go:171","msg":"trace[1259227690] transaction","detail":"{read_only:false; response_revision:29; number_of_response:1; }","duration":"471.303649ms","start":"2026-01-21T00:41:24.139693Z","end":"2026-01-21T00:41:24.610997Z","steps":["trace[1259227690] 'process raft request' (duration: 469.616339ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:41:24.611055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:41:24.139688Z","time spent":"471.3394ms","remote":"127.0.0.1:43486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":338,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-21T00:41:24.611333Z","caller":"traceutil/trace.go:171","msg":"trace[771375844] transaction","detail":"{read_only:false; response_revision:33; number_of_response:1; }","duration":"467.480557ms","start":"2026-01-21T00:41:24.14384Z","end":"2026-01-21T00:41:24.61132Z","steps":["trace[771375844] 'process raft request' (duration: 466.907373ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:41:24.611388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:41:24.143832Z","time spent":"467.530568ms","remote":"127.0.0.1:43872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":959,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-21T00:41:24.611526Z","caller":"traceutil/trace.go:171","msg":"trace[1287472085] transaction","detail":"{read_only:false; response_revision:30; number_of_response:1; }","duration":"469.069755ms","start":"2026-01-21T00:41:24.14244Z","end":"2026-01-21T00:41:24.61151Z","steps":["trace[1287472085] 'process raft request' (duration: 467.679422ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:41:24.611571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:41:24.142433Z","time spent":"469.115486ms","remote":"127.0.0.1:43770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":713,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-21T00:41:24.611751Z","caller":"traceutil/trace.go:171","msg":"trace[813587749] transaction","detail":"{read_only:false; response_revision:31; number_of_response:1; }","duration":"468.34249ms","start":"2026-01-21T00:41:24.143399Z","end":"2026-01-21T00:41:24.611741Z","steps":["trace[813587749] 'process raft request' (duration: 467.275824ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:41:24.611793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:41:24.143392Z","time spent":"468.38075ms","remote":"127.0.0.1:43482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3004,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-21T00:41:24.611934Z","caller":"traceutil/trace.go:171","msg":"trace[1779685707] transaction","detail":"{read_only:false; response_revision:32; number_of_response:1; }","duration":"468.173395ms","start":"2026-01-21T00:41:24.143753Z","end":"2026-01-21T00:41:24.611926Z","steps":["trace[1779685707] 'process raft request' (duration: 466.971376ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:41:24.611978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:41:24.143736Z","time spent":"468.219717ms","remote":"127.0.0.1:43872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":983,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-21T00:41:24.61257Z","caller":"traceutil/trace.go:171","msg":"trace[501292597] transaction","detail":"{read_only:false; response_revision:34; number_of_response:1; }","duration":"468.659976ms","start":"2026-01-21T00:41:24.143899Z","end":"2026-01-21T00:41:24.612559Z","steps":["trace[501292597] 'process raft request' (duration: 466.878593ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:41:24.612625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:41:24.143884Z","time spent":"468.715137ms","remote":"127.0.0.1:43872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":926,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-21T00:41:24.612731Z","caller":"traceutil/trace.go:171","msg":"trace[943186181] linearizableReadLoop","detail":"{readStateIndex:34; appliedIndex:30; }","duration":"470.136032ms","start":"2026-01-21T00:41:24.142586Z","end":"2026-01-21T00:41:24.612722Z","steps":["trace[943186181] 'read index received' (duration: 186.945169ms)","trace[943186181] 'applied index is now lower than readState.Index' (duration: 283.189133ms)"],"step_count":2} {"level":"warn","ts":"2026-01-21T00:41:24.612947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.357977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-21T00:41:24.612985Z","caller":"traceutil/trace.go:171","msg":"trace[939596766] range","detail":"{range_begin:/registry/clusterroles/view; range_end:; response_count:0; response_revision:35; }","duration":"470.399208ms","start":"2026-01-21T00:41:24.142574Z","end":"2026-01-21T00:41:24.612973Z","steps":["trace[939596766] 'agreement among raft nodes before linearized reading' (duration: 470.292825ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:41:24.613012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:41:24.142539Z","time spent":"470.46784ms","remote":"127.0.0.1:43666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":28,"request content":"key:\"/registry/clusterroles/view\" "} {"level":"warn","ts":"2026-01-21T00:41:24.613153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.214436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-21T00:41:24.613178Z","caller":"traceutil/trace.go:171","msg":"trace[985920404] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:35; }","duration":"420.294857ms","start":"2026-01-21T00:41:24.192876Z","end":"2026-01-21T00:41:24.613171Z","steps":["trace[985920404] 'agreement among raft nodes before linearized reading' (duration: 420.255167ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:41:24.613203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:41:24.192855Z","time spent":"420.341618ms","remote":"127.0.0.1:43486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/default\" "} {"level":"warn","ts":"2026-01-21T00:41:24.613385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.716391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-21T00:41:24.613414Z","caller":"traceutil/trace.go:171","msg":"trace[522941823] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:35; }","duration":"178.802783ms","start":"2026-01-21T00:41:24.434603Z","end":"2026-01-21T00:41:24.613406Z","steps":["trace[522941823] 'agreement among raft nodes before linearized reading' (duration: 178.762452ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:41:24.613642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.754833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-21T00:41:24.613676Z","caller":"traceutil/trace.go:171","msg":"trace[703362127] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:35; }","duration":"214.821715ms","start":"2026-01-21T00:41:24.398843Z","end":"2026-01-21T00:41:24.613665Z","steps":["trace[703362127] 'agreement among raft nodes before linearized reading' (duration: 214.771584ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:41:24.820913Z","caller":"traceutil/trace.go:171","msg":"trace[1568189] linearizableReadLoop","detail":"{readStateIndex:41; appliedIndex:40; }","duration":"113.428307ms","start":"2026-01-21T00:41:24.707456Z","end":"2026-01-21T00:41:24.820884Z","steps":["trace[1568189] 'read index received' (duration: 80.013186ms)","trace[1568189] 'applied index is now lower than readState.Index' (duration: 33.414261ms)"],"step_count":2} {"level":"info","ts":"2026-01-21T00:41:24.821045Z","caller":"traceutil/trace.go:171","msg":"trace[588304010] transaction","detail":"{read_only:false; response_revision:37; number_of_response:1; }","duration":"113.766864ms","start":"2026-01-21T00:41:24.707256Z","end":"2026-01-21T00:41:24.821023Z","steps":["trace[588304010] 'process raft request' (duration: 80.239911ms)","trace[588304010] 'compare' (duration: 33.223666ms)"],"step_count":2} {"level":"warn","ts":"2026-01-21T00:41:24.821359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.882518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-21T00:41:24.821401Z","caller":"traceutil/trace.go:171","msg":"trace[1348209020] range","detail":"{range_begin:/registry/clusterroles/admin; range_end:; response_count:0; response_revision:37; }","duration":"113.966299ms","start":"2026-01-21T00:41:24.707423Z","end":"2026-01-21T00:41:24.821389Z","steps":["trace[1348209020] 'agreement among raft nodes before linearized reading' (duration: 113.813075ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:41:24.821857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.466469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.188c982d1bc7942d\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-21T00:41:24.821901Z","caller":"traceutil/trace.go:171","msg":"trace[1362761993] range","detail":"{range_begin:/registry/events/default/instance.188c982d1bc7942d; range_end:; response_count:0; response_revision:38; }","duration":"113.51937ms","start":"2026-01-21T00:41:24.708369Z","end":"2026-01-21T00:41:24.821889Z","steps":["trace[1362761993] 'agreement among raft nodes before linearized reading' (duration: 113.418467ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:41:24.822152Z","caller":"traceutil/trace.go:171","msg":"trace[369159914] transaction","detail":"{read_only:false; response_revision:38; number_of_response:1; }","duration":"114.287728ms","start":"2026-01-21T00:41:24.707854Z","end":"2026-01-21T00:41:24.822141Z","steps":["trace[369159914] 'process raft request' (duration: 113.878018ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:41:25.057636Z","caller":"traceutil/trace.go:171","msg":"trace[1714407923] linearizableReadLoop","detail":"{readStateIndex:50; appliedIndex:46; }","duration":"105.60604ms","start":"2026-01-21T00:41:24.952009Z","end":"2026-01-21T00:41:25.057615Z","steps":["trace[1714407923] 'read index received' (duration: 42.375965ms)","trace[1714407923] 'applied index is now lower than readState.Index' (duration: 63.213675ms)"],"step_count":2} {"level":"info","ts":"2026-01-21T00:41:25.058372Z","caller":"traceutil/trace.go:171","msg":"trace[854859341] transaction","detail":"{read_only:false; response_revision:45; number_of_response:1; }","duration":"146.052758ms","start":"2026-01-21T00:41:24.912309Z","end":"2026-01-21T00:41:25.058362Z","steps":["trace[854859341] 'process raft request' (duration: 145.248939ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:41:25.058715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.693486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-21T00:41:25.059643Z","caller":"traceutil/trace.go:171","msg":"trace[1804913390] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:45; }","duration":"107.69798ms","start":"2026-01-21T00:41:24.951929Z","end":"2026-01-21T00:41:25.059627Z","steps":["trace[1804913390] 'agreement among raft nodes before linearized reading' (duration: 106.715237ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:41:25.05766Z","caller":"traceutil/trace.go:171","msg":"trace[1488660060] transaction","detail":"{read_only:false; response_revision:44; number_of_response:1; }","duration":"145.386802ms","start":"2026-01-21T00:41:24.912236Z","end":"2026-01-21T00:41:25.057622Z","steps":["trace[1488660060] 'process raft request' (duration: 145.263159ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:41:25.059311Z","caller":"traceutil/trace.go:171","msg":"trace[1585809971] transaction","detail":"{read_only:false; response_revision:43; number_of_response:1; }","duration":"145.974356ms","start":"2026-01-21T00:41:24.911756Z","end":"2026-01-21T00:41:25.05773Z","steps":["trace[1585809971] 'process raft request' (duration: 82.643919ms)","trace[1585809971] 'compare' (duration: 62.793294ms)"],"step_count":2} {"level":"info","ts":"2026-01-21T00:41:25.215278Z","caller":"traceutil/trace.go:171","msg":"trace[1923475881] transaction","detail":"{read_only:false; response_revision:51; number_of_response:1; }","duration":"111.907891ms","start":"2026-01-21T00:41:25.103344Z","end":"2026-01-21T00:41:25.215252Z","steps":["trace[1923475881] 'process raft request' (duration: 111.87385ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:41:25.217028Z","caller":"traceutil/trace.go:171","msg":"trace[1115683555] transaction","detail":"{read_only:false; response_revision:50; number_of_response:1; }","duration":"146.206923ms","start":"2026-01-21T00:41:25.070795Z","end":"2026-01-21T00:41:25.217001Z","steps":["trace[1115683555] 'process raft request' (duration: 144.392319ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:41:25.217361Z","caller":"traceutil/trace.go:171","msg":"trace[690707884] transaction","detail":"{read_only:false; response_revision:47; number_of_response:1; }","duration":"149.237935ms","start":"2026-01-21T00:41:25.068109Z","end":"2026-01-21T00:41:25.217347Z","steps":["trace[690707884] 'process raft request' (duration: 104.020333ms)","trace[690707884] 'compare' (duration: 42.798514ms)"],"step_count":2} {"level":"info","ts":"2026-01-21T00:41:25.217466Z","caller":"traceutil/trace.go:171","msg":"trace[2013962433] transaction","detail":"{read_only:false; response_revision:48; number_of_response:1; }","duration":"149.138462ms","start":"2026-01-21T00:41:25.068319Z","end":"2026-01-21T00:41:25.217458Z","steps":["trace[2013962433] 'process raft request' (duration: 146.777185ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:41:25.217779Z","caller":"traceutil/trace.go:171","msg":"trace[90833734] transaction","detail":"{read_only:false; response_revision:49; number_of_response:1; }","duration":"147.005442ms","start":"2026-01-21T00:41:25.070759Z","end":"2026-01-21T00:41:25.217764Z","steps":["trace[90833734] 'process raft request' (duration: 144.3996ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:41:25.218123Z","caller":"traceutil/trace.go:171","msg":"trace[1884250107] linearizableReadLoop","detail":"{readStateIndex:55; appliedIndex:51; }","duration":"121.843108ms","start":"2026-01-21T00:41:25.096268Z","end":"2026-01-21T00:41:25.218111Z","steps":["trace[1884250107] 'read index received' (duration: 75.871398ms)","trace[1884250107] 'applied index is now lower than readState.Index' (duration: 45.97071ms)"],"step_count":2} {"level":"warn","ts":"2026-01-21T00:41:25.219284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.38117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-21T00:41:25.219857Z","caller":"traceutil/trace.go:171","msg":"trace[1570572801] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:0; response_revision:51; }","duration":"152.963304ms","start":"2026-01-21T00:41:25.066882Z","end":"2026-01-21T00:41:25.219845Z","steps":["trace[1570572801] 'agreement among raft nodes before linearized reading' (duration: 152.359389ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:41:25.220148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.650444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.188c982d1bc7a981\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-21T00:41:25.22022Z","caller":"traceutil/trace.go:171","msg":"trace[476318317] range","detail":"{range_begin:/registry/events/default/instance.188c982d1bc7a981; range_end:; response_count:0; response_revision:51; }","duration":"116.749527ms","start":"2026-01-21T00:41:25.103463Z","end":"2026-01-21T00:41:25.220213Z","steps":["trace[476318317] 'agreement among raft nodes before linearized reading' (duration: 116.641915ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:41:25.388484Z","caller":"traceutil/trace.go:171","msg":"trace[993518494] linearizableReadLoop","detail":"{readStateIndex:59; appliedIndex:56; }","duration":"159.084371ms","start":"2026-01-21T00:41:25.229377Z","end":"2026-01-21T00:41:25.388462Z","steps":["trace[993518494] 'read index received' (duration: 141.692105ms)","trace[993518494] 'applied index is now lower than readState.Index' (duration: 17.391476ms)"],"step_count":2} {"level":"info","ts":"2026-01-21T00:41:25.389238Z","caller":"traceutil/trace.go:171","msg":"trace[951508450] transaction","detail":"{read_only:false; response_revision:55; number_of_response:1; }","duration":"158.687762ms","start":"2026-01-21T00:41:25.230514Z","end":"2026-01-21T00:41:25.389202Z","steps":["trace[951508450] 'process raft request' (duration: 157.926794ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:41:25.389619Z","caller":"traceutil/trace.go:171","msg":"trace[1964320629] transaction","detail":"{read_only:false; response_revision:52; number_of_response:1; }","duration":"165.007372ms","start":"2026-01-21T00:41:25.2246Z","end":"2026-01-21T00:41:25.389608Z","steps":["trace[1964320629] 'process raft request' (duration: 146.390647ms)","trace[1964320629] 'compare' (duration: 17.249423ms)"],"step_count":2} {"level":"warn","ts":"2026-01-21T00:41:25.389387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.990913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-01-21T00:41:25.3897Z","caller":"traceutil/trace.go:171","msg":"trace[877658719] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:55; }","duration":"160.34299ms","start":"2026-01-21T00:41:25.229349Z","end":"2026-01-21T00:41:25.389692Z","steps":["trace[877658719] 'agreement among raft nodes before linearized reading' (duration: 159.997052ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:41:25.389906Z","caller":"traceutil/trace.go:171","msg":"trace[662889551] transaction","detail":"{read_only:false; response_revision:53; number_of_response:1; }","duration":"160.72674ms","start":"2026-01-21T00:41:25.22917Z","end":"2026-01-21T00:41:25.389896Z","steps":["trace[662889551] 'process raft request' (duration: 159.205724ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:41:25.390078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.778009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-01-21T00:41:25.390173Z","caller":"traceutil/trace.go:171","msg":"trace[1744748883] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:55; }","duration":"157.887692ms","start":"2026-01-21T00:41:25.232279Z","end":"2026-01-21T00:41:25.390166Z","steps":["trace[1744748883] 'agreement among raft nodes before linearized reading' (duration: 157.773189ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:41:25.390287Z","caller":"traceutil/trace.go:171","msg":"trace[891615541] transaction","detail":"{read_only:false; response_revision:54; number_of_response:1; }","duration":"160.940725ms","start":"2026-01-21T00:41:25.229229Z","end":"2026-01-21T00:41:25.390169Z","steps":["trace[891615541] 'process raft request' (duration: 159.174363ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:41:25.551283Z","caller":"traceutil/trace.go:171","msg":"trace[751535477] transaction","detail":"{read_only:false; response_revision:56; number_of_response:1; }","duration":"155.738871ms","start":"2026-01-21T00:41:25.39551Z","end":"2026-01-21T00:41:25.551248Z","steps":["trace[751535477] 'process raft request' (duration: 113.792235ms)","trace[751535477] 'compare' (duration: 41.512575ms)"],"step_count":2} {"level":"info","ts":"2026-01-21T00:41:25.55247Z","caller":"traceutil/trace.go:171","msg":"trace[777670761] linearizableReadLoop","detail":"{readStateIndex:61; appliedIndex:60; }","duration":"156.710024ms","start":"2026-01-21T00:41:25.395745Z","end":"2026-01-21T00:41:25.552455Z","steps":["trace[777670761] 'read index received' (duration: 113.438568ms)","trace[777670761] 'applied index is now lower than readState.Index' (duration: 43.269116ms)"],"step_count":2} {"level":"warn","ts":"2026-01-21T00:41:25.552878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.083373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:basic-user\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-21T00:41:25.553124Z","caller":"traceutil/trace.go:171","msg":"trace[1273517335] range","detail":"{range_begin:/registry/clusterroles/system:basic-user; range_end:; response_count:0; response_revision:56; }","duration":"157.201007ms","start":"2026-01-21T00:41:25.395715Z","end":"2026-01-21T00:41:25.552916Z","steps":["trace[1273517335] 'agreement among raft nodes before linearized reading' (duration: 156.996111ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:41:25.554324Z","caller":"traceutil/trace.go:171","msg":"trace[498539324] transaction","detail":"{read_only:false; response_revision:57; number_of_response:1; }","duration":"158.514958ms","start":"2026-01-21T00:41:25.395796Z","end":"2026-01-21T00:41:25.554311Z","steps":["trace[498539324] 'process raft request' (duration: 156.54291ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:41:25.554827Z","caller":"traceutil/trace.go:171","msg":"trace[944012681] transaction","detail":"{read_only:false; response_revision:59; number_of_response:1; }","duration":"155.789531ms","start":"2026-01-21T00:41:25.399025Z","end":"2026-01-21T00:41:25.554814Z","steps":["trace[944012681] 'process raft request' (duration: 154.883669ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:41:25.555247Z","caller":"traceutil/trace.go:171","msg":"trace[8373246] transaction","detail":"{read_only:false; response_revision:58; number_of_response:1; }","duration":"156.253252ms","start":"2026-01-21T00:41:25.398981Z","end":"2026-01-21T00:41:25.555234Z","steps":["trace[8373246] 'process raft request' (duration: 154.873739ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:41:25.554023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.166389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.188c982d1bc7b575\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-21T00:41:25.556644Z","caller":"traceutil/trace.go:171","msg":"trace[2066728818] range","detail":"{range_begin:/registry/events/default/instance.188c982d1bc7b575; range_end:; response_count:0; response_revision:59; }","duration":"160.795583ms","start":"2026-01-21T00:41:25.395836Z","end":"2026-01-21T00:41:25.556631Z","steps":["trace[2066728818] 'agreement among raft nodes before linearized reading' (duration: 158.113068ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:41:25.794621Z","caller":"traceutil/trace.go:171","msg":"trace[898205973] linearizableReadLoop","detail":"{readStateIndex:72; appliedIndex:69; }","duration":"159.768457ms","start":"2026-01-21T00:41:25.634828Z","end":"2026-01-21T00:41:25.794597Z","steps":["trace[898205973] 'read index received' (duration: 85.896617ms)","trace[898205973] 'applied index is now lower than readState.Index' (duration: 73.87092ms)"],"step_count":2} {"level":"info","ts":"2026-01-21T00:41:25.794911Z","caller":"traceutil/trace.go:171","msg":"trace[1808911669] transaction","detail":"{read_only:false; response_revision:65; number_of_response:1; }","duration":"172.026151ms","start":"2026-01-21T00:41:25.622841Z","end":"2026-01-21T00:41:25.794868Z","steps":["trace[1808911669] 'process raft request' (duration: 97.812003ms)","trace[1808911669] 'compare' (duration: 73.570373ms)"],"step_count":2} {"level":"info","ts":"2026-01-21T00:41:25.795196Z","caller":"traceutil/trace.go:171","msg":"trace[228699820] transaction","detail":"{read_only:false; response_revision:66; number_of_response:1; }","duration":"161.577281ms","start":"2026-01-21T00:41:25.633602Z","end":"2026-01-21T00:41:25.79518Z","steps":["trace[228699820] 'process raft request' (duration: 160.809333ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:41:25.795417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.598332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-21T00:41:25.795487Z","caller":"traceutil/trace.go:171","msg":"trace[648316800] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:67; }","duration":"103.725635ms","start":"2026-01-21T00:41:25.69175Z","end":"2026-01-21T00:41:25.795476Z","steps":["trace[648316800] 'agreement among raft nodes before linearized reading' (duration: 103.594292ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:41:25.795636Z","caller":"traceutil/trace.go:171","msg":"trace[1484576876] transaction","detail":"{read_only:false; response_revision:67; number_of_response:1; }","duration":"161.946229ms","start":"2026-01-21T00:41:25.633682Z","end":"2026-01-21T00:41:25.795629Z","steps":["trace[1484576876] 'process raft request' (duration: 160.844893ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:41:25.795773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.952305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/endpoint-controller\" ","response":"range_response_count:1 size:1256"} {"level":"info","ts":"2026-01-21T00:41:25.795872Z","caller":"traceutil/trace.go:171","msg":"trace[1962113518] range","detail":"{range_begin:/registry/flowschemas/endpoint-controller; range_end:; response_count:1; response_revision:67; }","duration":"161.079498ms","start":"2026-01-21T00:41:25.634786Z","end":"2026-01-21T00:41:25.795866Z","steps":["trace[1962113518] 'agreement among raft nodes before linearized reading' (duration: 160.960845ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:41:26.303005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.013544ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-01-21T00:41:26.303686Z","caller":"traceutil/trace.go:171","msg":"trace[224417854] transaction","detail":"{read_only:false; response_revision:78; number_of_response:1; }","duration":"437.941882ms","start":"2026-01-21T00:41:25.865721Z","end":"2026-01-21T00:41:26.303663Z","steps":["trace[224417854] 'process raft request' (duration: 437.880279ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:41:26.303902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:41:25.865713Z","time spent":"438.127246ms","remote":"127.0.0.1:43770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":565,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-21T00:41:26.304708Z","caller":"traceutil/trace.go:171","msg":"trace[985392417] transaction","detail":"{read_only:false; response_revision:76; number_of_response:1; }","duration":"445.077731ms","start":"2026-01-21T00:41:25.859598Z","end":"2026-01-21T00:41:26.304676Z","steps":["trace[985392417] 'process raft request' (duration: 298.307255ms)","trace[985392417] 'compare' (duration: 144.633715ms)"],"step_count":2} {"level":"warn","ts":"2026-01-21T00:41:26.304898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:41:25.859585Z","time spent":"445.223316ms","remote":"127.0.0.1:43756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-21T00:41:26.305166Z","caller":"traceutil/trace.go:171","msg":"trace[282423809] linearizableReadLoop","detail":"{readStateIndex:81; appliedIndex:80; }","duration":"441.797834ms","start":"2026-01-21T00:41:25.863357Z","end":"2026-01-21T00:41:26.305155Z","steps":["trace[282423809] 'read index received' (duration: 294.470564ms)","trace[282423809] 'applied index is now lower than readState.Index' (duration: 147.32602ms)"],"step_count":2} {"level":"info","ts":"2026-01-21T00:41:26.305897Z","caller":"traceutil/trace.go:171","msg":"trace[1238933480] transaction","detail":"{read_only:false; response_revision:77; number_of_response:1; }","duration":"440.563723ms","start":"2026-01-21T00:41:25.865324Z","end":"2026-01-21T00:41:26.305887Z","steps":["trace[1238933480] 'process raft request' (duration: 438.219667ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:41:26.306102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:41:25.865313Z","time spent":"440.607294ms","remote":"127.0.0.1:43666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":710,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-01-21T00:41:26.305403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.205266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:2968"} {"level":"info","ts":"2026-01-21T00:41:26.306418Z","caller":"traceutil/trace.go:171","msg":"trace[1123738993] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:78; }","duration":"454.21448ms","start":"2026-01-21T00:41:25.852179Z","end":"2026-01-21T00:41:26.306394Z","steps":["trace[1123738993] 'agreement among raft nodes before linearized reading' (duration: 453.134845ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:41:26.306518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:41:25.852162Z","time spent":"454.339995ms","remote":"127.0.0.1:43536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":2992,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-01-21T00:41:26.30891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.599185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-21T00:41:26.308956Z","caller":"traceutil/trace.go:171","msg":"trace[324899017] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:78; }","duration":"174.598852ms","start":"2026-01-21T00:41:26.134345Z","end":"2026-01-21T00:41:26.308944Z","steps":["trace[324899017] 'agreement among raft nodes before linearized reading' (duration: 172.608355ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:41:26.305493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.098516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:2968"} {"level":"info","ts":"2026-01-21T00:41:26.309187Z","caller":"traceutil/trace.go:171","msg":"trace[1894772436] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:78; }","duration":"449.826785ms","start":"2026-01-21T00:41:25.859352Z","end":"2026-01-21T00:41:26.309179Z","steps":["trace[1894772436] 'agreement among raft nodes before linearized reading' (duration: 446.094236ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:41:26.309247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:41:25.859312Z","time spent":"449.916638ms","remote":"127.0.0.1:43536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":2992,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-01-21T00:41:26.305558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.500236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.188c982d1bc7b575\" ","response":"range_response_count:1 size:648"} {"level":"info","ts":"2026-01-21T00:41:26.309416Z","caller":"traceutil/trace.go:171","msg":"trace[1344067977] range","detail":"{range_begin:/registry/events/default/instance.188c982d1bc7b575; range_end:; response_count:1; response_revision:78; }","duration":"450.404499ms","start":"2026-01-21T00:41:25.859004Z","end":"2026-01-21T00:41:26.309409Z","steps":["trace[1344067977] 'agreement among raft nodes before linearized reading' (duration: 446.502075ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:41:26.309464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:41:25.858995Z","time spent":"450.460411ms","remote":"127.0.0.1:43444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":672,"request content":"key:\"/registry/events/default/instance.188c982d1bc7b575\" "} {"level":"info","ts":"2026-01-21T00:41:26.525616Z","caller":"traceutil/trace.go:171","msg":"trace[1231198165] linearizableReadLoop","detail":"{readStateIndex:92; appliedIndex:88; }","duration":"108.227473ms","start":"2026-01-21T00:41:26.417365Z","end":"2026-01-21T00:41:26.525593Z","steps":["trace[1231198165] 'read index received' (duration: 65.371706ms)","trace[1231198165] 'applied index is now lower than readState.Index' (duration: 42.855007ms)"],"step_count":2} {"level":"warn","ts":"2026-01-21T00:41:26.526294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.906459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-21T00:41:26.526414Z","caller":"traceutil/trace.go:171","msg":"trace[1341130442] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:87; }","duration":"109.077853ms","start":"2026-01-21T00:41:26.417323Z","end":"2026-01-21T00:41:26.526401Z","steps":["trace[1341130442] 'agreement among raft nodes before linearized reading' (duration: 108.896129ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:41:26.527245Z","caller":"traceutil/trace.go:171","msg":"trace[1276076874] transaction","detail":"{read_only:false; response_revision:84; number_of_response:1; }","duration":"173.781423ms","start":"2026-01-21T00:41:26.353453Z","end":"2026-01-21T00:41:26.527235Z","steps":["trace[1276076874] 'process raft request' (duration: 129.198425ms)","trace[1276076874] 'compare' (duration: 42.725654ms)"],"step_count":2} {"level":"info","ts":"2026-01-21T00:41:26.527793Z","caller":"traceutil/trace.go:171","msg":"trace[1293885075] transaction","detail":"{read_only:false; response_revision:85; number_of_response:1; }","duration":"173.424653ms","start":"2026-01-21T00:41:26.354272Z","end":"2026-01-21T00:41:26.527697Z","steps":["trace[1293885075] 'process raft request' (duration: 171.215401ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:41:26.528346Z","caller":"traceutil/trace.go:171","msg":"trace[2049410675] transaction","detail":"{read_only:false; response_revision:86; number_of_response:1; }","duration":"170.460653ms","start":"2026-01-21T00:41:26.357875Z","end":"2026-01-21T00:41:26.528336Z","steps":["trace[2049410675] 'process raft request' (duration: 167.642046ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:41:26.528563Z","caller":"traceutil/trace.go:171","msg":"trace[24028097] transaction","detail":"{read_only:false; response_revision:87; number_of_response:1; }","duration":"170.300829ms","start":"2026-01-21T00:41:26.358254Z","end":"2026-01-21T00:41:26.528555Z","steps":["trace[24028097] 'process raft request' (duration: 167.303438ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:41:26.833297Z","caller":"traceutil/trace.go:171","msg":"trace[1570613137] linearizableReadLoop","detail":"{readStateIndex:102; appliedIndex:101; }","duration":"146.387297ms","start":"2026-01-21T00:41:26.686856Z","end":"2026-01-21T00:41:26.833243Z","steps":["trace[1570613137] 'read index received' (duration: 103.852647ms)","trace[1570613137] 'applied index is now lower than readState.Index' (duration: 42.53327ms)"],"step_count":2} {"level":"info","ts":"2026-01-21T00:41:26.836367Z","caller":"traceutil/trace.go:171","msg":"trace[1918690865] transaction","detail":"{read_only:false; response_revision:97; number_of_response:1; }","duration":"153.661211ms","start":"2026-01-21T00:41:26.682673Z","end":"2026-01-21T00:41:26.836334Z","steps":["trace[1918690865] 'process raft request' (duration: 108.118779ms)","trace[1918690865] 'compare' (duration: 42.308154ms)"],"step_count":2} {"level":"warn","ts":"2026-01-21T00:41:26.838729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.845138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.188c982d1bc7a981\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-01-21T00:41:26.839189Z","caller":"traceutil/trace.go:171","msg":"trace[1661403643] range","detail":"{range_begin:/registry/events/default/instance.188c982d1bc7a981; range_end:; response_count:1; response_revision:97; }","duration":"152.34744ms","start":"2026-01-21T00:41:26.686824Z","end":"2026-01-21T00:41:26.839171Z","steps":["trace[1661403643] 'agreement among raft nodes before linearized reading' (duration: 146.989681ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:41:27.075098Z","caller":"traceutil/trace.go:171","msg":"trace[237251662] linearizableReadLoop","detail":"{readStateIndex:109; appliedIndex:108; }","duration":"156.256963ms","start":"2026-01-21T00:41:26.918813Z","end":"2026-01-21T00:41:27.07507Z","steps":["trace[237251662] 'read index received' (duration: 59.107226ms)","trace[237251662] 'applied index is now lower than readState.Index' (duration: 97.148747ms)"],"step_count":2} {"level":"info","ts":"2026-01-21T00:41:27.075105Z","caller":"traceutil/trace.go:171","msg":"trace[1751120137] transaction","detail":"{read_only:false; response_revision:104; number_of_response:1; }","duration":"157.669227ms","start":"2026-01-21T00:41:26.917415Z","end":"2026-01-21T00:41:27.075085Z","steps":["trace[1751120137] 'process raft request' (duration: 60.507199ms)","trace[1751120137] 'compare' (duration: 97.044905ms)"],"step_count":2} {"level":"warn","ts":"2026-01-21T00:41:27.075443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.623492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:kubelet-api-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-21T00:41:27.075557Z","caller":"traceutil/trace.go:171","msg":"trace[1413068246] range","detail":"{range_begin:/registry/clusterroles/system:kubelet-api-admin; range_end:; response_count:0; response_revision:104; }","duration":"156.785615ms","start":"2026-01-21T00:41:26.918756Z","end":"2026-01-21T00:41:27.075541Z","steps":["trace[1413068246] 'agreement among raft nodes before linearized reading' (duration: 156.407806ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:41:27.517203Z","caller":"traceutil/trace.go:171","msg":"trace[60017951] transaction","detail":"{read_only:false; response_revision:118; number_of_response:1; }","duration":"120.594259ms","start":"2026-01-21T00:41:27.396583Z","end":"2026-01-21T00:41:27.517177Z","steps":["trace[60017951] 'process raft request' (duration: 56.637327ms)","trace[60017951] 'compare' (duration: 63.816939ms)"],"step_count":2} {"level":"info","ts":"2026-01-21T00:41:27.51725Z","caller":"traceutil/trace.go:171","msg":"trace[1761348768] linearizableReadLoop","detail":"{readStateIndex:123; appliedIndex:122; }","duration":"115.60254ms","start":"2026-01-21T00:41:27.401567Z","end":"2026-01-21T00:41:27.517169Z","steps":["trace[1761348768] 'read index received' (duration: 51.606107ms)","trace[1761348768] 'applied index is now lower than readState.Index' (duration: 63.995223ms)"],"step_count":2} {"level":"warn","ts":"2026-01-21T00:41:27.518115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.44427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:persistent-volume-provisioner\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-21T00:41:27.51824Z","caller":"traceutil/trace.go:171","msg":"trace[392010461] range","detail":"{range_begin:/registry/clusterroles/system:persistent-volume-provisioner; range_end:; response_count:0; response_revision:118; }","duration":"116.695916ms","start":"2026-01-21T00:41:27.40153Z","end":"2026-01-21T00:41:27.518226Z","steps":["trace[392010461] 'agreement among raft nodes before linearized reading' (duration: 116.401299ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:41:27.859351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.123122ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-01-21T00:41:27.859617Z","caller":"traceutil/trace.go:171","msg":"trace[1171368678] linearizableReadLoop","detail":"{readStateIndex:138; appliedIndex:136; }","duration":"149.420379ms","start":"2026-01-21T00:41:27.710185Z","end":"2026-01-21T00:41:27.859605Z","steps":["trace[1171368678] 'read index received' (duration: 42.99039ms)","trace[1171368678] 'applied index is now lower than readState.Index' (duration: 106.429319ms)"],"step_count":2} {"level":"info","ts":"2026-01-21T00:41:27.859712Z","caller":"traceutil/trace.go:171","msg":"trace[1040171865] transaction","detail":"{read_only:false; response_revision:132; number_of_response:1; }","duration":"152.203605ms","start":"2026-01-21T00:41:27.707495Z","end":"2026-01-21T00:41:27.859699Z","steps":["trace[1040171865] 'process raft request' (duration: 45.682124ms)","trace[1040171865] 'compare' (duration: 106.002819ms)"],"step_count":2} {"level":"warn","ts":"2026-01-21T00:41:27.85974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.547642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:kubelet-serving-approver\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-21T00:41:27.859853Z","caller":"traceutil/trace.go:171","msg":"trace[2033944392] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:kubelet-serving-approver; range_end:; response_count:0; response_revision:133; }","duration":"149.660635ms","start":"2026-01-21T00:41:27.710159Z","end":"2026-01-21T00:41:27.85982Z","steps":["trace[2033944392] 'agreement among raft nodes before linearized reading' (duration: 149.479831ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:41:27.859758Z","caller":"traceutil/trace.go:171","msg":"trace[1236186950] transaction","detail":"{read_only:false; response_revision:133; number_of_response:1; }","duration":"150.933896ms","start":"2026-01-21T00:41:27.708819Z","end":"2026-01-21T00:41:27.859753Z","steps":["trace[1236186950] 'process raft request' (duration: 150.625558ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:41:28.063686Z","caller":"traceutil/trace.go:171","msg":"trace[556867413] transaction","detail":"{read_only:false; response_revision:140; number_of_response:1; }","duration":"111.429399ms","start":"2026-01-21T00:41:27.952237Z","end":"2026-01-21T00:41:28.063667Z","steps":["trace[556867413] 'process raft request' (duration: 111.03713ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:41:28.721464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.082265ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-01-21T00:41:28.721645Z","caller":"traceutil/trace.go:171","msg":"trace[925893940] transaction","detail":"{read_only:false; response_revision:152; number_of_response:1; }","duration":"456.032834ms","start":"2026-01-21T00:41:28.26559Z","end":"2026-01-21T00:41:28.721622Z","steps":["trace[925893940] 'process raft request' (duration: 133.706043ms)","trace[925893940] 'compare' (duration: 321.590383ms)"],"step_count":2} {"level":"warn","ts":"2026-01-21T00:41:28.721716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:41:28.265576Z","time spent":"456.111356ms","remote":"127.0.0.1:43666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":945,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-21T00:41:29.120025Z","caller":"traceutil/trace.go:171","msg":"trace[476951894] transaction","detail":"{read_only:false; response_revision:162; number_of_response:1; }","duration":"144.390818ms","start":"2026-01-21T00:41:28.974995Z","end":"2026-01-21T00:41:29.119386Z","steps":["trace[476951894] 'process raft request' (duration: 103.1841ms)","trace[476951894] 'compare' (duration: 39.993548ms)"],"step_count":2} {"level":"info","ts":"2026-01-21T00:41:29.309134Z","caller":"traceutil/trace.go:171","msg":"trace[1161929611] transaction","detail":"{read_only:false; response_revision:169; number_of_response:1; }","duration":"101.056981ms","start":"2026-01-21T00:41:29.208049Z","end":"2026-01-21T00:41:29.309106Z","steps":["trace[1161929611] 'process raft request' (duration: 52.064558ms)","trace[1161929611] 'compare' (duration: 48.556893ms)"],"step_count":2} {"level":"warn","ts":"2026-01-21T00:41:29.541792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.565392ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-01-21T00:41:29.541907Z","caller":"traceutil/trace.go:171","msg":"trace[116527400] transaction","detail":"{read_only:false; response_revision:175; number_of_response:1; }","duration":"149.347077ms","start":"2026-01-21T00:41:29.392536Z","end":"2026-01-21T00:41:29.541883Z","steps":["trace[116527400] 'process raft request' (duration: 27.632531ms)","trace[116527400] 'compare' (duration: 112.004564ms)"],"step_count":2} {"level":"warn","ts":"2026-01-21T00:41:34.443226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.601922ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-21T00:41:34.443462Z","caller":"traceutil/trace.go:171","msg":"trace[609529874] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"162.447741ms","start":"2026-01-21T00:41:34.280995Z","end":"2026-01-21T00:41:34.443442Z","steps":["trace[609529874] 'process raft request' (duration: 162.40569ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:41:34.443838Z","caller":"traceutil/trace.go:171","msg":"trace[154081812] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"691.837432ms","start":"2026-01-21T00:41:33.751988Z","end":"2026-01-21T00:41:34.443825Z","steps":["trace[154081812] 'process raft request' (duration: 324.526633ms)","trace[154081812] 'compare' (duration: 366.48453ms)"],"step_count":2} {"level":"warn","ts":"2026-01-21T00:41:34.443915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:41:33.751973Z","time spent":"691.907964ms","remote":"127.0.0.1:43590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-21T00:41:34.444286Z","caller":"traceutil/trace.go:171","msg":"trace[1252264759] transaction","detail":"{read_only:false; response_revision:323; number_of_response:1; }","duration":"689.141588ms","start":"2026-01-21T00:41:33.755133Z","end":"2026-01-21T00:41:34.444275Z","steps":["trace[1252264759] 'process raft request' (duration: 688.212856ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:41:34.444345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:41:33.755127Z","time spent":"689.193909ms","remote":"127.0.0.1:43556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":192,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-21T00:41:34.444461Z","caller":"traceutil/trace.go:171","msg":"trace[211362128] linearizableReadLoop","detail":"{readStateIndex:336; appliedIndex:334; }","duration":"372.388661ms","start":"2026-01-21T00:41:34.072062Z","end":"2026-01-21T00:41:34.44445Z","steps":["trace[211362128] 'read index received' (duration: 4.464217ms)","trace[211362128] 'applied index is now lower than readState.Index' (duration: 367.923304ms)"],"step_count":2} {"level":"warn","ts":"2026-01-21T00:41:34.444519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.452132ms","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-01-21T00:41:34.444543Z","caller":"traceutil/trace.go:171","msg":"trace[2544327] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:324; }","duration":"372.477823ms","start":"2026-01-21T00:41:34.072057Z","end":"2026-01-21T00:41:34.444535Z","steps":["trace[2544327] 'agreement among raft nodes before linearized reading' (duration: 372.426772ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:41:34.444573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:41:34.072002Z","time spent":"372.563715ms","remote":"127.0.0.1:43590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-01-21T00:41:59.124462Z","caller":"traceutil/trace.go:171","msg":"trace[666024040] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"299.499432ms","start":"2026-01-21T00:41:58.824941Z","end":"2026-01-21T00:41:59.12444Z","steps":["trace[666024040] 'process raft request' (duration: 299.292997ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:42:00.106944Z","caller":"traceutil/trace.go:171","msg":"trace[1932338624] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"226.680037ms","start":"2026-01-21T00:41:59.880249Z","end":"2026-01-21T00:42:00.106929Z","steps":["trace[1932338624] 'process raft request' (duration: 226.584454ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:42:00.444686Z","caller":"traceutil/trace.go:171","msg":"trace[1511905767] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"302.540965ms","start":"2026-01-21T00:42:00.142128Z","end":"2026-01-21T00:42:00.444669Z","steps":["trace[1511905767] 'process raft request' (duration: 301.216613ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:42:00.444786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:42:00.142116Z","time spent":"302.617107ms","remote":"127.0.0.1:43590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-21T00:42:02.360039Z","caller":"traceutil/trace.go:171","msg":"trace[1923014409] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"246.347643ms","start":"2026-01-21T00:42:02.113351Z","end":"2026-01-21T00:42:02.359699Z","steps":["trace[1923014409] 'process raft request' (duration: 246.141678ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:42:02.746837Z","caller":"traceutil/trace.go:171","msg":"trace[367478632] linearizableReadLoop","detail":"{readStateIndex:553; appliedIndex:552; }","duration":"149.820524ms","start":"2026-01-21T00:42:02.597001Z","end":"2026-01-21T00:42:02.746822Z","steps":["trace[367478632] 'read index received' (duration: 65.006832ms)","trace[367478632] 'applied index is now lower than readState.Index' (duration: 84.813082ms)"],"step_count":2} {"level":"warn","ts":"2026-01-21T00:42:02.746927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.919386ms","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-01-21T00:42:02.746944Z","caller":"traceutil/trace.go:171","msg":"trace[1981983961] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:532; }","duration":"149.959866ms","start":"2026-01-21T00:42:02.596978Z","end":"2026-01-21T00:42:02.746938Z","steps":["trace[1981983961] 'agreement among raft nodes before linearized reading' (duration: 149.898845ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:42:02.747114Z","caller":"traceutil/trace.go:171","msg":"trace[1846459508] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"153.256044ms","start":"2026-01-21T00:42:02.593851Z","end":"2026-01-21T00:42:02.747107Z","steps":["trace[1846459508] 'process raft request' (duration: 68.176576ms)","trace[1846459508] 'compare' (duration: 84.600156ms)"],"step_count":2} {"level":"warn","ts":"2026-01-21T00:42:08.87805Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.209568697s","expected-duration":"1s"} {"level":"info","ts":"2026-01-21T00:42:08.878367Z","caller":"traceutil/trace.go:171","msg":"trace[258389346] linearizableReadLoop","detail":"{readStateIndex:569; appliedIndex:568; }","duration":"475.249011ms","start":"2026-01-21T00:42:08.403094Z","end":"2026-01-21T00:42:08.878343Z","steps":["trace[258389346] 'read index received' (duration: 475.075547ms)","trace[258389346] 'applied index is now lower than readState.Index' (duration: 172.744µs)"],"step_count":2} {"level":"info","ts":"2026-01-21T00:42:08.878678Z","caller":"traceutil/trace.go:171","msg":"trace[797213573] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"1.210217072s","start":"2026-01-21T00:42:07.668378Z","end":"2026-01-21T00:42:08.878595Z","steps":["trace[797213573] 'process raft request' (duration: 1.209843593s)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:42:08.878776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:42:07.668362Z","time spent":"1.210349445s","remote":"127.0.0.1:43546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-21T00:42:08.879012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.912397ms","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-01-21T00:42:08.879045Z","caller":"traceutil/trace.go:171","msg":"trace[402322210] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:547; }","duration":"475.971659ms","start":"2026-01-21T00:42:08.403062Z","end":"2026-01-21T00:42:08.879034Z","steps":["trace[402322210] 'agreement among raft nodes before linearized reading' (duration: 475.864945ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:42:08.8791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:42:08.403046Z","time spent":"476.04721ms","remote":"127.0.0.1:43590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-21T00:42:08.879213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.946437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-21T00:42:08.87933Z","caller":"traceutil/trace.go:171","msg":"trace[23397294] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:547; }","duration":"419.124961ms","start":"2026-01-21T00:42:08.460172Z","end":"2026-01-21T00:42:08.879297Z","steps":["trace[23397294] 'agreement among raft nodes before linearized reading' (duration: 418.911226ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:42:08.879389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:42:08.460152Z","time spent":"419.227063ms","remote":"127.0.0.1:43386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-21T00:42:08.879585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.236681ms","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-01-21T00:42:08.879618Z","caller":"traceutil/trace.go:171","msg":"trace[344151022] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:547; }","duration":"112.272502ms","start":"2026-01-21T00:42:08.767338Z","end":"2026-01-21T00:42:08.87961Z","steps":["trace[344151022] 'agreement among raft nodes before linearized reading' (duration: 112.19172ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:42:08.87981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.612271ms","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-01-21T00:42:08.879844Z","caller":"traceutil/trace.go:171","msg":"trace[259637205] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:547; }","duration":"330.687162ms","start":"2026-01-21T00:42:08.549147Z","end":"2026-01-21T00:42:08.879834Z","steps":["trace[259637205] 'agreement among raft nodes before linearized reading' (duration: 330.529349ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:42:08.879871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:42:08.54912Z","time spent":"330.745494ms","remote":"127.0.0.1:43590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-21T00:42:08.884943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.473887ms","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-01-21T00:42:08.884986Z","caller":"traceutil/trace.go:171","msg":"trace[948476256] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:547; }","duration":"109.535058ms","start":"2026-01-21T00:42:08.775441Z","end":"2026-01-21T00:42:08.884976Z","steps":["trace[948476256] 'agreement among raft nodes before linearized reading' (duration: 104.256242ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:42:09.690168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.511807ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-21T00:42:09.690484Z","caller":"traceutil/trace.go:171","msg":"trace[1722276406] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"774.998759ms","start":"2026-01-21T00:42:08.915469Z","end":"2026-01-21T00:42:09.690468Z","steps":["trace[1722276406] 'process raft request' (duration: 774.958029ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:42:09.690547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:42:08.915464Z","time spent":"775.053241ms","remote":"127.0.0.1:43590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-21T00:42:09.690779Z","caller":"traceutil/trace.go:171","msg":"trace[595878055] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"781.980284ms","start":"2026-01-21T00:42:08.908787Z","end":"2026-01-21T00:42:09.690768Z","steps":["trace[595878055] 'process raft request' (duration: 679.777861ms)","trace[595878055] 'compare' (duration: 99.266584ms)"],"step_count":2} {"level":"warn","ts":"2026-01-21T00:42:09.69083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:42:08.908776Z","time spent":"782.026296ms","remote":"127.0.0.1:43590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-21T00:42:09.690935Z","caller":"traceutil/trace.go:171","msg":"trace[2102350124] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"779.020615ms","start":"2026-01-21T00:42:08.911906Z","end":"2026-01-21T00:42:09.690927Z","steps":["trace[2102350124] 'process raft request' (duration: 778.397931ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:42:09.690979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:42:08.911885Z","time spent":"779.069786ms","remote":"127.0.0.1:43590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-21T00:42:09.691087Z","caller":"traceutil/trace.go:171","msg":"trace[791450612] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"776.619598ms","start":"2026-01-21T00:42:08.91446Z","end":"2026-01-21T00:42:09.691079Z","steps":["trace[791450612] 'process raft request' (duration: 775.934522ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:42:09.691132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:42:08.914452Z","time spent":"776.653999ms","remote":"127.0.0.1:43590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-21T00:42:10.807514Z","caller":"traceutil/trace.go:171","msg":"trace[342728215] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"112.047437ms","start":"2026-01-21T00:42:10.695446Z","end":"2026-01-21T00:42:10.807493Z","steps":["trace[342728215] 'process raft request' (duration: 85.244682ms)","trace[342728215] 'compare' (duration: 26.684853ms)"],"step_count":2} {"level":"warn","ts":"2026-01-21T00:42:11.753219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.131917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-21T00:42:11.753298Z","caller":"traceutil/trace.go:171","msg":"trace[1709727368] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:556; }","duration":"324.2567ms","start":"2026-01-21T00:42:11.429028Z","end":"2026-01-21T00:42:11.753284Z","steps":["trace[1709727368] 'range keys from in-memory index tree' (duration: 324.071116ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:42:11.753325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:42:11.428997Z","time spent":"324.323201ms","remote":"127.0.0.1:43386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-21T00:42:13.575865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.465118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-21T00:42:13.575964Z","caller":"traceutil/trace.go:171","msg":"trace[1678430869] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:565; }","duration":"147.608612ms","start":"2026-01-21T00:42:13.428332Z","end":"2026-01-21T00:42:13.57594Z","steps":["trace[1678430869] 'range keys from in-memory index tree' (duration: 147.401886ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:42:13.576029Z","caller":"traceutil/trace.go:171","msg":"trace[1628630833] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"120.58129ms","start":"2026-01-21T00:42:13.455413Z","end":"2026-01-21T00:42:13.575995Z","steps":["trace[1628630833] 'process raft request' (duration: 94.540233ms)","trace[1628630833] 'compare' (duration: 25.775971ms)"],"step_count":2} {"level":"info","ts":"2026-01-21T00:42:16.151274Z","caller":"traceutil/trace.go:171","msg":"trace[257949743] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"113.43241ms","start":"2026-01-21T00:42:16.03783Z","end":"2026-01-21T00:42:16.151262Z","steps":["trace[257949743] 'process raft request' (duration: 113.117542ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:42:22.450026Z","caller":"traceutil/trace.go:171","msg":"trace[1586418780] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"106.809953ms","start":"2026-01-21T00:42:22.343198Z","end":"2026-01-21T00:42:22.450008Z","steps":["trace[1586418780] 'process raft request' (duration: 98.960796ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:42:22.744925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.770909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-21T00:42:22.744973Z","caller":"traceutil/trace.go:171","msg":"trace[113583063] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:677; }","duration":"124.85361ms","start":"2026-01-21T00:42:22.620109Z","end":"2026-01-21T00:42:22.744963Z","steps":["trace[113583063] 'count revisions from in-memory index tree' (duration: 124.705237ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:42:23.233136Z","caller":"traceutil/trace.go:171","msg":"trace[700208855] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"129.501531ms","start":"2026-01-21T00:42:23.103617Z","end":"2026-01-21T00:42:23.233118Z","steps":["trace[700208855] 'process raft request' (duration: 129.401469ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:42:24.295407Z","caller":"traceutil/trace.go:171","msg":"trace[1341187300] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"292.498107ms","start":"2026-01-21T00:42:24.002894Z","end":"2026-01-21T00:42:24.295392Z","steps":["trace[1341187300] 'process raft request' (duration: 292.418215ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:42:24.436428Z","caller":"traceutil/trace.go:171","msg":"trace[1265500545] linearizableReadLoop","detail":"{readStateIndex:711; appliedIndex:709; }","duration":"390.517501ms","start":"2026-01-21T00:42:24.045899Z","end":"2026-01-21T00:42:24.436417Z","steps":["trace[1265500545] 'read index received' (duration: 249.332123ms)","trace[1265500545] 'applied index is now lower than readState.Index' (duration: 141.184868ms)"],"step_count":2} {"level":"info","ts":"2026-01-21T00:42:24.436592Z","caller":"traceutil/trace.go:171","msg":"trace[845327673] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"432.157999ms","start":"2026-01-21T00:42:24.004426Z","end":"2026-01-21T00:42:24.436584Z","steps":["trace[845327673] 'process raft request' (duration: 406.118962ms)","trace[845327673] 'compare' (duration: 25.819902ms)"],"step_count":2} {"level":"warn","ts":"2026-01-21T00:42:24.436648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:42:24.00442Z","time spent":"432.18655ms","remote":"127.0.0.1:43590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-21T00:42:24.436768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.874849ms","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-01-21T00:42:24.436784Z","caller":"traceutil/trace.go:171","msg":"trace[13354432] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:685; }","duration":"390.981702ms","start":"2026-01-21T00:42:24.045798Z","end":"2026-01-21T00:42:24.43678Z","steps":["trace[13354432] 'agreement among raft nodes before linearized reading' (duration: 390.929361ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:42:24.436799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:42:24.045705Z","time spent":"391.091234ms","remote":"127.0.0.1:43590","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-01-21T00:42:24.436904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.643907ms","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-01-21T00:42:24.436917Z","caller":"traceutil/trace.go:171","msg":"trace[1693883982] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:685; }","duration":"361.675998ms","start":"2026-01-21T00:42:24.075237Z","end":"2026-01-21T00:42:24.436913Z","steps":["trace[1693883982] 'agreement among raft nodes before linearized reading' (duration: 361.637567ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:42:24.436929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:42:24.075112Z","time spent":"361.814461ms","remote":"127.0.0.1:43590","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-01-21T00:42:24.437107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.24268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-01-21T00:42:24.437122Z","caller":"traceutil/trace.go:171","msg":"trace[523950388] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:685; }","duration":"176.301932ms","start":"2026-01-21T00:42:24.260816Z","end":"2026-01-21T00:42:24.437118Z","steps":["trace[523950388] 'agreement among raft nodes before linearized reading' (duration: 176.259981ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:42:26.795762Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.096185648s","expected-duration":"1s"} {"level":"info","ts":"2026-01-21T00:42:26.796576Z","caller":"traceutil/trace.go:171","msg":"trace[1860206435] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"1.097057839s","start":"2026-01-21T00:42:25.699498Z","end":"2026-01-21T00:42:26.796556Z","steps":["trace[1860206435] 'process raft request' (duration: 1.096911005s)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:42:26.796715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:42:25.699479Z","time spent":"1.097153241s","remote":"127.0.0.1:43590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-21T00:42:27.015726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.324959ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-01-21T00:42:27.015855Z","caller":"traceutil/trace.go:171","msg":"trace[2059277435] linearizableReadLoop","detail":"{readStateIndex:717; appliedIndex:715; }","duration":"717.364523ms","start":"2026-01-21T00:42:26.298478Z","end":"2026-01-21T00:42:27.015842Z","steps":["trace[2059277435] 'read index received' (duration: 497.898068ms)","trace[2059277435] 'applied index is now lower than readState.Index' (duration: 219.465505ms)"],"step_count":2} {"level":"warn","ts":"2026-01-21T00:42:27.01586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:42:26.419156Z","time spent":"596.701082ms","remote":"127.0.0.1:43444","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-01-21T00:42:27.016008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"717.523017ms","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-01-21T00:42:27.016029Z","caller":"traceutil/trace.go:171","msg":"trace[1159835898] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:690; }","duration":"717.589249ms","start":"2026-01-21T00:42:26.298433Z","end":"2026-01-21T00:42:27.016022Z","steps":["trace[1159835898] 'agreement among raft nodes before linearized reading' (duration: 717.473226ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:42:27.016051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:42:26.298414Z","time spent":"717.630659ms","remote":"127.0.0.1:43590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-21T00:42:27.016154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.975602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-21T00:42:27.016179Z","caller":"traceutil/trace.go:171","msg":"trace[2030640609] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:690; }","duration":"590.011933ms","start":"2026-01-21T00:42:26.426159Z","end":"2026-01-21T00:42:27.016171Z","steps":["trace[2030640609] 'agreement among raft nodes before linearized reading' (duration: 589.973882ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:42:27.016197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:42:26.426152Z","time spent":"590.040694ms","remote":"127.0.0.1:43386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-21T00:42:27.016318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.229883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-01-21T00:42:27.01633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.703257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-01-21T00:42:27.016339Z","caller":"traceutil/trace.go:171","msg":"trace[1503568379] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:690; }","duration":"519.269135ms","start":"2026-01-21T00:42:26.497063Z","end":"2026-01-21T00:42:27.016333Z","steps":["trace[1503568379] 'agreement among raft nodes before linearized reading' (duration: 519.188923ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:42:27.01635Z","caller":"traceutil/trace.go:171","msg":"trace[2001006294] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:690; }","duration":"522.740708ms","start":"2026-01-21T00:42:26.493602Z","end":"2026-01-21T00:42:27.016343Z","steps":["trace[2001006294] 'agreement among raft nodes before linearized reading' (duration: 522.686007ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:42:27.01636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:42:26.497053Z","time spent":"519.302295ms","remote":"127.0.0.1:43590","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-01-21T00:42:27.016366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:42:26.493591Z","time spent":"522.771628ms","remote":"127.0.0.1:43590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-21T00:42:27.016449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.779675ms","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-01-21T00:42:27.016465Z","caller":"traceutil/trace.go:171","msg":"trace[1116997515] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:690; }","duration":"575.795537ms","start":"2026-01-21T00:42:26.440665Z","end":"2026-01-21T00:42:27.016461Z","steps":["trace[1116997515] 'agreement among raft nodes before linearized reading' (duration: 575.749806ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:42:27.01648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:42:26.440635Z","time spent":"575.841937ms","remote":"127.0.0.1:43590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-21T00:42:27.016496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.721902ms","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-01-21T00:42:27.016515Z","caller":"traceutil/trace.go:171","msg":"trace[1887664389] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:690; }","duration":"518.772013ms","start":"2026-01-21T00:42:26.497737Z","end":"2026-01-21T00:42:27.016509Z","steps":["trace[1887664389] 'agreement among raft nodes before linearized reading' (duration: 518.720942ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:42:27.016531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:42:26.497715Z","time spent":"518.811884ms","remote":"127.0.0.1:43590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-01-21T00:42:27.167025Z","caller":"traceutil/trace.go:171","msg":"trace[619438515] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"145.208444ms","start":"2026-01-21T00:42:27.0218Z","end":"2026-01-21T00:42:27.167008Z","steps":["trace[619438515] 'process raft request' (duration: 143.871761ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:42:27.167172Z","caller":"traceutil/trace.go:171","msg":"trace[693475156] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"145.094991ms","start":"2026-01-21T00:42:27.022071Z","end":"2026-01-21T00:42:27.167166Z","steps":["trace[693475156] 'process raft request' (duration: 144.889137ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:42:27.167374Z","caller":"traceutil/trace.go:171","msg":"trace[144358727] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"143.949615ms","start":"2026-01-21T00:42:27.023411Z","end":"2026-01-21T00:42:27.167361Z","steps":["trace[144358727] 'process raft request' (duration: 143.73106ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:42:27.167484Z","caller":"traceutil/trace.go:171","msg":"trace[1665624843] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"141.184878ms","start":"2026-01-21T00:42:27.026292Z","end":"2026-01-21T00:42:27.167477Z","steps":["trace[1665624843] 'process raft request' (duration: 141.020434ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:42:27.167584Z","caller":"traceutil/trace.go:171","msg":"trace[833713792] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"141.146397ms","start":"2026-01-21T00:42:27.026432Z","end":"2026-01-21T00:42:27.167578Z","steps":["trace[833713792] 'process raft request' (duration: 141.021854ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:42:28.252215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.283783ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-21T00:42:28.252457Z","caller":"traceutil/trace.go:171","msg":"trace[607250751] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"267.342049ms","start":"2026-01-21T00:42:27.985054Z","end":"2026-01-21T00:42:28.252396Z","steps":["trace[607250751] 'process raft request' (duration: 135.026112ms)","trace[607250751] 'compare' (duration: 130.866744ms)"],"step_count":2} {"level":"info","ts":"2026-01-21T00:42:28.512099Z","caller":"traceutil/trace.go:171","msg":"trace[486925840] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"522.189225ms","start":"2026-01-21T00:42:27.989887Z","end":"2026-01-21T00:42:28.512076Z","steps":["trace[486925840] 'process raft request' (duration: 521.98553ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:42:28.512235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:42:27.989879Z","time spent":"522.285728ms","remote":"127.0.0.1:43818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3782,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-21T00:42:28.512594Z","caller":"traceutil/trace.go:171","msg":"trace[1265249598] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"522.661136ms","start":"2026-01-21T00:42:27.989922Z","end":"2026-01-21T00:42:28.512583Z","steps":["trace[1265249598] 'process raft request' (duration: 522.084072ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:42:28.512656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:42:27.989919Z","time spent":"522.704276ms","remote":"127.0.0.1:43598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1458,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-21T00:42:28.512661Z","caller":"traceutil/trace.go:171","msg":"trace[1597978960] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"522.702806ms","start":"2026-01-21T00:42:27.989947Z","end":"2026-01-21T00:42:28.51265Z","steps":["trace[1597978960] 'process raft request' (duration: 522.45431ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:42:28.512711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:42:27.989944Z","time spent":"522.740037ms","remote":"127.0.0.1:43546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4809,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-21T00:42:28.523564Z","caller":"traceutil/trace.go:171","msg":"trace[1961236117] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"533.470752ms","start":"2026-01-21T00:42:27.990081Z","end":"2026-01-21T00:42:28.523552Z","steps":["trace[1961236117] 'process raft request' (duration: 522.527042ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:42:28.523645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:42:27.990053Z","time spent":"533.558074ms","remote":"127.0.0.1:43444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-21T00:42:28.528225Z","caller":"traceutil/trace.go:171","msg":"trace[1016268135] linearizableReadLoop","detail":"{readStateIndex:753; appliedIndex:747; }","duration":"473.607012ms","start":"2026-01-21T00:42:28.054582Z","end":"2026-01-21T00:42:28.528189Z","steps":["trace[1016268135] 'read index received' (duration: 65.568245ms)","trace[1016268135] 'applied index is now lower than readState.Index' (duration: 408.037307ms)"],"step_count":2} {"level":"info","ts":"2026-01-21T00:42:28.528436Z","caller":"traceutil/trace.go:171","msg":"trace[16281999] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"531.27679ms","start":"2026-01-21T00:42:27.997152Z","end":"2026-01-21T00:42:28.528429Z","steps":["trace[16281999] 'process raft request' (duration: 525.597876ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:42:28.528493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:42:27.997143Z","time spent":"531.314891ms","remote":"127.0.0.1:43784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4164,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-21T00:42:28.528615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.030542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-21T00:42:28.528646Z","caller":"traceutil/trace.go:171","msg":"trace[12283716] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:724; }","duration":"474.078423ms","start":"2026-01-21T00:42:28.054558Z","end":"2026-01-21T00:42:28.528637Z","steps":["trace[12283716] 'agreement among raft nodes before linearized reading' (duration: 474.028172ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:42:28.528663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:42:28.054545Z","time spent":"474.114174ms","remote":"127.0.0.1:43606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":29,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-01-21T00:42:28.540155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.436191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-21T00:42:28.540278Z","caller":"traceutil/trace.go:171","msg":"trace[156318547] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:725; }","duration":"113.589204ms","start":"2026-01-21T00:42:28.426675Z","end":"2026-01-21T00:42:28.540264Z","steps":["trace[156318547] 'agreement among raft nodes before linearized reading' (duration: 113.447341ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:42:28.540533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.458995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-21T00:42:28.541471Z","caller":"traceutil/trace.go:171","msg":"trace[1186626056] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:725; }","duration":"422.491369ms","start":"2026-01-21T00:42:28.118973Z","end":"2026-01-21T00:42:28.541464Z","steps":["trace[1186626056] 'agreement among raft nodes before linearized reading' (duration: 421.533816ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:42:28.54153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:42:28.118955Z","time spent":"422.55249ms","remote":"127.0.0.1:43720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":29,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-01-21T00:42:30.858022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.693756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-21T00:42:30.858077Z","caller":"traceutil/trace.go:171","msg":"trace[1601572164] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:734; }","duration":"148.757688ms","start":"2026-01-21T00:42:30.709306Z","end":"2026-01-21T00:42:30.858064Z","steps":["trace[1601572164] 'count revisions from in-memory index tree' (duration: 148.639965ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:42:31.316631Z","caller":"traceutil/trace.go:171","msg":"trace[1263440377] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"119.744471ms","start":"2026-01-21T00:42:31.196867Z","end":"2026-01-21T00:42:31.316612Z","steps":["trace[1263440377] 'process raft request' (duration: 119.624408ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:42:31.317582Z","caller":"traceutil/trace.go:171","msg":"trace[1766329007] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"117.707322ms","start":"2026-01-21T00:42:31.199857Z","end":"2026-01-21T00:42:31.317564Z","steps":["trace[1766329007] 'process raft request' (duration: 117.681021ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:42:31.317921Z","caller":"traceutil/trace.go:171","msg":"trace[706172300] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"119.736909ms","start":"2026-01-21T00:42:31.198177Z","end":"2026-01-21T00:42:31.317914Z","steps":["trace[706172300] 'process raft request' (duration: 119.216437ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:42:31.318016Z","caller":"traceutil/trace.go:171","msg":"trace[1484096756] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"118.210924ms","start":"2026-01-21T00:42:31.199799Z","end":"2026-01-21T00:42:31.31801Z","steps":["trace[1484096756] 'process raft request' (duration: 117.694711ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:42:31.318083Z","caller":"traceutil/trace.go:171","msg":"trace[1370292922] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"118.242875ms","start":"2026-01-21T00:42:31.199836Z","end":"2026-01-21T00:42:31.318078Z","steps":["trace[1370292922] 'process raft request' (duration: 117.681531ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:42:33.775037Z","caller":"traceutil/trace.go:171","msg":"trace[1069450949] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"445.301171ms","start":"2026-01-21T00:42:33.32972Z","end":"2026-01-21T00:42:33.775021Z","steps":["trace[1069450949] 'process raft request' (duration: 445.211909ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:42:33.775147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:42:33.329712Z","time spent":"445.370612ms","remote":"127.0.0.1:43590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-21T00:42:33.777816Z","caller":"traceutil/trace.go:171","msg":"trace[1594114120] linearizableReadLoop","detail":"{readStateIndex:774; appliedIndex:769; }","duration":"351.587769ms","start":"2026-01-21T00:42:33.426216Z","end":"2026-01-21T00:42:33.777804Z","steps":["trace[1594114120] 'read index received' (duration: 348.772922ms)","trace[1594114120] 'applied index is now lower than readState.Index' (duration: 2.814217ms)"],"step_count":2} {"level":"warn","ts":"2026-01-21T00:42:33.777879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.658661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-21T00:42:33.777899Z","caller":"traceutil/trace.go:171","msg":"trace[2076298486] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:744; }","duration":"351.706022ms","start":"2026-01-21T00:42:33.426186Z","end":"2026-01-21T00:42:33.777892Z","steps":["trace[2076298486] 'agreement among raft nodes before linearized reading' (duration: 351.652901ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:42:33.777917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:42:33.426169Z","time spent":"351.742973ms","remote":"127.0.0.1:43386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-01-21T00:42:33.778062Z","caller":"traceutil/trace.go:171","msg":"trace[1253674278] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"448.21167ms","start":"2026-01-21T00:42:33.329845Z","end":"2026-01-21T00:42:33.778057Z","steps":["trace[1253674278] 'process raft request' (duration: 447.854341ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:42:33.778093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:42:33.329839Z","time spent":"448.23478ms","remote":"127.0.0.1:43590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-21T00:42:33.778152Z","caller":"traceutil/trace.go:171","msg":"trace[1342551486] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"448.147769ms","start":"2026-01-21T00:42:33.329999Z","end":"2026-01-21T00:42:33.778147Z","steps":["trace[1342551486] 'process raft request' (duration: 447.735419ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:42:33.778174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:42:33.329991Z","time spent":"448.170079ms","remote":"127.0.0.1:43590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-21T00:42:33.778242Z","caller":"traceutil/trace.go:171","msg":"trace[668461880] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"448.171389ms","start":"2026-01-21T00:42:33.330053Z","end":"2026-01-21T00:42:33.778225Z","steps":["trace[668461880] 'process raft request' (duration: 447.700418ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:42:33.778266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:42:33.330047Z","time spent":"448.20533ms","remote":"127.0.0.1:43590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-21T00:42:33.778332Z","caller":"traceutil/trace.go:171","msg":"trace[1975270607] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"444.672606ms","start":"2026-01-21T00:42:33.333654Z","end":"2026-01-21T00:42:33.778326Z","steps":["trace[1975270607] 'process raft request' (duration: 444.126213ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:42:33.778356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:42:33.333645Z","time spent":"444.697007ms","remote":"127.0.0.1:43590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-21T00:42:38.010448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.725306ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-21T00:42:38.010559Z","caller":"traceutil/trace.go:171","msg":"trace[1917349221] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"164.42324ms","start":"2026-01-21T00:42:37.846118Z","end":"2026-01-21T00:42:38.010541Z","steps":["trace[1917349221] 'process raft request' (duration: 53.536659ms)","trace[1917349221] 'compare' (duration: 110.450719ms)"],"step_count":2} {"level":"info","ts":"2026-01-21T00:42:38.160936Z","caller":"traceutil/trace.go:171","msg":"trace[761578266] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"311.6425ms","start":"2026-01-21T00:42:37.849273Z","end":"2026-01-21T00:42:38.160916Z","steps":["trace[761578266] 'process raft request' (duration: 311.508537ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:42:38.160984Z","caller":"traceutil/trace.go:171","msg":"trace[1816588397] linearizableReadLoop","detail":"{readStateIndex:794; appliedIndex:790; }","duration":"272.441911ms","start":"2026-01-21T00:42:37.888525Z","end":"2026-01-21T00:42:38.160967Z","steps":["trace[1816588397] 'read index received' (duration: 11.134794ms)","trace[1816588397] 'applied index is now lower than readState.Index' (duration: 261.306017ms)"],"step_count":2} {"level":"warn","ts":"2026-01-21T00:42:38.161065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:42:37.849264Z","time spent":"311.737453ms","remote":"127.0.0.1:43590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-21T00:42:38.161091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.554254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-21T00:42:38.161124Z","caller":"traceutil/trace.go:171","msg":"trace[140472168] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:762; }","duration":"272.647516ms","start":"2026-01-21T00:42:37.888468Z","end":"2026-01-21T00:42:38.161115Z","steps":["trace[140472168] 'agreement among raft nodes before linearized reading' (duration: 272.571104ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:42:38.16135Z","caller":"traceutil/trace.go:171","msg":"trace[1718918188] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"314.519349ms","start":"2026-01-21T00:42:37.846814Z","end":"2026-01-21T00:42:38.161333Z","steps":["trace[1718918188] 'process raft request' (duration: 295.300394ms)","trace[1718918188] 'compare' (duration: 18.556999ms)"],"step_count":2} {"level":"warn","ts":"2026-01-21T00:42:38.161407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:42:37.846804Z","time spent":"314.57206ms","remote":"127.0.0.1:43590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-21T00:42:38.16146Z","caller":"traceutil/trace.go:171","msg":"trace[1618451991] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"311.428405ms","start":"2026-01-21T00:42:37.850006Z","end":"2026-01-21T00:42:38.161435Z","steps":["trace[1618451991] 'process raft request' (duration: 310.866872ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:42:38.161528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:42:37.849998Z","time spent":"311.489687ms","remote":"127.0.0.1:43590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-21T00:42:44.907411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"726.318405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-01-21T00:42:44.907457Z","caller":"traceutil/trace.go:171","msg":"trace[220490473] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:775; }","duration":"726.384549ms","start":"2026-01-21T00:42:44.181061Z","end":"2026-01-21T00:42:44.907446Z","steps":["trace[220490473] 'range keys from in-memory index tree' (duration: 726.226214ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:42:44.907478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:42:44.181058Z","time spent":"726.415029ms","remote":"127.0.0.1:43590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-21T00:42:44.90762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"726.597464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-01-21T00:42:44.907636Z","caller":"traceutil/trace.go:171","msg":"trace[190220445] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:775; }","duration":"726.640334ms","start":"2026-01-21T00:42:44.180992Z","end":"2026-01-21T00:42:44.907632Z","steps":["trace[190220445] 'range keys from in-memory index tree' (duration: 726.48896ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:42:44.907654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:42:44.180977Z","time spent":"726.674216ms","remote":"127.0.0.1:43590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-21T00:42:44.907806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"720.385496ms","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-01-21T00:42:44.907847Z","caller":"traceutil/trace.go:171","msg":"trace[1312082459] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:775; }","duration":"720.441038ms","start":"2026-01-21T00:42:44.187392Z","end":"2026-01-21T00:42:44.907834Z","steps":["trace[1312082459] 'range keys from in-memory index tree' (duration: 720.277533ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:42:44.907868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:42:44.187381Z","time spent":"720.482858ms","remote":"127.0.0.1:43590","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-01-21T00:42:44.908063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.148389ms","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-01-21T00:42:44.908111Z","caller":"traceutil/trace.go:171","msg":"trace[23830215] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:775; }","duration":"421.19931ms","start":"2026-01-21T00:42:44.486906Z","end":"2026-01-21T00:42:44.908105Z","steps":["trace[23830215] 'range keys from in-memory index tree' (duration: 421.143089ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:42:44.908452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.329385ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-21T00:42:44.908494Z","caller":"traceutil/trace.go:171","msg":"trace[1891002946] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"517.589476ms","start":"2026-01-21T00:42:44.390896Z","end":"2026-01-21T00:42:44.908485Z","steps":["trace[1891002946] 'process raft request' (duration: 55.189999ms)","trace[1891002946] 'compare' (duration: 461.936046ms)"],"step_count":2} {"level":"warn","ts":"2026-01-21T00:42:44.908527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:42:44.39088Z","time spent":"517.631607ms","remote":"127.0.0.1:43590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-21T00:42:45.088109Z","caller":"traceutil/trace.go:171","msg":"trace[224008218] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"175.772549ms","start":"2026-01-21T00:42:44.912308Z","end":"2026-01-21T00:42:45.08808Z","steps":["trace[224008218] 'process raft request' (duration: 167.188896ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:42:45.57997Z","caller":"traceutil/trace.go:171","msg":"trace[2013846797] linearizableReadLoop","detail":"{readStateIndex:812; appliedIndex:809; }","duration":"570.97711ms","start":"2026-01-21T00:42:45.008976Z","end":"2026-01-21T00:42:45.579953Z","steps":["trace[2013846797] 'read index received' (duration: 70.525763ms)","trace[2013846797] 'applied index is now lower than readState.Index' (duration: 500.450757ms)"],"step_count":2} {"level":"info","ts":"2026-01-21T00:42:45.580236Z","caller":"traceutil/trace.go:171","msg":"trace[1931757121] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"667.847648ms","start":"2026-01-21T00:42:44.912381Z","end":"2026-01-21T00:42:45.580229Z","steps":["trace[1931757121] 'process raft request' (duration: 667.442918ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:42:45.580309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:42:44.912378Z","time spent":"667.878068ms","remote":"127.0.0.1:43590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-21T00:42:45.580423Z","caller":"traceutil/trace.go:171","msg":"trace[747121175] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"666.180108ms","start":"2026-01-21T00:42:44.914235Z","end":"2026-01-21T00:42:45.580415Z","steps":["trace[747121175] 'process raft request' (duration: 665.690937ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:42:45.580454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:42:44.914229Z","time spent":"666.209099ms","remote":"127.0.0.1:43590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-21T00:42:45.580561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.586484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-21T00:42:45.580584Z","caller":"traceutil/trace.go:171","msg":"trace[1092043927] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:779; }","duration":"571.632466ms","start":"2026-01-21T00:42:45.008945Z","end":"2026-01-21T00:42:45.580577Z","steps":["trace[1092043927] 'agreement among raft nodes before linearized reading' (duration: 571.589915ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:42:45.580603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:42:45.00893Z","time spent":"571.668216ms","remote":"127.0.0.1:43732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-01-21T00:42:45.580706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.239079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-21T00:42:45.580722Z","caller":"traceutil/trace.go:171","msg":"trace[1060733068] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:779; }","duration":"154.276949ms","start":"2026-01-21T00:42:45.426441Z","end":"2026-01-21T00:42:45.580718Z","steps":["trace[1060733068] 'agreement among raft nodes before linearized reading' (duration: 154.252359ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:42:54.354618Z","caller":"traceutil/trace.go:171","msg":"trace[1148943008] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"248.919103ms","start":"2026-01-21T00:42:54.105675Z","end":"2026-01-21T00:42:54.354594Z","steps":["trace[1148943008] 'process raft request' (duration: 248.78724ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:42:54.648008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.467936ms","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-01-21T00:42:54.648237Z","caller":"traceutil/trace.go:171","msg":"trace[1669965281] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:803; }","duration":"160.709002ms","start":"2026-01-21T00:42:54.487499Z","end":"2026-01-21T00:42:54.648208Z","steps":["trace[1669965281] 'range keys from in-memory index tree' (duration: 160.447886ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:42:56.496546Z","caller":"traceutil/trace.go:171","msg":"trace[2032716548] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"134.136131ms","start":"2026-01-21T00:42:56.362392Z","end":"2026-01-21T00:42:56.496528Z","steps":["trace[2032716548] 'process raft request' (duration: 133.880385ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:42:59.798028Z","caller":"traceutil/trace.go:171","msg":"trace[1528093752] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"103.875844ms","start":"2026-01-21T00:42:59.694095Z","end":"2026-01-21T00:42:59.797971Z","steps":["trace[1528093752] 'process raft request' (duration: 103.494175ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:43:08.748149Z","caller":"traceutil/trace.go:171","msg":"trace[253773679] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"160.297146ms","start":"2026-01-21T00:43:08.587832Z","end":"2026-01-21T00:43:08.748129Z","steps":["trace[253773679] 'process raft request' (duration: 160.163453ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:43:11.637708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.451794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-01-21T00:43:11.637768Z","caller":"traceutil/trace.go:171","msg":"trace[2070686718] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:902; }","duration":"316.520526ms","start":"2026-01-21T00:43:11.321231Z","end":"2026-01-21T00:43:11.637752Z","steps":["trace[2070686718] 'range keys from in-memory index tree' (duration: 316.328772ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:43:11.63779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:43:11.321187Z","time spent":"316.598928ms","remote":"127.0.0.1:43590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-21T00:43:11.637914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.340798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-21T00:43:11.637936Z","caller":"traceutil/trace.go:171","msg":"trace[960960416] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:902; }","duration":"210.363788ms","start":"2026-01-21T00:43:11.427566Z","end":"2026-01-21T00:43:11.63793Z","steps":["trace[960960416] 'range keys from in-memory index tree' (duration: 210.281976ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:43:11.74285Z","caller":"traceutil/trace.go:171","msg":"trace[276332011] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"102.125862ms","start":"2026-01-21T00:43:11.640704Z","end":"2026-01-21T00:43:11.74283Z","steps":["trace[276332011] 'process raft request' (duration: 101.980739ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:43:12.077091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.084248ms","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-01-21T00:43:12.077147Z","caller":"traceutil/trace.go:171","msg":"trace[653337613] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:903; }","duration":"104.183ms","start":"2026-01-21T00:43:11.97295Z","end":"2026-01-21T00:43:12.077133Z","steps":["trace[653337613] 'range keys from in-memory index tree' (duration: 103.970555ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:43:12.077385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.876112ms","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-01-21T00:43:12.077416Z","caller":"traceutil/trace.go:171","msg":"trace[842216804] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:903; }","duration":"192.920583ms","start":"2026-01-21T00:43:11.884477Z","end":"2026-01-21T00:43:12.077398Z","steps":["trace[842216804] 'range keys from in-memory index tree' (duration: 192.802411ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:43:12.077509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.555969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-21T00:43:12.077526Z","caller":"traceutil/trace.go:171","msg":"trace[823359265] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:903; }","duration":"183.588431ms","start":"2026-01-21T00:43:11.893932Z","end":"2026-01-21T00:43:12.07752Z","steps":["trace[823359265] 'count revisions from in-memory index tree' (duration: 183.524919ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:43:12.383585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110ms","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-01-21T00:43:12.383629Z","caller":"traceutil/trace.go:171","msg":"trace[1257516506] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:905; }","duration":"110.075641ms","start":"2026-01-21T00:43:12.273543Z","end":"2026-01-21T00:43:12.383619Z","steps":["trace[1257516506] 'range keys from in-memory index tree' (duration: 109.894517ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:43:12.532505Z","caller":"traceutil/trace.go:171","msg":"trace[1715138615] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"145.725399ms","start":"2026-01-21T00:43:12.386765Z","end":"2026-01-21T00:43:12.53249Z","steps":["trace[1715138615] 'process raft request' (duration: 145.624766ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:43:14.884546Z","caller":"traceutil/trace.go:171","msg":"trace[937369133] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"346.131698ms","start":"2026-01-21T00:43:14.538393Z","end":"2026-01-21T00:43:14.884525Z","steps":["trace[937369133] 'process raft request' (duration: 345.985455ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:43:14.884698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:43:14.538381Z","time spent":"346.23505ms","remote":"127.0.0.1:43590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-21T00:43:14.888319Z","caller":"traceutil/trace.go:171","msg":"trace[2107283031] linearizableReadLoop","detail":"{readStateIndex:951; appliedIndex:951; }","duration":"117.858285ms","start":"2026-01-21T00:43:14.770446Z","end":"2026-01-21T00:43:14.888305Z","steps":["trace[2107283031] 'read index received' (duration: 117.851795ms)","trace[2107283031] 'applied index is now lower than readState.Index' (duration: 5.26µs)"],"step_count":2} {"level":"warn","ts":"2026-01-21T00:43:14.888469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.014078ms","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-01-21T00:43:14.888503Z","caller":"traceutil/trace.go:171","msg":"trace[2113145342] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:911; }","duration":"118.074139ms","start":"2026-01-21T00:43:14.770421Z","end":"2026-01-21T00:43:14.888495Z","steps":["trace[2113145342] 'agreement among raft nodes before linearized reading' (duration: 117.935646ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:43:15.040161Z","caller":"traceutil/trace.go:171","msg":"trace[1299063998] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"146.769414ms","start":"2026-01-21T00:43:14.893375Z","end":"2026-01-21T00:43:15.040145Z","steps":["trace[1299063998] 'process raft request' (duration: 49.430667ms)","trace[1299063998] 'compare' (duration: 97.274546ms)"],"step_count":2} {"level":"warn","ts":"2026-01-21T00:43:20.727981Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673540114324522514,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-21T00:43:20.752176Z","caller":"traceutil/trace.go:171","msg":"trace[1822243754] linearizableReadLoop","detail":"{readStateIndex:967; appliedIndex:966; }","duration":"524.565578ms","start":"2026-01-21T00:43:20.227593Z","end":"2026-01-21T00:43:20.752158Z","steps":["trace[1822243754] 'read index received' (duration: 524.427595ms)","trace[1822243754] 'applied index is now lower than readState.Index' (duration: 137.313µs)"],"step_count":2} {"level":"info","ts":"2026-01-21T00:43:20.75244Z","caller":"traceutil/trace.go:171","msg":"trace[1517252423] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"586.954354ms","start":"2026-01-21T00:43:20.165476Z","end":"2026-01-21T00:43:20.75243Z","steps":["trace[1517252423] 'process raft request' (duration: 586.589426ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:43:20.752512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:43:20.165468Z","time spent":"586.993495ms","remote":"127.0.0.1:43590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-21T00:43:20.75268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.08933ms","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-01-21T00:43:20.7527Z","caller":"traceutil/trace.go:171","msg":"trace[679526126] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:926; }","duration":"525.144392ms","start":"2026-01-21T00:43:20.22755Z","end":"2026-01-21T00:43:20.752694Z","steps":["trace[679526126] 'agreement among raft nodes before linearized reading' (duration: 525.06757ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:43:20.752719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:43:20.227536Z","time spent":"525.178782ms","remote":"127.0.0.1:43590","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-01-21T00:43:20.752804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.344819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-21T00:43:20.75282Z","caller":"traceutil/trace.go:171","msg":"trace[1796401168] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:926; }","duration":"326.360019ms","start":"2026-01-21T00:43:20.426454Z","end":"2026-01-21T00:43:20.752814Z","steps":["trace[1796401168] 'agreement among raft nodes before linearized reading' (duration: 326.334079ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:43:20.752866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:43:20.426415Z","time spent":"326.446452ms","remote":"127.0.0.1:43386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-21T00:43:20.752987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.755617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.195\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-01-21T00:43:20.753Z","caller":"traceutil/trace.go:171","msg":"trace[1128184818] range","detail":"{range_begin:/registry/masterleases/162.253.55.195; range_end:; response_count:1; response_revision:926; }","duration":"210.794828ms","start":"2026-01-21T00:43:20.542201Z","end":"2026-01-21T00:43:20.752996Z","steps":["trace[1128184818] 'agreement among raft nodes before linearized reading' (duration: 210.755827ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:43:21.256618Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673540114324522517,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-21T00:43:22.065142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:43:20.841645Z","time spent":"1.223492778s","remote":"127.0.0.1:41588","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-01-21T00:43:22.065541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.232768ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-01-21T00:43:22.065593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:43:20.755969Z","time spent":"1.309621028s","remote":"127.0.0.1:43410","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-01-21T00:43:22.081212Z","caller":"traceutil/trace.go:171","msg":"trace[1746834159] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"1.323025948s","start":"2026-01-21T00:43:20.758179Z","end":"2026-01-21T00:43:22.081205Z","steps":["trace[1746834159] 'process raft request' (duration: 1.322781552s)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:43:22.081288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:43:20.758172Z","time spent":"1.323075119s","remote":"127.0.0.1:43590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-21T00:43:22.081457Z","caller":"traceutil/trace.go:171","msg":"trace[1977374623] linearizableReadLoop","detail":"{readStateIndex:969; appliedIndex:967; }","duration":"1.324918703s","start":"2026-01-21T00:43:20.756135Z","end":"2026-01-21T00:43:22.081054Z","steps":["trace[1977374623] 'read index received' (duration: 862.274809ms)","trace[1977374623] 'applied index is now lower than readState.Index' (duration: 462.643344ms)"],"step_count":2} {"level":"warn","ts":"2026-01-21T00:43:22.081514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.325375944s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-21T00:43:22.081529Z","caller":"traceutil/trace.go:171","msg":"trace[1428868371] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:927; }","duration":"1.325407764s","start":"2026-01-21T00:43:20.756117Z","end":"2026-01-21T00:43:22.081524Z","steps":["trace[1428868371] 'agreement among raft nodes before linearized reading' (duration: 1.325366673s)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:43:22.081544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:43:20.756111Z","time spent":"1.325428404s","remote":"127.0.0.1:43398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-21T00:43:22.085898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.182523072s","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-01-21T00:43:22.085951Z","caller":"traceutil/trace.go:171","msg":"trace[373103377] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:928; }","duration":"1.182585304s","start":"2026-01-21T00:43:20.903353Z","end":"2026-01-21T00:43:22.085938Z","steps":["trace[373103377] 'agreement among raft nodes before linearized reading' (duration: 1.182401089s)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:43:22.085979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:43:20.903315Z","time spent":"1.182658696s","remote":"127.0.0.1:43590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-21T00:43:22.086193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.539006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-01-21T00:43:22.086214Z","caller":"traceutil/trace.go:171","msg":"trace[976461424] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:928; }","duration":"253.592817ms","start":"2026-01-21T00:43:21.832614Z","end":"2026-01-21T00:43:22.086206Z","steps":["trace[976461424] 'agreement among raft nodes before linearized reading' (duration: 253.535656ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:43:22.086377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"975.928074ms","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-01-21T00:43:22.086411Z","caller":"traceutil/trace.go:171","msg":"trace[1530417992] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:928; }","duration":"975.989936ms","start":"2026-01-21T00:43:21.110414Z","end":"2026-01-21T00:43:22.086404Z","steps":["trace[1530417992] 'agreement among raft nodes before linearized reading' (duration: 975.876633ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:43:22.08643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:43:21.1104Z","time spent":"976.025676ms","remote":"127.0.0.1:43590","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":"info","ts":"2026-01-21T00:43:24.210011Z","caller":"traceutil/trace.go:171","msg":"trace[1814041480] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"120.956ms","start":"2026-01-21T00:43:24.089039Z","end":"2026-01-21T00:43:24.209995Z","steps":["trace[1814041480] 'process raft request' (duration: 120.832917ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:43:24.784816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.582914ms","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-01-21T00:43:24.784901Z","caller":"traceutil/trace.go:171","msg":"trace[726108292] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:933; }","duration":"297.675946ms","start":"2026-01-21T00:43:24.487215Z","end":"2026-01-21T00:43:24.784891Z","steps":["trace[726108292] 'range keys from in-memory index tree' (duration: 297.572794ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:43:24.785215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.686136ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-21T00:43:24.785302Z","caller":"traceutil/trace.go:171","msg":"trace[429878313] linearizableReadLoop","detail":"{readStateIndex:978; appliedIndex:975; }","duration":"355.211377ms","start":"2026-01-21T00:43:24.430084Z","end":"2026-01-21T00:43:24.785296Z","steps":["trace[429878313] 'read index received' (duration: 311.26966ms)","trace[429878313] 'applied index is now lower than readState.Index' (duration: 43.941067ms)"],"step_count":2} {"level":"info","ts":"2026-01-21T00:43:24.785434Z","caller":"traceutil/trace.go:171","msg":"trace[1583877788] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"569.920489ms","start":"2026-01-21T00:43:24.215506Z","end":"2026-01-21T00:43:24.785427Z","steps":["trace[1583877788] 'process raft request' (duration: 30.977757ms)","trace[1583877788] 'compare' (duration: 538.149382ms)"],"step_count":2} {"level":"warn","ts":"2026-01-21T00:43:24.785467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:43:24.215494Z","time spent":"569.955969ms","remote":"127.0.0.1:43590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-21T00:43:24.785547Z","caller":"traceutil/trace.go:171","msg":"trace[1907750369] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"569.049727ms","start":"2026-01-21T00:43:24.216492Z","end":"2026-01-21T00:43:24.785541Z","steps":["trace[1907750369] 'process raft request' (duration: 568.761851ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:43:24.785569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:43:24.216486Z","time spent":"569.070158ms","remote":"127.0.0.1:43590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-21T00:43:24.785622Z","caller":"traceutil/trace.go:171","msg":"trace[1174149422] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"567.936461ms","start":"2026-01-21T00:43:24.217681Z","end":"2026-01-21T00:43:24.785618Z","steps":["trace[1174149422] 'process raft request' (duration: 567.598203ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:43:24.785646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:43:24.217646Z","time spent":"567.986273ms","remote":"127.0.0.1:43590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-21T00:43:24.785711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.627206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-21T00:43:24.785728Z","caller":"traceutil/trace.go:171","msg":"trace[237263401] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:936; }","duration":"355.687558ms","start":"2026-01-21T00:43:24.430035Z","end":"2026-01-21T00:43:24.785723Z","steps":["trace[237263401] 'agreement among raft nodes before linearized reading' (duration: 355.657937ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:43:24.785743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:43:24.430015Z","time spent":"355.724299ms","remote":"127.0.0.1:43386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-21T00:43:24.785867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.772697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-21T00:43:24.785881Z","caller":"traceutil/trace.go:171","msg":"trace[321536424] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:936; }","duration":"199.788247ms","start":"2026-01-21T00:43:24.586089Z","end":"2026-01-21T00:43:24.785877Z","steps":["trace[321536424] 'agreement among raft nodes before linearized reading' (duration: 199.730906ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:43:24.786045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.602626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-21T00:43:24.786063Z","caller":"traceutil/trace.go:171","msg":"trace[1499524461] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:936; }","duration":"174.623367ms","start":"2026-01-21T00:43:24.611435Z","end":"2026-01-21T00:43:24.786058Z","steps":["trace[1499524461] 'agreement among raft nodes before linearized reading' (duration: 174.584046ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:43:25.501807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.335117ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-21T00:43:25.501876Z","caller":"traceutil/trace.go:171","msg":"trace[1193765586] linearizableReadLoop","detail":"{readStateIndex:979; appliedIndex:978; }","duration":"648.116219ms","start":"2026-01-21T00:43:24.853749Z","end":"2026-01-21T00:43:25.501865Z","steps":["trace[1193765586] 'read index received' (duration: 114.584918ms)","trace[1193765586] 'applied index is now lower than readState.Index' (duration: 533.530431ms)"],"step_count":2} {"level":"warn","ts":"2026-01-21T00:43:25.501954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.209161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-21T00:43:25.501973Z","caller":"traceutil/trace.go:171","msg":"trace[490403562] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:937; }","duration":"648.228822ms","start":"2026-01-21T00:43:24.853737Z","end":"2026-01-21T00:43:25.501966Z","steps":["trace[490403562] 'agreement among raft nodes before linearized reading' (duration: 648.15808ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:43:25.501995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:43:24.853689Z","time spent":"648.299874ms","remote":"127.0.0.1:43770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-01-21T00:43:25.50223Z","caller":"traceutil/trace.go:171","msg":"trace[1998603016] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"664.087321ms","start":"2026-01-21T00:43:24.838133Z","end":"2026-01-21T00:43:25.50222Z","steps":["trace[1998603016] 'process raft request' (duration: 130.256661ms)","trace[1998603016] 'compare' (duration: 532.966518ms)"],"step_count":2} {"level":"warn","ts":"2026-01-21T00:43:25.502269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:43:24.838123Z","time spent":"664.125032ms","remote":"127.0.0.1:43590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-21T00:43:25.581636Z","caller":"traceutil/trace.go:171","msg":"trace[2036979139] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"233.541931ms","start":"2026-01-21T00:43:25.348056Z","end":"2026-01-21T00:43:25.581598Z","steps":["trace[2036979139] 'process raft request' (duration: 233.436838ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:43:26.828737Z","caller":"traceutil/trace.go:171","msg":"trace[161425692] linearizableReadLoop","detail":"{readStateIndex:982; appliedIndex:981; }","duration":"173.71969ms","start":"2026-01-21T00:43:26.655003Z","end":"2026-01-21T00:43:26.828722Z","steps":["trace[161425692] 'read index received' (duration: 173.585006ms)","trace[161425692] 'applied index is now lower than readState.Index' (duration: 134.144µs)"],"step_count":2} {"level":"info","ts":"2026-01-21T00:43:26.828937Z","caller":"traceutil/trace.go:171","msg":"trace[274004960] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"608.692161ms","start":"2026-01-21T00:43:26.220239Z","end":"2026-01-21T00:43:26.828931Z","steps":["trace[274004960] 'process raft request' (duration: 608.383774ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:43:26.828999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:43:26.22023Z","time spent":"608.723682ms","remote":"127.0.0.1:43590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-21T00:43:26.829098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.092228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-21T00:43:26.829113Z","caller":"traceutil/trace.go:171","msg":"trace[416879117] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:939; }","duration":"174.13272ms","start":"2026-01-21T00:43:26.654976Z","end":"2026-01-21T00:43:26.829109Z","steps":["trace[416879117] 'agreement among raft nodes before linearized reading' (duration: 174.104669ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:43:26.829208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.130259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-21T00:43:26.82922Z","caller":"traceutil/trace.go:171","msg":"trace[178581846] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:939; }","duration":"174.15111ms","start":"2026-01-21T00:43:26.655065Z","end":"2026-01-21T00:43:26.829217Z","steps":["trace[178581846] 'agreement among raft nodes before linearized reading' (duration: 174.12522ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:43:26.829531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.647307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-21T00:43:26.829549Z","caller":"traceutil/trace.go:171","msg":"trace[2062842720] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:939; }","duration":"162.689178ms","start":"2026-01-21T00:43:26.666855Z","end":"2026-01-21T00:43:26.829544Z","steps":["trace[2062842720] 'agreement among raft nodes before linearized reading' (duration: 162.660577ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:43:26.947747Z","caller":"traceutil/trace.go:171","msg":"trace[65435125] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"107.94014ms","start":"2026-01-21T00:43:26.839784Z","end":"2026-01-21T00:43:26.947724Z","steps":["trace[65435125] 'process raft request' (duration: 107.869307ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:43:26.948331Z","caller":"traceutil/trace.go:171","msg":"trace[1713501828] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"111.822592ms","start":"2026-01-21T00:43:26.836495Z","end":"2026-01-21T00:43:26.948318Z","steps":["trace[1713501828] 'process raft request' (duration: 111.045813ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:43:30.817415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.274764ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-21T00:43:30.817517Z","caller":"traceutil/trace.go:171","msg":"trace[462921437] linearizableReadLoop","detail":"{readStateIndex:1003; appliedIndex:1002; }","duration":"151.590429ms","start":"2026-01-21T00:43:30.665912Z","end":"2026-01-21T00:43:30.817503Z","steps":["trace[462921437] 'read index received' (duration: 49.961µs)","trace[462921437] 'applied index is now lower than readState.Index' (duration: 151.539348ms)"],"step_count":2} {"level":"info","ts":"2026-01-21T00:43:30.817583Z","caller":"traceutil/trace.go:171","msg":"trace[95124565] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"261.164807ms","start":"2026-01-21T00:43:30.55641Z","end":"2026-01-21T00:43:30.817574Z","steps":["trace[95124565] 'process raft request' (duration: 103.648518ms)","trace[95124565] 'compare' (duration: 157.045148ms)"],"step_count":2} {"level":"warn","ts":"2026-01-21T00:43:30.817791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.888606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-21T00:43:30.817806Z","caller":"traceutil/trace.go:171","msg":"trace[1428753912] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:958; }","duration":"151.960488ms","start":"2026-01-21T00:43:30.66584Z","end":"2026-01-21T00:43:30.817801Z","steps":["trace[1428753912] 'agreement among raft nodes before linearized reading' (duration: 151.870205ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:43:31.566031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.343302ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-21T00:43:31.566094Z","caller":"traceutil/trace.go:171","msg":"trace[629183583] linearizableReadLoop","detail":"{readStateIndex:1004; appliedIndex:1003; }","duration":"703.1505ms","start":"2026-01-21T00:43:30.862934Z","end":"2026-01-21T00:43:31.566084Z","steps":["trace[629183583] 'read index received' (duration: 76.615963ms)","trace[629183583] 'applied index is now lower than readState.Index' (duration: 626.533807ms)"],"step_count":2} {"level":"info","ts":"2026-01-21T00:43:31.566192Z","caller":"traceutil/trace.go:171","msg":"trace[1116048846] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"711.036388ms","start":"2026-01-21T00:43:30.855149Z","end":"2026-01-21T00:43:31.566186Z","steps":["trace[1116048846] 'process raft request' (duration: 84.491023ms)","trace[1116048846] 'compare' (duration: 626.111225ms)"],"step_count":2} {"level":"warn","ts":"2026-01-21T00:43:31.566226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:43:30.85513Z","time spent":"711.079579ms","remote":"127.0.0.1:43590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-21T00:43:31.566354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.422216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-01-21T00:43:31.566375Z","caller":"traceutil/trace.go:171","msg":"trace[996659666] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:959; }","duration":"703.480578ms","start":"2026-01-21T00:43:30.86289Z","end":"2026-01-21T00:43:31.56637Z","steps":["trace[996659666] 'agreement among raft nodes before linearized reading' (duration: 703.413486ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:43:31.566389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:43:30.862878Z","time spent":"703.507748ms","remote":"127.0.0.1:43410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"warn","ts":"2026-01-21T00:43:31.566483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.259754ms","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-01-21T00:43:31.5665Z","caller":"traceutil/trace.go:171","msg":"trace[1035971154] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:959; }","duration":"635.340345ms","start":"2026-01-21T00:43:30.931157Z","end":"2026-01-21T00:43:31.566497Z","steps":["trace[1035971154] 'agreement among raft nodes before linearized reading' (duration: 635.297204ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:43:31.566513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:43:30.931124Z","time spent":"635.385957ms","remote":"127.0.0.1:43590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-21T00:43:31.566664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.259547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-21T00:43:31.566682Z","caller":"traceutil/trace.go:171","msg":"trace[36465259] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:959; }","duration":"137.34752ms","start":"2026-01-21T00:43:31.42933Z","end":"2026-01-21T00:43:31.566678Z","steps":["trace[36465259] 'agreement among raft nodes before linearized reading' (duration: 137.320659ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:43:31.566748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.310831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-21T00:43:31.566764Z","caller":"traceutil/trace.go:171","msg":"trace[174258718] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:959; }","duration":"164.358292ms","start":"2026-01-21T00:43:31.402403Z","end":"2026-01-21T00:43:31.566761Z","steps":["trace[174258718] 'agreement among raft nodes before linearized reading' (duration: 164.332261ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:43:31.56686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.038943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-21T00:43:31.566879Z","caller":"traceutil/trace.go:171","msg":"trace[688819243] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:959; }","duration":"379.113655ms","start":"2026-01-21T00:43:31.187761Z","end":"2026-01-21T00:43:31.566875Z","steps":["trace[688819243] 'agreement among raft nodes before linearized reading' (duration: 379.083705ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:43:31.566892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:43:31.187733Z","time spent":"379.155836ms","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-01-21T00:43:31.56702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.814355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-21T00:43:31.567038Z","caller":"traceutil/trace.go:171","msg":"trace[360696405] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:959; }","duration":"448.881366ms","start":"2026-01-21T00:43:31.118153Z","end":"2026-01-21T00:43:31.567035Z","steps":["trace[360696405] 'agreement among raft nodes before linearized reading' (duration: 448.809934ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:43:31.567052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:43:31.118125Z","time spent":"448.923667ms","remote":"127.0.0.1:43444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":115,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-01-21T00:43:31.622105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.729563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-01-21T00:43:31.622198Z","caller":"traceutil/trace.go:171","msg":"trace[1788403385] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:959; }","duration":"653.928567ms","start":"2026-01-21T00:43:30.968251Z","end":"2026-01-21T00:43:31.62218Z","steps":["trace[1788403385] 'agreement among raft nodes before linearized reading' (duration: 640.98354ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:43:31.622243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:43:30.968225Z","time spent":"654.00675ms","remote":"127.0.0.1:43590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-21T00:43:31.62242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.88598ms","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-01-21T00:43:31.622432Z","caller":"traceutil/trace.go:171","msg":"trace[1059898856] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:959; }","duration":"659.93825ms","start":"2026-01-21T00:43:30.96249Z","end":"2026-01-21T00:43:31.622428Z","steps":["trace[1059898856] 'agreement among raft nodes before linearized reading' (duration: 659.810657ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:43:31.622445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:43:30.962466Z","time spent":"659.975252ms","remote":"127.0.0.1:43590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-21T00:43:31.93076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.081828ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-21T00:43:31.931113Z","caller":"traceutil/trace.go:171","msg":"trace[245304885] linearizableReadLoop","detail":"{readStateIndex:1008; appliedIndex:1004; }","duration":"179.416961ms","start":"2026-01-21T00:43:31.751681Z","end":"2026-01-21T00:43:31.931098Z","steps":["trace[245304885] 'read index received' (duration: 53.566085ms)","trace[245304885] 'applied index is now lower than readState.Index' (duration: 125.849846ms)"],"step_count":2} {"level":"info","ts":"2026-01-21T00:43:31.931201Z","caller":"traceutil/trace.go:171","msg":"trace[2018979637] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"223.364267ms","start":"2026-01-21T00:43:31.707805Z","end":"2026-01-21T00:43:31.931169Z","steps":["trace[2018979637] 'process raft request' (duration: 223.137671ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:43:31.931293Z","caller":"traceutil/trace.go:171","msg":"trace[1202554432] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"200.476372ms","start":"2026-01-21T00:43:31.730807Z","end":"2026-01-21T00:43:31.931283Z","steps":["trace[1202554432] 'process raft request' (duration: 200.231497ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:43:31.931425Z","caller":"traceutil/trace.go:171","msg":"trace[1474043252] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"236.305966ms","start":"2026-01-21T00:43:31.695107Z","end":"2026-01-21T00:43:31.931413Z","steps":["trace[1474043252] 'process raft request' (duration: 110.120661ms)","trace[1474043252] 'compare' (duration: 124.949735ms)"],"step_count":2} {"level":"info","ts":"2026-01-21T00:43:31.931544Z","caller":"traceutil/trace.go:171","msg":"trace[1881423199] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"199.580982ms","start":"2026-01-21T00:43:31.731957Z","end":"2026-01-21T00:43:31.931538Z","steps":["trace[1881423199] 'process raft request' (duration: 199.11886ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:43:31.931239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.535645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-21T00:43:31.931685Z","caller":"traceutil/trace.go:171","msg":"trace[475441922] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:963; }","duration":"180.047747ms","start":"2026-01-21T00:43:31.751624Z","end":"2026-01-21T00:43:31.931672Z","steps":["trace[475441922] 'agreement among raft nodes before linearized reading' (duration: 179.537244ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:43:38.012555Z","caller":"traceutil/trace.go:171","msg":"trace[819392289] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"287.071314ms","start":"2026-01-21T00:43:37.725468Z","end":"2026-01-21T00:43:38.012539Z","steps":["trace[819392289] 'process raft request' (duration: 286.91931ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:43:38.147345Z","caller":"traceutil/trace.go:171","msg":"trace[427401213] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"130.308051ms","start":"2026-01-21T00:43:38.01702Z","end":"2026-01-21T00:43:38.147328Z","steps":["trace[427401213] 'process raft request' (duration: 129.744248ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:43:38.150041Z","caller":"traceutil/trace.go:171","msg":"trace[1122080225] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"131.189222ms","start":"2026-01-21T00:43:38.01877Z","end":"2026-01-21T00:43:38.149959Z","steps":["trace[1122080225] 'process raft request' (duration: 131.156101ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:43:38.155045Z","caller":"traceutil/trace.go:171","msg":"trace[365191156] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"136.317234ms","start":"2026-01-21T00:43:38.018716Z","end":"2026-01-21T00:43:38.155033Z","steps":["trace[365191156] 'process raft request' (duration: 131.083969ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:43:46.573133Z","caller":"traceutil/trace.go:171","msg":"trace[1020539572] linearizableReadLoop","detail":"{readStateIndex:1101; appliedIndex:1100; }","duration":"383.212493ms","start":"2026-01-21T00:43:46.189902Z","end":"2026-01-21T00:43:46.573115Z","steps":["trace[1020539572] 'read index received' (duration: 383.05622ms)","trace[1020539572] 'applied index is now lower than readState.Index' (duration: 155.623µs)"],"step_count":2} {"level":"info","ts":"2026-01-21T00:43:46.573327Z","caller":"traceutil/trace.go:171","msg":"trace[1474751788] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"453.903206ms","start":"2026-01-21T00:43:46.119414Z","end":"2026-01-21T00:43:46.573318Z","steps":["trace[1474751788] 'process raft request' (duration: 453.593119ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:43:46.573414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:43:46.119406Z","time spent":"453.939957ms","remote":"127.0.0.1:43590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-21T00:43:46.573672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.353538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-01-21T00:43:46.573658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.737756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-01-21T00:43:46.573717Z","caller":"traceutil/trace.go:171","msg":"trace[1624731555] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1053; }","duration":"381.41507ms","start":"2026-01-21T00:43:46.192292Z","end":"2026-01-21T00:43:46.573707Z","steps":["trace[1624731555] 'agreement among raft nodes before linearized reading' (duration: 381.307428ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:43:46.573739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:43:46.19228Z","time spent":"381.453761ms","remote":"127.0.0.1:43590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-01-21T00:43:46.573729Z","caller":"traceutil/trace.go:171","msg":"trace[1651115754] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1053; }","duration":"383.842708ms","start":"2026-01-21T00:43:46.189866Z","end":"2026-01-21T00:43:46.573709Z","steps":["trace[1651115754] 'agreement among raft nodes before linearized reading' (duration: 383.652954ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:43:46.573769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:43:46.189848Z","time spent":"383.91284ms","remote":"127.0.0.1:43590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-21T00:43:46.573921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.60738ms","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-01-21T00:43:46.573945Z","caller":"traceutil/trace.go:171","msg":"trace[2007077985] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1053; }","duration":"372.643892ms","start":"2026-01-21T00:43:46.201295Z","end":"2026-01-21T00:43:46.573939Z","steps":["trace[2007077985] 'agreement among raft nodes before linearized reading' (duration: 372.5889ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:43:46.573961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:43:46.201289Z","time spent":"372.669152ms","remote":"127.0.0.1:43590","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-01-21T00:43:46.574076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.234637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-01-21T00:43:46.574092Z","caller":"traceutil/trace.go:171","msg":"trace[1853416037] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1053; }","duration":"376.266447ms","start":"2026-01-21T00:43:46.197821Z","end":"2026-01-21T00:43:46.574088Z","steps":["trace[1853416037] 'agreement among raft nodes before linearized reading' (duration: 376.221676ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:43:46.57411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:43:46.197813Z","time spent":"376.293638ms","remote":"127.0.0.1:43590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-01-21T00:43:46.784626Z","caller":"traceutil/trace.go:171","msg":"trace[812561489] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"203.658639ms","start":"2026-01-21T00:43:46.580941Z","end":"2026-01-21T00:43:46.7846Z","steps":["trace[812561489] 'process raft request' (duration: 203.510365ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:43:46.785209Z","caller":"traceutil/trace.go:171","msg":"trace[1610092317] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"204.016507ms","start":"2026-01-21T00:43:46.581168Z","end":"2026-01-21T00:43:46.785185Z","steps":["trace[1610092317] 'process raft request' (duration: 203.944516ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:43:46.785298Z","caller":"traceutil/trace.go:171","msg":"trace[1275966878] linearizableReadLoop","detail":"{readStateIndex:1106; appliedIndex:1102; }","duration":"115.138761ms","start":"2026-01-21T00:43:46.670144Z","end":"2026-01-21T00:43:46.785283Z","steps":["trace[1275966878] 'read index received' (duration: 114.640469ms)","trace[1275966878] 'applied index is now lower than readState.Index' (duration: 497.462µs)"],"step_count":2} {"level":"warn","ts":"2026-01-21T00:43:46.785445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.287754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:536"} {"level":"info","ts":"2026-01-21T00:43:46.785477Z","caller":"traceutil/trace.go:171","msg":"trace[790393254] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1058; }","duration":"115.352856ms","start":"2026-01-21T00:43:46.670113Z","end":"2026-01-21T00:43:46.785466Z","steps":["trace[790393254] 'agreement among raft nodes before linearized reading' (duration: 115.217583ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:43:46.785573Z","caller":"traceutil/trace.go:171","msg":"trace[800451890] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"204.361665ms","start":"2026-01-21T00:43:46.5812Z","end":"2026-01-21T00:43:46.785562Z","steps":["trace[800451890] 'process raft request' (duration: 203.949776ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:43:46.785636Z","caller":"traceutil/trace.go:171","msg":"trace[1879676366] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"190.939756ms","start":"2026-01-21T00:43:46.594689Z","end":"2026-01-21T00:43:46.785629Z","steps":["trace[1879676366] 'process raft request' (duration: 190.544867ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:43:46.785699Z","caller":"traceutil/trace.go:171","msg":"trace[1420535191] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"204.56498ms","start":"2026-01-21T00:43:46.581125Z","end":"2026-01-21T00:43:46.78569Z","steps":["trace[1420535191] 'process raft request' (duration: 203.868614ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:46:35.810475Z","caller":"traceutil/trace.go:171","msg":"trace[1415863337] transaction","detail":"{read_only:false; response_revision:1747; number_of_response:1; }","duration":"100.3845ms","start":"2026-01-21T00:46:35.710071Z","end":"2026-01-21T00:46:35.810456Z","steps":["trace[1415863337] 'process raft request' (duration: 99.9624ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:46:35.99097Z","caller":"traceutil/trace.go:171","msg":"trace[1237590961] linearizableReadLoop","detail":"{readStateIndex:1831; appliedIndex:1830; }","duration":"103.550855ms","start":"2026-01-21T00:46:35.887397Z","end":"2026-01-21T00:46:35.990947Z","steps":["trace[1237590961] 'read index received' (duration: 5.689004ms)","trace[1237590961] 'applied index is now lower than readState.Index' (duration: 97.860601ms)"],"step_count":2} {"level":"warn","ts":"2026-01-21T00:46:35.991158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.74336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-01-21T00:46:35.991197Z","caller":"traceutil/trace.go:171","msg":"trace[1612299233] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1749; }","duration":"103.829022ms","start":"2026-01-21T00:46:35.887358Z","end":"2026-01-21T00:46:35.991187Z","steps":["trace[1612299233] 'agreement among raft nodes before linearized reading' (duration: 103.674408ms)"],"step_count":1} {"level":"info","ts":"2026-01-21T00:46:52.055967Z","caller":"traceutil/trace.go:171","msg":"trace[841209345] linearizableReadLoop","detail":"{readStateIndex:1923; appliedIndex:1922; }","duration":"130.849939ms","start":"2026-01-21T00:46:51.925091Z","end":"2026-01-21T00:46:52.055941Z","steps":["trace[841209345] 'read index received' (duration: 130.688416ms)","trace[841209345] 'applied index is now lower than readState.Index' (duration: 160.943µs)"],"step_count":2} {"level":"info","ts":"2026-01-21T00:46:52.056163Z","caller":"traceutil/trace.go:171","msg":"trace[1247382464] transaction","detail":"{read_only:false; response_revision:1837; number_of_response:1; }","duration":"179.619573ms","start":"2026-01-21T00:46:51.876535Z","end":"2026-01-21T00:46:52.056154Z","steps":["trace[1247382464] 'process raft request' (duration: 179.302805ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:46:52.056477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.744411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-01-21T00:46:52.056507Z","caller":"traceutil/trace.go:171","msg":"trace[1274589698] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1837; }","duration":"128.803802ms","start":"2026-01-21T00:46:51.927697Z","end":"2026-01-21T00:46:52.0565Z","steps":["trace[1274589698] 'agreement among raft nodes before linearized reading' (duration: 128.72477ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:46:52.056648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.557287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-01-21T00:46:52.056663Z","caller":"traceutil/trace.go:171","msg":"trace[1571120060] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1837; }","duration":"131.599448ms","start":"2026-01-21T00:46:51.925059Z","end":"2026-01-21T00:46:52.056658Z","steps":["trace[1571120060] 'agreement among raft nodes before linearized reading' (duration: 131.309611ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:46:52.271901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.109953ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-21T00:46:52.272225Z","caller":"traceutil/trace.go:171","msg":"trace[1604664360] linearizableReadLoop","detail":"{readStateIndex:1925; appliedIndex:1923; }","duration":"139.935956ms","start":"2026-01-21T00:46:52.132276Z","end":"2026-01-21T00:46:52.272212Z","steps":["trace[1604664360] 'read index received' (duration: 72.406521ms)","trace[1604664360] 'applied index is now lower than readState.Index' (duration: 67.528675ms)"],"step_count":2} {"level":"info","ts":"2026-01-21T00:46:52.273644Z","caller":"traceutil/trace.go:171","msg":"trace[558840121] transaction","detail":"{read_only:false; response_revision:1838; number_of_response:1; }","duration":"211.938805ms","start":"2026-01-21T00:46:52.061676Z","end":"2026-01-21T00:46:52.273614Z","steps":["trace[558840121] 'process raft request' (duration: 58.054951ms)","trace[558840121] 'compare' (duration: 152.034421ms)"],"step_count":2} {"level":"info","ts":"2026-01-21T00:46:52.27381Z","caller":"traceutil/trace.go:171","msg":"trace[1147845572] transaction","detail":"{read_only:false; response_revision:1839; number_of_response:1; }","duration":"211.043105ms","start":"2026-01-21T00:46:52.06276Z","end":"2026-01-21T00:46:52.273803Z","steps":["trace[1147845572] 'process raft request' (duration: 209.394096ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:46:52.273974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.682066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-01-21T00:46:52.274005Z","caller":"traceutil/trace.go:171","msg":"trace[641712507] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1839; }","duration":"141.724657ms","start":"2026-01-21T00:46:52.13227Z","end":"2026-01-21T00:46:52.273994Z","steps":["trace[641712507] 'agreement among raft nodes before linearized reading' (duration: 141.592344ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:46:52.274108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.893585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-01-21T00:46:52.274131Z","caller":"traceutil/trace.go:171","msg":"trace[272301196] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1839; }","duration":"128.964266ms","start":"2026-01-21T00:46:52.14516Z","end":"2026-01-21T00:46:52.274124Z","steps":["trace[272301196] 'agreement among raft nodes before linearized reading' (duration: 128.902845ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:47:00.719141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.412472ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-01-21T00:47:00.719232Z","caller":"traceutil/trace.go:171","msg":"trace[1917885140] linearizableReadLoop","detail":"{readStateIndex:1967; appliedIndex:1966; }","duration":"140.054398ms","start":"2026-01-21T00:47:00.579162Z","end":"2026-01-21T00:47:00.719217Z","steps":["trace[1917885140] 'read index received' (duration: 39.507104ms)","trace[1917885140] 'applied index is now lower than readState.Index' (duration: 100.545724ms)"],"step_count":2} {"level":"warn","ts":"2026-01-21T00:47:00.719305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.13819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-21T00:47:00.71933Z","caller":"traceutil/trace.go:171","msg":"trace[1630352280] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1879; }","duration":"140.187591ms","start":"2026-01-21T00:47:00.579134Z","end":"2026-01-21T00:47:00.719322Z","steps":["trace[1630352280] 'agreement among raft nodes before linearized reading' (duration: 140.125169ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:47:01.27896Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673540114324524907,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-21T00:47:01.279202Z","caller":"traceutil/trace.go:171","msg":"trace[126916009] linearizableReadLoop","detail":"{readStateIndex:1968; appliedIndex:1967; }","duration":"500.81995ms","start":"2026-01-21T00:47:00.778366Z","end":"2026-01-21T00:47:01.279186Z","steps":["trace[126916009] 'read index received' (duration: 500.686407ms)","trace[126916009] 'applied index is now lower than readState.Index' (duration: 133.033µs)"],"step_count":2} {"level":"warn","ts":"2026-01-21T00:47:01.279436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.058275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-01-21T00:47:01.279459Z","caller":"traceutil/trace.go:171","msg":"trace[1038635674] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1880; }","duration":"501.119976ms","start":"2026-01-21T00:47:00.778331Z","end":"2026-01-21T00:47:01.279451Z","steps":["trace[1038635674] 'agreement among raft nodes before linearized reading' (duration: 500.990753ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:47:01.279481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:47:00.778318Z","time spent":"501.158078ms","remote":"127.0.0.1:43590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":498,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-01-21T00:47:01.279616Z","caller":"traceutil/trace.go:171","msg":"trace[1065215488] transaction","detail":"{read_only:false; response_revision:1880; number_of_response:1; }","duration":"558.524132ms","start":"2026-01-21T00:47:00.721085Z","end":"2026-01-21T00:47:01.279609Z","steps":["trace[1065215488] 'process raft request' (duration: 558.0047ms)"],"step_count":1} {"level":"warn","ts":"2026-01-21T00:47:01.279671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-21T00:47:00.721064Z","time spent":"558.568483ms","remote":"127.0.0.1:43410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"}