{"level":"warn","ts":"2026-10-03T22:18:09.328784Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-03T22:18:09.328896Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.220: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.220:2380","--initial-cluster=instance=https://162.253.55.220:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.220:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.220: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-10-03T22:18:09.328971Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-03T22:18:09.328994Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.220:2380"]} {"level":"info","ts":"2026-10-03T22:18:09.329041Z","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-10-03T22:18:09.329654Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.220:2379"]} {"level":"info","ts":"2026-10-03T22:18:09.329745Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.220:2380"],"listen-peer-urls":["https://162.253.55.220:2380"],"advertise-client-urls":["https://162.253.55.220:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.220:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.220: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-10-03T22:18:09.332442Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.445455ms"} {"level":"info","ts":"2026-10-03T22:18:09.33984Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4c62f064ba268721","cluster-id":"65b58f2bbdfe5647"} {"level":"info","ts":"2026-10-03T22:18:09.339932Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c62f064ba268721 switched to configuration voters=()"} {"level":"info","ts":"2026-10-03T22:18:09.339962Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c62f064ba268721 became follower at term 0"} {"level":"info","ts":"2026-10-03T22:18:09.339986Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4c62f064ba268721 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-03T22:18:09.34Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c62f064ba268721 became follower at term 1"} {"level":"info","ts":"2026-10-03T22:18:09.340046Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c62f064ba268721 switched to configuration voters=(5504226010010650401)"} {"level":"warn","ts":"2026-10-03T22:18:09.346491Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-03T22:18:09.348111Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-03T22:18:09.349663Z","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-10-03T22:18:09.351906Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4c62f064ba268721","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-03T22:18:09.352137Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4c62f064ba268721","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-03T22:18:09.352055Z","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-10-03T22:18:09.35417Z","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-10-03T22:18:09.35423Z","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-10-03T22:18:09.35468Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c62f064ba268721 switched to configuration voters=(5504226010010650401)"} {"level":"info","ts":"2026-10-03T22:18:09.355038Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"65b58f2bbdfe5647","local-member-id":"4c62f064ba268721","added-peer-id":"4c62f064ba268721","added-peer-peer-urls":["https://162.253.55.220:2380"]} {"level":"info","ts":"2026-10-03T22:18:09.359899Z","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-10-03T22:18:09.360017Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.220:2380"} {"level":"info","ts":"2026-10-03T22:18:09.360095Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.220:2380"} {"level":"info","ts":"2026-10-03T22:18:09.360176Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4c62f064ba268721","initial-advertise-peer-urls":["https://162.253.55.220:2380"],"listen-peer-urls":["https://162.253.55.220:2380"],"advertise-client-urls":["https://162.253.55.220:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.220:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-03T22:18:09.360239Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-03T22:18:09.740793Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c62f064ba268721 is starting a new election at term 1"} {"level":"info","ts":"2026-10-03T22:18:09.740916Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c62f064ba268721 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-03T22:18:09.740949Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c62f064ba268721 received MsgPreVoteResp from 4c62f064ba268721 at term 1"} {"level":"info","ts":"2026-10-03T22:18:09.741071Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c62f064ba268721 became candidate at term 2"} {"level":"info","ts":"2026-10-03T22:18:09.741094Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c62f064ba268721 received MsgVoteResp from 4c62f064ba268721 at term 2"} {"level":"info","ts":"2026-10-03T22:18:09.74111Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c62f064ba268721 became leader at term 2"} {"level":"info","ts":"2026-10-03T22:18:09.741147Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4c62f064ba268721 elected leader 4c62f064ba268721 at term 2"} {"level":"info","ts":"2026-10-03T22:18:09.745278Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-03T22:18:09.749273Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4c62f064ba268721","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.220:2379]}","request-path":"/0/members/4c62f064ba268721/attributes","cluster-id":"65b58f2bbdfe5647","publish-timeout":"7s"} {"level":"info","ts":"2026-10-03T22:18:09.749389Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-03T22:18:09.749442Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-03T22:18:09.74982Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-03T22:18:09.74987Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-03T22:18:09.752261Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.220:2379"} {"level":"info","ts":"2026-10-03T22:18:09.757366Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-03T22:18:09.762227Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"65b58f2bbdfe5647","local-member-id":"4c62f064ba268721","cluster-version":"3.5"} {"level":"info","ts":"2026-10-03T22:18:09.762396Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-03T22:18:09.762441Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-03T22:18:11.47989Z","caller":"traceutil/trace.go:171","msg":"trace[22368171] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"301.431056ms","start":"2026-10-03T22:18:11.178438Z","end":"2026-10-03T22:18:11.479869Z","steps":["trace[22368171] 'process raft request' (duration: 301.216962ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:18:11.4805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:18:11.17842Z","time spent":"301.546869ms","remote":"127.0.0.1:54840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T22:18:12.320656Z","caller":"traceutil/trace.go:171","msg":"trace[375159367] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"1.130156168s","start":"2026-10-03T22:18:11.190476Z","end":"2026-10-03T22:18:12.320632Z","steps":["trace[375159367] 'read index received' (duration: 289.254209ms)","trace[375159367] 'applied index is now lower than readState.Index' (duration: 840.900989ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:18:12.320771Z","caller":"traceutil/trace.go:171","msg":"trace[1649760981] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"1.075864443s","start":"2026-10-03T22:18:11.244895Z","end":"2026-10-03T22:18:12.320759Z","steps":["trace[1649760981] 'process raft request' (duration: 1.075588177s)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:18:12.32086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:18:11.244871Z","time spent":"1.075925594s","remote":"127.0.0.1:55266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-03T22:18:12.320877Z","caller":"traceutil/trace.go:171","msg":"trace[324235958] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"1.07621514s","start":"2026-10-03T22:18:11.244496Z","end":"2026-10-03T22:18:12.320711Z","steps":["trace[324235958] 'process raft request' (duration: 1.075961885s)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:18:12.321106Z","caller":"traceutil/trace.go:171","msg":"trace[1758279798] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"1.076102798s","start":"2026-10-03T22:18:11.244986Z","end":"2026-10-03T22:18:12.321089Z","steps":["trace[1758279798] 'process raft request' (duration: 1.075519395s)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:18:12.321159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:18:11.244978Z","time spent":"1.076159079s","remote":"127.0.0.1:55266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-03T22:18:12.321264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:18:11.244484Z","time spent":"1.076551878s","remote":"127.0.0.1:55266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-03T22:18:12.321421Z","caller":"traceutil/trace.go:171","msg":"trace[116673890] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"1.056263116s","start":"2026-10-03T22:18:11.265149Z","end":"2026-10-03T22:18:12.321412Z","steps":["trace[116673890] 'process raft request' (duration: 1.055398227s)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:18:12.321471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:18:11.265134Z","time spent":"1.056316248s","remote":"127.0.0.1:55002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-03T22:18:12.321716Z","caller":"traceutil/trace.go:171","msg":"trace[1380593201] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"1.077308586s","start":"2026-10-03T22:18:11.244396Z","end":"2026-10-03T22:18:12.321705Z","steps":["trace[1380593201] 'process raft request' (duration: 1.076033927s)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:18:12.321787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:18:11.244383Z","time spent":"1.077380497s","remote":"127.0.0.1:55266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-03T22:18:12.321289Z","caller":"traceutil/trace.go:171","msg":"trace[1092353341] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"1.076117638s","start":"2026-10-03T22:18:11.245163Z","end":"2026-10-03T22:18:12.32128Z","steps":["trace[1092353341] 'process raft request' (duration: 1.075363591s)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:18:12.321869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.213834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-10-03T22:18:12.321937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.073396707s","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":"warn","ts":"2026-10-03T22:18:12.322005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:18:11.245148Z","time spent":"1.076779433s","remote":"127.0.0.1:55266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-03T22:18:12.322042Z","caller":"traceutil/trace.go:171","msg":"trace[414943956] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"1.07355891s","start":"2026-10-03T22:18:11.248456Z","end":"2026-10-03T22:18:12.322015Z","steps":["trace[414943956] 'agreement among raft nodes before linearized reading' (duration: 1.073370066s)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:18:12.322044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.025298723s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-10-03T22:18:12.322089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:18:11.248443Z","time spent":"1.073636092s","remote":"127.0.0.1:54860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":26,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"info","ts":"2026-10-03T22:18:12.322118Z","caller":"traceutil/trace.go:171","msg":"trace[579732324] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"1.025413566s","start":"2026-10-03T22:18:11.296684Z","end":"2026-10-03T22:18:12.322097Z","steps":["trace[579732324] 'agreement among raft nodes before linearized reading' (duration: 1.025257232s)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:18:12.322161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:18:11.296672Z","time spent":"1.025481687s","remote":"127.0.0.1:54884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-10-03T22:18:12.322048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.173528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-03T22:18:12.322245Z","caller":"traceutil/trace.go:171","msg":"trace[673634826] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"1.056438011s","start":"2026-10-03T22:18:11.265792Z","end":"2026-10-03T22:18:12.32223Z","steps":["trace[673634826] 'process raft request' (duration: 1.054809224s)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:18:12.322254Z","caller":"traceutil/trace.go:171","msg":"trace[1018768969] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:11; }","duration":"307.443504ms","start":"2026-10-03T22:18:12.014788Z","end":"2026-10-03T22:18:12.322232Z","steps":["trace[1018768969] 'agreement among raft nodes before linearized reading' (duration: 307.079706ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:18:12.322329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:18:11.265775Z","time spent":"1.056510313s","remote":"127.0.0.1:54940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3159,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-03T22:18:12.322391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:18:12.014767Z","time spent":"307.577436ms","remote":"127.0.0.1:54884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/default\" "} {"level":"warn","ts":"2026-10-03T22:18:12.322509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.077636163s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.220\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-10-03T22:18:12.322524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.792257ms","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-10-03T22:18:12.321977Z","caller":"traceutil/trace.go:171","msg":"trace[2116122812] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:11; }","duration":"178.377788ms","start":"2026-10-03T22:18:12.143581Z","end":"2026-10-03T22:18:12.321959Z","steps":["trace[2116122812] 'agreement among raft nodes before linearized reading' (duration: 178.054921ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:18:12.32255Z","caller":"traceutil/trace.go:171","msg":"trace[1996585499] range","detail":"{range_begin:/registry/masterleases/162.253.55.220; range_end:; response_count:0; response_revision:11; }","duration":"1.077693085s","start":"2026-10-03T22:18:11.244846Z","end":"2026-10-03T22:18:12.322539Z","steps":["trace[1996585499] 'agreement among raft nodes before linearized reading' (duration: 1.077637123s)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:18:12.322582Z","caller":"traceutil/trace.go:171","msg":"trace[890977201] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:11; }","duration":"178.855139ms","start":"2026-10-03T22:18:12.143694Z","end":"2026-10-03T22:18:12.322549Z","steps":["trace[890977201] 'agreement among raft nodes before linearized reading' (duration: 178.799738ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:18:12.322606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:18:11.244835Z","time spent":"1.077764226s","remote":"127.0.0.1:54818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":26,"request content":"key:\"/registry/masterleases/162.253.55.220\" "} {"level":"info","ts":"2026-10-03T22:18:12.322851Z","caller":"traceutil/trace.go:171","msg":"trace[2005849474] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"1.07885837s","start":"2026-10-03T22:18:11.243978Z","end":"2026-10-03T22:18:12.322837Z","steps":["trace[2005849474] 'process raft request' (duration: 1.076417995s)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:18:12.32295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:18:11.243956Z","time spent":"1.078964682s","remote":"127.0.0.1:55182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-03T22:18:12.322866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.132380347s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-10-03T22:18:12.323082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.078338449s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-03T22:18:12.323123Z","caller":"traceutil/trace.go:171","msg":"trace[1426079640] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"1.07838139s","start":"2026-10-03T22:18:11.244732Z","end":"2026-10-03T22:18:12.323114Z","steps":["trace[1426079640] 'agreement among raft nodes before linearized reading' (duration: 1.078318859s)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:18:12.323124Z","caller":"traceutil/trace.go:171","msg":"trace[721364841] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"1.132650564s","start":"2026-10-03T22:18:11.190458Z","end":"2026-10-03T22:18:12.323109Z","steps":["trace[721364841] 'agreement among raft nodes before linearized reading' (duration: 1.13203497s)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:18:12.32315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:18:11.244692Z","time spent":"1.078451552s","remote":"127.0.0.1:54884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-10-03T22:18:12.323215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:18:11.190451Z","time spent":"1.132741356s","remote":"127.0.0.1:55130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":26,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-10-03T22:18:12.321705Z","caller":"traceutil/trace.go:171","msg":"trace[1867494284] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"1.142781036s","start":"2026-10-03T22:18:11.178909Z","end":"2026-10-03T22:18:12.32169Z","steps":["trace[1867494284] 'process raft request' (duration: 1.141379204s)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:18:12.323577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:18:11.178896Z","time spent":"1.144604987s","remote":"127.0.0.1:54854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T22:18:12.323603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.093816331s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-03T22:18:12.323714Z","caller":"traceutil/trace.go:171","msg":"trace[1937926799] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"1.093959944s","start":"2026-10-03T22:18:11.22974Z","end":"2026-10-03T22:18:12.3237Z","steps":["trace[1937926799] 'agreement among raft nodes before linearized reading' (duration: 1.091891388s)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:18:12.323801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:18:11.229725Z","time spent":"1.094065306s","remote":"127.0.0.1:54884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-10-03T22:18:19.594968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.524239ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-03T22:18:19.595274Z","caller":"traceutil/trace.go:171","msg":"trace[859543565] linearizableReadLoop","detail":"{readStateIndex:372; appliedIndex:370; }","duration":"516.774205ms","start":"2026-10-03T22:18:19.07848Z","end":"2026-10-03T22:18:19.595254Z","steps":["trace[859543565] 'read index received' (duration: 21.782036ms)","trace[859543565] 'applied index is now lower than readState.Index' (duration: 494.988969ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:18:19.595353Z","caller":"traceutil/trace.go:171","msg":"trace[799329731] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"528.574923ms","start":"2026-10-03T22:18:19.066751Z","end":"2026-10-03T22:18:19.595326Z","steps":["trace[799329731] 'process raft request' (duration: 528.415639ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:18:19.595423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.925579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-10-03T22:18:19.595308Z","caller":"traceutil/trace.go:171","msg":"trace[1772732838] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"532.160425ms","start":"2026-10-03T22:18:19.063101Z","end":"2026-10-03T22:18:19.595261Z","steps":["trace[1772732838] 'process raft request' (duration: 37.158686ms)","trace[1772732838] 'compare' (duration: 494.365895ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:18:19.595487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:18:19.066737Z","time spent":"528.693717ms","remote":"127.0.0.1:55190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3691,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T22:18:19.595494Z","caller":"traceutil/trace.go:171","msg":"trace[783752871] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:363; }","duration":"517.032912ms","start":"2026-10-03T22:18:19.07844Z","end":"2026-10-03T22:18:19.595472Z","steps":["trace[783752871] 'agreement among raft nodes before linearized reading' (duration: 516.895728ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:18:19.595587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:18:19.07842Z","time spent":"517.119033ms","remote":"127.0.0.1:54950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":217,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"warn","ts":"2026-10-03T22:18:19.595625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.311187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"warn","ts":"2026-10-03T22:18:19.595656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:18:19.063075Z","time spent":"532.414771ms","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":680,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-03T22:18:19.595677Z","caller":"traceutil/trace.go:171","msg":"trace[91719215] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:363; }","duration":"466.418819ms","start":"2026-10-03T22:18:19.129244Z","end":"2026-10-03T22:18:19.595663Z","steps":["trace[91719215] 'agreement among raft nodes before linearized reading' (duration: 466.263216ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:18:19.595892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:18:19.129228Z","time spent":"466.644745ms","remote":"127.0.0.1:54950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":236,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-10-03T22:18:19.595661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.888356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-10-03T22:18:19.595998Z","caller":"traceutil/trace.go:171","msg":"trace[1354653253] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:363; }","duration":"367.258394ms","start":"2026-10-03T22:18:19.228716Z","end":"2026-10-03T22:18:19.595974Z","steps":["trace[1354653253] 'agreement among raft nodes before linearized reading' (duration: 366.835435ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:18:19.595702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.95269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-10-03T22:18:19.596058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:18:19.228694Z","time spent":"367.348195ms","remote":"127.0.0.1:54950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":229,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"info","ts":"2026-10-03T22:18:19.596122Z","caller":"traceutil/trace.go:171","msg":"trace[812209486] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:363; }","duration":"317.37281ms","start":"2026-10-03T22:18:19.278719Z","end":"2026-10-03T22:18:19.596092Z","steps":["trace[812209486] 'agreement among raft nodes before linearized reading' (duration: 316.91762ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:18:19.596187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:18:19.278663Z","time spent":"317.506072ms","remote":"127.0.0.1:54950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":229,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"warn","ts":"2026-10-03T22:18:19.596739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.66454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-10-03T22:18:19.596928Z","caller":"traceutil/trace.go:171","msg":"trace[1193446701] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:363; }","duration":"417.887937ms","start":"2026-10-03T22:18:19.179025Z","end":"2026-10-03T22:18:19.596913Z","steps":["trace[1193446701] 'agreement among raft nodes before linearized reading' (duration: 416.534646ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:18:19.59719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:18:19.17901Z","time spent":"418.163392ms","remote":"127.0.0.1:54950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":225,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"info","ts":"2026-10-03T22:18:42.991472Z","caller":"traceutil/trace.go:171","msg":"trace[135181928] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"326.687903ms","start":"2026-10-03T22:18:42.664757Z","end":"2026-10-03T22:18:42.991445Z","steps":["trace[135181928] 'process raft request' (duration: 326.530039ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:18:42.991679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:18:42.664737Z","time spent":"326.814055ms","remote":"127.0.0.1:55010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T22:18:43.000225Z","caller":"traceutil/trace.go:171","msg":"trace[2111679693] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"332.883299ms","start":"2026-10-03T22:18:42.667139Z","end":"2026-10-03T22:18:43.000022Z","steps":["trace[2111679693] 'process raft request' (duration: 332.735296ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:18:43.000371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:18:42.667124Z","time spent":"333.172276ms","remote":"127.0.0.1:55010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T22:18:46.869628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.723309ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-03T22:18:46.869737Z","caller":"traceutil/trace.go:171","msg":"trace[467278182] linearizableReadLoop","detail":"{readStateIndex:541; appliedIndex:540; }","duration":"318.596123ms","start":"2026-10-03T22:18:46.55112Z","end":"2026-10-03T22:18:46.869716Z","steps":["trace[467278182] 'read index received' (duration: 44.281µs)","trace[467278182] 'applied index is now lower than readState.Index' (duration: 318.550252ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:18:46.869824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.701695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-03T22:18:46.869857Z","caller":"traceutil/trace.go:171","msg":"trace[444978777] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:525; }","duration":"318.771307ms","start":"2026-10-03T22:18:46.551076Z","end":"2026-10-03T22:18:46.869847Z","steps":["trace[444978777] 'agreement among raft nodes before linearized reading' (duration: 318.687525ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:18:46.869887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:18:46.551056Z","time spent":"318.823177ms","remote":"127.0.0.1:54786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-03T22:18:46.869936Z","caller":"traceutil/trace.go:171","msg":"trace[1178546415] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"511.966738ms","start":"2026-10-03T22:18:46.357923Z","end":"2026-10-03T22:18:46.86989Z","steps":["trace[1178546415] 'process raft request' (duration: 186.86467ms)","trace[1178546415] 'compare' (duration: 324.462964ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:18:46.870092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:18:46.357898Z","time spent":"512.116311ms","remote":"127.0.0.1:55010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T22:18:47.672415Z","caller":"traceutil/trace.go:171","msg":"trace[1061223501] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"171.208036ms","start":"2026-10-03T22:18:47.501178Z","end":"2026-10-03T22:18:47.672386Z","steps":["trace[1061223501] 'process raft request' (duration: 122.393707ms)","trace[1061223501] 'compare' (duration: 48.671105ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:18:47.672643Z","caller":"traceutil/trace.go:171","msg":"trace[1166410663] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"132.760955ms","start":"2026-10-03T22:18:47.539863Z","end":"2026-10-03T22:18:47.672624Z","steps":["trace[1166410663] 'process raft request' (duration: 132.705464ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:18:47.672764Z","caller":"traceutil/trace.go:171","msg":"trace[809272686] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"155.492348ms","start":"2026-10-03T22:18:47.517209Z","end":"2026-10-03T22:18:47.672702Z","steps":["trace[809272686] 'process raft request' (duration: 155.186772ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:18:47.672853Z","caller":"traceutil/trace.go:171","msg":"trace[300494028] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"155.570759ms","start":"2026-10-03T22:18:47.517269Z","end":"2026-10-03T22:18:47.672839Z","steps":["trace[300494028] 'process raft request' (duration: 155.15665ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:18:47.672852Z","caller":"traceutil/trace.go:171","msg":"trace[42929330] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"154.549848ms","start":"2026-10-03T22:18:47.518263Z","end":"2026-10-03T22:18:47.672813Z","steps":["trace[42929330] 'process raft request' (duration: 154.18438ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:18:47.672917Z","caller":"traceutil/trace.go:171","msg":"trace[2017794480] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"150.117679ms","start":"2026-10-03T22:18:47.522791Z","end":"2026-10-03T22:18:47.672909Z","steps":["trace[2017794480] 'process raft request' (duration: 149.684999ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:18:47.67288Z","caller":"traceutil/trace.go:171","msg":"trace[1286021855] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"170.395528ms","start":"2026-10-03T22:18:47.502475Z","end":"2026-10-03T22:18:47.672871Z","steps":["trace[1286021855] 'process raft request' (duration: 169.864246ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:18:47.673061Z","caller":"traceutil/trace.go:171","msg":"trace[826967255] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"155.949448ms","start":"2026-10-03T22:18:47.517102Z","end":"2026-10-03T22:18:47.673051Z","steps":["trace[826967255] 'process raft request' (duration: 155.266473ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:18:47.707144Z","caller":"traceutil/trace.go:171","msg":"trace[1932508189] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"158.758549ms","start":"2026-10-03T22:18:47.548367Z","end":"2026-10-03T22:18:47.707125Z","steps":["trace[1932508189] 'process raft request' (duration: 158.497454ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:18:47.707273Z","caller":"traceutil/trace.go:171","msg":"trace[1450422360] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"149.844333ms","start":"2026-10-03T22:18:47.55741Z","end":"2026-10-03T22:18:47.707254Z","steps":["trace[1450422360] 'process raft request' (duration: 149.646329ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:18:47.707469Z","caller":"traceutil/trace.go:171","msg":"trace[387732146] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"142.404198ms","start":"2026-10-03T22:18:47.564995Z","end":"2026-10-03T22:18:47.707399Z","steps":["trace[387732146] 'process raft request' (duration: 142.115611ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:18:47.707514Z","caller":"traceutil/trace.go:171","msg":"trace[797035117] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"129.518534ms","start":"2026-10-03T22:18:47.577981Z","end":"2026-10-03T22:18:47.7075Z","steps":["trace[797035117] 'process raft request' (duration: 129.281378ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:18:47.707584Z","caller":"traceutil/trace.go:171","msg":"trace[2098302666] linearizableReadLoop","detail":"{readStateIndex:553; appliedIndex:544; }","duration":"156.216625ms","start":"2026-10-03T22:18:47.551342Z","end":"2026-10-03T22:18:47.707559Z","steps":["trace[2098302666] 'read index received' (duration: 72.172057ms)","trace[2098302666] 'applied index is now lower than readState.Index' (duration: 84.042848ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:18:47.707928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.564902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-03T22:18:47.707983Z","caller":"traceutil/trace.go:171","msg":"trace[303254178] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:540; }","duration":"156.704945ms","start":"2026-10-03T22:18:47.551264Z","end":"2026-10-03T22:18:47.707968Z","steps":["trace[303254178] 'agreement among raft nodes before linearized reading' (duration: 156.559111ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:18:47.98773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.77917ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-03T22:18:47.988326Z","caller":"traceutil/trace.go:171","msg":"trace[919600826] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"296.998616ms","start":"2026-10-03T22:18:47.691311Z","end":"2026-10-03T22:18:47.98831Z","steps":["trace[919600826] 'process raft request' (duration: 296.838023ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:18:47.988492Z","caller":"traceutil/trace.go:171","msg":"trace[1838701797] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"302.687422ms","start":"2026-10-03T22:18:47.685782Z","end":"2026-10-03T22:18:47.988469Z","steps":["trace[1838701797] 'process raft request' (duration: 302.299493ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:18:47.988525Z","caller":"traceutil/trace.go:171","msg":"trace[496558247] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"297.341144ms","start":"2026-10-03T22:18:47.691173Z","end":"2026-10-03T22:18:47.988514Z","steps":["trace[496558247] 'process raft request' (duration: 296.931975ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:18:47.988558Z","caller":"traceutil/trace.go:171","msg":"trace[1958180662] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"305.640107ms","start":"2026-10-03T22:18:47.682907Z","end":"2026-10-03T22:18:47.988547Z","steps":["trace[1958180662] 'process raft request' (duration: 305.106306ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:18:47.988489Z","caller":"traceutil/trace.go:171","msg":"trace[1838279553] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"304.684995ms","start":"2026-10-03T22:18:47.68368Z","end":"2026-10-03T22:18:47.988365Z","steps":["trace[1838279553] 'process raft request' (duration: 304.376889ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:18:47.988751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:18:47.682901Z","time spent":"305.729759ms","remote":"127.0.0.1:54834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6888,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T22:18:47.988847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:18:47.685758Z","time spent":"302.877216ms","remote":"127.0.0.1:55266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-03T22:18:47.98888Z","caller":"traceutil/trace.go:171","msg":"trace[1993558900] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"307.607131ms","start":"2026-10-03T22:18:47.681247Z","end":"2026-10-03T22:18:47.988854Z","steps":["trace[1993558900] 'process raft request' (duration: 306.562717ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:18:47.988882Z","caller":"traceutil/trace.go:171","msg":"trace[1997845710] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"307.676822ms","start":"2026-10-03T22:18:47.681157Z","end":"2026-10-03T22:18:47.988834Z","steps":["trace[1997845710] 'process raft request' (duration: 95.769627ms)","trace[1997845710] 'compare' (duration: 210.571656ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:18:47.988985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:18:47.681235Z","time spent":"307.702784ms","remote":"127.0.0.1:55266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-03T22:18:47.989042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:18:47.681136Z","time spent":"307.806006ms","remote":"127.0.0.1:54834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-03T22:18:47.989157Z","caller":"traceutil/trace.go:171","msg":"trace[693746637] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"307.857327ms","start":"2026-10-03T22:18:47.681281Z","end":"2026-10-03T22:18:47.989138Z","steps":["trace[693746637] 'process raft request' (duration: 306.694181ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:18:47.989234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:18:47.681275Z","time spent":"307.923538ms","remote":"127.0.0.1:55266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-03T22:18:47.988795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:18:47.683674Z","time spent":"305.063113ms","remote":"127.0.0.1:55266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-03T22:18:47.989546Z","caller":"traceutil/trace.go:171","msg":"trace[746715985] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"298.128522ms","start":"2026-10-03T22:18:47.691373Z","end":"2026-10-03T22:18:47.989502Z","steps":["trace[746715985] 'process raft request' (duration: 296.892774ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:18:47.98966Z","caller":"traceutil/trace.go:171","msg":"trace[858619440] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"293.482948ms","start":"2026-10-03T22:18:47.696117Z","end":"2026-10-03T22:18:47.9896Z","steps":["trace[858619440] 'process raft request' (duration: 293.271753ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:18:47.989719Z","caller":"traceutil/trace.go:171","msg":"trace[1328304283] linearizableReadLoop","detail":"{readStateIndex:569; appliedIndex:556; }","duration":"127.370006ms","start":"2026-10-03T22:18:47.862324Z","end":"2026-10-03T22:18:47.989694Z","steps":["trace[1328304283] 'read index received' (duration: 69.20789ms)","trace[1328304283] 'applied index is now lower than readState.Index' (duration: 58.161246ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:18:47.989919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.57926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-03T22:18:47.989946Z","caller":"traceutil/trace.go:171","msg":"trace[259124244] transaction","detail":"{read_only:false; number_of_response:1; response_revision:550; }","duration":"272.29546ms","start":"2026-10-03T22:18:47.717638Z","end":"2026-10-03T22:18:47.989933Z","steps":["trace[259124244] 'process raft request' (duration: 271.949713ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:18:47.989962Z","caller":"traceutil/trace.go:171","msg":"trace[309379082] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:551; }","duration":"127.660622ms","start":"2026-10-03T22:18:47.86229Z","end":"2026-10-03T22:18:47.989951Z","steps":["trace[309379082] 'agreement among raft nodes before linearized reading' (duration: 127.497829ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:18:47.99001Z","caller":"traceutil/trace.go:171","msg":"trace[993239895] transaction","detail":"{read_only:false; number_of_response:0; response_revision:550; }","duration":"278.843814ms","start":"2026-10-03T22:18:47.711149Z","end":"2026-10-03T22:18:47.989993Z","steps":["trace[993239895] 'process raft request' (duration: 278.409745ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:18:47.990153Z","caller":"traceutil/trace.go:171","msg":"trace[1254336099] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"261.625893ms","start":"2026-10-03T22:18:47.728515Z","end":"2026-10-03T22:18:47.990141Z","steps":["trace[1254336099] 'process raft request' (duration: 261.135483ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:18:48.300994Z","caller":"traceutil/trace.go:171","msg":"trace[1540575719] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"101.498354ms","start":"2026-10-03T22:18:48.199467Z","end":"2026-10-03T22:18:48.300965Z","steps":["trace[1540575719] 'process raft request' (duration: 95.541193ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:18:50.508864Z","caller":"traceutil/trace.go:171","msg":"trace[1347155172] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"567.64112ms","start":"2026-10-03T22:18:49.941156Z","end":"2026-10-03T22:18:50.508797Z","steps":["trace[1347155172] 'process raft request' (duration: 567.365704ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:18:50.509171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:18:49.940971Z","time spent":"568.07722ms","remote":"127.0.0.1:54834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-03T22:18:50.509139Z","caller":"traceutil/trace.go:171","msg":"trace[1279533668] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:657; }","duration":"482.279343ms","start":"2026-10-03T22:18:50.026807Z","end":"2026-10-03T22:18:50.509086Z","steps":["trace[1279533668] 'read index received' (duration: 482.265603ms)","trace[1279533668] 'applied index is now lower than readState.Index' (duration: 10.43µs)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:18:50.509513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.675101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-03T22:18:50.509562Z","caller":"traceutil/trace.go:171","msg":"trace[1116993164] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:633; }","duration":"482.778764ms","start":"2026-10-03T22:18:50.026771Z","end":"2026-10-03T22:18:50.509549Z","steps":["trace[1116993164] 'agreement among raft nodes before linearized reading' (duration: 482.540659ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:18:50.5096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:18:50.026753Z","time spent":"482.837295ms","remote":"127.0.0.1:55010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-03T22:18:50.5775Z","caller":"traceutil/trace.go:171","msg":"trace[2115066630] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"232.152482ms","start":"2026-10-03T22:18:50.345291Z","end":"2026-10-03T22:18:50.577443Z","steps":["trace[2115066630] 'process raft request' (duration: 231.986289ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:18:50.577681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.441623ms","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-10-03T22:18:50.577763Z","caller":"traceutil/trace.go:171","msg":"trace[1901693213] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:635; }","duration":"267.576347ms","start":"2026-10-03T22:18:50.310166Z","end":"2026-10-03T22:18:50.577742Z","steps":["trace[1901693213] 'agreement among raft nodes before linearized reading' (duration: 267.32157ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:18:50.577841Z","caller":"traceutil/trace.go:171","msg":"trace[1655002752] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"432.643105ms","start":"2026-10-03T22:18:50.145181Z","end":"2026-10-03T22:18:50.577824Z","steps":["trace[1655002752] 'process raft request' (duration: 418.005121ms)","trace[1655002752] 'compare' (duration: 13.971879ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:18:50.578094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:18:50.145156Z","time spent":"432.723787ms","remote":"127.0.0.1:54834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-03T22:18:53.429819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.157471ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-03T22:18:53.429943Z","caller":"traceutil/trace.go:171","msg":"trace[358328015] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"265.862608ms","start":"2026-10-03T22:18:53.164051Z","end":"2026-10-03T22:18:53.429914Z","steps":["trace[358328015] 'process raft request' (duration: 91.541313ms)","trace[358328015] 'compare' (duration: 174.018007ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:18:53.666198Z","caller":"traceutil/trace.go:171","msg":"trace[974284732] linearizableReadLoop","detail":"{readStateIndex:698; appliedIndex:697; }","duration":"126.258761ms","start":"2026-10-03T22:18:53.539916Z","end":"2026-10-03T22:18:53.666175Z","steps":["trace[974284732] 'read index received' (duration: 30.364341ms)","trace[974284732] 'applied index is now lower than readState.Index' (duration: 95.89358ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:18:53.666298Z","caller":"traceutil/trace.go:171","msg":"trace[266395427] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"144.995995ms","start":"2026-10-03T22:18:53.521216Z","end":"2026-10-03T22:18:53.666212Z","steps":["trace[266395427] 'process raft request' (duration: 48.85607ms)","trace[266395427] 'compare' (duration: 95.861739ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:18:53.666472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.538018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188252"} {"level":"info","ts":"2026-10-03T22:18:53.666514Z","caller":"traceutil/trace.go:171","msg":"trace[1053326101] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:673; }","duration":"126.62553ms","start":"2026-10-03T22:18:53.539876Z","end":"2026-10-03T22:18:53.666501Z","steps":["trace[1053326101] 'agreement among raft nodes before linearized reading' (duration: 126.400814ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:18:53.794544Z","caller":"traceutil/trace.go:171","msg":"trace[1195614569] linearizableReadLoop","detail":"{readStateIndex:699; appliedIndex:698; }","duration":"128.265716ms","start":"2026-10-03T22:18:53.666251Z","end":"2026-10-03T22:18:53.794517Z","steps":["trace[1195614569] 'read index received' (duration: 125.073686ms)","trace[1195614569] 'applied index is now lower than readState.Index' (duration: 3.19096ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:18:53.794686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.082143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-03T22:18:53.794674Z","caller":"traceutil/trace.go:171","msg":"trace[1052609377] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"235.808583ms","start":"2026-10-03T22:18:53.558796Z","end":"2026-10-03T22:18:53.794605Z","steps":["trace[1052609377] 'process raft request' (duration: 233.356488ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:18:53.794857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.67903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-10-03T22:18:53.794932Z","caller":"traceutil/trace.go:171","msg":"trace[49345677] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:674; }","duration":"126.795333ms","start":"2026-10-03T22:18:53.668124Z","end":"2026-10-03T22:18:53.79492Z","steps":["trace[49345677] 'agreement among raft nodes before linearized reading' (duration: 126.574558ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:18:53.794742Z","caller":"traceutil/trace.go:171","msg":"trace[1632605565] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:674; }","duration":"243.238887ms","start":"2026-10-03T22:18:53.551488Z","end":"2026-10-03T22:18:53.794727Z","steps":["trace[1632605565] 'agreement among raft nodes before linearized reading' (duration: 243.140665ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:18:58.786356Z","caller":"traceutil/trace.go:171","msg":"trace[502316146] linearizableReadLoop","detail":"{readStateIndex:727; appliedIndex:726; }","duration":"101.789619ms","start":"2026-10-03T22:18:58.684542Z","end":"2026-10-03T22:18:58.786331Z","steps":["trace[502316146] 'read index received' (duration: 101.589285ms)","trace[502316146] 'applied index is now lower than readState.Index' (duration: 199.244µs)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:18:58.786435Z","caller":"traceutil/trace.go:171","msg":"trace[55012623] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"121.948496ms","start":"2026-10-03T22:18:58.66446Z","end":"2026-10-03T22:18:58.786408Z","steps":["trace[55012623] 'process raft request' (duration: 121.720281ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:18:58.786577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.011285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-03T22:18:58.786662Z","caller":"traceutil/trace.go:171","msg":"trace[1202415558] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:702; }","duration":"102.129828ms","start":"2026-10-03T22:18:58.684516Z","end":"2026-10-03T22:18:58.786646Z","steps":["trace[1202415558] 'agreement among raft nodes before linearized reading' (duration: 101.928673ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:18:59.208108Z","caller":"traceutil/trace.go:171","msg":"trace[1979614878] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"417.670714ms","start":"2026-10-03T22:18:58.790419Z","end":"2026-10-03T22:18:59.20809Z","steps":["trace[1979614878] 'process raft request' (duration: 382.553257ms)","trace[1979614878] 'compare' (duration: 35.030245ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:18:59.208223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:18:58.790393Z","time spent":"417.771947ms","remote":"127.0.0.1:55010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T22:18:59.37612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.88755ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-10-03T22:18:59.376206Z","caller":"traceutil/trace.go:171","msg":"trace[674002218] linearizableReadLoop","detail":"{readStateIndex:729; appliedIndex:728; }","duration":"201.224889ms","start":"2026-10-03T22:18:59.174965Z","end":"2026-10-03T22:18:59.37619Z","steps":["trace[674002218] 'read index received' (duration: 91.189086ms)","trace[674002218] 'applied index is now lower than readState.Index' (duration: 110.034583ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:18:59.376365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.385572ms","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-10-03T22:18:59.376409Z","caller":"traceutil/trace.go:171","msg":"trace[241963087] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:703; }","duration":"201.458364ms","start":"2026-10-03T22:18:59.174937Z","end":"2026-10-03T22:18:59.376395Z","steps":["trace[241963087] 'agreement among raft nodes before linearized reading' (duration: 201.303071ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:18:59.3764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.551097ms","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-10-03T22:18:59.376458Z","caller":"traceutil/trace.go:171","msg":"trace[808005983] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:703; }","duration":"173.637809ms","start":"2026-10-03T22:18:59.202805Z","end":"2026-10-03T22:18:59.376443Z","steps":["trace[808005983] 'agreement among raft nodes before linearized reading' (duration: 173.466056ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:19:00.052657Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9737240907242684885,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-10-03T22:19:00.552804Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9737240907242684885,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-10-03T22:19:00.930329Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.451776797s","expected-duration":"1s"} {"level":"info","ts":"2026-10-03T22:19:00.942273Z","caller":"traceutil/trace.go:171","msg":"trace[277554939] linearizableReadLoop","detail":"{readStateIndex:732; appliedIndex:731; }","duration":"1.390518412s","start":"2026-10-03T22:18:59.55173Z","end":"2026-10-03T22:19:00.942248Z","steps":["trace[277554939] 'read index received' (duration: 1.378832854s)","trace[277554939] 'applied index is now lower than readState.Index' (duration: 11.684218ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:19:00.942392Z","caller":"traceutil/trace.go:171","msg":"trace[1785386157] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"1.466357518s","start":"2026-10-03T22:18:59.476023Z","end":"2026-10-03T22:19:00.94238Z","steps":["trace[1785386157] 'process raft request' (duration: 1.454624569s)","trace[1785386157] 'compare' (duration: 11.435542ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:19:00.942472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.195284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-10-03T22:19:00.942487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:18:59.476001Z","time spent":"1.466413739s","remote":"127.0.0.1:55010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T22:19:00.942526Z","caller":"traceutil/trace.go:171","msg":"trace[644657655] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:706; }","duration":"152.288576ms","start":"2026-10-03T22:19:00.790222Z","end":"2026-10-03T22:19:00.942511Z","steps":["trace[644657655] 'agreement among raft nodes before linearized reading' (duration: 152.158724ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:19:00.942611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.3908869s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-03T22:19:00.942642Z","caller":"traceutil/trace.go:171","msg":"trace[706267762] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:706; }","duration":"1.39094127s","start":"2026-10-03T22:18:59.551696Z","end":"2026-10-03T22:19:00.942637Z","steps":["trace[706267762] 'agreement among raft nodes before linearized reading' (duration: 1.390901569s)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:19:00.942657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:18:59.55168Z","time spent":"1.390972461s","remote":"127.0.0.1:54786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-03T22:19:04.855Z","caller":"traceutil/trace.go:171","msg":"trace[840903739] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"108.475819ms","start":"2026-10-03T22:19:04.746462Z","end":"2026-10-03T22:19:04.854938Z","steps":["trace[840903739] 'process raft request' (duration: 101.832671ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:19:05.478673Z","caller":"traceutil/trace.go:171","msg":"trace[857958331] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"187.774481ms","start":"2026-10-03T22:19:05.290852Z","end":"2026-10-03T22:19:05.478626Z","steps":["trace[857958331] 'process raft request' (duration: 168.872013ms)","trace[857958331] 'compare' (duration: 18.766555ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:19:05.71145Z","caller":"traceutil/trace.go:171","msg":"trace[1377070219] linearizableReadLoop","detail":"{readStateIndex:763; appliedIndex:762; }","duration":"218.49729ms","start":"2026-10-03T22:19:05.492927Z","end":"2026-10-03T22:19:05.711424Z","steps":["trace[1377070219] 'read index received' (duration: 218.335377ms)","trace[1377070219] 'applied index is now lower than readState.Index' (duration: 160.553µs)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:19:05.711576Z","caller":"traceutil/trace.go:171","msg":"trace[71625773] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"228.4404ms","start":"2026-10-03T22:19:05.483086Z","end":"2026-10-03T22:19:05.711526Z","steps":["trace[71625773] 'process raft request' (duration: 228.170804ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:19:05.711735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.792588ms","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-10-03T22:19:05.7118Z","caller":"traceutil/trace.go:171","msg":"trace[67254420] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:736; }","duration":"218.9013ms","start":"2026-10-03T22:19:05.492887Z","end":"2026-10-03T22:19:05.711789Z","steps":["trace[67254420] 'agreement among raft nodes before linearized reading' (duration: 218.666224ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:19:05.733586Z","caller":"traceutil/trace.go:171","msg":"trace[636695569] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"210.909643ms","start":"2026-10-03T22:19:05.522662Z","end":"2026-10-03T22:19:05.733572Z","steps":["trace[636695569] 'process raft request' (duration: 210.816851ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:19:05.733924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.183928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-03T22:19:05.734004Z","caller":"traceutil/trace.go:171","msg":"trace[588818974] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:737; }","duration":"182.317921ms","start":"2026-10-03T22:19:05.551668Z","end":"2026-10-03T22:19:05.733986Z","steps":["trace[588818974] 'agreement among raft nodes before linearized reading' (duration: 182.136927ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:19:06.87247Z","caller":"traceutil/trace.go:171","msg":"trace[456460753] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"121.975628ms","start":"2026-10-03T22:19:06.750469Z","end":"2026-10-03T22:19:06.872445Z","steps":["trace[456460753] 'process raft request' (duration: 121.811154ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:19:07.012928Z","caller":"traceutil/trace.go:171","msg":"trace[260235120] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"245.916587ms","start":"2026-10-03T22:19:06.76698Z","end":"2026-10-03T22:19:07.012896Z","steps":["trace[260235120] 'process raft request' (duration: 245.719503ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:19:07.804873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.492055ms","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-10-03T22:19:07.804966Z","caller":"traceutil/trace.go:171","msg":"trace[256232477] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:745; }","duration":"453.628949ms","start":"2026-10-03T22:19:07.351316Z","end":"2026-10-03T22:19:07.804945Z","steps":["trace[256232477] 'range keys from in-memory index tree' (duration: 453.351362ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:19:07.805055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:19:07.351298Z","time spent":"453.737382ms","remote":"127.0.0.1:55010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-03T22:19:07.835252Z","caller":"traceutil/trace.go:171","msg":"trace[227765430] linearizableReadLoop","detail":"{readStateIndex:773; appliedIndex:772; }","duration":"283.860456ms","start":"2026-10-03T22:19:07.551375Z","end":"2026-10-03T22:19:07.835236Z","steps":["trace[227765430] 'read index received' (duration: 283.821625ms)","trace[227765430] 'applied index is now lower than readState.Index' (duration: 37.861µs)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:19:07.835336Z","caller":"traceutil/trace.go:171","msg":"trace[593309717] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"333.995224ms","start":"2026-10-03T22:19:07.501332Z","end":"2026-10-03T22:19:07.835327Z","steps":["trace[593309717] 'process raft request' (duration: 333.7916ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:19:07.835509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:19:07.501305Z","time spent":"334.060855ms","remote":"127.0.0.1:55010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T22:19:07.835513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.127421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-03T22:19:07.835602Z","caller":"traceutil/trace.go:171","msg":"trace[82970939] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:746; }","duration":"284.255984ms","start":"2026-10-03T22:19:07.551324Z","end":"2026-10-03T22:19:07.83558Z","steps":["trace[82970939] 'agreement among raft nodes before linearized reading' (duration: 284.129772ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:19:07.839612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.181443ms","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-10-03T22:19:07.839702Z","caller":"traceutil/trace.go:171","msg":"trace[1569131287] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:747; }","duration":"123.309616ms","start":"2026-10-03T22:19:07.716376Z","end":"2026-10-03T22:19:07.839685Z","steps":["trace[1569131287] 'agreement among raft nodes before linearized reading' (duration: 123.123852ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:19:08.022381Z","caller":"traceutil/trace.go:171","msg":"trace[870774803] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"178.449055ms","start":"2026-10-03T22:19:07.843911Z","end":"2026-10-03T22:19:08.02236Z","steps":["trace[870774803] 'process raft request' (duration: 178.252861ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:19:08.022583Z","caller":"traceutil/trace.go:171","msg":"trace[845814358] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"166.063312ms","start":"2026-10-03T22:19:07.85651Z","end":"2026-10-03T22:19:08.022573Z","steps":["trace[845814358] 'process raft request' (duration: 165.782616ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:19:16.954424Z","caller":"traceutil/trace.go:171","msg":"trace[818299277] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"113.633442ms","start":"2026-10-03T22:19:16.840756Z","end":"2026-10-03T22:19:16.954389Z","steps":["trace[818299277] 'process raft request' (duration: 113.183253ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:19:16.954532Z","caller":"traceutil/trace.go:171","msg":"trace[1404290460] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"106.537766ms","start":"2026-10-03T22:19:16.84791Z","end":"2026-10-03T22:19:16.954448Z","steps":["trace[1404290460] 'process raft request' (duration: 106.302721ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:19:17.328615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.910075ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-03T22:19:17.32888Z","caller":"traceutil/trace.go:171","msg":"trace[1196535666] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"367.601897ms","start":"2026-10-03T22:19:16.961242Z","end":"2026-10-03T22:19:17.328844Z","steps":["trace[1196535666] 'process raft request' (duration: 107.365844ms)","trace[1196535666] 'compare' (duration: 259.743392ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:19:17.32892Z","caller":"traceutil/trace.go:171","msg":"trace[806213785] linearizableReadLoop","detail":"{readStateIndex:844; appliedIndex:842; }","duration":"220.866973ms","start":"2026-10-03T22:19:17.108015Z","end":"2026-10-03T22:19:17.328882Z","steps":["trace[806213785] 'read index received' (duration: 43.011µs)","trace[806213785] 'applied index is now lower than readState.Index' (duration: 220.822432ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:19:17.328977Z","caller":"traceutil/trace.go:171","msg":"trace[1188280883] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"365.8916ms","start":"2026-10-03T22:19:16.96305Z","end":"2026-10-03T22:19:17.328941Z","steps":["trace[1188280883] 'process raft request' (duration: 365.705266ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:19:17.329089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:19:16.961219Z","time spent":"367.739401ms","remote":"127.0.0.1:54924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":780,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T22:19:17.329248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.223201ms","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-10-03T22:19:17.329324Z","caller":"traceutil/trace.go:171","msg":"trace[125033533] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:813; }","duration":"221.342673ms","start":"2026-10-03T22:19:17.107962Z","end":"2026-10-03T22:19:17.329304Z","steps":["trace[125033533] 'agreement among raft nodes before linearized reading' (duration: 221.154779ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:19:17.329319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:19:16.963037Z","time spent":"366.034013ms","remote":"127.0.0.1:55212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3852,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T22:19:17.329329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.407302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-10-03T22:19:17.329381Z","caller":"traceutil/trace.go:171","msg":"trace[1924236035] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:813; }","duration":"153.495874ms","start":"2026-10-03T22:19:17.175869Z","end":"2026-10-03T22:19:17.329365Z","steps":["trace[1924236035] 'agreement among raft nodes before linearized reading' (duration: 153.32267ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:19:20.626267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.803408ms","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-10-03T22:19:20.626351Z","caller":"traceutil/trace.go:171","msg":"trace[2132311449] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:830; }","duration":"650.934731ms","start":"2026-10-03T22:19:19.975396Z","end":"2026-10-03T22:19:20.626331Z","steps":["trace[2132311449] 'range keys from in-memory index tree' (duration: 650.605314ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:19:20.626396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:19:19.975382Z","time spent":"651.001573ms","remote":"127.0.0.1:55010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-03T22:19:20.626489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.73496ms","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-10-03T22:19:20.626607Z","caller":"traceutil/trace.go:171","msg":"trace[544518144] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:830; }","duration":"490.922094ms","start":"2026-10-03T22:19:20.135666Z","end":"2026-10-03T22:19:20.626588Z","steps":["trace[544518144] 'range keys from in-memory index tree' (duration: 490.325401ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:19:20.626676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:19:20.135622Z","time spent":"491.021236ms","remote":"127.0.0.1:55010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-03T22:19:20.626622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.050865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-03T22:19:20.626752Z","caller":"traceutil/trace.go:171","msg":"trace[903723375] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:830; }","duration":"718.230999ms","start":"2026-10-03T22:19:19.908498Z","end":"2026-10-03T22:19:20.626729Z","steps":["trace[903723375] 'range keys from in-memory index tree' (duration: 717.399931ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:19:20.626817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:19:19.908479Z","time spent":"718.327421ms","remote":"127.0.0.1:55010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-03T22:19:20.626839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"800.968118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-hrxls\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-10-03T22:19:20.626926Z","caller":"traceutil/trace.go:171","msg":"trace[582156808] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-hrxls; range_end:; response_count:1; response_revision:830; }","duration":"801.104741ms","start":"2026-10-03T22:19:19.825798Z","end":"2026-10-03T22:19:20.626902Z","steps":["trace[582156808] 'range keys from in-memory index tree' (duration: 800.384095ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:19:20.626976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:19:19.825777Z","time spent":"801.187503ms","remote":"127.0.0.1:54942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-hrxls\" "} {"level":"info","ts":"2026-10-03T22:19:25.491646Z","caller":"traceutil/trace.go:171","msg":"trace[1709551812] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"112.679952ms","start":"2026-10-03T22:19:25.378936Z","end":"2026-10-03T22:19:25.491616Z","steps":["trace[1709551812] 'process raft request' (duration: 78.650899ms)","trace[1709551812] 'compare' (duration: 33.89737ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:19:32.222989Z","caller":"traceutil/trace.go:171","msg":"trace[1452079577] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"145.234311ms","start":"2026-10-03T22:19:32.077734Z","end":"2026-10-03T22:19:32.222969Z","steps":["trace[1452079577] 'process raft request' (duration: 145.094158ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:19:56.933879Z","caller":"traceutil/trace.go:171","msg":"trace[1640473052] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"100.766147ms","start":"2026-10-03T22:19:56.833088Z","end":"2026-10-03T22:19:56.933854Z","steps":["trace[1640473052] 'process raft request' (duration: 100.574503ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:19:57.029336Z","caller":"traceutil/trace.go:171","msg":"trace[1090863422] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"156.685223ms","start":"2026-10-03T22:19:56.872624Z","end":"2026-10-03T22:19:57.029309Z","steps":["trace[1090863422] 'process raft request' (duration: 94.882569ms)","trace[1090863422] 'compare' (duration: 61.64184ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:20:01.308946Z","caller":"traceutil/trace.go:171","msg":"trace[718956552] linearizableReadLoop","detail":"{readStateIndex:1155; appliedIndex:1154; }","duration":"258.502222ms","start":"2026-10-03T22:20:01.050394Z","end":"2026-10-03T22:20:01.308896Z","steps":["trace[718956552] 'read index received' (duration: 256.864857ms)","trace[718956552] 'applied index is now lower than readState.Index' (duration: 1.636235ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:20:01.309084Z","caller":"traceutil/trace.go:171","msg":"trace[1777809900] transaction","detail":"{read_only:false; response_revision:1114; number_of_response:1; }","duration":"344.154319ms","start":"2026-10-03T22:20:00.964897Z","end":"2026-10-03T22:20:01.309052Z","steps":["trace[1777809900] 'process raft request' (duration: 342.38325ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:20:01.309185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.778799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-10-03T22:20:01.309205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:20:00.964881Z","time spent":"344.260552ms","remote":"127.0.0.1:55010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T22:20:01.309222Z","caller":"traceutil/trace.go:171","msg":"trace[849713432] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1114; }","duration":"258.865471ms","start":"2026-10-03T22:20:01.050343Z","end":"2026-10-03T22:20:01.309209Z","steps":["trace[849713432] 'agreement among raft nodes before linearized reading' (duration: 258.709068ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:20:02.72691Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9737240907242685791,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-10-03T22:20:02.859984Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.137468688s","expected-duration":"1s"} {"level":"info","ts":"2026-10-03T22:20:02.860304Z","caller":"traceutil/trace.go:171","msg":"trace[1315018915] transaction","detail":"{read_only:false; response_revision:1117; number_of_response:1; }","duration":"1.137845635s","start":"2026-10-03T22:20:01.722434Z","end":"2026-10-03T22:20:02.86028Z","steps":["trace[1315018915] 'process raft request' (duration: 1.137713852s)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:20:02.860487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:20:01.722418Z","time spent":"1.137993939s","remote":"127.0.0.1:55010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T22:20:03.563716Z","caller":"traceutil/trace.go:171","msg":"trace[654478250] linearizableReadLoop","detail":"{readStateIndex:1159; appliedIndex:1157; }","duration":"1.337341305s","start":"2026-10-03T22:20:02.226331Z","end":"2026-10-03T22:20:03.563672Z","steps":["trace[654478250] 'read index received' (duration: 633.839955ms)","trace[654478250] 'applied index is now lower than readState.Index' (duration: 703.50007ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:20:03.563792Z","caller":"traceutil/trace.go:171","msg":"trace[1267292761] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"1.209121705s","start":"2026-10-03T22:20:02.354633Z","end":"2026-10-03T22:20:03.563754Z","steps":["trace[1267292761] 'process raft request' (duration: 1.208893911s)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:20:03.563888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.337515718s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-03T22:20:03.563932Z","caller":"traceutil/trace.go:171","msg":"trace[159473474] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1118; }","duration":"1.337639761s","start":"2026-10-03T22:20:02.22628Z","end":"2026-10-03T22:20:03.563919Z","steps":["trace[159473474] 'agreement among raft nodes before linearized reading' (duration: 1.337534989s)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:20:03.563921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.011985387s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-03T22:20:03.563971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:20:02.226262Z","time spent":"1.337699363s","remote":"127.0.0.1:44912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-10-03T22:20:03.563985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:20:02.354606Z","time spent":"1.209281159s","remote":"127.0.0.1:55010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T22:20:03.564017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.333914ms","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-10-03T22:20:03.564089Z","caller":"traceutil/trace.go:171","msg":"trace[1710892500] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1118; }","duration":"250.439726ms","start":"2026-10-03T22:20:03.313631Z","end":"2026-10-03T22:20:03.56407Z","steps":["trace[1710892500] 'agreement among raft nodes before linearized reading' (duration: 250.247691ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:20:03.563977Z","caller":"traceutil/trace.go:171","msg":"trace[1523219787] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1118; }","duration":"1.012091929s","start":"2026-10-03T22:20:02.551871Z","end":"2026-10-03T22:20:03.563963Z","steps":["trace[1523219787] 'agreement among raft nodes before linearized reading' (duration: 1.011993387s)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:20:03.564129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"784.188088ms","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-10-03T22:20:03.564151Z","caller":"traceutil/trace.go:171","msg":"trace[931306054] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1118; }","duration":"784.24775ms","start":"2026-10-03T22:20:02.779896Z","end":"2026-10-03T22:20:03.564144Z","steps":["trace[931306054] 'agreement among raft nodes before linearized reading' (duration: 784.213979ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:20:03.564155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:20:02.551851Z","time spent":"1.012291145s","remote":"127.0.0.1:54786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-03T22:20:03.56418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:20:02.779859Z","time spent":"784.314012ms","remote":"127.0.0.1:55018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":27,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-10-03T22:20:03.564313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.499528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.220\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-10-03T22:20:03.564375Z","caller":"traceutil/trace.go:171","msg":"trace[55137827] range","detail":"{range_begin:/registry/masterleases/162.253.55.220; range_end:; response_count:1; response_revision:1118; }","duration":"119.59687ms","start":"2026-10-03T22:20:03.444763Z","end":"2026-10-03T22:20:03.56436Z","steps":["trace[55137827] 'agreement among raft nodes before linearized reading' (duration: 119.432087ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:20:03.564394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.252139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-10-03T22:20:03.564096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.792532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-03T22:20:03.564427Z","caller":"traceutil/trace.go:171","msg":"trace[1570126018] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1118; }","duration":"243.3231ms","start":"2026-10-03T22:20:03.321094Z","end":"2026-10-03T22:20:03.564417Z","steps":["trace[1570126018] 'agreement among raft nodes before linearized reading' (duration: 243.186028ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:20:03.564472Z","caller":"traceutil/trace.go:171","msg":"trace[2083996319] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1118; }","duration":"601.19283ms","start":"2026-10-03T22:20:02.963261Z","end":"2026-10-03T22:20:03.564454Z","steps":["trace[2083996319] 'agreement among raft nodes before linearized reading' (duration: 600.73256ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:20:03.564533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:20:02.963207Z","time spent":"601.308923ms","remote":"127.0.0.1:55010","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-10-03T22:20:17.120438Z","caller":"traceutil/trace.go:171","msg":"trace[1058663665] transaction","detail":"{read_only:false; response_revision:1164; number_of_response:1; }","duration":"174.585804ms","start":"2026-10-03T22:20:16.945782Z","end":"2026-10-03T22:20:17.120368Z","steps":["trace[1058663665] 'process raft request' (duration: 174.278697ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:20:17.758146Z","caller":"traceutil/trace.go:171","msg":"trace[313629281] transaction","detail":"{read_only:false; response_revision:1166; number_of_response:1; }","duration":"104.324655ms","start":"2026-10-03T22:20:17.653797Z","end":"2026-10-03T22:20:17.758122Z","steps":["trace[313629281] 'process raft request' (duration: 90.213866ms)","trace[313629281] 'compare' (duration: 13.916265ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:20:19.299122Z","caller":"traceutil/trace.go:171","msg":"trace[1646870700] transaction","detail":"{read_only:false; response_revision:1170; number_of_response:1; }","duration":"358.759498ms","start":"2026-10-03T22:20:18.940321Z","end":"2026-10-03T22:20:19.29908Z","steps":["trace[1646870700] 'process raft request' (duration: 358.590865ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:20:19.299298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:20:18.940288Z","time spent":"358.930542ms","remote":"127.0.0.1:55010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T22:20:19.777972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.217755ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-10-03T22:20:19.77826Z","caller":"traceutil/trace.go:171","msg":"trace[1336102051] linearizableReadLoop","detail":"{readStateIndex:1215; appliedIndex:1213; }","duration":"652.209137ms","start":"2026-10-03T22:20:19.126002Z","end":"2026-10-03T22:20:19.778211Z","steps":["trace[1336102051] 'read index received' (duration: 172.896837ms)","trace[1336102051] 'applied index is now lower than readState.Index' (duration: 479.308639ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:20:19.77829Z","caller":"traceutil/trace.go:171","msg":"trace[895282931] transaction","detail":"{read_only:false; response_revision:1171; number_of_response:1; }","duration":"146.901128ms","start":"2026-10-03T22:20:19.631365Z","end":"2026-10-03T22:20:19.778266Z","steps":["trace[895282931] 'process raft request' (duration: 146.727144ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:20:19.77849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.453703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-03T22:20:19.778583Z","caller":"traceutil/trace.go:171","msg":"trace[1404934107] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1171; }","duration":"652.615786ms","start":"2026-10-03T22:20:19.125946Z","end":"2026-10-03T22:20:19.778562Z","steps":["trace[1404934107] 'agreement among raft nodes before linearized reading' (duration: 652.373311ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:20:19.778628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:20:19.12592Z","time spent":"652.698407ms","remote":"127.0.0.1:55010","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-10-03T22:20:20.016687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.682556ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-03T22:20:20.017036Z","caller":"traceutil/trace.go:171","msg":"trace[1149143041] linearizableReadLoop","detail":"{readStateIndex:1218; appliedIndex:1216; }","duration":"232.521705ms","start":"2026-10-03T22:20:19.784497Z","end":"2026-10-03T22:20:20.017019Z","steps":["trace[1149143041] 'read index received' (duration: 26.415739ms)","trace[1149143041] 'applied index is now lower than readState.Index' (duration: 206.104836ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:20:20.017094Z","caller":"traceutil/trace.go:171","msg":"trace[474070921] transaction","detail":"{read_only:false; response_revision:1173; number_of_response:1; }","duration":"233.747371ms","start":"2026-10-03T22:20:19.783338Z","end":"2026-10-03T22:20:20.017085Z","steps":["trace[474070921] 'process raft request' (duration: 233.571016ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:20:20.017029Z","caller":"traceutil/trace.go:171","msg":"trace[992357500] transaction","detail":"{read_only:false; response_revision:1172; number_of_response:1; }","duration":"234.576529ms","start":"2026-10-03T22:20:19.782409Z","end":"2026-10-03T22:20:20.016986Z","steps":["trace[992357500] 'process raft request' (duration: 28.491744ms)","trace[992357500] 'compare' (duration: 205.555263ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:20:20.017446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.87787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-10-03T22:20:20.01744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.899462ms","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-10-03T22:20:20.017501Z","caller":"traceutil/trace.go:171","msg":"trace[772503188] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1173; }","duration":"210.960861ms","start":"2026-10-03T22:20:19.806525Z","end":"2026-10-03T22:20:20.017486Z","steps":["trace[772503188] 'agreement among raft nodes before linearized reading' (duration: 210.769197ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:20:20.01755Z","caller":"traceutil/trace.go:171","msg":"trace[1629623662] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1173; }","duration":"233.057246ms","start":"2026-10-03T22:20:19.784466Z","end":"2026-10-03T22:20:20.017523Z","steps":["trace[1629623662] 'agreement among raft nodes before linearized reading' (duration: 232.659048ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:20:20.017899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.591925ms","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-10-03T22:20:20.017949Z","caller":"traceutil/trace.go:171","msg":"trace[64506111] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1173; }","duration":"232.662297ms","start":"2026-10-03T22:20:19.785274Z","end":"2026-10-03T22:20:20.017936Z","steps":["trace[64506111] 'agreement among raft nodes before linearized reading' (duration: 231.996473ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:20:20.394878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.872815ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-03T22:20:20.395183Z","caller":"traceutil/trace.go:171","msg":"trace[949703572] transaction","detail":"{read_only:false; response_revision:1174; number_of_response:1; }","duration":"374.092075ms","start":"2026-10-03T22:20:20.021044Z","end":"2026-10-03T22:20:20.395136Z","steps":["trace[949703572] 'process raft request' (duration: 185.866081ms)","trace[949703572] 'compare' (duration: 187.707572ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:20:20.395288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:20:20.020987Z","time spent":"374.235969ms","remote":"127.0.0.1:55010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T22:20:20.638106Z","caller":"traceutil/trace.go:171","msg":"trace[230704384] transaction","detail":"{read_only:false; response_revision:1175; number_of_response:1; }","duration":"616.140006ms","start":"2026-10-03T22:20:20.021945Z","end":"2026-10-03T22:20:20.638085Z","steps":["trace[230704384] 'process raft request' (duration: 615.672546ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:20:20.638295Z","caller":"traceutil/trace.go:171","msg":"trace[1105538511] linearizableReadLoop","detail":"{readStateIndex:1221; appliedIndex:1218; }","duration":"595.615406ms","start":"2026-10-03T22:20:20.042657Z","end":"2026-10-03T22:20:20.638272Z","steps":["trace[1105538511] 'read index received' (duration: 164.244717ms)","trace[1105538511] 'applied index is now lower than readState.Index' (duration: 431.368979ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:20:20.638427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:20:20.021923Z","time spent":"616.402322ms","remote":"127.0.0.1:55010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T22:20:20.638477Z","caller":"traceutil/trace.go:171","msg":"trace[708641751] transaction","detail":"{read_only:false; response_revision:1176; number_of_response:1; }","duration":"616.485274ms","start":"2026-10-03T22:20:20.02198Z","end":"2026-10-03T22:20:20.638466Z","steps":["trace[708641751] 'process raft request' (duration: 616.030295ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:20:20.638578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:20:20.021965Z","time spent":"616.546595ms","remote":"127.0.0.1:55010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T22:20:20.63875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:20:20.114976Z","time spent":"523.772464ms","remote":"127.0.0.1:54858","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-10-03T22:20:20.638921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.255021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-03T22:20:20.638956Z","caller":"traceutil/trace.go:171","msg":"trace[875617981] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1176; }","duration":"596.328942ms","start":"2026-10-03T22:20:20.042616Z","end":"2026-10-03T22:20:20.638945Z","steps":["trace[875617981] 'agreement among raft nodes before linearized reading' (duration: 596.262351ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:20:20.638982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:20:20.042604Z","time spent":"596.370993ms","remote":"127.0.0.1:54940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":29,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-10-03T22:20:20.981245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.041558ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-03T22:20:20.981388Z","caller":"traceutil/trace.go:171","msg":"trace[1284086184] linearizableReadLoop","detail":"{readStateIndex:1223; appliedIndex:1222; }","duration":"144.588307ms","start":"2026-10-03T22:20:20.836775Z","end":"2026-10-03T22:20:20.981363Z","steps":["trace[1284086184] 'read index received' (duration: 44.371µs)","trace[1284086184] 'applied index is now lower than readState.Index' (duration: 144.542156ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:20:20.981423Z","caller":"traceutil/trace.go:171","msg":"trace[1687515137] transaction","detail":"{read_only:false; response_revision:1177; number_of_response:1; }","duration":"341.440489ms","start":"2026-10-03T22:20:20.639944Z","end":"2026-10-03T22:20:20.981385Z","steps":["trace[1687515137] 'process raft request' (duration: 132.132605ms)","trace[1687515137] 'compare' (duration: 208.850214ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:20:20.981553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:20:20.639931Z","time spent":"341.561252ms","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":839,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-03T22:20:20.981572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.791821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-03T22:20:20.981632Z","caller":"traceutil/trace.go:171","msg":"trace[570865663] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1177; }","duration":"144.927574ms","start":"2026-10-03T22:20:20.83669Z","end":"2026-10-03T22:20:20.981617Z","steps":["trace[570865663] 'agreement among raft nodes before linearized reading' (duration: 144.766001ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:20:22.521472Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9737240907242685948,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-03T22:20:22.587307Z","caller":"traceutil/trace.go:171","msg":"trace[1537696102] linearizableReadLoop","detail":"{readStateIndex:1224; appliedIndex:1223; }","duration":"566.289864ms","start":"2026-10-03T22:20:22.020997Z","end":"2026-10-03T22:20:22.587287Z","steps":["trace[1537696102] 'read index received' (duration: 566.040299ms)","trace[1537696102] 'applied index is now lower than readState.Index' (duration: 248.395µs)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:20:22.587402Z","caller":"traceutil/trace.go:171","msg":"trace[2024734557] transaction","detail":"{read_only:false; response_revision:1178; number_of_response:1; }","duration":"804.267297ms","start":"2026-10-03T22:20:21.783123Z","end":"2026-10-03T22:20:22.58739Z","steps":["trace[2024734557] 'process raft request' (duration: 804.029822ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:20:22.587506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:20:21.783091Z","time spent":"804.332709ms","remote":"127.0.0.1:55010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T22:20:22.587577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.537708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-03T22:20:22.587647Z","caller":"traceutil/trace.go:171","msg":"trace[404995902] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1178; }","duration":"565.658511ms","start":"2026-10-03T22:20:22.02197Z","end":"2026-10-03T22:20:22.587629Z","steps":["trace[404995902] 'agreement among raft nodes before linearized reading' (duration: 565.437917ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:20:22.587683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:20:22.021948Z","time spent":"565.727163ms","remote":"127.0.0.1:55010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-03T22:20:22.587743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.763706ms","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-10-03T22:20:22.587774Z","caller":"traceutil/trace.go:171","msg":"trace[2049959794] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1178; }","duration":"566.847407ms","start":"2026-10-03T22:20:22.020917Z","end":"2026-10-03T22:20:22.587764Z","steps":["trace[2049959794] 'agreement among raft nodes before linearized reading' (duration: 566.717974ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:20:22.587799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:20:22.020888Z","time spent":"566.904828ms","remote":"127.0.0.1:55010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-03T22:20:22.588078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.54082ms","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-10-03T22:20:22.588209Z","caller":"traceutil/trace.go:171","msg":"trace[521760918] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1178; }","duration":"188.668003ms","start":"2026-10-03T22:20:22.399475Z","end":"2026-10-03T22:20:22.588143Z","steps":["trace[521760918] 'agreement among raft nodes before linearized reading' (duration: 188.354367ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:20:23.101962Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9737240907242685951,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-03T22:20:23.435674Z","caller":"traceutil/trace.go:171","msg":"trace[1275053556] transaction","detail":"{read_only:false; response_revision:1179; number_of_response:1; }","duration":"843.134579ms","start":"2026-10-03T22:20:22.592508Z","end":"2026-10-03T22:20:23.435643Z","steps":["trace[1275053556] 'process raft request' (duration: 798.184674ms)","trace[1275053556] 'compare' (duration: 44.579437ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:20:23.436403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:20:22.592485Z","time spent":"843.280232ms","remote":"127.0.0.1:55010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T22:20:23.960105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.877278ms","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-10-03T22:20:23.960218Z","caller":"traceutil/trace.go:171","msg":"trace[1677102146] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1179; }","duration":"278.011041ms","start":"2026-10-03T22:20:23.682191Z","end":"2026-10-03T22:20:23.960202Z","steps":["trace[1677102146] 'range keys from in-memory index tree' (duration: 277.857187ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:20:23.960538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.104773ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-03T22:20:23.96075Z","caller":"traceutil/trace.go:171","msg":"trace[560698614] linearizableReadLoop","detail":"{readStateIndex:1227; appliedIndex:1224; }","duration":"1.359591792s","start":"2026-10-03T22:20:22.601142Z","end":"2026-10-03T22:20:23.960733Z","steps":["trace[560698614] 'read index received' (duration: 789.477163ms)","trace[560698614] 'applied index is now lower than readState.Index' (duration: 570.086178ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:20:23.960838Z","caller":"traceutil/trace.go:171","msg":"trace[1971684830] transaction","detail":"{read_only:false; response_revision:1180; number_of_response:1; }","duration":"1.367985276s","start":"2026-10-03T22:20:22.592841Z","end":"2026-10-03T22:20:23.960826Z","steps":["trace[1971684830] 'process raft request' (duration: 983.498124ms)","trace[1971684830] 'compare' (duration: 383.782867ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:20:23.960891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:20:22.592824Z","time spent":"1.368035546s","remote":"127.0.0.1:55010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T22:20:23.960943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.359806647s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-03T22:20:23.960989Z","caller":"traceutil/trace.go:171","msg":"trace[1546349362] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1181; }","duration":"1.359857148s","start":"2026-10-03T22:20:22.601116Z","end":"2026-10-03T22:20:23.960973Z","steps":["trace[1546349362] 'agreement among raft nodes before linearized reading' (duration: 1.359747026s)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:20:23.961066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:20:22.601067Z","time spent":"1.359985731s","remote":"127.0.0.1:44932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-10-03T22:20:23.961128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.330371242s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-03T22:20:23.961158Z","caller":"traceutil/trace.go:171","msg":"trace[579682745] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1181; }","duration":"1.330447024s","start":"2026-10-03T22:20:22.630701Z","end":"2026-10-03T22:20:23.961148Z","steps":["trace[579682745] 'agreement among raft nodes before linearized reading' (duration: 1.330398173s)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:20:23.961176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.658953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-03T22:20:23.961189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:20:22.630688Z","time spent":"1.330493105s","remote":"127.0.0.1:55704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"info","ts":"2026-10-03T22:20:23.961189Z","caller":"traceutil/trace.go:171","msg":"trace[413135013] transaction","detail":"{read_only:false; response_revision:1181; number_of_response:1; }","duration":"1.368292922s","start":"2026-10-03T22:20:22.59287Z","end":"2026-10-03T22:20:23.961163Z","steps":["trace[413135013] 'process raft request' (duration: 1.36774932s)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:20:23.961275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.317648354s","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-10-03T22:20:23.961207Z","caller":"traceutil/trace.go:171","msg":"trace[1499109959] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1181; }","duration":"409.739556ms","start":"2026-10-03T22:20:23.551457Z","end":"2026-10-03T22:20:23.961197Z","steps":["trace[1499109959] 'agreement among raft nodes before linearized reading' (duration: 409.679493ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:20:23.961331Z","caller":"traceutil/trace.go:171","msg":"trace[2071081415] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1181; }","duration":"1.317720806s","start":"2026-10-03T22:20:22.643595Z","end":"2026-10-03T22:20:23.961316Z","steps":["trace[2071081415] 'agreement among raft nodes before linearized reading' (duration: 1.317545461s)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:20:23.961341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:20:23.551431Z","time spent":"409.90239ms","remote":"127.0.0.1:54786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-03T22:20:23.961372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:20:22.643587Z","time spent":"1.317772827s","remote":"127.0.0.1:55010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-03T22:20:23.961379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:20:22.592848Z","time spent":"1.368428816s","remote":"127.0.0.1:55010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T22:20:23.961425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.354259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.220\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-10-03T22:20:23.961398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.318219116s","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-10-03T22:20:23.96145Z","caller":"traceutil/trace.go:171","msg":"trace[1368885242] range","detail":"{range_begin:/registry/masterleases/162.253.55.220; range_end:; response_count:1; response_revision:1181; }","duration":"515.429981ms","start":"2026-10-03T22:20:23.446014Z","end":"2026-10-03T22:20:23.961444Z","steps":["trace[1368885242] 'agreement among raft nodes before linearized reading' (duration: 515.329329ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:20:23.961477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:20:23.44599Z","time spent":"515.480921ms","remote":"127.0.0.1:54818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/162.253.55.220\" "} {"level":"info","ts":"2026-10-03T22:20:23.961472Z","caller":"traceutil/trace.go:171","msg":"trace[695495962] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1181; }","duration":"1.318318498s","start":"2026-10-03T22:20:22.643137Z","end":"2026-10-03T22:20:23.961455Z","steps":["trace[695495962] 'agreement among raft nodes before linearized reading' (duration: 1.318090343s)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:20:23.962481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:20:22.643084Z","time spent":"1.319385441s","remote":"127.0.0.1:55010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-03T22:20:24.352684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.431002ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"warn","ts":"2026-10-03T22:20:24.353044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:20:23.964272Z","time spent":"388.766465ms","remote":"127.0.0.1:54818","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-10-03T22:20:24.353074Z","caller":"traceutil/trace.go:171","msg":"trace[826802479] transaction","detail":"{read_only:false; response_revision:1182; number_of_response:1; }","duration":"386.990816ms","start":"2026-10-03T22:20:23.966061Z","end":"2026-10-03T22:20:24.353051Z","steps":["trace[826802479] 'process raft request' (duration: 386.774422ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:20:24.353191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:20:23.966041Z","time spent":"387.079469ms","remote":"127.0.0.1:55010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T22:20:24.353443Z","caller":"traceutil/trace.go:171","msg":"trace[321879928] linearizableReadLoop","detail":"{readStateIndex:1231; appliedIndex:1227; }","duration":"147.581263ms","start":"2026-10-03T22:20:24.205838Z","end":"2026-10-03T22:20:24.35342Z","steps":["trace[321879928] 'read index received' (duration: 40.741µs)","trace[321879928] 'applied index is now lower than readState.Index' (duration: 147.539352ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:20:24.353504Z","caller":"traceutil/trace.go:171","msg":"trace[266588167] transaction","detail":"{read_only:false; response_revision:1183; number_of_response:1; }","duration":"386.64922ms","start":"2026-10-03T22:20:23.966836Z","end":"2026-10-03T22:20:24.353485Z","steps":["trace[266588167] 'process raft request' (duration: 386.136028ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:20:24.35357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:20:23.96682Z","time spent":"386.712511ms","remote":"127.0.0.1:55010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T22:20:24.353602Z","caller":"traceutil/trace.go:171","msg":"trace[420126644] transaction","detail":"{read_only:false; response_revision:1184; number_of_response:1; }","duration":"305.905372ms","start":"2026-10-03T22:20:24.04768Z","end":"2026-10-03T22:20:24.353585Z","steps":["trace[420126644] 'process raft request' (duration: 305.651606ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:20:24.353648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.799708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5331"} {"level":"info","ts":"2026-10-03T22:20:24.353679Z","caller":"traceutil/trace.go:171","msg":"trace[450885625] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1184; }","duration":"147.884179ms","start":"2026-10-03T22:20:24.205785Z","end":"2026-10-03T22:20:24.353669Z","steps":["trace[450885625] 'agreement among raft nodes before linearized reading' (duration: 147.717046ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:20:24.353679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:20:24.047654Z","time spent":"305.985223ms","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":751,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-03T22:20:24.659322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.942875ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-03T22:20:24.659442Z","caller":"traceutil/trace.go:171","msg":"trace[381025711] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"305.457651ms","start":"2026-10-03T22:20:24.353963Z","end":"2026-10-03T22:20:24.659421Z","steps":["trace[381025711] 'process raft request' (duration: 123.317951ms)","trace[381025711] 'compare' (duration: 181.771672ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:20:24.659501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:20:24.353945Z","time spent":"305.527323ms","remote":"127.0.0.1:54818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T22:20:25.108796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.5104ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-03T22:20:25.108976Z","caller":"traceutil/trace.go:171","msg":"trace[1049665810] transaction","detail":"{read_only:false; response_revision:1187; number_of_response:1; }","duration":"515.655806ms","start":"2026-10-03T22:20:24.5933Z","end":"2026-10-03T22:20:25.108955Z","steps":["trace[1049665810] 'process raft request' (duration: 515.586584ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:20:25.109098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:20:24.593279Z","time spent":"515.778498ms","remote":"127.0.0.1:55010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T22:20:25.109299Z","caller":"traceutil/trace.go:171","msg":"trace[102155656] transaction","detail":"{read_only:false; response_revision:1186; number_of_response:1; }","duration":"693.546913ms","start":"2026-10-03T22:20:24.415739Z","end":"2026-10-03T22:20:25.109286Z","steps":["trace[102155656] 'process raft request' (duration: 493.46234ms)","trace[102155656] 'compare' (duration: 198.993139ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:20:25.109354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:20:24.41568Z","time spent":"693.648695ms","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":751,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-03T22:20:25.109523Z","caller":"traceutil/trace.go:171","msg":"trace[126460946] linearizableReadLoop","detail":"{readStateIndex:1233; appliedIndex:1231; }","duration":"633.745263ms","start":"2026-10-03T22:20:24.475766Z","end":"2026-10-03T22:20:25.109511Z","steps":["trace[126460946] 'read index received' (duration: 1.512163ms)","trace[126460946] 'applied index is now lower than readState.Index' (duration: 632.23169ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:20:25.109846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.074229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-03T22:20:25.109897Z","caller":"traceutil/trace.go:171","msg":"trace[1197179080] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1187; }","duration":"634.208003ms","start":"2026-10-03T22:20:24.47568Z","end":"2026-10-03T22:20:25.109888Z","steps":["trace[1197179080] 'agreement among raft nodes before linearized reading' (duration: 634.131131ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:20:25.109925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:20:24.475646Z","time spent":"634.272614ms","remote":"127.0.0.1:55126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":27,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" "} {"level":"warn","ts":"2026-10-03T22:20:25.110084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.00708ms","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-10-03T22:20:25.110119Z","caller":"traceutil/trace.go:171","msg":"trace[1632703237] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1187; }","duration":"572.097832ms","start":"2026-10-03T22:20:24.538013Z","end":"2026-10-03T22:20:25.110111Z","steps":["trace[1632703237] 'agreement among raft nodes before linearized reading' (duration: 572.040731ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:20:25.110145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:20:24.537976Z","time spent":"572.159924ms","remote":"127.0.0.1:55112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":29,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-10-03T22:20:25.110374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.901063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-10-03T22:20:25.110414Z","caller":"traceutil/trace.go:171","msg":"trace[272329924] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1187; }","duration":"448.936973ms","start":"2026-10-03T22:20:24.661465Z","end":"2026-10-03T22:20:25.110402Z","steps":["trace[272329924] 'agreement among raft nodes before linearized reading' (duration: 448.874352ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:20:25.110438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:20:24.661403Z","time spent":"449.028685ms","remote":"127.0.0.1:54924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":444,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-10-03T22:20:25.110577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.622446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-03T22:20:25.110608Z","caller":"traceutil/trace.go:171","msg":"trace[830293853] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1187; }","duration":"558.696518ms","start":"2026-10-03T22:20:24.551904Z","end":"2026-10-03T22:20:25.110601Z","steps":["trace[830293853] 'agreement among raft nodes before linearized reading' (duration: 558.650407ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:20:25.11063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:20:24.551882Z","time spent":"558.743609ms","remote":"127.0.0.1:54786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-03T22:20:25.817647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.694896ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-03T22:20:25.817877Z","caller":"traceutil/trace.go:171","msg":"trace[1462551086] transaction","detail":"{read_only:false; response_revision:1189; number_of_response:1; }","duration":"435.436158ms","start":"2026-10-03T22:20:25.382422Z","end":"2026-10-03T22:20:25.817859Z","steps":["trace[1462551086] 'process raft request' (duration: 435.342786ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:20:25.817952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:20:25.382404Z","time spent":"435.50338ms","remote":"127.0.0.1:55010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T22:20:25.818155Z","caller":"traceutil/trace.go:171","msg":"trace[1670987239] transaction","detail":"{read_only:false; response_revision:1188; number_of_response:1; }","duration":"703.091851ms","start":"2026-10-03T22:20:25.115047Z","end":"2026-10-03T22:20:25.818139Z","steps":["trace[1670987239] 'process raft request' (duration: 433.858613ms)","trace[1670987239] 'compare' (duration: 268.491562ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:20:25.818203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:20:25.115019Z","time spent":"703.160113ms","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":822,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-03T22:20:25.818332Z","caller":"traceutil/trace.go:171","msg":"trace[1645615652] linearizableReadLoop","detail":"{readStateIndex:1235; appliedIndex:1234; }","duration":"702.57697ms","start":"2026-10-03T22:20:25.115742Z","end":"2026-10-03T22:20:25.818319Z","steps":["trace[1645615652] 'read index received' (duration: 433.299132ms)","trace[1645615652] 'applied index is now lower than readState.Index' (duration: 269.276198ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:20:25.818503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.020964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-10-03T22:20:25.818484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.717543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-03T22:20:25.818537Z","caller":"traceutil/trace.go:171","msg":"trace[1935939386] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1189; }","duration":"369.091976ms","start":"2026-10-03T22:20:25.449435Z","end":"2026-10-03T22:20:25.818527Z","steps":["trace[1935939386] 'agreement among raft nodes before linearized reading' (duration: 368.978983ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:20:25.818556Z","caller":"traceutil/trace.go:171","msg":"trace[501931690] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1189; }","duration":"702.832066ms","start":"2026-10-03T22:20:25.115706Z","end":"2026-10-03T22:20:25.818538Z","steps":["trace[501931690] 'agreement among raft nodes before linearized reading' (duration: 702.673452ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:20:25.818568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:20:25.449415Z","time spent":"369.145746ms","remote":"127.0.0.1:55010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-03T22:20:25.818599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:20:25.115673Z","time spent":"702.916198ms","remote":"127.0.0.1:55014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-10-03T22:20:26.123288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.561843ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-03T22:20:26.123406Z","caller":"traceutil/trace.go:171","msg":"trace[359997984] linearizableReadLoop","detail":"{readStateIndex:1237; appliedIndex:1236; }","duration":"158.437571ms","start":"2026-10-03T22:20:25.964948Z","end":"2026-10-03T22:20:26.123385Z","steps":["trace[359997984] 'read index received' (duration: 30.321µs)","trace[359997984] 'applied index is now lower than readState.Index' (duration: 158.40524ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:20:26.123453Z","caller":"traceutil/trace.go:171","msg":"trace[1468250268] transaction","detail":"{read_only:false; response_revision:1190; number_of_response:1; }","duration":"300.692977ms","start":"2026-10-03T22:20:25.822739Z","end":"2026-10-03T22:20:26.123432Z","steps":["trace[1468250268] 'process raft request' (duration: 115.878858ms)","trace[1468250268] 'compare' (duration: 184.318087ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:20:26.123563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:20:25.822693Z","time spent":"300.809809ms","remote":"127.0.0.1:55010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T22:20:26.123646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.696806ms","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-10-03T22:20:26.123696Z","caller":"traceutil/trace.go:171","msg":"trace[1493418907] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1190; }","duration":"158.773718ms","start":"2026-10-03T22:20:25.964908Z","end":"2026-10-03T22:20:26.123682Z","steps":["trace[1493418907] 'agreement among raft nodes before linearized reading' (duration: 158.578404ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:20:26.124031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.471961ms","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-10-03T22:20:26.124083Z","caller":"traceutil/trace.go:171","msg":"trace[566082093] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1190; }","duration":"158.537933ms","start":"2026-10-03T22:20:25.965533Z","end":"2026-10-03T22:20:26.12407Z","steps":["trace[566082093] 'agreement among raft nodes before linearized reading' (duration: 158.40073ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:20:26.26136Z","caller":"traceutil/trace.go:171","msg":"trace[1061912920] transaction","detail":"{read_only:false; response_revision:1191; number_of_response:1; }","duration":"133.454163ms","start":"2026-10-03T22:20:26.127873Z","end":"2026-10-03T22:20:26.261327Z","steps":["trace[1061912920] 'process raft request' (duration: 133.296259ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:20:26.263479Z","caller":"traceutil/trace.go:171","msg":"trace[1178331901] transaction","detail":"{read_only:false; response_revision:1192; number_of_response:1; }","duration":"135.532859ms","start":"2026-10-03T22:20:26.127915Z","end":"2026-10-03T22:20:26.263448Z","steps":["trace[1178331901] 'process raft request' (duration: 135.349365ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:20:36.66618Z","caller":"traceutil/trace.go:171","msg":"trace[868109169] linearizableReadLoop","detail":"{readStateIndex:1328; appliedIndex:1327; }","duration":"419.304496ms","start":"2026-10-03T22:20:36.246853Z","end":"2026-10-03T22:20:36.666158Z","steps":["trace[868109169] 'read index received' (duration: 419.11222ms)","trace[868109169] 'applied index is now lower than readState.Index' (duration: 191.626µs)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:20:36.666287Z","caller":"traceutil/trace.go:171","msg":"trace[1321947424] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"619.674813ms","start":"2026-10-03T22:20:36.046567Z","end":"2026-10-03T22:20:36.666241Z","steps":["trace[1321947424] 'process raft request' (duration: 619.464519ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:20:36.666389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.52388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-03T22:20:36.666429Z","caller":"traceutil/trace.go:171","msg":"trace[483797041] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1279; }","duration":"419.616962ms","start":"2026-10-03T22:20:36.2468Z","end":"2026-10-03T22:20:36.666417Z","steps":["trace[483797041] 'agreement among raft nodes before linearized reading' (duration: 419.473029ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:20:36.666461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:20:36.24678Z","time spent":"419.672663ms","remote":"127.0.0.1:55010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-10-03T22:20:36.666576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.26478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-10-03T22:20:36.666577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.749353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-10-03T22:20:36.666545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:20:36.04654Z","time spent":"619.818967ms","remote":"127.0.0.1:55010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T22:20:36.666643Z","caller":"traceutil/trace.go:171","msg":"trace[1173347579] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1279; }","duration":"325.372333ms","start":"2026-10-03T22:20:36.341254Z","end":"2026-10-03T22:20:36.666626Z","steps":["trace[1173347579] 'agreement among raft nodes before linearized reading' (duration: 325.201139ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:20:36.666651Z","caller":"traceutil/trace.go:171","msg":"trace[1119152366] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1279; }","duration":"229.854866ms","start":"2026-10-03T22:20:36.43678Z","end":"2026-10-03T22:20:36.666635Z","steps":["trace[1119152366] 'agreement among raft nodes before linearized reading' (duration: 229.621791ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:20:36.66669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:20:36.341233Z","time spent":"325.445574ms","remote":"127.0.0.1:55010","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-10-03T22:20:36.666581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.044457ms","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-10-03T22:20:36.666817Z","caller":"traceutil/trace.go:171","msg":"trace[598336035] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1279; }","duration":"295.304213ms","start":"2026-10-03T22:20:36.371492Z","end":"2026-10-03T22:20:36.666797Z","steps":["trace[598336035] 'agreement among raft nodes before linearized reading' (duration: 294.931015ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:20:36.666895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.236993ms","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-10-03T22:20:36.666939Z","caller":"traceutil/trace.go:171","msg":"trace[2066900254] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1279; }","duration":"229.304574ms","start":"2026-10-03T22:20:36.437621Z","end":"2026-10-03T22:20:36.666926Z","steps":["trace[2066900254] 'agreement among raft nodes before linearized reading' (duration: 228.786303ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:20:36.667218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.95894ms","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-10-03T22:20:36.667268Z","caller":"traceutil/trace.go:171","msg":"trace[1645632314] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1279; }","duration":"390.015701ms","start":"2026-10-03T22:20:36.277238Z","end":"2026-10-03T22:20:36.667254Z","steps":["trace[1645632314] 'agreement among raft nodes before linearized reading' (duration: 389.565661ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:20:36.667308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:20:36.277181Z","time spent":"390.115374ms","remote":"127.0.0.1:55010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-03T22:20:37.216416Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9737240907242686134,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-03T22:20:37.222834Z","caller":"traceutil/trace.go:171","msg":"trace[1351522783] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"551.18632ms","start":"2026-10-03T22:20:36.671604Z","end":"2026-10-03T22:20:37.222791Z","steps":["trace[1351522783] 'process raft request' (duration: 548.282396ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:20:37.223092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:20:36.671575Z","time spent":"551.370403ms","remote":"127.0.0.1:55010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":492,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T22:20:37.224359Z","caller":"traceutil/trace.go:171","msg":"trace[89394400] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"552.64211ms","start":"2026-10-03T22:20:36.671693Z","end":"2026-10-03T22:20:37.224335Z","steps":["trace[89394400] 'process raft request' (duration: 552.487367ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:20:37.224538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:20:36.671671Z","time spent":"552.760022ms","remote":"127.0.0.1:55010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T22:20:37.224928Z","caller":"traceutil/trace.go:171","msg":"trace[22050628] linearizableReadLoop","detail":"{readStateIndex:1335; appliedIndex:1328; }","duration":"509.116496ms","start":"2026-10-03T22:20:36.715792Z","end":"2026-10-03T22:20:37.224908Z","steps":["trace[22050628] 'read index received' (duration: 504.106555ms)","trace[22050628] 'applied index is now lower than readState.Index' (duration: 5.008881ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:20:37.225045Z","caller":"traceutil/trace.go:171","msg":"trace[761735014] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"550.810932ms","start":"2026-10-03T22:20:36.674103Z","end":"2026-10-03T22:20:37.224914Z","steps":["trace[761735014] 'process raft request' (duration: 550.417962ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:20:37.22497Z","caller":"traceutil/trace.go:171","msg":"trace[1444897019] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"550.705139ms","start":"2026-10-03T22:20:36.674245Z","end":"2026-10-03T22:20:37.22495Z","steps":["trace[1444897019] 'process raft request' (duration: 550.541716ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:20:37.225122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:20:36.674083Z","time spent":"550.998675ms","remote":"127.0.0.1:55010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T22:20:37.225158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:20:36.674224Z","time spent":"550.885193ms","remote":"127.0.0.1:55010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T22:20:37.225264Z","caller":"traceutil/trace.go:171","msg":"trace[1429893644] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"551.125748ms","start":"2026-10-03T22:20:36.674126Z","end":"2026-10-03T22:20:37.225252Z","steps":["trace[1429893644] 'process raft request' (duration: 550.573066ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:20:37.225286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.487394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-10-03T22:20:37.225323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:20:36.674112Z","time spent":"551.18188ms","remote":"127.0.0.1:55010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T22:20:37.225331Z","caller":"traceutil/trace.go:171","msg":"trace[1634765357] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"553.52706ms","start":"2026-10-03T22:20:36.671792Z","end":"2026-10-03T22:20:37.225319Z","steps":["trace[1634765357] 'process raft request' (duration: 552.505678ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:20:37.225336Z","caller":"traceutil/trace.go:171","msg":"trace[1761283350] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1286; }","duration":"509.578335ms","start":"2026-10-03T22:20:36.715743Z","end":"2026-10-03T22:20:37.225322Z","steps":["trace[1761283350] 'agreement among raft nodes before linearized reading' (duration: 509.461513ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:20:37.225381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:20:36.715703Z","time spent":"509.663787ms","remote":"127.0.0.1:55130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":29,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-10-03T22:20:37.225387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:20:36.671785Z","time spent":"553.570681ms","remote":"127.0.0.1:55010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T22:20:37.225638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.777449ms","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-10-03T22:20:37.225684Z","caller":"traceutil/trace.go:171","msg":"trace[821950152] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1286; }","duration":"406.853731ms","start":"2026-10-03T22:20:36.818818Z","end":"2026-10-03T22:20:37.225672Z","steps":["trace[821950152] 'agreement among raft nodes before linearized reading' (duration: 406.689017ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:20:37.225714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:20:36.8188Z","time spent":"406.906712ms","remote":"127.0.0.1:55010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-10-03T22:20:37.225816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.696017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-03T22:20:37.22586Z","caller":"traceutil/trace.go:171","msg":"trace[500285503] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1286; }","duration":"149.787369ms","start":"2026-10-03T22:20:37.07606Z","end":"2026-10-03T22:20:37.225848Z","steps":["trace[500285503] 'agreement among raft nodes before linearized reading' (duration: 149.720477ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:20:58.659342Z","caller":"traceutil/trace.go:171","msg":"trace[1784517912] linearizableReadLoop","detail":"{readStateIndex:1435; appliedIndex:1434; }","duration":"107.299945ms","start":"2026-10-03T22:20:58.55202Z","end":"2026-10-03T22:20:58.65932Z","steps":["trace[1784517912] 'read index received' (duration: 16.279556ms)","trace[1784517912] 'applied index is now lower than readState.Index' (duration: 91.019148ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:20:58.659465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.437789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-03T22:20:58.659518Z","caller":"traceutil/trace.go:171","msg":"trace[1003589679] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1381; }","duration":"107.540201ms","start":"2026-10-03T22:20:58.551965Z","end":"2026-10-03T22:20:58.659505Z","steps":["trace[1003589679] 'agreement among raft nodes before linearized reading' (duration: 107.419139ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:21:13.772436Z","caller":"traceutil/trace.go:171","msg":"trace[1640912424] transaction","detail":"{read_only:false; response_revision:1450; number_of_response:1; }","duration":"108.843439ms","start":"2026-10-03T22:21:13.663563Z","end":"2026-10-03T22:21:13.772406Z","steps":["trace[1640912424] 'process raft request' (duration: 81.789345ms)","trace[1640912424] 'compare' (duration: 26.819499ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:22:08.344542Z","caller":"traceutil/trace.go:171","msg":"trace[12603257] transaction","detail":"{read_only:false; response_revision:1699; number_of_response:1; }","duration":"151.070677ms","start":"2026-10-03T22:22:08.193445Z","end":"2026-10-03T22:22:08.344516Z","steps":["trace[12603257] 'process raft request' (duration: 150.880003ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:22:08.344768Z","caller":"traceutil/trace.go:171","msg":"trace[894593287] linearizableReadLoop","detail":"{readStateIndex:1768; appliedIndex:1764; }","duration":"142.959758ms","start":"2026-10-03T22:22:08.201787Z","end":"2026-10-03T22:22:08.344747Z","steps":["trace[894593287] 'read index received' (duration: 19.534949ms)","trace[894593287] 'applied index is now lower than readState.Index' (duration: 123.424089ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:22:08.344888Z","caller":"traceutil/trace.go:171","msg":"trace[1458267566] transaction","detail":"{read_only:false; response_revision:1700; number_of_response:1; }","duration":"151.089197ms","start":"2026-10-03T22:22:08.193773Z","end":"2026-10-03T22:22:08.344863Z","steps":["trace[1458267566] 'process raft request' (duration: 150.696509ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:22:08.344936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.133932ms","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:534"} {"level":"info","ts":"2026-10-03T22:22:08.344961Z","caller":"traceutil/trace.go:171","msg":"trace[1909735498] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1701; }","duration":"143.188994ms","start":"2026-10-03T22:22:08.201761Z","end":"2026-10-03T22:22:08.34495Z","steps":["trace[1909735498] 'agreement among raft nodes before linearized reading' (duration: 143.082042ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:22:08.345043Z","caller":"traceutil/trace.go:171","msg":"trace[1296331128] transaction","detail":"{read_only:false; response_revision:1701; number_of_response:1; }","duration":"150.765431ms","start":"2026-10-03T22:22:08.194272Z","end":"2026-10-03T22:22:08.345037Z","steps":["trace[1296331128] 'process raft request' (duration: 150.388623ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:25:13.567673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.242744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.220\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-10-03T22:25:13.568015Z","caller":"traceutil/trace.go:171","msg":"trace[1725705950] range","detail":"{range_begin:/registry/masterleases/162.253.55.220; range_end:; response_count:1; response_revision:2564; }","duration":"106.425788ms","start":"2026-10-03T22:25:13.461347Z","end":"2026-10-03T22:25:13.567773Z","steps":["trace[1725705950] 'range keys from in-memory index tree' (duration: 105.950087ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:25:16.62033Z","caller":"traceutil/trace.go:171","msg":"trace[895194811] transaction","detail":"{read_only:false; response_revision:2583; number_of_response:1; }","duration":"222.891136ms","start":"2026-10-03T22:25:16.397409Z","end":"2026-10-03T22:25:16.6203Z","steps":["trace[895194811] 'process raft request' (duration: 222.63414ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:25:24.982135Z","caller":"traceutil/trace.go:171","msg":"trace[1861127326] transaction","detail":"{read_only:false; response_revision:2625; number_of_response:1; }","duration":"322.155114ms","start":"2026-10-03T22:25:24.659954Z","end":"2026-10-03T22:25:24.982109Z","steps":["trace[1861127326] 'process raft request' (duration: 321.804547ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:25:24.982322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:25:24.659934Z","time spent":"322.307558ms","remote":"127.0.0.1:55010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T22:26:01.268541Z","caller":"traceutil/trace.go:171","msg":"trace[1655290862] transaction","detail":"{read_only:false; response_revision:2799; number_of_response:1; }","duration":"111.245445ms","start":"2026-10-03T22:26:01.157256Z","end":"2026-10-03T22:26:01.268501Z","steps":["trace[1655290862] 'process raft request' (duration: 111.048759ms)"],"step_count":1}