{"level":"warn","ts":"2026-09-18T01:25:15.906529Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-18T01:25:15.90663Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.3: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.3:2380","--initial-cluster=instance=https://162.253.55.3:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.3:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.3: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-09-18T01:25:15.906707Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-18T01:25:15.906723Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.3:2380"]} {"level":"info","ts":"2026-09-18T01:25:15.906748Z","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-09-18T01:25:15.907533Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.3:2379"]} {"level":"info","ts":"2026-09-18T01:25:15.907637Z","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.3:2380"],"listen-peer-urls":["https://162.253.55.3:2380"],"advertise-client-urls":["https://162.253.55.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.3: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-09-18T01:25:15.911468Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.464112ms"} {"level":"info","ts":"2026-09-18T01:25:15.919868Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a99b527d1e65fdd9","cluster-id":"9bd8b4a41eaf5d40"} {"level":"info","ts":"2026-09-18T01:25:15.919956Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a99b527d1e65fdd9 switched to configuration voters=()"} {"level":"info","ts":"2026-09-18T01:25:15.919978Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a99b527d1e65fdd9 became follower at term 0"} {"level":"info","ts":"2026-09-18T01:25:15.919987Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a99b527d1e65fdd9 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-18T01:25:15.919995Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a99b527d1e65fdd9 became follower at term 1"} {"level":"info","ts":"2026-09-18T01:25:15.920054Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a99b527d1e65fdd9 switched to configuration voters=(12221452711134363097)"} {"level":"warn","ts":"2026-09-18T01:25:15.928856Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-18T01:25:15.931261Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-18T01:25:15.932378Z","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-09-18T01:25:15.934817Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a99b527d1e65fdd9","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-18T01:25:15.936031Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a99b527d1e65fdd9","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-18T01:25:15.940808Z","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-09-18T01:25:15.940917Z","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-09-18T01:25:15.940928Z","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-09-18T01:25:15.941059Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a99b527d1e65fdd9 switched to configuration voters=(12221452711134363097)"} {"level":"info","ts":"2026-09-18T01:25:15.941134Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9bd8b4a41eaf5d40","local-member-id":"a99b527d1e65fdd9","added-peer-id":"a99b527d1e65fdd9","added-peer-peer-urls":["https://162.253.55.3:2380"]} {"level":"info","ts":"2026-09-18T01:25:15.941595Z","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-09-18T01:25:15.941761Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a99b527d1e65fdd9","initial-advertise-peer-urls":["https://162.253.55.3:2380"],"listen-peer-urls":["https://162.253.55.3:2380"],"advertise-client-urls":["https://162.253.55.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-18T01:25:15.941786Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-18T01:25:15.9419Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.3:2380"} {"level":"info","ts":"2026-09-18T01:25:15.941917Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.3:2380"} {"level":"info","ts":"2026-09-18T01:25:16.620768Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a99b527d1e65fdd9 is starting a new election at term 1"} {"level":"info","ts":"2026-09-18T01:25:16.620825Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a99b527d1e65fdd9 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-18T01:25:16.620886Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a99b527d1e65fdd9 received MsgPreVoteResp from a99b527d1e65fdd9 at term 1"} {"level":"info","ts":"2026-09-18T01:25:16.620909Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a99b527d1e65fdd9 became candidate at term 2"} {"level":"info","ts":"2026-09-18T01:25:16.620929Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a99b527d1e65fdd9 received MsgVoteResp from a99b527d1e65fdd9 at term 2"} {"level":"info","ts":"2026-09-18T01:25:16.620945Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a99b527d1e65fdd9 became leader at term 2"} {"level":"info","ts":"2026-09-18T01:25:16.620963Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a99b527d1e65fdd9 elected leader a99b527d1e65fdd9 at term 2"} {"level":"info","ts":"2026-09-18T01:25:16.622199Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T01:25:16.62315Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a99b527d1e65fdd9","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.3:2379]}","request-path":"/0/members/a99b527d1e65fdd9/attributes","cluster-id":"9bd8b4a41eaf5d40","publish-timeout":"7s"} {"level":"info","ts":"2026-09-18T01:25:16.62339Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-18T01:25:16.623715Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9bd8b4a41eaf5d40","local-member-id":"a99b527d1e65fdd9","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T01:25:16.623883Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T01:25:16.62393Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T01:25:16.624058Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-18T01:25:16.62409Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-18T01:25:16.624103Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-18T01:25:16.626436Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.3:2379"} {"level":"info","ts":"2026-09-18T01:25:16.627055Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-18T01:25:18.265634Z","caller":"traceutil/trace.go:171","msg":"trace[696620811] linearizableReadLoop","detail":"{readStateIndex:5; appliedIndex:4; }","duration":"115.080385ms","start":"2026-09-18T01:25:18.150526Z","end":"2026-09-18T01:25:18.265606Z","steps":["trace[696620811] 'read index received' (duration: 115.034964ms)","trace[696620811] 'applied index is now lower than readState.Index' (duration: 43.971µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T01:25:18.265656Z","caller":"traceutil/trace.go:171","msg":"trace[39324927] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"115.31956ms","start":"2026-09-18T01:25:18.1503Z","end":"2026-09-18T01:25:18.265619Z","steps":["trace[39324927] 'process raft request' (duration: 115.130436ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:25:18.265873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.29885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-18T01:25:18.265934Z","caller":"traceutil/trace.go:171","msg":"trace[1522408083] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:2; }","duration":"115.410832ms","start":"2026-09-18T01:25:18.150512Z","end":"2026-09-18T01:25:18.265922Z","steps":["trace[1522408083] 'agreement among raft nodes before linearized reading' (duration: 115.226698ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:25:18.572208Z","caller":"traceutil/trace.go:171","msg":"trace[1696747325] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"342.906061ms","start":"2026-09-18T01:25:18.229269Z","end":"2026-09-18T01:25:18.572175Z","steps":["trace[1696747325] 'process raft request' (duration: 342.489172ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:25:18.572404Z","caller":"traceutil/trace.go:171","msg":"trace[2065081639] linearizableReadLoop","detail":"{readStateIndex:13; appliedIndex:5; }","duration":"306.669632ms","start":"2026-09-18T01:25:18.265715Z","end":"2026-09-18T01:25:18.572384Z","steps":["trace[2065081639] 'read index received' (duration: 306.022477ms)","trace[2065081639] 'applied index is now lower than readState.Index' (duration: 646.295µs)"],"step_count":2} {"level":"warn","ts":"2026-09-18T01:25:18.572735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.766428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.3\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-18T01:25:18.57281Z","caller":"traceutil/trace.go:171","msg":"trace[1648173794] range","detail":"{range_begin:/registry/masterleases/162.253.55.3; range_end:; response_count:0; response_revision:10; }","duration":"345.87151ms","start":"2026-09-18T01:25:18.22692Z","end":"2026-09-18T01:25:18.572791Z","steps":["trace[1648173794] 'agreement among raft nodes before linearized reading' (duration: 345.540292ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:25:18.572822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.9835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-09-18T01:25:18.572835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.572505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-18T01:25:18.572914Z","caller":"traceutil/trace.go:171","msg":"trace[1063546248] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"342.102662ms","start":"2026-09-18T01:25:18.230782Z","end":"2026-09-18T01:25:18.572885Z","steps":["trace[1063546248] 'agreement among raft nodes before linearized reading' (duration: 341.97806ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:25:18.572918Z","caller":"traceutil/trace.go:171","msg":"trace[1777327050] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"366.687368ms","start":"2026-09-18T01:25:18.206205Z","end":"2026-09-18T01:25:18.572892Z","steps":["trace[1777327050] 'agreement among raft nodes before linearized reading' (duration: 366.582665ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:25:18.572942Z","caller":"traceutil/trace.go:171","msg":"trace[1299266683] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"342.626245ms","start":"2026-09-18T01:25:18.230284Z","end":"2026-09-18T01:25:18.57291Z","steps":["trace[1299266683] 'process raft request' (duration: 341.672792ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:25:18.572945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:25:18.23076Z","time spent":"342.178275ms","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-09-18T01:25:18.572752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.746333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-18T01:25:18.573059Z","caller":"traceutil/trace.go:171","msg":"trace[2040973215] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"342.674146ms","start":"2026-09-18T01:25:18.230377Z","end":"2026-09-18T01:25:18.573051Z","steps":["trace[2040973215] 'process raft request' (duration: 341.641842ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:25:18.57307Z","caller":"traceutil/trace.go:171","msg":"trace[1572687333] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"365.09236ms","start":"2026-09-18T01:25:18.207968Z","end":"2026-09-18T01:25:18.57306Z","steps":["trace[1572687333] 'agreement among raft nodes before linearized reading' (duration: 364.590328ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:25:18.573085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.740063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-09-18T01:25:18.573098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:25:18.207961Z","time spent":"365.127592ms","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-09-18T01:25:18.57314Z","caller":"traceutil/trace.go:171","msg":"trace[1648540888] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"367.795094ms","start":"2026-09-18T01:25:18.205313Z","end":"2026-09-18T01:25:18.573109Z","steps":["trace[1648540888] 'agreement among raft nodes before linearized reading' (duration: 367.746453ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:25:18.573151Z","caller":"traceutil/trace.go:171","msg":"trace[404948963] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"342.706206ms","start":"2026-09-18T01:25:18.23043Z","end":"2026-09-18T01:25:18.573136Z","steps":["trace[404948963] 'process raft request' (duration: 341.710272ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:25:18.573199Z","caller":"traceutil/trace.go:171","msg":"trace[301840628] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"342.197733ms","start":"2026-09-18T01:25:18.230995Z","end":"2026-09-18T01:25:18.573192Z","steps":["trace[301840628] 'process raft request' (duration: 341.20239ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:25:18.573206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:25:18.229253Z","time spent":"343.042504ms","remote":"127.0.0.1:51412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-18T01:25:18.573285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:25:18.205298Z","time spent":"367.872156ms","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-09-18T01:25:18.573304Z","caller":"traceutil/trace.go:171","msg":"trace[883003819] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"322.665907ms","start":"2026-09-18T01:25:18.250595Z","end":"2026-09-18T01:25:18.573261Z","steps":["trace[883003819] 'process raft request' (duration: 321.721754ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:25:18.573249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:25:18.230982Z","time spent":"342.241605ms","remote":"127.0.0.1:51492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-18T01:25:18.572879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:25:18.226909Z","time spent":"345.929731ms","remote":"127.0.0.1:50958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":28,"request content":"key:\"/registry/masterleases/162.253.55.3\" "} {"level":"warn","ts":"2026-09-18T01:25:18.573402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:25:18.250574Z","time spent":"322.760299ms","remote":"127.0.0.1:51202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-18T01:25:18.573243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.607454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-18T01:25:18.574122Z","caller":"traceutil/trace.go:171","msg":"trace[29665059] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:10; }","duration":"343.493165ms","start":"2026-09-18T01:25:18.23061Z","end":"2026-09-18T01:25:18.574103Z","steps":["trace[29665059] 'agreement among raft nodes before linearized reading' (duration: 342.576803ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:25:18.574196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:25:18.230603Z","time spent":"343.565797ms","remote":"127.0.0.1:51012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-09-18T01:25:18.572958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:25:18.206189Z","time spent":"366.75849ms","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-09-18T01:25:18.573535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.225308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-18T01:25:18.57454Z","caller":"traceutil/trace.go:171","msg":"trace[1260865189] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:0; response_revision:10; }","duration":"423.226601ms","start":"2026-09-18T01:25:18.151289Z","end":"2026-09-18T01:25:18.574516Z","steps":["trace[1260865189] 'agreement among raft nodes before linearized reading' (duration: 422.204597ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:25:18.574599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:25:18.151284Z","time spent":"423.300024ms","remote":"127.0.0.1:50992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":28,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-09-18T01:25:18.573593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.368111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-18T01:25:18.574767Z","caller":"traceutil/trace.go:171","msg":"trace[366081946] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:10; }","duration":"423.54609ms","start":"2026-09-18T01:25:18.151195Z","end":"2026-09-18T01:25:18.574741Z","steps":["trace[366081946] 'agreement among raft nodes before linearized reading' (duration: 422.366322ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:25:18.5749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:25:18.151189Z","time spent":"423.692723ms","remote":"127.0.0.1:50912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-18T01:25:18.573695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.282529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3169"} {"level":"info","ts":"2026-09-18T01:25:18.575137Z","caller":"traceutil/trace.go:171","msg":"trace[454905334] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:10; }","duration":"306.745894ms","start":"2026-09-18T01:25:18.268371Z","end":"2026-09-18T01:25:18.575116Z","steps":["trace[454905334] 'agreement among raft nodes before linearized reading' (duration: 305.27538ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:25:18.575197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:25:18.268358Z","time spent":"306.823136ms","remote":"127.0.0.1:51122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3193,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-09-18T01:25:18.573648Z","caller":"traceutil/trace.go:171","msg":"trace[148869613] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"339.236245ms","start":"2026-09-18T01:25:18.234284Z","end":"2026-09-18T01:25:18.57352Z","steps":["trace[148869613] 'process raft request' (duration: 337.990495ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:25:18.575401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:25:18.234261Z","time spent":"341.104088ms","remote":"127.0.0.1:51122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3157,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T01:25:18.573686Z","caller":"traceutil/trace.go:171","msg":"trace[483864011] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"341.996659ms","start":"2026-09-18T01:25:18.23167Z","end":"2026-09-18T01:25:18.573667Z","steps":["trace[483864011] 'process raft request' (duration: 340.559085ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:25:18.575553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:25:18.231626Z","time spent":"343.891034ms","remote":"127.0.0.1:51492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-18T01:25:18.573656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:25:18.23037Z","time spent":"342.711917ms","remote":"127.0.0.1:51492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-18T01:25:18.57374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:25:18.230423Z","time spent":"342.750978ms","remote":"127.0.0.1:51492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-18T01:25:18.573794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.563729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-18T01:25:18.575705Z","caller":"traceutil/trace.go:171","msg":"trace[913080829] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"296.502184ms","start":"2026-09-18T01:25:18.279186Z","end":"2026-09-18T01:25:18.575688Z","steps":["trace[913080829] 'agreement among raft nodes before linearized reading' (duration: 294.572198ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:25:18.573801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:25:18.23027Z","time spent":"342.730497ms","remote":"127.0.0.1:51492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T01:25:19.048873Z","caller":"traceutil/trace.go:171","msg":"trace[1137311156] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"469.603039ms","start":"2026-09-18T01:25:18.579202Z","end":"2026-09-18T01:25:19.048805Z","steps":["trace[1137311156] 'process raft request' (duration: 466.359432ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:25:19.049024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:25:18.57906Z","time spent":"469.890445ms","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":338,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T01:25:19.049379Z","caller":"traceutil/trace.go:171","msg":"trace[738287717] transaction","detail":"{read_only:false; number_of_response:0; response_revision:12; }","duration":"469.045375ms","start":"2026-09-18T01:25:18.580316Z","end":"2026-09-18T01:25:19.049361Z","steps":["trace[738287717] 'process raft request' (duration: 468.268957ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:25:19.049468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:25:18.580304Z","time spent":"469.127917ms","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T01:25:19.049567Z","caller":"traceutil/trace.go:171","msg":"trace[1219942005] linearizableReadLoop","detail":"{readStateIndex:17; appliedIndex:13; }","duration":"467.928939ms","start":"2026-09-18T01:25:18.581613Z","end":"2026-09-18T01:25:19.049542Z","steps":["trace[1219942005] 'read index received' (duration: 4.621018ms)","trace[1219942005] 'applied index is now lower than readState.Index' (duration: 463.306931ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T01:25:19.049678Z","caller":"traceutil/trace.go:171","msg":"trace[1699198003] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"467.635812ms","start":"2026-09-18T01:25:18.582031Z","end":"2026-09-18T01:25:19.049667Z","steps":["trace[1699198003] 'process raft request' (duration: 467.370496ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:25:19.049779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:25:18.582018Z","time spent":"467.691843ms","remote":"127.0.0.1:51492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T01:25:19.049804Z","caller":"traceutil/trace.go:171","msg":"trace[1735478795] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"467.757745ms","start":"2026-09-18T01:25:18.58203Z","end":"2026-09-18T01:25:19.049787Z","steps":["trace[1735478795] 'process raft request' (duration: 467.410677ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:25:19.049905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:25:18.58202Z","time spent":"467.848317ms","remote":"127.0.0.1:51492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T01:25:19.049936Z","caller":"traceutil/trace.go:171","msg":"trace[812386785] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"468.389439ms","start":"2026-09-18T01:25:18.581527Z","end":"2026-09-18T01:25:19.049916Z","steps":["trace[812386785] 'process raft request' (duration: 467.675923ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:25:19.050017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:25:18.581474Z","time spent":"468.502352ms","remote":"127.0.0.1:51348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":651,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T01:25:19.05003Z","caller":"traceutil/trace.go:171","msg":"trace[73007997] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"468.070182ms","start":"2026-09-18T01:25:18.581946Z","end":"2026-09-18T01:25:19.050016Z","steps":["trace[73007997] 'process raft request' (duration: 467.411997ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:25:19.050065Z","caller":"traceutil/trace.go:171","msg":"trace[1924307592] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"467.729314ms","start":"2026-09-18T01:25:18.582315Z","end":"2026-09-18T01:25:19.050044Z","steps":["trace[1924307592] 'process raft request' (duration: 467.148421ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:25:19.050105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:25:18.581935Z","time spent":"468.138915ms","remote":"127.0.0.1:51412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-18T01:25:19.050141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:25:18.582288Z","time spent":"467.801147ms","remote":"127.0.0.1:51492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":923,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T01:25:19.049914Z","caller":"traceutil/trace.go:171","msg":"trace[856024961] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"466.174218ms","start":"2026-09-18T01:25:18.58371Z","end":"2026-09-18T01:25:19.049884Z","steps":["trace[856024961] 'process raft request' (duration: 465.80162ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:25:19.049926Z","caller":"traceutil/trace.go:171","msg":"trace[830818234] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"467.061318ms","start":"2026-09-18T01:25:18.582673Z","end":"2026-09-18T01:25:19.049734Z","steps":["trace[830818234] 'process raft request' (duration: 466.815252ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:25:19.05039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:25:18.583701Z","time spent":"466.519117ms","remote":"127.0.0.1:51492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-18T01:25:19.050689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:25:18.582663Z","time spent":"467.671463ms","remote":"127.0.0.1:51492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-18T01:25:19.050972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.346482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-nt2cf\" ","response":"range_response_count:1 size:861"} {"level":"info","ts":"2026-09-18T01:25:19.051025Z","caller":"traceutil/trace.go:171","msg":"trace[942075573] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-nt2cf; range_end:; response_count:1; response_revision:19; }","duration":"469.430174ms","start":"2026-09-18T01:25:18.581581Z","end":"2026-09-18T01:25:19.051011Z","steps":["trace[942075573] 'agreement among raft nodes before linearized reading' (duration: 468.223536ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:25:19.051464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:25:18.581569Z","time spent":"469.882145ms","remote":"127.0.0.1:51202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":885,"request content":"key:\"/registry/certificatesigningrequests/csr-nt2cf\" "} {"level":"warn","ts":"2026-09-18T01:25:19.052119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.133293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-09-18T01:25:19.052182Z","caller":"traceutil/trace.go:171","msg":"trace[743705516] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:20; }","duration":"415.241345ms","start":"2026-09-18T01:25:18.636926Z","end":"2026-09-18T01:25:19.052167Z","steps":["trace[743705516] 'agreement among raft nodes before linearized reading' (duration: 415.069041ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:25:19.052224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:25:18.636898Z","time spent":"415.314897ms","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-09-18T01:25:19.052396Z","caller":"traceutil/trace.go:171","msg":"trace[84482411] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"465.178255ms","start":"2026-09-18T01:25:18.587198Z","end":"2026-09-18T01:25:19.052376Z","steps":["trace[84482411] 'process raft request' (duration: 464.640143ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:25:19.052534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.55611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-18T01:25:19.055221Z","caller":"traceutil/trace.go:171","msg":"trace[1072822605] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:20; }","duration":"274.238613ms","start":"2026-09-18T01:25:18.780959Z","end":"2026-09-18T01:25:19.055197Z","steps":["trace[1072822605] 'agreement among raft nodes before linearized reading' (duration: 271.52742ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:25:19.056543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:25:18.587183Z","time spent":"465.79758ms","remote":"127.0.0.1:51122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T01:25:53.269629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.219421ms","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-09-18T01:25:53.26973Z","caller":"traceutil/trace.go:171","msg":"trace[432998842] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:487; }","duration":"204.354753ms","start":"2026-09-18T01:25:53.06535Z","end":"2026-09-18T01:25:53.269705Z","steps":["trace[432998842] 'range keys from in-memory index tree' (duration: 204.051117ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:25:58.971076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.817632ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-18T01:25:58.971209Z","caller":"traceutil/trace.go:171","msg":"trace[1334887514] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"296.895171ms","start":"2026-09-18T01:25:58.674287Z","end":"2026-09-18T01:25:58.971183Z","steps":["trace[1334887514] 'process raft request' (duration: 53.745452ms)","trace[1334887514] 'compare' (duration: 242.492383ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T01:26:06.027061Z","caller":"traceutil/trace.go:171","msg":"trace[1437170942] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"112.451662ms","start":"2026-09-18T01:26:05.914573Z","end":"2026-09-18T01:26:06.027024Z","steps":["trace[1437170942] 'process raft request' (duration: 112.236287ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:26:06.306172Z","caller":"traceutil/trace.go:171","msg":"trace[926267870] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"262.970112ms","start":"2026-09-18T01:26:06.043173Z","end":"2026-09-18T01:26:06.306143Z","steps":["trace[926267870] 'process raft request' (duration: 262.828498ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:26:07.228993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.556416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T01:26:07.229079Z","caller":"traceutil/trace.go:171","msg":"trace[400306179] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:704; }","duration":"151.706979ms","start":"2026-09-18T01:26:07.077355Z","end":"2026-09-18T01:26:07.229062Z","steps":["trace[400306179] 'range keys from in-memory index tree' (duration: 151.468713ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:26:07.229157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.652006ms","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-09-18T01:26:07.229242Z","caller":"traceutil/trace.go:171","msg":"trace[534825420] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:704; }","duration":"174.786489ms","start":"2026-09-18T01:26:07.054432Z","end":"2026-09-18T01:26:07.229219Z","steps":["trace[534825420] 'range keys from in-memory index tree' (duration: 174.478512ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:26:07.481209Z","caller":"traceutil/trace.go:171","msg":"trace[1543029718] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"247.398861ms","start":"2026-09-18T01:26:07.233783Z","end":"2026-09-18T01:26:07.481182Z","steps":["trace[1543029718] 'process raft request' (duration: 246.96713ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:26:25.577775Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18291828048412546742,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-18T01:26:25.721075Z","caller":"traceutil/trace.go:171","msg":"trace[63932519] linearizableReadLoop","detail":"{readStateIndex:856; appliedIndex:855; }","duration":"643.316114ms","start":"2026-09-18T01:26:25.07774Z","end":"2026-09-18T01:26:25.721056Z","steps":["trace[63932519] 'read index received' (duration: 643.165121ms)","trace[63932519] 'applied index is now lower than readState.Index' (duration: 150.323µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T01:26:25.721117Z","caller":"traceutil/trace.go:171","msg":"trace[320284289] transaction","detail":"{read_only:false; response_revision:826; number_of_response:1; }","duration":"666.607359ms","start":"2026-09-18T01:26:25.054492Z","end":"2026-09-18T01:26:25.721099Z","steps":["trace[320284289] 'process raft request' (duration: 666.450786ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:26:25.721381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.589887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-18T01:26:25.721387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.862968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T01:26:25.72142Z","caller":"traceutil/trace.go:171","msg":"trace[1240208139] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:826; }","duration":"124.93208ms","start":"2026-09-18T01:26:25.596478Z","end":"2026-09-18T01:26:25.72141Z","steps":["trace[1240208139] 'agreement among raft nodes before linearized reading' (duration: 124.882258ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:26:25.721415Z","caller":"traceutil/trace.go:171","msg":"trace[1245919815] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:826; }","duration":"206.655318ms","start":"2026-09-18T01:26:25.514748Z","end":"2026-09-18T01:26:25.721404Z","steps":["trace[1245919815] 'agreement among raft nodes before linearized reading' (duration: 206.603507ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:26:25.721553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:26:25.054471Z","time spent":"666.898657ms","remote":"127.0.0.1:51138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4023,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T01:26:25.721646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.905093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T01:26:25.721673Z","caller":"traceutil/trace.go:171","msg":"trace[1714455984] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:826; }","duration":"125.956814ms","start":"2026-09-18T01:26:25.595709Z","end":"2026-09-18T01:26:25.721665Z","steps":["trace[1714455984] 'agreement among raft nodes before linearized reading' (duration: 125.912113ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:26:25.721227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.465898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-18T01:26:25.721807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.476437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T01:26:25.721845Z","caller":"traceutil/trace.go:171","msg":"trace[947748114] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:826; }","duration":"160.547948ms","start":"2026-09-18T01:26:25.561286Z","end":"2026-09-18T01:26:25.721834Z","steps":["trace[947748114] 'agreement among raft nodes before linearized reading' (duration: 160.485997ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:26:25.721864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.554258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T01:26:25.721902Z","caller":"traceutil/trace.go:171","msg":"trace[616883621] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:826; }","duration":"160.641831ms","start":"2026-09-18T01:26:25.561253Z","end":"2026-09-18T01:26:25.721894Z","steps":["trace[616883621] 'agreement among raft nodes before linearized reading' (duration: 160.570699ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:26:25.722044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.512966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-18T01:26:25.722042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.907923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-18T01:26:25.722071Z","caller":"traceutil/trace.go:171","msg":"trace[868476352] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:826; }","duration":"206.570838ms","start":"2026-09-18T01:26:25.515492Z","end":"2026-09-18T01:26:25.722063Z","steps":["trace[868476352] 'agreement among raft nodes before linearized reading' (duration: 206.498266ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:26:25.722102Z","caller":"traceutil/trace.go:171","msg":"trace[1970106355] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:826; }","duration":"144.002625ms","start":"2026-09-18T01:26:25.578087Z","end":"2026-09-18T01:26:25.72209Z","steps":["trace[1970106355] 'agreement among raft nodes before linearized reading' (duration: 143.8163ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:26:25.722135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.293355ms","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-09-18T01:26:25.722144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.409216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T01:26:25.722173Z","caller":"traceutil/trace.go:171","msg":"trace[149421291] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:826; }","duration":"158.348647ms","start":"2026-09-18T01:26:25.563815Z","end":"2026-09-18T01:26:25.722164Z","steps":["trace[149421291] 'agreement among raft nodes before linearized reading' (duration: 158.245505ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:26:25.72219Z","caller":"traceutil/trace.go:171","msg":"trace[2070884458] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:826; }","duration":"126.491857ms","start":"2026-09-18T01:26:25.595682Z","end":"2026-09-18T01:26:25.722174Z","steps":["trace[2070884458] 'agreement among raft nodes before linearized reading' (duration: 126.413825ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:26:25.722246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.054619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T01:26:25.722299Z","caller":"traceutil/trace.go:171","msg":"trace[1418664409] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:826; }","duration":"207.151401ms","start":"2026-09-18T01:26:25.51514Z","end":"2026-09-18T01:26:25.722291Z","steps":["trace[1418664409] 'agreement among raft nodes before linearized reading' (duration: 207.081609ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:26:25.722472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.303236ms","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-09-18T01:26:25.72253Z","caller":"traceutil/trace.go:171","msg":"trace[1831093160] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:826; }","duration":"101.386138ms","start":"2026-09-18T01:26:25.621138Z","end":"2026-09-18T01:26:25.722524Z","steps":["trace[1831093160] 'agreement among raft nodes before linearized reading' (duration: 101.255695ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:26:25.722632Z","caller":"traceutil/trace.go:171","msg":"trace[1787748680] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:826; }","duration":"644.121103ms","start":"2026-09-18T01:26:25.077714Z","end":"2026-09-18T01:26:25.721835Z","steps":["trace[1787748680] 'agreement among raft nodes before linearized reading' (duration: 643.459998ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:26:25.722688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:26:25.077703Z","time spent":"644.971254ms","remote":"127.0.0.1:50912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-18T01:26:25.724829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.448499ms","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-09-18T01:26:25.724904Z","caller":"traceutil/trace.go:171","msg":"trace[100167176] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:826; }","duration":"106.555351ms","start":"2026-09-18T01:26:25.618339Z","end":"2026-09-18T01:26:25.724894Z","steps":["trace[100167176] 'agreement among raft nodes before linearized reading' (duration: 103.642242ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:26:25.899573Z","caller":"traceutil/trace.go:171","msg":"trace[536162317] transaction","detail":"{read_only:false; response_revision:827; number_of_response:1; }","duration":"172.435833ms","start":"2026-09-18T01:26:25.727111Z","end":"2026-09-18T01:26:25.899546Z","steps":["trace[536162317] 'process raft request' (duration: 140.197754ms)","trace[536162317] 'compare' (duration: 32.118206ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T01:26:25.90236Z","caller":"traceutil/trace.go:171","msg":"trace[1327827329] transaction","detail":"{read_only:false; response_revision:828; number_of_response:1; }","duration":"175.209419ms","start":"2026-09-18T01:26:25.727132Z","end":"2026-09-18T01:26:25.902341Z","steps":["trace[1327827329] 'process raft request' (duration: 174.84864ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:26:25.90244Z","caller":"traceutil/trace.go:171","msg":"trace[1497346667] linearizableReadLoop","detail":"{readStateIndex:860; appliedIndex:856; }","duration":"169.859321ms","start":"2026-09-18T01:26:25.732555Z","end":"2026-09-18T01:26:25.902415Z","steps":["trace[1497346667] 'read index received' (duration: 134.811435ms)","trace[1497346667] 'applied index is now lower than readState.Index' (duration: 35.045996ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T01:26:25.902539Z","caller":"traceutil/trace.go:171","msg":"trace[1029197657] transaction","detail":"{read_only:false; response_revision:829; number_of_response:1; }","duration":"174.547393ms","start":"2026-09-18T01:26:25.727969Z","end":"2026-09-18T01:26:25.902517Z","steps":["trace[1029197657] 'process raft request' (duration: 174.276667ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:26:25.902625Z","caller":"traceutil/trace.go:171","msg":"trace[1149752620] transaction","detail":"{read_only:false; response_revision:830; number_of_response:1; }","duration":"173.515009ms","start":"2026-09-18T01:26:25.729093Z","end":"2026-09-18T01:26:25.902608Z","steps":["trace[1149752620] 'process raft request' (duration: 173.201411ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:26:25.902751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.166799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T01:26:25.902806Z","caller":"traceutil/trace.go:171","msg":"trace[1320936416] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:830; }","duration":"170.250901ms","start":"2026-09-18T01:26:25.732539Z","end":"2026-09-18T01:26:25.90279Z","steps":["trace[1320936416] 'agreement among raft nodes before linearized reading' (duration: 170.150379ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:26:25.905331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.815247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T01:26:25.905401Z","caller":"traceutil/trace.go:171","msg":"trace[84520039] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:830; }","duration":"119.90498ms","start":"2026-09-18T01:26:25.785476Z","end":"2026-09-18T01:26:25.905381Z","steps":["trace[84520039] 'agreement among raft nodes before linearized reading' (duration: 119.806007ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:26:25.9054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.299896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T01:26:25.905445Z","caller":"traceutil/trace.go:171","msg":"trace[752485485] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:830; }","duration":"119.360438ms","start":"2026-09-18T01:26:25.786073Z","end":"2026-09-18T01:26:25.905433Z","steps":["trace[752485485] 'agreement among raft nodes before linearized reading' (duration: 119.289297ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:26:25.905538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.068304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T01:26:25.905568Z","caller":"traceutil/trace.go:171","msg":"trace[462303405] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:830; }","duration":"120.122375ms","start":"2026-09-18T01:26:25.785438Z","end":"2026-09-18T01:26:25.905561Z","steps":["trace[462303405] 'agreement among raft nodes before linearized reading' (duration: 120.079464ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:26:25.905776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.530055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T01:26:25.905833Z","caller":"traceutil/trace.go:171","msg":"trace[382953488] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:830; }","duration":"172.681848ms","start":"2026-09-18T01:26:25.733136Z","end":"2026-09-18T01:26:25.905818Z","steps":["trace[382953488] 'agreement among raft nodes before linearized reading' (duration: 172.507924ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:26:25.907221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.691969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T01:26:25.907275Z","caller":"traceutil/trace.go:171","msg":"trace[763571069] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:830; }","duration":"174.720567ms","start":"2026-09-18T01:26:25.73254Z","end":"2026-09-18T01:26:25.90726Z","steps":["trace[763571069] 'agreement among raft nodes before linearized reading' (duration: 172.683168ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:27:05.342722Z","caller":"traceutil/trace.go:171","msg":"trace[48117356] linearizableReadLoop","detail":"{readStateIndex:1147; appliedIndex:1146; }","duration":"264.951707ms","start":"2026-09-18T01:27:05.077747Z","end":"2026-09-18T01:27:05.342699Z","steps":["trace[48117356] 'read index received' (duration: 224.31848ms)","trace[48117356] 'applied index is now lower than readState.Index' (duration: 40.631847ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T01:27:05.342833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.072149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T01:27:05.342863Z","caller":"traceutil/trace.go:171","msg":"trace[844305750] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1107; }","duration":"265.158321ms","start":"2026-09-18T01:27:05.077694Z","end":"2026-09-18T01:27:05.342853Z","steps":["trace[844305750] 'agreement among raft nodes before linearized reading' (duration: 265.09157ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:27:23.006187Z","caller":"traceutil/trace.go:171","msg":"trace[1895052649] transaction","detail":"{read_only:false; response_revision:1174; number_of_response:1; }","duration":"557.804432ms","start":"2026-09-18T01:27:22.448348Z","end":"2026-09-18T01:27:23.006152Z","steps":["trace[1895052649] 'process raft request' (duration: 514.732248ms)","trace[1895052649] 'compare' (duration: 42.51801ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T01:27:23.00637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:27:22.448326Z","time spent":"557.950875ms","remote":"127.0.0.1:51214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T01:27:23.192346Z","caller":"traceutil/trace.go:171","msg":"trace[1016312231] transaction","detail":"{read_only:false; response_revision:1175; number_of_response:1; }","duration":"218.088905ms","start":"2026-09-18T01:27:22.974236Z","end":"2026-09-18T01:27:23.192325Z","steps":["trace[1016312231] 'process raft request' (duration: 217.990913ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:27:23.192717Z","caller":"traceutil/trace.go:171","msg":"trace[21990519] linearizableReadLoop","detail":"{readStateIndex:1218; appliedIndex:1218; }","duration":"114.463925ms","start":"2026-09-18T01:27:23.078245Z","end":"2026-09-18T01:27:23.192709Z","steps":["trace[21990519] 'read index received' (duration: 114.460645ms)","trace[21990519] 'applied index is now lower than readState.Index' (duration: 2.78µs)"],"step_count":2} {"level":"warn","ts":"2026-09-18T01:27:23.192807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.546107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T01:27:23.192841Z","caller":"traceutil/trace.go:171","msg":"trace[657516241] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1175; }","duration":"114.631158ms","start":"2026-09-18T01:27:23.078202Z","end":"2026-09-18T01:27:23.192833Z","steps":["trace[657516241] 'agreement among raft nodes before linearized reading' (duration: 114.531796ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:27:24.674349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.618286ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T01:27:24.674725Z","caller":"traceutil/trace.go:171","msg":"trace[1410620656] linearizableReadLoop","detail":"{readStateIndex:1225; appliedIndex:1224; }","duration":"416.902303ms","start":"2026-09-18T01:27:24.257802Z","end":"2026-09-18T01:27:24.674705Z","steps":["trace[1410620656] 'read index received' (duration: 282.780347ms)","trace[1410620656] 'applied index is now lower than readState.Index' (duration: 134.120336ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T01:27:24.675042Z","caller":"traceutil/trace.go:171","msg":"trace[1195342254] transaction","detail":"{read_only:false; response_revision:1182; number_of_response:1; }","duration":"583.972138ms","start":"2026-09-18T01:27:24.091053Z","end":"2026-09-18T01:27:24.675025Z","steps":["trace[1195342254] 'process raft request' (duration: 449.562964ms)","trace[1195342254] 'compare' (duration: 133.255896ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T01:27:24.675162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:27:24.091037Z","time spent":"584.08846ms","remote":"127.0.0.1:51348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":797,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T01:27:24.675507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.711483ms","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-09-18T01:27:24.675587Z","caller":"traceutil/trace.go:171","msg":"trace[173348297] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1182; }","duration":"417.839085ms","start":"2026-09-18T01:27:24.257735Z","end":"2026-09-18T01:27:24.675574Z","steps":["trace[173348297] 'agreement among raft nodes before linearized reading' (duration: 417.640291ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:27:24.675704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:27:24.257706Z","time spent":"417.958038ms","remote":"127.0.0.1:51214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-18T01:27:25.094189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.052362ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T01:27:25.094523Z","caller":"traceutil/trace.go:171","msg":"trace[588789403] transaction","detail":"{read_only:false; response_revision:1184; number_of_response:1; }","duration":"408.791523ms","start":"2026-09-18T01:27:24.685709Z","end":"2026-09-18T01:27:25.094501Z","steps":["trace[588789403] 'process raft request' (duration: 408.708011ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:27:25.094687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:27:24.685676Z","time spent":"408.958517ms","remote":"127.0.0.1:51214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T01:27:25.09504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.087098ms","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-09-18T01:27:25.095062Z","caller":"traceutil/trace.go:171","msg":"trace[239222497] transaction","detail":"{read_only:false; response_revision:1183; number_of_response:1; }","duration":"645.767573ms","start":"2026-09-18T01:27:24.449262Z","end":"2026-09-18T01:27:25.09503Z","steps":["trace[239222497] 'process raft request' (duration: 483.775929ms)","trace[239222497] 'compare' (duration: 160.814986ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T01:27:25.095206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:27:24.449227Z","time spent":"645.896575ms","remote":"127.0.0.1:51214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T01:27:25.095215Z","caller":"traceutil/trace.go:171","msg":"trace[1610340324] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1184; }","duration":"702.240692ms","start":"2026-09-18T01:27:24.392864Z","end":"2026-09-18T01:27:25.095104Z","steps":["trace[1610340324] 'agreement among raft nodes before linearized reading' (duration: 702.060688ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:27:25.095268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:27:24.392843Z","time spent":"702.411356ms","remote":"127.0.0.1:51214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-18T01:27:25.094753Z","caller":"traceutil/trace.go:171","msg":"trace[43257300] linearizableReadLoop","detail":"{readStateIndex:1226; appliedIndex:1225; }","duration":"419.922324ms","start":"2026-09-18T01:27:24.674798Z","end":"2026-09-18T01:27:25.094721Z","steps":["trace[43257300] 'read index received' (duration: 258.32926ms)","trace[43257300] 'applied index is now lower than readState.Index' (duration: 161.589894ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T01:27:25.095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"784.785795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-09-18T01:27:25.095539Z","caller":"traceutil/trace.go:171","msg":"trace[1613947772] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1184; }","duration":"785.358628ms","start":"2026-09-18T01:27:24.310161Z","end":"2026-09-18T01:27:25.09552Z","steps":["trace[1613947772] 'agreement among raft nodes before linearized reading' (duration: 784.633551ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:27:25.09566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:27:24.310142Z","time spent":"785.499991ms","remote":"127.0.0.1:51214","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-09-18T01:27:25.095024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"700.551272ms","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-09-18T01:27:25.095952Z","caller":"traceutil/trace.go:171","msg":"trace[566409721] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1184; }","duration":"701.523925ms","start":"2026-09-18T01:27:24.394405Z","end":"2026-09-18T01:27:25.095929Z","steps":["trace[566409721] 'agreement among raft nodes before linearized reading' (duration: 700.490231ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:27:25.096015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:27:24.394373Z","time spent":"701.624886ms","remote":"127.0.0.1:51214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-18T01:27:25.095015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.312447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-18T01:27:25.096146Z","caller":"traceutil/trace.go:171","msg":"trace[330235823] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1184; }","duration":"625.515204ms","start":"2026-09-18T01:27:24.470616Z","end":"2026-09-18T01:27:25.096131Z","steps":["trace[330235823] 'agreement among raft nodes before linearized reading' (duration: 624.246805ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:27:25.096179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:27:24.470593Z","time spent":"625.572696ms","remote":"127.0.0.1:51492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":29,"response size":31,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-09-18T01:27:25.155373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.347938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-18T01:27:25.155451Z","caller":"traceutil/trace.go:171","msg":"trace[548655558] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1184; }","duration":"144.481591ms","start":"2026-09-18T01:27:25.010951Z","end":"2026-09-18T01:27:25.155433Z","steps":["trace[548655558] 'agreement among raft nodes before linearized reading' (duration: 144.145383ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:27:25.268876Z","caller":"traceutil/trace.go:171","msg":"trace[200393847] transaction","detail":"{read_only:false; response_revision:1188; number_of_response:1; }","duration":"109.199321ms","start":"2026-09-18T01:27:25.159651Z","end":"2026-09-18T01:27:25.268851Z","steps":["trace[200393847] 'process raft request' (duration: 55.002245ms)","trace[200393847] 'compare' (duration: 54.078513ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T01:27:27.011585Z","caller":"traceutil/trace.go:171","msg":"trace[541294976] transaction","detail":"{read_only:false; response_revision:1189; number_of_response:1; }","duration":"208.912068ms","start":"2026-09-18T01:27:26.802642Z","end":"2026-09-18T01:27:27.011554Z","steps":["trace[541294976] 'process raft request' (duration: 188.306254ms)","trace[541294976] 'compare' (duration: 20.074362ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T01:27:27.207408Z","caller":"traceutil/trace.go:171","msg":"trace[656874513] transaction","detail":"{read_only:false; response_revision:1191; number_of_response:1; }","duration":"107.049969ms","start":"2026-09-18T01:27:27.100333Z","end":"2026-09-18T01:27:27.207383Z","steps":["trace[656874513] 'process raft request' (duration: 106.942297ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:27:38.940594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.865975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-09-18T01:27:38.940679Z","caller":"traceutil/trace.go:171","msg":"trace[640195844] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1279; }","duration":"744.992228ms","start":"2026-09-18T01:27:38.195666Z","end":"2026-09-18T01:27:38.940658Z","steps":["trace[640195844] 'range keys from in-memory index tree' (duration: 744.697661ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:27:38.940729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:27:38.195647Z","time spent":"745.068109ms","remote":"127.0.0.1:51214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-09-18T01:27:39.221275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.002293ms","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:531"} {"level":"warn","ts":"2026-09-18T01:27:39.221297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.615947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T01:27:39.221366Z","caller":"traceutil/trace.go:171","msg":"trace[2138841218] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1280; }","duration":"173.165487ms","start":"2026-09-18T01:27:39.048176Z","end":"2026-09-18T01:27:39.221341Z","steps":["trace[2138841218] 'range keys from in-memory index tree' (duration: 172.82902ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:27:39.221421Z","caller":"traceutil/trace.go:171","msg":"trace[1090460372] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1280; }","duration":"142.788441ms","start":"2026-09-18T01:27:39.078607Z","end":"2026-09-18T01:27:39.221395Z","steps":["trace[1090460372] 'range keys from in-memory index tree' (duration: 142.534256ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:29:42.070162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.338038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-09-18T01:29:42.07022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.938399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-18T01:29:42.070263Z","caller":"traceutil/trace.go:171","msg":"trace[258741537] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1846; }","duration":"119.503872ms","start":"2026-09-18T01:29:41.950741Z","end":"2026-09-18T01:29:42.070244Z","steps":["trace[258741537] 'range keys from in-memory index tree' (duration: 119.105323ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:29:42.070306Z","caller":"traceutil/trace.go:171","msg":"trace[1728312414] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1846; }","duration":"122.052722ms","start":"2026-09-18T01:29:41.948236Z","end":"2026-09-18T01:29:42.070288Z","steps":["trace[1728312414] 'range keys from in-memory index tree' (duration: 121.816736ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:29:42.070468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.352853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-18T01:29:42.070506Z","caller":"traceutil/trace.go:171","msg":"trace[124707529] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1846; }","duration":"127.439707ms","start":"2026-09-18T01:29:41.943057Z","end":"2026-09-18T01:29:42.070497Z","steps":["trace[124707529] 'range keys from in-memory index tree' (duration: 127.21277ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:29:42.070614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.943183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-18T01:29:42.070689Z","caller":"traceutil/trace.go:171","msg":"trace[1670462944] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1846; }","duration":"120.063735ms","start":"2026-09-18T01:29:41.950608Z","end":"2026-09-18T01:29:42.070672Z","steps":["trace[1670462944] 'range keys from in-memory index tree' (duration: 119.80303ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:29:48.187769Z","caller":"traceutil/trace.go:171","msg":"trace[2146943540] linearizableReadLoop","detail":"{readStateIndex:1946; appliedIndex:1945; }","duration":"109.378677ms","start":"2026-09-18T01:29:48.078343Z","end":"2026-09-18T01:29:48.187722Z","steps":["trace[2146943540] 'read index received' (duration: 76.296776ms)","trace[2146943540] 'applied index is now lower than readState.Index' (duration: 33.079851ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T01:29:48.188513Z","caller":"traceutil/trace.go:171","msg":"trace[796408632] transaction","detail":"{read_only:false; response_revision:1873; number_of_response:1; }","duration":"141.188156ms","start":"2026-09-18T01:29:48.047274Z","end":"2026-09-18T01:29:48.188463Z","steps":["trace[796408632] 'process raft request' (duration: 107.419451ms)","trace[796408632] 'compare' (duration: 32.830013ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T01:29:48.188814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.437041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T01:29:48.188888Z","caller":"traceutil/trace.go:171","msg":"trace[1940928802] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1873; }","duration":"110.563154ms","start":"2026-09-18T01:29:48.078301Z","end":"2026-09-18T01:29:48.188865Z","steps":["trace[1940928802] 'agreement among raft nodes before linearized reading' (duration: 110.445151ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:29:48.426447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.356911ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T01:29:48.426663Z","caller":"traceutil/trace.go:171","msg":"trace[1537271519] linearizableReadLoop","detail":"{readStateIndex:1947; appliedIndex:1946; }","duration":"228.831657ms","start":"2026-09-18T01:29:48.197805Z","end":"2026-09-18T01:29:48.426637Z","steps":["trace[1537271519] 'read index received' (duration: 90.253821ms)","trace[1537271519] 'applied index is now lower than readState.Index' (duration: 138.576636ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T01:29:48.426732Z","caller":"traceutil/trace.go:171","msg":"trace[1797128795] transaction","detail":"{read_only:false; response_revision:1874; number_of_response:1; }","duration":"231.510459ms","start":"2026-09-18T01:29:48.195163Z","end":"2026-09-18T01:29:48.426673Z","steps":["trace[1797128795] 'process raft request' (duration: 92.853961ms)","trace[1797128795] 'compare' (duration: 138.239649ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T01:29:48.42686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.031561ms","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-09-18T01:29:48.42692Z","caller":"traceutil/trace.go:171","msg":"trace[942351555] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1874; }","duration":"229.155744ms","start":"2026-09-18T01:29:48.197745Z","end":"2026-09-18T01:29:48.426901Z","steps":["trace[942351555] 'agreement among raft nodes before linearized reading' (duration: 228.98053ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:29:48.427016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.860662ms","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-09-18T01:29:48.427126Z","caller":"traceutil/trace.go:171","msg":"trace[258241996] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1874; }","duration":"200.019217ms","start":"2026-09-18T01:29:48.227096Z","end":"2026-09-18T01:29:48.427116Z","steps":["trace[258241996] 'agreement among raft nodes before linearized reading' (duration: 199.684448ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:29:48.427133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.046435ms","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-09-18T01:29:48.427256Z","caller":"traceutil/trace.go:171","msg":"trace[776598188] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1874; }","duration":"199.182988ms","start":"2026-09-18T01:29:48.228056Z","end":"2026-09-18T01:29:48.427239Z","steps":["trace[776598188] 'agreement among raft nodes before linearized reading' (duration: 199.005353ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:29:48.427412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.551212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-18T01:29:48.427475Z","caller":"traceutil/trace.go:171","msg":"trace[1454605774] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1874; }","duration":"198.645395ms","start":"2026-09-18T01:29:48.22882Z","end":"2026-09-18T01:29:48.427465Z","steps":["trace[1454605774] 'agreement among raft nodes before linearized reading' (duration: 198.03231ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:29:50.014206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.081229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.3\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-09-18T01:29:50.014342Z","caller":"traceutil/trace.go:171","msg":"trace[1371601214] range","detail":"{range_begin:/registry/masterleases/162.253.55.3; range_end:; response_count:1; response_revision:1880; }","duration":"115.241192ms","start":"2026-09-18T01:29:49.899066Z","end":"2026-09-18T01:29:50.014307Z","steps":["trace[1371601214] 'range keys from in-memory index tree' (duration: 114.911034ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:29:50.175942Z","caller":"traceutil/trace.go:171","msg":"trace[2035246511] transaction","detail":"{read_only:false; response_revision:1882; number_of_response:1; }","duration":"103.187352ms","start":"2026-09-18T01:29:50.072718Z","end":"2026-09-18T01:29:50.175906Z","steps":["trace[2035246511] 'process raft request' (duration: 80.345489ms)","trace[2035246511] 'compare' (duration: 22.694848ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T01:29:50.592674Z","caller":"traceutil/trace.go:171","msg":"trace[1226314082] linearizableReadLoop","detail":"{readStateIndex:1960; appliedIndex:1959; }","duration":"104.121263ms","start":"2026-09-18T01:29:50.488515Z","end":"2026-09-18T01:29:50.592637Z","steps":["trace[1226314082] 'read index received' (duration: 64.762737ms)","trace[1226314082] 'applied index is now lower than readState.Index' (duration: 39.357596ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T01:29:50.592685Z","caller":"traceutil/trace.go:171","msg":"trace[2037228722] transaction","detail":"{read_only:false; response_revision:1886; number_of_response:1; }","duration":"143.285655ms","start":"2026-09-18T01:29:50.449368Z","end":"2026-09-18T01:29:50.592654Z","steps":["trace[2037228722] 'process raft request' (duration: 103.96599ms)","trace[2037228722] 'compare' (duration: 39.190872ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T01:29:50.592893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.309028ms","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-09-18T01:29:50.592939Z","caller":"traceutil/trace.go:171","msg":"trace[1191100235] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1886; }","duration":"104.372809ms","start":"2026-09-18T01:29:50.488554Z","end":"2026-09-18T01:29:50.592927Z","steps":["trace[1191100235] 'agreement among raft nodes before linearized reading' (duration: 104.254906ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:29:50.593038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.524942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-18T01:29:50.59305Z","caller":"traceutil/trace.go:171","msg":"trace[1229808953] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1886; }","duration":"104.574844ms","start":"2026-09-18T01:29:50.488472Z","end":"2026-09-18T01:29:50.593047Z","steps":["trace[1229808953] 'agreement among raft nodes before linearized reading' (duration: 104.538783ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:30:50.047799Z","caller":"traceutil/trace.go:171","msg":"trace[758487633] transaction","detail":"{read_only:false; response_revision:2157; number_of_response:1; }","duration":"110.692639ms","start":"2026-09-18T01:30:49.937079Z","end":"2026-09-18T01:30:50.047772Z","steps":["trace[758487633] 'process raft request' (duration: 81.356385ms)","trace[758487633] 'compare' (duration: 29.17279ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T01:31:27.239081Z","caller":"traceutil/trace.go:171","msg":"trace[190806210] transaction","detail":"{read_only:false; response_revision:2327; number_of_response:1; }","duration":"144.794774ms","start":"2026-09-18T01:31:27.094263Z","end":"2026-09-18T01:31:27.239058Z","steps":["trace[190806210] 'process raft request' (duration: 70.850962ms)","trace[190806210] 'compare' (duration: 73.653036ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T01:31:53.715776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.601654ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T01:31:53.716035Z","caller":"traceutil/trace.go:171","msg":"trace[2146115412] linearizableReadLoop","detail":"{readStateIndex:2546; appliedIndex:2544; }","duration":"249.65593ms","start":"2026-09-18T01:31:53.466359Z","end":"2026-09-18T01:31:53.716015Z","steps":["trace[2146115412] 'read index received' (duration: 15.699827ms)","trace[2146115412] 'applied index is now lower than readState.Index' (duration: 233.954903ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T01:31:53.716073Z","caller":"traceutil/trace.go:171","msg":"trace[889996583] transaction","detail":"{read_only:false; response_revision:2448; number_of_response:1; }","duration":"278.86025ms","start":"2026-09-18T01:31:53.437184Z","end":"2026-09-18T01:31:53.716044Z","steps":["trace[889996583] 'process raft request' (duration: 278.718187ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:31:53.716131Z","caller":"traceutil/trace.go:171","msg":"trace[853647108] transaction","detail":"{read_only:false; response_revision:2447; number_of_response:1; }","duration":"278.940491ms","start":"2026-09-18T01:31:53.43717Z","end":"2026-09-18T01:31:53.71611Z","steps":["trace[853647108] 'process raft request' (duration: 44.884156ms)","trace[853647108] 'compare' (duration: 233.455351ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T01:31:53.716196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.833894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-18T01:31:53.716238Z","caller":"traceutil/trace.go:171","msg":"trace[2052047611] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2448; }","duration":"249.876955ms","start":"2026-09-18T01:31:53.466347Z","end":"2026-09-18T01:31:53.716224Z","steps":["trace[2052047611] 'agreement among raft nodes before linearized reading' (duration: 249.728021ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:32:14.254692Z","caller":"traceutil/trace.go:171","msg":"trace[827051179] transaction","detail":"{read_only:false; response_revision:2561; number_of_response:1; }","duration":"340.956021ms","start":"2026-09-18T01:32:13.913704Z","end":"2026-09-18T01:32:14.25466Z","steps":["trace[827051179] 'process raft request' (duration: 245.031895ms)","trace[827051179] 'compare' (duration: 95.541196ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T01:32:14.254834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:32:13.913668Z","time spent":"341.095874ms","remote":"127.0.0.1:51214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T01:32:14.255268Z","caller":"traceutil/trace.go:171","msg":"trace[1951287905] transaction","detail":"{read_only:false; response_revision:2562; number_of_response:1; }","duration":"341.339269ms","start":"2026-09-18T01:32:13.913914Z","end":"2026-09-18T01:32:14.255253Z","steps":["trace[1951287905] 'process raft request' (duration: 340.885589ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:32:14.255344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:32:13.913905Z","time spent":"341.40192ms","remote":"127.0.0.1:51214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T01:32:14.255966Z","caller":"traceutil/trace.go:171","msg":"trace[826010606] linearizableReadLoop","detail":"{readStateIndex:2666; appliedIndex:2663; }","duration":"178.160184ms","start":"2026-09-18T01:32:14.077765Z","end":"2026-09-18T01:32:14.255925Z","steps":["trace[826010606] 'read index received' (duration: 80.886677ms)","trace[826010606] 'applied index is now lower than readState.Index' (duration: 97.272327ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T01:32:14.25609Z","caller":"traceutil/trace.go:171","msg":"trace[1550442991] transaction","detail":"{read_only:false; response_revision:2563; number_of_response:1; }","duration":"341.722267ms","start":"2026-09-18T01:32:13.91434Z","end":"2026-09-18T01:32:14.256063Z","steps":["trace[1550442991] 'process raft request' (duration: 341.338728ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:32:14.256243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:32:13.914324Z","time spent":"341.82114ms","remote":"127.0.0.1:51214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T01:32:14.256277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.501202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T01:32:14.256527Z","caller":"traceutil/trace.go:171","msg":"trace[1693112581] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2563; }","duration":"178.775507ms","start":"2026-09-18T01:32:14.07773Z","end":"2026-09-18T01:32:14.256506Z","steps":["trace[1693112581] 'agreement among raft nodes before linearized reading' (duration: 178.353788ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:32:22.784774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.049145ms","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-09-18T01:32:22.784893Z","caller":"traceutil/trace.go:171","msg":"trace[1649434532] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:2606; }","duration":"148.241519ms","start":"2026-09-18T01:32:22.636626Z","end":"2026-09-18T01:32:22.784868Z","steps":["trace[1649434532] 'count revisions from in-memory index tree' (duration: 147.960263ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:32:22.784899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.911094ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-18T01:32:22.78517Z","caller":"traceutil/trace.go:171","msg":"trace[908670332] linearizableReadLoop","detail":"{readStateIndex:2712; appliedIndex:2711; }","duration":"141.619366ms","start":"2026-09-18T01:32:22.643529Z","end":"2026-09-18T01:32:22.785148Z","steps":["trace[908670332] 'read index received' (duration: 36.326523ms)","trace[908670332] 'applied index is now lower than readState.Index' (duration: 105.291282ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T01:32:22.785204Z","caller":"traceutil/trace.go:171","msg":"trace[559561441] transaction","detail":"{read_only:false; response_revision:2607; number_of_response:1; }","duration":"142.290391ms","start":"2026-09-18T01:32:22.642879Z","end":"2026-09-18T01:32:22.785169Z","steps":["trace[559561441] 'process raft request' (duration: 37.006078ms)","trace[559561441] 'compare' (duration: 104.71551ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T01:32:22.785412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.862891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2579"} {"level":"info","ts":"2026-09-18T01:32:22.785453Z","caller":"traceutil/trace.go:171","msg":"trace[150983029] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:2607; }","duration":"141.937192ms","start":"2026-09-18T01:32:22.643504Z","end":"2026-09-18T01:32:22.785441Z","steps":["trace[150983029] 'agreement among raft nodes before linearized reading' (duration: 141.706307ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:32:24.1562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.954412ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T01:32:24.156378Z","caller":"traceutil/trace.go:171","msg":"trace[391557852] linearizableReadLoop","detail":"{readStateIndex:2713; appliedIndex:2712; }","duration":"1.077929717s","start":"2026-09-18T01:32:23.078385Z","end":"2026-09-18T01:32:24.156315Z","steps":["trace[391557852] 'read index received' (duration: 398.74939ms)","trace[391557852] 'applied index is now lower than readState.Index' (duration: 679.178717ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T01:32:24.156577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.078200494s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T01:32:24.156544Z","caller":"traceutil/trace.go:171","msg":"trace[150761389] transaction","detail":"{read_only:false; response_revision:2608; number_of_response:1; }","duration":"1.407296378s","start":"2026-09-18T01:32:22.749198Z","end":"2026-09-18T01:32:24.156495Z","steps":["trace[150761389] 'process raft request' (duration: 727.924526ms)","trace[150761389] 'compare' (duration: 678.727617ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T01:32:24.156626Z","caller":"traceutil/trace.go:171","msg":"trace[679124396] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2608; }","duration":"1.078290056s","start":"2026-09-18T01:32:23.078322Z","end":"2026-09-18T01:32:24.156612Z","steps":["trace[679124396] 'agreement among raft nodes before linearized reading' (duration: 1.078185673s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:32:24.156666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:32:23.078298Z","time spent":"1.078358987s","remote":"127.0.0.1:50912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-18T01:32:24.156797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:32:22.749177Z","time spent":"1.407471242s","remote":"127.0.0.1:51214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T01:32:24.157075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.693764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-09-18T01:32:24.157182Z","caller":"traceutil/trace.go:171","msg":"trace[424548634] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2608; }","duration":"268.846807ms","start":"2026-09-18T01:32:23.888315Z","end":"2026-09-18T01:32:24.157162Z","steps":["trace[424548634] 'agreement among raft nodes before linearized reading' (duration: 268.500929ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:32:24.157248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.729273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-09-18T01:32:24.157089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.290944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-09-18T01:32:24.157398Z","caller":"traceutil/trace.go:171","msg":"trace[818977155] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2608; }","duration":"743.648812ms","start":"2026-09-18T01:32:23.413734Z","end":"2026-09-18T01:32:24.157383Z","steps":["trace[818977155] 'agreement among raft nodes before linearized reading' (duration: 743.169121ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:32:24.15746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:32:23.413715Z","time spent":"743.727134ms","remote":"127.0.0.1:51214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-09-18T01:32:24.157342Z","caller":"traceutil/trace.go:171","msg":"trace[1856108090] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2608; }","duration":"405.868856ms","start":"2026-09-18T01:32:23.751448Z","end":"2026-09-18T01:32:24.157317Z","steps":["trace[1856108090] 'agreement among raft nodes before linearized reading' (duration: 405.373935ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:32:24.157615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"951.299459ms","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-09-18T01:32:24.157617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:32:23.751422Z","time spent":"406.178133ms","remote":"127.0.0.1:51214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-18T01:32:24.157673Z","caller":"traceutil/trace.go:171","msg":"trace[768853786] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2608; }","duration":"951.388532ms","start":"2026-09-18T01:32:23.20627Z","end":"2026-09-18T01:32:24.157658Z","steps":["trace[768853786] 'agreement among raft nodes before linearized reading' (duration: 951.205327ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:32:24.157793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:32:23.206254Z","time spent":"951.523415ms","remote":"127.0.0.1:51214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-18T01:32:24.95022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.361393ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T01:32:24.950502Z","caller":"traceutil/trace.go:171","msg":"trace[1678430797] transaction","detail":"{read_only:false; response_revision:2609; number_of_response:1; }","duration":"786.138469ms","start":"2026-09-18T01:32:24.164321Z","end":"2026-09-18T01:32:24.95046Z","steps":["trace[1678430797] 'process raft request' (duration: 171.457548ms)","trace[1678430797] 'compare' (duration: 613.582415ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T01:32:24.950662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:32:24.164294Z","time spent":"786.284472ms","remote":"127.0.0.1:51214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T01:32:24.950824Z","caller":"traceutil/trace.go:171","msg":"trace[1300092799] linearizableReadLoop","detail":"{readStateIndex:2717; appliedIndex:2713; }","duration":"786.028456ms","start":"2026-09-18T01:32:24.164786Z","end":"2026-09-18T01:32:24.950814Z","steps":["trace[1300092799] 'read index received' (duration: 171.008348ms)","trace[1300092799] 'applied index is now lower than readState.Index' (duration: 615.018858ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T01:32:24.950837Z","caller":"traceutil/trace.go:171","msg":"trace[995437650] transaction","detail":"{read_only:false; response_revision:2611; number_of_response:1; }","duration":"786.080048ms","start":"2026-09-18T01:32:24.164469Z","end":"2026-09-18T01:32:24.950549Z","steps":["trace[995437650] 'process raft request' (duration: 786.006306ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:32:24.951152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"782.669598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-18T01:32:24.951252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.700227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-09-18T01:32:24.951241Z","caller":"traceutil/trace.go:171","msg":"trace[788149659] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2612; }","duration":"782.825641ms","start":"2026-09-18T01:32:24.168402Z","end":"2026-09-18T01:32:24.951228Z","steps":["trace[788149659] 'agreement among raft nodes before linearized reading' (duration: 782.657867ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:32:24.951296Z","caller":"traceutil/trace.go:171","msg":"trace[2109976907] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2612; }","duration":"119.785628ms","start":"2026-09-18T01:32:24.831501Z","end":"2026-09-18T01:32:24.951286Z","steps":["trace[2109976907] 'agreement among raft nodes before linearized reading' (duration: 119.633945ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:32:24.951286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.885905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-09-18T01:32:24.951325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:32:24.168388Z","time spent":"782.925683ms","remote":"127.0.0.1:50912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-18T01:32:24.95143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:32:24.164429Z","time spent":"786.744413ms","remote":"127.0.0.1:51214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T01:32:24.951058Z","caller":"traceutil/trace.go:171","msg":"trace[337296768] transaction","detail":"{read_only:false; response_revision:2610; number_of_response:1; }","duration":"786.64963ms","start":"2026-09-18T01:32:24.164376Z","end":"2026-09-18T01:32:24.951026Z","steps":["trace[337296768] 'process raft request' (duration: 786.023826ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:32:24.951587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.347045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-18T01:32:24.951618Z","caller":"traceutil/trace.go:171","msg":"trace[1479369123] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2612; }","duration":"621.415306ms","start":"2026-09-18T01:32:24.330193Z","end":"2026-09-18T01:32:24.951609Z","steps":["trace[1479369123] 'agreement among raft nodes before linearized reading' (duration: 621.345005ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:32:24.951666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:32:24.330172Z","time spent":"621.481368ms","remote":"127.0.0.1:51214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-09-18T01:32:24.951346Z","caller":"traceutil/trace.go:171","msg":"trace[1526153185] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2612; }","duration":"611.959637ms","start":"2026-09-18T01:32:24.339368Z","end":"2026-09-18T01:32:24.951328Z","steps":["trace[1526153185] 'agreement among raft nodes before linearized reading' (duration: 611.810894ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:32:24.951784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:32:24.339358Z","time spent":"612.391068ms","remote":"127.0.0.1:51214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-18T01:32:24.95145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.597505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-18T01:32:24.952075Z","caller":"traceutil/trace.go:171","msg":"trace[404883945] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2612; }","duration":"539.24563ms","start":"2026-09-18T01:32:24.412814Z","end":"2026-09-18T01:32:24.95206Z","steps":["trace[404883945] 'agreement among raft nodes before linearized reading' (duration: 538.562833ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:32:24.952148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:32:24.412799Z","time spent":"539.336052ms","remote":"127.0.0.1:51214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-18T01:32:24.951662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:32:24.164347Z","time spent":"787.259094ms","remote":"127.0.0.1:51214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T01:32:24.951473Z","caller":"traceutil/trace.go:171","msg":"trace[1277841119] transaction","detail":"{read_only:false; response_revision:2612; number_of_response:1; }","duration":"786.896495ms","start":"2026-09-18T01:32:24.164566Z","end":"2026-09-18T01:32:24.951462Z","steps":["trace[1277841119] 'process raft request' (duration: 785.950084ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:32:24.952405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:32:24.164557Z","time spent":"787.780597ms","remote":"127.0.0.1:51214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T01:32:24.951512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"786.732682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T01:32:24.952569Z","caller":"traceutil/trace.go:171","msg":"trace[1165713513] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:2612; }","duration":"787.811247ms","start":"2026-09-18T01:32:24.164741Z","end":"2026-09-18T01:32:24.952552Z","steps":["trace[1165713513] 'agreement among raft nodes before linearized reading' (duration: 786.750533ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:32:24.95262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:32:24.164727Z","time spent":"787.879628ms","remote":"127.0.0.1:51094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"warn","ts":"2026-09-18T01:32:25.539097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.130129ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T01:32:25.539193Z","caller":"traceutil/trace.go:171","msg":"trace[1003273451] transaction","detail":"{read_only:false; response_revision:2613; number_of_response:1; }","duration":"581.287535ms","start":"2026-09-18T01:32:24.957887Z","end":"2026-09-18T01:32:25.539175Z","steps":["trace[1003273451] 'process raft request' (duration: 432.003702ms)","trace[1003273451] 'compare' (duration: 148.849593ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T01:32:25.53947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:32:24.957867Z","time spent":"581.352387ms","remote":"127.0.0.1:51214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T01:32:25.891821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.917133ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T01:32:25.892283Z","caller":"traceutil/trace.go:171","msg":"trace[939347626] transaction","detail":"{read_only:false; response_revision:2614; number_of_response:1; }","duration":"933.795263ms","start":"2026-09-18T01:32:24.958438Z","end":"2026-09-18T01:32:25.892234Z","steps":["trace[939347626] 'process raft request' (duration: 822.345439ms)","trace[939347626] 'compare' (duration: 110.684537ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T01:32:25.892381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:32:24.958417Z","time spent":"933.918116ms","remote":"127.0.0.1:51214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T01:32:25.892484Z","caller":"traceutil/trace.go:171","msg":"trace[891735390] transaction","detail":"{read_only:false; response_revision:2616; number_of_response:1; }","duration":"932.33551ms","start":"2026-09-18T01:32:24.960135Z","end":"2026-09-18T01:32:25.89247Z","steps":["trace[891735390] 'process raft request' (duration: 931.915679ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:32:25.892469Z","caller":"traceutil/trace.go:171","msg":"trace[239751356] transaction","detail":"{read_only:false; response_revision:2615; number_of_response:1; }","duration":"933.492097ms","start":"2026-09-18T01:32:24.958899Z","end":"2026-09-18T01:32:25.892392Z","steps":["trace[239751356] 'process raft request' (duration: 933.044906ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:32:25.89262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:32:24.960122Z","time spent":"932.413461ms","remote":"127.0.0.1:51214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T01:32:25.892626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:32:24.958888Z","time spent":"933.65921ms","remote":"127.0.0.1:51214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T01:32:26.229211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.094351ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-09-18T01:32:26.229322Z","caller":"traceutil/trace.go:171","msg":"trace[321435752] linearizableReadLoop","detail":"{readStateIndex:2722; appliedIndex:2718; }","duration":"687.546291ms","start":"2026-09-18T01:32:25.541758Z","end":"2026-09-18T01:32:26.229304Z","steps":["trace[321435752] 'read index received' (duration: 238.986505ms)","trace[321435752] 'applied index is now lower than readState.Index' (duration: 448.558286ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T01:32:26.229636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.863188ms","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:509"} {"level":"info","ts":"2026-09-18T01:32:26.229689Z","caller":"traceutil/trace.go:171","msg":"trace[267620316] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2616; }","duration":"687.95284ms","start":"2026-09-18T01:32:25.541722Z","end":"2026-09-18T01:32:26.229675Z","steps":["trace[267620316] 'agreement among raft nodes before linearized reading' (duration: 687.778036ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:32:26.229724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:32:25.541704Z","time spent":"688.010841ms","remote":"127.0.0.1:51214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-09-18T01:32:26.275437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.215475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T01:32:26.275463Z","caller":"traceutil/trace.go:171","msg":"trace[720295446] transaction","detail":"{read_only:false; response_revision:2617; number_of_response:1; }","duration":"112.252104ms","start":"2026-09-18T01:32:26.163188Z","end":"2026-09-18T01:32:26.27544Z","steps":["trace[720295446] 'process raft request' (duration: 112.054949ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:32:26.275494Z","caller":"traceutil/trace.go:171","msg":"trace[1435799108] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2617; }","duration":"197.277107ms","start":"2026-09-18T01:32:26.078205Z","end":"2026-09-18T01:32:26.275482Z","steps":["trace[1435799108] 'agreement among raft nodes before linearized reading' (duration: 197.180365ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:32:26.493496Z","caller":"traceutil/trace.go:171","msg":"trace[283427233] linearizableReadLoop","detail":"{readStateIndex:2724; appliedIndex:2723; }","duration":"216.040462ms","start":"2026-09-18T01:32:26.277435Z","end":"2026-09-18T01:32:26.493476Z","steps":["trace[283427233] 'read index received' (duration: 151.445843ms)","trace[283427233] 'applied index is now lower than readState.Index' (duration: 64.593609ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T01:32:26.493691Z","caller":"traceutil/trace.go:171","msg":"trace[2129875007] transaction","detail":"{read_only:false; response_revision:2618; number_of_response:1; }","duration":"258.027376ms","start":"2026-09-18T01:32:26.235653Z","end":"2026-09-18T01:32:26.493681Z","steps":["trace[2129875007] 'process raft request' (duration: 193.178012ms)","trace[2129875007] 'compare' (duration: 64.323942ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T01:32:26.494352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.408021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T01:32:26.494409Z","caller":"traceutil/trace.go:171","msg":"trace[225435446] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2618; }","duration":"217.031775ms","start":"2026-09-18T01:32:26.27737Z","end":"2026-09-18T01:32:26.494402Z","steps":["trace[225435446] 'agreement among raft nodes before linearized reading' (duration: 216.444252ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:32:27.549385Z","caller":"traceutil/trace.go:171","msg":"trace[1625110462] transaction","detail":"{read_only:false; response_revision:2625; number_of_response:1; }","duration":"141.173686ms","start":"2026-09-18T01:32:27.408173Z","end":"2026-09-18T01:32:27.549347Z","steps":["trace[1625110462] 'process raft request' (duration: 140.945841ms)"],"step_count":1}